builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0281 starttime: 1487259098.12 results: success (0) buildid: 20170216043618 builduid: 32bf7002178d4777ab1775442a32dc7f revision: d273229cde3cccea129e2450ec1234e9ac51efc4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:38.118625) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:38.119065) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:38.119338) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:38.145566) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:38.145869) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-16 07:31:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 176M=0s 2017-02-16 07:31:38 (176 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087504 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:38.257229) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:38.257550) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.025602 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:38.306334) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 07:31:38.306692) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d273229cde3cccea129e2450ec1234e9ac51efc4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d273229cde3cccea129e2450ec1234e9ac51efc4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-16 07:31:38,411 truncating revision to first 12 chars 2017-02-16 07:31:38,411 Setting DEBUG logging. 2017-02-16 07:31:38,412 attempt 1/10 2017-02-16 07:31:38,412 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d273229cde3c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-16 07:31:39,322 unpacking tar archive at: mozilla-beta-d273229cde3c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.177573 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 07:31:39.507190) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:39.507518) ========= script_repo_revision: d273229cde3cccea129e2450ec1234e9ac51efc4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:39.507887) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:39.508190) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 07:31:39.527837) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-16 07:31:39.528186) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:31:39 INFO - MultiFileLogger online at 20170216 07:31:39 in /builds/slave/test 07:31:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 07:31:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:31:39 INFO - {'append_to_log': False, 07:31:39 INFO - 'base_work_dir': '/builds/slave/test', 07:31:39 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:31:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:31:39 INFO - 'branch': 'Mozilla-Beta', 07:31:39 INFO - 'buildbot_json_path': 'buildprops.json', 07:31:39 INFO - 'config_files': ('talos/mac_config.py',), 07:31:39 INFO - 'default_actions': ('clobber', 07:31:39 INFO - 'read-buildbot-config', 07:31:39 INFO - 'download-and-extract', 07:31:39 INFO - 'populate-webroot', 07:31:39 INFO - 'create-virtualenv', 07:31:39 INFO - 'install', 07:31:39 INFO - 'run-tests'), 07:31:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:31:39 INFO - 'download_minidump_stackwalk': True, 07:31:39 INFO - 'download_symbols': 'ondemand', 07:31:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:31:39 INFO - 'tooltool.py': '/tools/tooltool.py', 07:31:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:31:39 INFO - '/tools/misc-python/virtualenv.py')}, 07:31:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:31:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:31:39 INFO - 'installer_path': 'installer.exe', 07:31:39 INFO - 'log_level': 'info', 07:31:39 INFO - 'log_name': 'talos', 07:31:39 INFO - 'log_to_console': True, 07:31:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:31:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:31:39 INFO - 'opt_config_files': (), 07:31:39 INFO - 'pip_index': False, 07:31:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:39 INFO - 'cmd': ('bash', 07:31:39 INFO - '-c', 07:31:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:39 INFO - 'enabled': True, 07:31:39 INFO - 'halt_on_failure': False, 07:31:39 INFO - 'name': 'check_screen_resolution'},), 07:31:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:39 INFO - 'cmd': ('bash', 07:31:39 INFO - '-c', 07:31:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:39 INFO - 'enabled': True, 07:31:39 INFO - 'halt_on_failure': False, 07:31:39 INFO - 'name': 'check_screen_resolution'},), 07:31:39 INFO - 'run_cmd_checks_enabled': True, 07:31:39 INFO - 'sps_profile': False, 07:31:39 INFO - 'sps_profile_interval': 0, 07:31:39 INFO - 'suite': 'dromaeojs-e10s', 07:31:39 INFO - 'system_bits': '32', 07:31:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:31:39 INFO - 'title': 't-yosemite-r7-0281', 07:31:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:31:39 INFO - 'use_talos_json': True, 07:31:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:31:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:31:39 INFO - 'work_dir': 'build'} 07:31:39 INFO - [mozharness: 2017-02-16 15:31:39.739214Z] Running clobber step. 07:31:39 INFO - Running pre-action listener: _resource_record_pre_action 07:31:39 INFO - Running main action method: clobber 07:31:39 INFO - rmtree: /builds/slave/test/build 07:31:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:31:42 INFO - Running post-action listener: _resource_record_post_action 07:31:42 INFO - [mozharness: 2017-02-16 15:31:42.279039Z] Finished clobber step (success) 07:31:42 INFO - [mozharness: 2017-02-16 15:31:42.279173Z] Running read-buildbot-config step. 07:31:42 INFO - Running pre-action listener: _resource_record_pre_action 07:31:42 INFO - Running main action method: read_buildbot_config 07:31:42 INFO - Using buildbot properties: 07:31:42 INFO - { 07:31:42 INFO - "project": "", 07:31:42 INFO - "product": "firefox", 07:31:42 INFO - "script_repo_revision": "production", 07:31:42 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 07:31:42 INFO - "repository": "", 07:31:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 07:31:42 INFO - "buildid": "20170216043618", 07:31:42 INFO - "pgo_build": "False", 07:31:42 INFO - "basedir": "/builds/slave/test", 07:31:42 INFO - "buildnumber": 7, 07:31:42 INFO - "slavename": "t-yosemite-r7-0281", 07:31:42 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:31:42 INFO - "platform": "yosemite_r7", 07:31:42 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 07:31:42 INFO - "branch": "mozilla-beta", 07:31:42 INFO - "repo_path": "releases/mozilla-beta", 07:31:42 INFO - "revision": "d273229cde3cccea129e2450ec1234e9ac51efc4", 07:31:42 INFO - "stage_platform": "macosx64", 07:31:42 INFO - "builduid": "32bf7002178d4777ab1775442a32dc7f", 07:31:42 INFO - "slavebuilddir": "test" 07:31:42 INFO - } 07:31:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 07:31:42 INFO - Running post-action listener: _resource_record_post_action 07:31:42 INFO - [mozharness: 2017-02-16 15:31:42.282422Z] Finished read-buildbot-config step (success) 07:31:42 INFO - [mozharness: 2017-02-16 15:31:42.282542Z] Running download-and-extract step. 07:31:42 INFO - Running pre-action listener: _resource_record_pre_action 07:31:42 INFO - Running main action method: download_and_extract 07:31:42 INFO - mkdir: /builds/slave/test/build/tests 07:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:42 INFO - trying https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 07:31:42 INFO - Downloading https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 07:31:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 07:31:43 INFO - Downloaded 1165 bytes. 07:31:43 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 07:31:43 INFO - Using the following test package requirements: 07:31:43 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 07:31:43 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 07:31:43 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 07:31:43 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 07:31:43 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 07:31:43 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:31:43 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 07:31:43 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 07:31:43 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 07:31:43 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 07:31:43 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 07:31:43 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 07:31:43 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 07:31:43 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 07:31:43 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 07:31:43 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 07:31:43 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 07:31:43 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 07:31:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 07:31:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 07:31:43 INFO - Fetch https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 07:31:44 INFO - Http code: 200 07:31:44 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 07:31:44 INFO - x-amz-cf-id: 0UbuCrTY89M65x8U7yvdkVtWGYbIrGmw-dxc-hoAyn5Hp-kNiEHtRQ== 07:31:44 INFO - x-amz-version-id: ooLmpISPh6FAdbNGXD9yf_H4m8cHsRtU 07:31:44 INFO - Expected file size: 16094097 07:31:44 INFO - Obtained file size: 16094097 07:31:51 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 07:31:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 07:31:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 07:31:51 INFO - Fetch https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 07:31:51 INFO - Http code: 200 07:31:51 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 07:31:51 INFO - x-amz-cf-id: 4mfFMacS8_VxCrDBAKB4dZnwrMdqpwC0pIU8SUgxLdzgrFgCUmZN5w== 07:31:51 INFO - x-amz-version-id: ooLmpISPh6FAdbNGXD9yf_H4m8cHsRtU 07:31:52 INFO - Expected file size: 16094097 07:31:52 INFO - Obtained file size: 16094097 07:31:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 07:31:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 07:31:58 INFO - Fetch https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 07:31:59 INFO - Http code: 200 07:31:59 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 07:31:59 INFO - x-amz-cf-id: HoTyXgt_PXLp3eUG3SECzHrOMruIjjMsbsU90YGRkMCyKLRmoR5LRw== 07:31:59 INFO - x-amz-version-id: .QJM1Qn0QQGavYfj6yRLVuJi9RwSVp.L 07:31:59 INFO - Expected file size: 14403005 07:31:59 INFO - Obtained file size: 14403005 07:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:59 INFO - trying https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 07:31:59 INFO - Downloading https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 07:31:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 07:32:01 INFO - Downloaded 82114135 bytes. 07:32:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 07:32:01 INFO - mkdir: /builds/slave/test/properties 07:32:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:32:01 INFO - Writing to file /builds/slave/test/properties/build_url 07:32:01 INFO - Contents: 07:32:01 INFO - build_url:https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 07:32:02 INFO - Running post-action listener: _resource_record_post_action 07:32:02 INFO - Running post-action listener: set_extra_try_arguments 07:32:02 INFO - [mozharness: 2017-02-16 15:32:02.598456Z] Finished download-and-extract step (success) 07:32:02 INFO - [mozharness: 2017-02-16 15:32:02.598594Z] Running populate-webroot step. 07:32:02 INFO - Running pre-action listener: _resource_record_pre_action 07:32:02 INFO - Running main action method: populate_webroot 07:32:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:32:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:32:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:32:02 INFO - '--tppagecycles', 07:32:02 INFO - '7'], 07:32:02 INFO - 'tests': ['tsvgm']}}, 07:32:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:32:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:32:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:32:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:32:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:32:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:32:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:02 INFO - 'talos_options': ['--disable-e10s'], 07:32:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:02 INFO - 'talos_options': ['--disable-e10s'], 07:32:02 INFO - 'tests': ['damp', 'tps']}, 07:32:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:02 INFO - 'tests': ['damp', 'tps']}, 07:32:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:32:02 INFO - 'tests': ['dromaeo_dom']}, 07:32:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:32:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:32:02 INFO - 'tests': ['basic_compositor_video']}, 07:32:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:32:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:32:02 INFO - 'tests': ['a11yr', 07:32:02 INFO - 'ts_paint', 07:32:02 INFO - 'tpaint', 07:32:02 INFO - 'sessionrestore', 07:32:02 INFO - 'sessionrestore_no_auto_restore', 07:32:02 INFO - 'tabpaint']}, 07:32:02 INFO - 'other-e10s': {'tests': ['a11yr', 07:32:02 INFO - 'ts_paint', 07:32:02 INFO - 'tpaint', 07:32:02 INFO - 'sessionrestore', 07:32:02 INFO - 'sessionrestore_no_auto_restore', 07:32:02 INFO - 'tabpaint']}, 07:32:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:32:02 INFO - 'ts_paint', 07:32:02 INFO - 'tpaint', 07:32:02 INFO - 'sessionrestore', 07:32:02 INFO - 'sessionrestore_no_auto_restore', 07:32:02 INFO - 'tabpaint']}, 07:32:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:32:02 INFO - 'ts_paint', 07:32:02 INFO - 'tpaint', 07:32:02 INFO - 'sessionrestore', 07:32:02 INFO - 'sessionrestore_no_auto_restore', 07:32:02 INFO - 'tabpaint']}, 07:32:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:32:02 INFO - 'tests': ['a11yr', 07:32:02 INFO - 'ts_paint', 07:32:02 INFO - 'tpaint', 07:32:02 INFO - 'sessionrestore', 07:32:02 INFO - 'sessionrestore_no_auto_restore', 07:32:02 INFO - 'tabpaint']}, 07:32:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:32:02 INFO - 'tests': ['a11yr', 07:32:02 INFO - 'ts_paint', 07:32:02 INFO - 'tpaint', 07:32:02 INFO - 'sessionrestore', 07:32:02 INFO - 'sessionrestore_no_auto_restore', 07:32:02 INFO - 'tabpaint']}, 07:32:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:32:02 INFO - 'tests': ['tsvgx', 07:32:02 INFO - 'tsvgr_opacity', 07:32:02 INFO - 'tart', 07:32:02 INFO - 'tscrollx', 07:32:02 INFO - 'cart']}, 07:32:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:32:02 INFO - 'tsvgr_opacity', 07:32:02 INFO - 'tart', 07:32:02 INFO - 'tscrollx', 07:32:02 INFO - 'cart']}, 07:32:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:02 INFO - 'talos_options': ['--disable-e10s'], 07:32:02 INFO - 'tests': ['tp5o']}, 07:32:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:02 INFO - 'tests': ['tp5o']}, 07:32:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:02 INFO - 'talos_options': ['--disable-e10s', 07:32:02 INFO - '--xperf_path', 07:32:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:02 INFO - 'tests': ['tp5n']}, 07:32:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:02 INFO - 'talos_options': ['--xperf_path', 07:32:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:02 INFO - 'tests': ['tp5n']}}, 07:32:02 INFO - 'talos.zip': {'path': '', 07:32:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:32:02 INFO - Running post-action listener: _resource_record_post_action 07:32:02 INFO - [mozharness: 2017-02-16 15:32:02.617478Z] Finished populate-webroot step (success) 07:32:02 INFO - [mozharness: 2017-02-16 15:32:02.617614Z] Running create-virtualenv step. 07:32:02 INFO - Running pre-action listener: _resource_record_pre_action 07:32:02 INFO - Running main action method: create_virtualenv 07:32:02 INFO - Creating virtualenv /builds/slave/test/build/venv 07:32:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:32:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:32:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:32:02 INFO - Using real prefix '/tools/python27' 07:32:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:32:03 INFO - Installing distribute.............................................................................................................................................................................................done. 07:32:06 INFO - Installing pip.................done. 07:32:06 INFO - Return code: 0 07:32:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:32:06 INFO - Reading from file tmpfile_stdout 07:32:06 INFO - Output received: 07:32:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:32:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f33f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2b5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50da3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f153d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 07:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:06 INFO - 'HOME': '/Users/cltbld', 07:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:06 INFO - 'LOGNAME': 'cltbld', 07:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:06 INFO - 'MOZ_NO_REMOTE': '1', 07:32:06 INFO - 'NO_EM_RESTART': '1', 07:32:06 INFO - 'PAGER': '/bin/cat', 07:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:06 INFO - 'PWD': '/builds/slave/test', 07:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:06 INFO - 'SHELL': '/bin/bash', 07:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 07:32:06 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 07:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:06 INFO - 'USER': 'cltbld', 07:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:06 INFO - 'XPC_FLAGS': '0x0', 07:32:06 INFO - 'XPC_SERVICE_NAME': '0', 07:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:32:07 INFO - Cleaning up... 07:32:07 INFO - Return code: 0 07:32:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f33f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2b5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50da3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f153d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 07:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:07 INFO - 'HOME': '/Users/cltbld', 07:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:07 INFO - 'LOGNAME': 'cltbld', 07:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:07 INFO - 'MOZ_NO_REMOTE': '1', 07:32:07 INFO - 'NO_EM_RESTART': '1', 07:32:07 INFO - 'PAGER': '/bin/cat', 07:32:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:07 INFO - 'PWD': '/builds/slave/test', 07:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:07 INFO - 'SHELL': '/bin/bash', 07:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 07:32:07 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 07:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:07 INFO - 'USER': 'cltbld', 07:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:07 INFO - 'XPC_FLAGS': '0x0', 07:32:07 INFO - 'XPC_SERVICE_NAME': '0', 07:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:07 INFO - Downloading/unpacking psutil>=3.1.1 07:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:32:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:10 INFO - Installing collected packages: psutil 07:32:10 INFO - Running setup.py install for psutil 07:32:10 INFO - building 'psutil._psutil_osx' extension 07:32:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:32:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:32:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:32:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:32:11 INFO - building 'psutil._psutil_posix' extension 07:32:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:32:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:32:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:32:11 INFO - ^ 07:32:11 INFO - 1 warning generated. 07:32:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:32:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:11 INFO - Successfully installed psutil 07:32:11 INFO - Cleaning up... 07:32:12 INFO - Return code: 0 07:32:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f33f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2b5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50da3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f153d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:32:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 07:32:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:12 INFO - 'HOME': '/Users/cltbld', 07:32:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:12 INFO - 'LOGNAME': 'cltbld', 07:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:12 INFO - 'MOZ_NO_REMOTE': '1', 07:32:12 INFO - 'NO_EM_RESTART': '1', 07:32:12 INFO - 'PAGER': '/bin/cat', 07:32:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:12 INFO - 'PWD': '/builds/slave/test', 07:32:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:12 INFO - 'SHELL': '/bin/bash', 07:32:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 07:32:12 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 07:32:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:12 INFO - 'USER': 'cltbld', 07:32:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:12 INFO - 'XPC_FLAGS': '0x0', 07:32:12 INFO - 'XPC_SERVICE_NAME': '0', 07:32:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:32:14 INFO - Installing collected packages: mozsystemmonitor 07:32:14 INFO - Running setup.py install for mozsystemmonitor 07:32:15 INFO - Successfully installed mozsystemmonitor 07:32:15 INFO - Cleaning up... 07:32:15 INFO - Return code: 0 07:32:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f33f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2b5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50da3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f153d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 07:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:15 INFO - 'HOME': '/Users/cltbld', 07:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:15 INFO - 'LOGNAME': 'cltbld', 07:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:15 INFO - 'MOZ_NO_REMOTE': '1', 07:32:15 INFO - 'NO_EM_RESTART': '1', 07:32:15 INFO - 'PAGER': '/bin/cat', 07:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:15 INFO - 'PWD': '/builds/slave/test', 07:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:15 INFO - 'SHELL': '/bin/bash', 07:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 07:32:15 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 07:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:15 INFO - 'USER': 'cltbld', 07:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:15 INFO - 'XPC_FLAGS': '0x0', 07:32:15 INFO - 'XPC_SERVICE_NAME': '0', 07:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:15 INFO - Downloading/unpacking jsonschema==2.5.1 07:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:32:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:18 INFO - Downloading functools32-3.2.3-2.tar.gz 07:32:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:32:18 INFO - warning: no files found matching '*.txt' 07:32:18 INFO - no previously-included directories found matching 'build' 07:32:18 INFO - no previously-included directories found matching 'dist' 07:32:18 INFO - no previously-included directories found matching '.git*' 07:32:18 INFO - Installing collected packages: jsonschema, functools32 07:32:18 INFO - Running setup.py install for functools32 07:32:18 INFO - warning: no files found matching '*.txt' 07:32:18 INFO - no previously-included directories found matching 'build' 07:32:18 INFO - no previously-included directories found matching 'dist' 07:32:18 INFO - no previously-included directories found matching '.git*' 07:32:18 INFO - Successfully installed jsonschema functools32 07:32:18 INFO - Cleaning up... 07:32:18 INFO - Return code: 0 07:32:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f33f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2b5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50da3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f153d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:32:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 07:32:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:18 INFO - 'HOME': '/Users/cltbld', 07:32:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:18 INFO - 'LOGNAME': 'cltbld', 07:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:18 INFO - 'MOZ_NO_REMOTE': '1', 07:32:18 INFO - 'NO_EM_RESTART': '1', 07:32:18 INFO - 'PAGER': '/bin/cat', 07:32:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:18 INFO - 'PWD': '/builds/slave/test', 07:32:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:18 INFO - 'SHELL': '/bin/bash', 07:32:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 07:32:18 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 07:32:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:18 INFO - 'USER': 'cltbld', 07:32:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:18 INFO - 'XPC_FLAGS': '0x0', 07:32:18 INFO - 'XPC_SERVICE_NAME': '0', 07:32:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:32:19 INFO - Cleaning up... 07:32:19 INFO - Return code: 0 07:32:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f33f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2b5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50da3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f153d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:32:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 07:32:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:19 INFO - 'HOME': '/Users/cltbld', 07:32:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:19 INFO - 'LOGNAME': 'cltbld', 07:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:19 INFO - 'MOZ_NO_REMOTE': '1', 07:32:19 INFO - 'NO_EM_RESTART': '1', 07:32:19 INFO - 'PAGER': '/bin/cat', 07:32:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:19 INFO - 'PWD': '/builds/slave/test', 07:32:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:19 INFO - 'SHELL': '/bin/bash', 07:32:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 07:32:19 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 07:32:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:19 INFO - 'USER': 'cltbld', 07:32:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:19 INFO - 'XPC_FLAGS': '0x0', 07:32:19 INFO - 'XPC_SERVICE_NAME': '0', 07:32:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:19 INFO - Downloading/unpacking blobuploader==1.2.4 07:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:21 INFO - Downloading blobuploader-1.2.4.tar.gz 07:32:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:32:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:32:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:23 INFO - Downloading docopt-0.6.1.tar.gz 07:32:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:32:23 INFO - Installing collected packages: blobuploader, requests, docopt 07:32:23 INFO - Running setup.py install for blobuploader 07:32:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:32:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:32:23 INFO - Running setup.py install for requests 07:32:24 INFO - Running setup.py install for docopt 07:32:24 INFO - Successfully installed blobuploader requests docopt 07:32:24 INFO - Cleaning up... 07:32:24 INFO - Return code: 0 07:32:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f33f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2b5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50da3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f153d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:32:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 07:32:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:24 INFO - 'HOME': '/Users/cltbld', 07:32:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:24 INFO - 'LOGNAME': 'cltbld', 07:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:24 INFO - 'MOZ_NO_REMOTE': '1', 07:32:24 INFO - 'NO_EM_RESTART': '1', 07:32:24 INFO - 'PAGER': '/bin/cat', 07:32:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:24 INFO - 'PWD': '/builds/slave/test', 07:32:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:24 INFO - 'SHELL': '/bin/bash', 07:32:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 07:32:24 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 07:32:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:24 INFO - 'USER': 'cltbld', 07:32:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:24 INFO - 'XPC_FLAGS': '0x0', 07:32:24 INFO - 'XPC_SERVICE_NAME': '0', 07:32:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:24 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-7wm4j2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:24 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-8h2fA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:24 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-0QA46P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:24 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-JZ8kyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:24 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-xyUVYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:25 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-9VG7og-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:25 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-o0nqtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:25 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-KbzaQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:25 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-81D_P7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:25 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-YQ8RMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:25 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-OvXlI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:25 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Uq7o8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:25 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-ZfBkSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:25 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-hf4txz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:26 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-nRnabf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:26 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-HIdkrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:26 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-bvXNAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:32:26 INFO - Running setup.py install for manifestparser 07:32:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:32:26 INFO - Running setup.py install for mozcrash 07:32:26 INFO - Running setup.py install for mozdebug 07:32:26 INFO - Running setup.py install for mozdevice 07:32:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:32:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:32:26 INFO - Running setup.py install for mozfile 07:32:27 INFO - Running setup.py install for mozhttpd 07:32:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:32:27 INFO - Running setup.py install for mozinfo 07:32:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:32:27 INFO - Running setup.py install for mozInstall 07:32:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:32:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:32:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:32:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:32:27 INFO - Running setup.py install for mozleak 07:32:27 INFO - Running setup.py install for mozlog 07:32:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:32:27 INFO - Running setup.py install for moznetwork 07:32:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:32:27 INFO - Running setup.py install for mozprocess 07:32:28 INFO - Running setup.py install for mozprofile 07:32:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:32:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:32:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:32:28 INFO - Running setup.py install for mozrunner 07:32:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:32:28 INFO - Running setup.py install for mozscreenshot 07:32:28 INFO - Running setup.py install for moztest 07:32:28 INFO - Running setup.py install for mozversion 07:32:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:32:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:32:28 INFO - Cleaning up... 07:32:28 INFO - Return code: 0 07:32:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f33f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2b5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50da3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f153d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:32:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 07:32:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:28 INFO - 'HOME': '/Users/cltbld', 07:32:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:28 INFO - 'LOGNAME': 'cltbld', 07:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:28 INFO - 'MOZ_NO_REMOTE': '1', 07:32:28 INFO - 'NO_EM_RESTART': '1', 07:32:28 INFO - 'PAGER': '/bin/cat', 07:32:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:28 INFO - 'PWD': '/builds/slave/test', 07:32:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:28 INFO - 'SHELL': '/bin/bash', 07:32:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 07:32:28 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 07:32:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:28 INFO - 'USER': 'cltbld', 07:32:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:28 INFO - 'XPC_FLAGS': '0x0', 07:32:28 INFO - 'XPC_SERVICE_NAME': '0', 07:32:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:29 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-6Kd7Tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:29 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-QV7Lrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:29 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-PX4SxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:29 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-RWPbVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:29 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-3oQpZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:29 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-4I2cKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:29 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-e4w9sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:32:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:29 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-v6cA2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:30 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-HHpEzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:30 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Y_yF7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:30 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-qRJDjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:30 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-2MppyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:30 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-GCRHKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:30 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-L0pJsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:30 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-rRYBgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:30 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-cl8lXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:30 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-nSXpBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:32:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:33 INFO - Downloading blessings-1.6.tar.gz 07:32:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:32:33 INFO - Installing collected packages: blessings 07:32:33 INFO - Running setup.py install for blessings 07:32:33 INFO - Successfully installed blessings 07:32:33 INFO - Cleaning up... 07:32:33 INFO - Return code: 0 07:32:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:32:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:34 INFO - Reading from file tmpfile_stdout 07:32:34 INFO - Current package versions: 07:32:34 INFO - blessings == 1.6 07:32:34 INFO - blobuploader == 1.2.4 07:32:34 INFO - docopt == 0.6.1 07:32:34 INFO - functools32 == 3.2.3-2 07:32:34 INFO - jsonschema == 2.5.1 07:32:34 INFO - manifestparser == 1.1 07:32:34 INFO - mozInstall == 1.12 07:32:34 INFO - mozcrash == 1.0 07:32:34 INFO - mozdebug == 0.1 07:32:34 INFO - mozdevice == 0.48 07:32:34 INFO - mozfile == 1.2 07:32:34 INFO - mozhttpd == 0.7 07:32:34 INFO - mozinfo == 0.9 07:32:34 INFO - mozleak == 0.1 07:32:34 INFO - mozlog == 3.4 07:32:34 INFO - moznetwork == 0.27 07:32:34 INFO - mozprocess == 0.23 07:32:34 INFO - mozprofile == 0.28 07:32:34 INFO - mozrunner == 6.13 07:32:34 INFO - mozscreenshot == 0.1 07:32:34 INFO - mozsystemmonitor == 0.3 07:32:34 INFO - moztest == 0.8 07:32:34 INFO - mozversion == 1.4 07:32:34 INFO - psutil == 3.1.1 07:32:34 INFO - requests == 1.2.3 07:32:34 INFO - wsgiref == 0.1.2 07:32:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f33f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2b5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50da3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f153d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:32:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 07:32:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:35 INFO - 'HOME': '/Users/cltbld', 07:32:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:35 INFO - 'LOGNAME': 'cltbld', 07:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:35 INFO - 'MOZ_NO_REMOTE': '1', 07:32:35 INFO - 'NO_EM_RESTART': '1', 07:32:35 INFO - 'PAGER': '/bin/cat', 07:32:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:35 INFO - 'PWD': '/builds/slave/test', 07:32:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:35 INFO - 'SHELL': '/bin/bash', 07:32:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 07:32:35 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 07:32:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:35 INFO - 'USER': 'cltbld', 07:32:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:35 INFO - 'XPC_FLAGS': '0x0', 07:32:35 INFO - 'XPC_SERVICE_NAME': '0', 07:32:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:32:35 INFO - Cleaning up... 07:32:35 INFO - Return code: 0 07:32:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f33f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2b5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca50da3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f153d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14bb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:32:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 07:32:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:35 INFO - 'HOME': '/Users/cltbld', 07:32:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:35 INFO - 'LOGNAME': 'cltbld', 07:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:35 INFO - 'MOZ_NO_REMOTE': '1', 07:32:35 INFO - 'NO_EM_RESTART': '1', 07:32:35 INFO - 'PAGER': '/bin/cat', 07:32:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:35 INFO - 'PWD': '/builds/slave/test', 07:32:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:35 INFO - 'SHELL': '/bin/bash', 07:32:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 07:32:35 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 07:32:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:35 INFO - 'USER': 'cltbld', 07:32:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:35 INFO - 'XPC_FLAGS': '0x0', 07:32:35 INFO - 'XPC_SERVICE_NAME': '0', 07:32:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:32:36 INFO - Cleaning up... 07:32:36 INFO - Return code: 0 07:32:36 INFO - Running post-action listener: _resource_record_post_action 07:32:36 INFO - Running post-action listener: _start_resource_monitoring 07:32:36 INFO - Starting resource monitoring. 07:32:36 INFO - [mozharness: 2017-02-16 15:32:36.236092Z] Finished create-virtualenv step (success) 07:32:36 INFO - [mozharness: 2017-02-16 15:32:36.236989Z] Running install step. 07:32:36 INFO - Running pre-action listener: _resource_record_pre_action 07:32:36 INFO - Running main action method: install 07:32:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:36 INFO - Reading from file tmpfile_stdout 07:32:36 INFO - Detecting whether we're running mozinstall >=1.0... 07:32:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:32:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:32:36 INFO - Reading from file tmpfile_stdout 07:32:36 INFO - Output received: 07:32:36 INFO - Usage: mozinstall [options] installer 07:32:36 INFO - Options: 07:32:36 INFO - -h, --help show this help message and exit 07:32:36 INFO - -d DEST, --destination=DEST 07:32:36 INFO - Directory to install application into. [default: 07:32:36 INFO - "/builds/slave/test"] 07:32:36 INFO - --app=APP Application being installed. [default: firefox] 07:32:36 INFO - mkdir: /builds/slave/test/build/application 07:32:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:32:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 07:32:58 INFO - Reading from file tmpfile_stdout 07:32:58 INFO - Output received: 07:32:58 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 07:32:58 INFO - Running post-action listener: _resource_record_post_action 07:32:58 INFO - [mozharness: 2017-02-16 15:32:58.028432Z] Finished install step (success) 07:32:58 INFO - [mozharness: 2017-02-16 15:32:58.028592Z] Running run-tests step. 07:32:58 INFO - Running pre-action listener: _resource_record_pre_action 07:32:58 INFO - Running main action method: run_tests 07:32:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:32:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:32:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:32:58 INFO - 2017-02-16 07:32:58.042 screenresolution[1623:7442] starting screenresolution argv=screenresolution get 07:32:58 INFO - 2017-02-16 07:32:58.045 screenresolution[1623:7442] Display 0: 1600x1200x32@60 07:32:58 INFO - 2017-02-16 07:32:58.051 screenresolution[1624:7444] starting screenresolution argv=screenresolution list 07:32:58 INFO - Available Modes on Display 0 07:32:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:32:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:32:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:32:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:32:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:32:58 INFO - Intel Iris: 07:32:58 INFO - Chipset Model: Intel Iris 07:32:58 INFO - Type: GPU 07:32:58 INFO - Bus: Built-In 07:32:58 INFO - VRAM (Dynamic, Max): 1536 MB 07:32:58 INFO - Vendor: Intel (0x8086) 07:32:58 INFO - Device ID: 0x0a2e 07:32:58 INFO - Revision ID: 0x0009 07:32:58 INFO - Displays: 07:32:58 INFO - Display: 07:32:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:32:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:32:58 INFO - Display Serial Number: 1600x1200 60 07:32:58 INFO - Main Display: Yes 07:32:58 INFO - Mirror: Off 07:32:58 INFO - Online: Yes 07:32:58 INFO - Rotation: Supported 07:32:58 INFO - Return code: 0 07:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:32:58 INFO - Python 2.7.3 07:32:58 INFO - Return code: 0 07:32:58 INFO - grabbing minidump binary from tooltool 07:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:58 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f153d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:32:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:32:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:32:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:32:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:32:58 INFO - Return code: 0 07:32:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:32:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:32:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:32:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:32:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0281 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:32:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render', 07:32:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:58 INFO - 'HOME': '/Users/cltbld', 07:32:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:58 INFO - 'LOGNAME': 'cltbld', 07:32:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:32:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:58 INFO - 'MOZ_NO_REMOTE': '1', 07:32:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:32:58 INFO - 'NO_EM_RESTART': '1', 07:32:58 INFO - 'PAGER': '/bin/cat', 07:32:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:58 INFO - 'PWD': '/builds/slave/test', 07:32:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:32:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:58 INFO - 'SHELL': '/bin/bash', 07:32:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners', 07:32:58 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 07:32:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:58 INFO - 'USER': 'cltbld', 07:32:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:58 INFO - 'XPC_FLAGS': '0x0', 07:32:58 INFO - 'XPC_SERVICE_NAME': '0', 07:32:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:32:58 INFO - mozversion application_buildid: 20170216043618 07:32:58 INFO - mozversion application_changeset: d273229cde3cccea129e2450ec1234e9ac51efc4 07:32:58 INFO - mozversion application_display_name: Firefox 07:32:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:32:58 INFO - mozversion application_name: Firefox 07:32:58 INFO - mozversion application_remotingname: firefox 07:32:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:32:58 INFO - mozversion application_vendor: Mozilla 07:32:58 INFO - mozversion application_version: 52.0 07:32:58 INFO - mozversion platform_buildid: 20170216043618 07:32:58 INFO - mozversion platform_changeset: d273229cde3cccea129e2450ec1234e9ac51efc4 07:32:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:32:58 INFO - mozversion platform_version: 52.0 07:32:58 INFO - using testdate: 1487259178 07:32:58 INFO - actual date: 1487259178 07:32:58 INFO - starting webserver on 'localhost:49244' 07:32:58 INFO - SUITE-START | Running 2 tests 07:32:58 INFO - TEST-START | dromaeo_css 07:32:58 INFO - operating with platform_type : mac_ 07:32:58 INFO - Initialising browser for dromaeo_css test... 07:32:58 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpf__T64/profile http://localhost:49244/getInfo.html) 07:33:01 INFO - PROCESS | 1634 | 2017-02-16 07:33:01.491 plugin-container[1637:7605] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 07:33:01 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:01 INFO - PROCESS | 1634 | 2017-02-16 07:33:01.491 plugin-container[1637:7605] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 07:33:01 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:01 INFO - PROCESS | 1634 | 2017-02-16 07:33:01.491 plugin-container[1637:7605] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:33:01 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 07:33:01 INFO - PROCESS | 1634 | colorDepth:24 07:33:01 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 07:33:01 INFO - PROCESS | 1634 | __metrics 07:33:01 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 07:33:02 INFO - TEST-INFO | 1634: exit 0 07:33:02 INFO - Browser initialized. 07:33:02 INFO - Running cycle 1/1 for dromaeo_css test... 07:33:02 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpf__T64/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:05 INFO - PROCESS | 1638 | 2017-02-16 07:33:05.126 plugin-container[1639:7738] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 07:33:05 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:05 INFO - PROCESS | 1638 | 2017-02-16 07:33:05.127 plugin-container[1639:7738] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.CFPasteboardClient' 07:33:05 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:05 INFO - PROCESS | 1638 | 2017-02-16 07:33:05.127 plugin-container[1639:7738] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:33:14 INFO - PROCESS | 1638 | RSS: Main: 177053696 07:33:14 INFO - PROCESS | 1638 | 07:36:16 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 07:36:18 INFO - PROCESS | 1638 | RSS: Main: 182722560 07:36:18 INFO - PROCESS | 1638 | 07:39:20 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 07:39:21 INFO - PROCESS | 1638 | RSS: Main: 195997696 07:39:21 INFO - PROCESS | 1638 | 07:42:23 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 07:42:23 INFO - PROCESS | 1638 | RSS: Main: 197767168 07:42:23 INFO - PROCESS | 1638 | 07:45:25 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 07:45:26 INFO - PROCESS | 1638 | RSS: Main: 200765440 07:45:26 INFO - PROCESS | 1638 | 07:48:29 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 07:48:29 INFO - PROCESS | 1638 | RSS: Main: 202293248 07:48:29 INFO - PROCESS | 1638 | 07:51:32 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 07:51:32 INFO - PROCESS | 1638 | RSS: Main: 202833920 07:51:32 INFO - PROCESS | 1638 | 07:51:32 INFO - PROCESS | 1638 | __start_tp_report 07:51:32 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 07:51:32 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 07:51:32 INFO - PROCESS | 1638 | |i|pagename|runs| 07:51:32 INFO - PROCESS | 1638 | |0;dojo.html;2916.4896143174938;3495.318412909484;3524.68277854993;3570.482280069389;3603.837827297771;5819.563532735041;5934.3768904265025;5941.168236446895;5941.405859414056;5946.345901950782;4472.44094488189;5286.233496143057;5456.508914197721;5620.437956204388;5855.092460668592;5211.24436956643;5242.2136679498;5300.787968481275;5322.20166974953;5365.839024829262;5343.7328133593355;5366.758495867695;5371.5165635092835;5391.919121213185;5413.53984911283;5228.816991405301;5262.81580144695;5355.036093503168;5355.812546560871;5357.973210133963;5253.0544501989625;5270.235815806705;5316.282301889234;5346.385165705955;5387.892242155174;5212.192110222922;5280.1815718563685;5292.894142117175;5299.6025298102795;5314.441983591706;5718.685472299022;5905.320888097881;5954.55340849438;5985.6408615483215;5999.76179605772;6202.34875338092;6215.782447614311;6218.564700470969;6235.875282494325;6328.493720502349;6287.622742635456;6293.46505547029;6293.905591416133;6302.55882088254;6316.715747791361;5861.765529378819;5884.176215329857;5887.941120588825;5901.73442195098;5920.111983202554;5240.4497527759795;6212.285587157535;6221.875562488815;6258.655773932477;6266.624002559861;6188.721507532172;6258.655773932477;6258.687065646699;6267.4045965633195;6294.685265736786;6272.09054687077;6332.240131184288;6332.873342533124;6346.428821406003;6346.936530634727;6151.200343955346;6191.84520386994;6200;6213.1922850029205;6216.844578885473;5219.869503262373;6244.406781355764;6288.308286088527;6292.276388215414;6300.71646775887;5093.54158125763;5095.8980820383385;5097.745112744348;5104.744762761921;5108.7190204538365;4429.9778501107285;4448.822047118144;4502.302143167814;4559.202139625619;4591.9540804591525;4975.875603109951;5972.343042265347;6029.728662210211;6091.48222401092;6101.633901965896;6295.055741638791;6302.338254483305;6304.5586808922935;6317.557770956081;6330.39861213184;5158.736109653084;5839.445252700986;5901.5868889177345;5913.142594323799;5958.910616340759;5076.44159142494;5459.972700136554;5582.218489411485;5594.776208951678;5606.747696353592;6531.771388001397;6542.967285163544;6560.770373036921;6578.144841170617;6585.934140658532;6133.1510485532735;6149.2313460817395;6153.538484613582;6193.566450348612;6286.5599408041;6555.770548030796;6571.277159512545;6577.309382514958;6582.144321238208;6596.439302659328;6218.471429928326;6228.750849965951;6244.1570387997035;6262.060690896402;6264.436200741955;6122.550631083031;6147.477464415575;6155.876882462236;6261.226073846991;6289.119523266655;5169.793208271627;6088.178095956989;6110.649890384107;6216.191895053614;6286.52851036165;6245.750169993149;6245.937540624535;6250.906236406367;6252.030935205051;6278.591891527219;5955.940440595538;6005.87988240224;6078.696065196811;6133.938660613337;6203.96898015507;6181.474574661204;6221.47117495018;6248.218972628421;6273.811785646438;6288.8427789303805;6153.723082461211;6158.261008678808;6165.013597824327;6172.382761723792;6172.598781079216 07:51:32 INFO - PROCESS | 1638 | |1;ext.html;524.5200641413107;543.2937181663839;562.8649124210191;568.6522940630704;579.5276849367763;39033;41754.37368439472;41857;41868.371974420406;41967;43082;43413.56586434135;43459;43545;43685.56314436855;8267;9449.291303152257;9673.70978870635;9686.66096686616;9691;9438.292128090387;9449.102335278138;9546.331756777028;9551.80896382072;9565.186959108483;9410.764730881749;9506.382085164476;9557.426554406724;9564.473953932531;9567.947525772159;9508.572114254875;9511.667091651794;9539.04609539044;9549.570269337826;9577.856332155023;9563.617455301779;9576.760580985461;9607.039296070407;9633.710988670351;9722.75693107678;40259;40783.184336313105;40801.591984080376;40888.79555602233;40935;3251.7561182911377;3369.22507823202;3416.6070901846356;3421.7775844570024;3536.982315088434;29563.852180739173;29832.10503684893;31420.371592568248;32071.55677994496;32082.877099301644;3235.288236587947;3265.657106003859;3288.19439237386;3318.8174650394217;3323.667633236681;500941;507668;508553;509716;513345;61322.693386533676;61459;62001.689991549756;62006.68996654988;62103.689481552305;65559.67220163964;65925.01112483317;65941.67029164919;66027.66986165039;66197;14181.503647372292;16677.49967500977;16812.327506899586;16877.91561042187;17103.572410689583;11221.438928053565;13123.950083993257;13209;13332.400041998133;13335.999800014844;14163;15916;17236.569085772957;17372.218250178772;17460.60315174783;3264.281857991238;3349.363620912019;6168.938310616926;6264.655443950626;6287.717052732638;844.8310337932438;937.2221056523069;959.7456673981401;1020.7958408318366;1064.8935106489444;24050.759492405203;31074.84462577673;31417.172454479205;32303.838480807444;33164;998.9600415983425;1024.3700124423574;1032.8037672842136;1065.840123981409;1117.944102794857;14708.779368309484;15666.764998525032;15673.373065077269;15710.450134245246;15776.921115394349;671695;684093;686418;686436;687202;56636;57102.7144864273;57129.71435142881;57283.71358143266;57338.42661573414;1330.9933450332687;1333.4732780551433;1337.2444568818587;1385.3142694366354;1411.7741161414124;29383.55924661089;29896.701032989393;30624.540631890093;31073.223169420944;32019;57974.7101264491;58725.11912321404;58868.705656471444;58926.410735893805;58982;618168;621659;622625;622721;625090;27212;28199.57700634533;41654.58345416507;41994.37008444815;42183;46139.3079103807;47213.055738884344;47223.52776472191;48175;48352.5164748348;38078.048048799;39302.01744956399;39347.803260984656;40302.59697402988;40865.1826963465;33072.50391244085;38417.61582384141;39100.6089939097;39820.80089599649;39959.80020099881;38322.42516362201;38724.2255154901;39168;39239.60760392359;39314.606853931095;32552.348953020333;32819.015429537154;34544;35314.82342588271;35733.46399804057;23238.419039524146;25030.37424064413;25943.610845837706;25998.960041598126;26070.86964565165 07:51:32 INFO - PROCESS | 1638 | |2;jquery.html;378.6024674092204;387.62400471542605;391.83542911976974;395.3693858296017;410.3557414858671;17964;20414.387568372935;20434.284800032;20463.48841278967;20810.479738006557;19142.71285930712;19832.305869294578;20004.29984950527;20083.698744518842;20401;5307.893842123155;5506.779728810843;6010.459058684717;6070.848228794272;6084.574079814415;5945.554083443737;6000.279966404038;6016.4885984691355;6024.819255422344;6042;4734.147853386389;5349.946500534984;5377.085895397791;5405.351357837065;5414.945850541503;5189.143791274435;5314.601404894648;5375.274337964363;5431.972840135793;5435.972820135874;5218.895622087537;5241;5256.316678831728;5261.6842989420375;5272.077386457355;10967.561297548089;13103.279319637417;13220.603381898558;13311.334433278394;13408;2176.4994051368117;2233.6761169630477;2255.379770563092;2279.5782780185555;2311.15642790382;7381.48329616927;9942.502874856229;10112.747181320452;10480.737981550446;14864.182469964147;1899.6105798311312;1908.3988543608718;1914.6553620348332;1960.8137226963415;1979.4952287166825;99608;112840.87159128385;113752.43123784328;114879.2560371978;118614.81385186124;11933.641990740292;11988.220765650205;12072.698182545506;12215.755684886253;12359;12228.877711222953;14820.481283155037;14954;14994.925025374803;15000.69998600022;8545.74362769118;8745.300375969908;8826.51454170014;8998.235150012322;9004.819903602021;8543.231109200202;8601.096884827002;8800.075992020873;8824.911750882537;8895.644174232959;4569;4583.289590113538;4641.419822522184;4733.83431579893;4735.266033764703;1976.6837306030966;2001.5896741168026;2012.6679097948675;2016.7277417548737;2026.2907982206405;845.750503601439;850.9021462531764;893.9317515568963;896.9910300897038;915.9129882661136;16055.116968566841;20369.694454583194;21500.354989350344;21599.05309367683;21599.568008640053;793.3732351442296;876.5222953490363;959.2805395953035;965.3435663748718;1017.9949100254452;10043.995600440043;10680.946595267129;10706.036456718934;11146.386948717736;11255.718607034725;604315;608706;617086;620458;623899;120246.3987680056;127469.72530274764;128251.35874320568;132984;134952.3252383732;990.4008075114596;1005.4922264256504;1028.3521381529738;1028.9897101029044;1030.8711411073616;14567.781483277548;18086.276548938422;18249;18711.25154993785;18909.43271701851;34292.65707342895;41210;54577.45422545724;56522;56738.7163064182;79036.81444778448;81348;81605.18394815976;82495;82989;6661.866762664817;7965.522068675898;8424;8500.234978851933;8624.965004199285;9199.126083022102;12300.81548776787;12618.9904807617;13148.211107333591;13241.668958276121;10915.290506117077;10916.727081823017;11142.387168705474;11234.382108984084;11379.715507112389;10854.620088296871;11050.900328937476;11191.66425007251;11209;11315.660530184108;10578.523966421684;11356.829647555129;11357.943210283896;11465.828012579652;11482.483288251882;7139.643017848983;7475.588842613708;7585.582792946441;7842.960785196037;8653.17794809492;8536.701215457677;9455.858162127435;10533.262671612914;11062.557497700001;11386.487608057492 07:51:32 INFO - PROCESS | 1638 | |3;mootools.html;1505.3000354834999;1544.7065057639047;1708.6070203853114;1819.7725284339458;1829.4420201838434;7671.925930369753;7863.213678632134;8149.796255093618;8548.444351117188;8559.486430814148;6750.426213771835;7100.502964792467;8326.250637442628;8370.665173393058;8440.493570385766;2341.110378056345;2360.1975328388344;2379.357573455166;2382.24959137871;2382.618780995041;2360.480694247263;2371.976280237193;2399.0763556030965;2408.14510848648;2416.9879150604224;2286.8056215221727;2291.7822806833324;2318.13070098713;2337.1002164166835;2362.137819695816;2318.3508617587104;2322.93031209064;2337.263761915008;2346.636271377939;2348.6495265222566;2343.625019996792;2351.2358483492762;2378.1557547070715;2379.58357287474;2389.7371289158177;8419.494830310201;8446.197611226924;8452.535110568917;8471.449355791854;8498.532580708059;5107.438181799999;5163.173892177234;5219.921701174486;5257.789688412459;5267.26258323835;2797.692253852075;3535.8939231823088;3571.499990001402;3637.0725465006517;3660.798656073915;1006.8842083160457;1052.9202083904624;1076.6339444588878;1109.4341885638303;1129.790988667091;10845.620403285926;10893.782124357469;10902.563897444094;10902.945485272523;10912.290701104403;4483.708558943658;4633.119707255611;4719.197736384823;4738.976305118453;4787.593054590329;9544.761380965532;9566.426014439017;9568.760780980392;9570;9592.424454532751;5250.107481728039;5287.788488460418;5288.444713305047;5289.994900968881;5309.548688361532;4144.689148313889;4366.388705581221;4797.736124513115;4863.027394521039;4885.731284779371;3375.4599264118115;3879.7090218233743;3899.1811719539214;3978.144698889753;4009.258287216874;1153.7366583590954;1211.8424604801496;1303.8044293355883;1318.4857905416895;1325.1121748428577;863.5164307987548;881.4579033894237;907.9954600226956;923.3351986569659;930.4882314727008;4361.5856493633055;5638.154276858504;5752.971235143881;5870.148828419855;5949.048152295699;766.8849672549162;950.3252690589759;968.5109019945023;1035.5754140802235;1074.6131392698621;3392.2876195999115;3686.152184997412;3962.4848769660334;3975.7216994810665;4038.9394159087637;9948.204143668489;10298.021687939634;10309.74225644365;10315.432651204255;10320.948395258125;9818.656347027963;10001.79996400068;10006.749831254278;10238.385696858213;10293.691189264184;6834.4874134440115;7938.444308898322;7961.203879612109;7999.840003199904;8090.838183236302;5553.555715542666;5562.7774889005605;5699.629524081088;5702.287214098238;5733.0540460823495;9274.211692006254;9387.624495020396;9465.66870159541;9470.431774093577;9491.288153388385;9412.388194767593;9472;9515.904840951778;9629.32594718363;9647.228221742384;6313.084602732486;6511.413972742476;6643.867122657616;6761.087253220949;6904.998775177672;7809.960950195213;7903.209679032051;8001.199880011952;8014.398920080901;8024.518528888286;7099.0416293801745;7223;7259.056322678018;7303.379212766762;7345.485815993043;7023;7075.433965282868;7108.644567771694;7204.819879502845;7221.4222862171955;6696;6699.464042876461;6740.561863478858;6741.258461569321;6741.730330786713;4817.710937343771;4850.320955066364;4870.975645121894;4887.975560122319;4956.182229932021;4730.314104454904;4736.147493451212;4830.8792280193275;4901;4906.141425250496 07:51:32 INFO - PROCESS | 1638 | |4;prototype.html;308.0204948264518;315.53458648493466;322.65314786604404;324.4597744754983;325.9934801303974;4615.561521655446;4921.409430868296;5303.310569625948;5533.723313834303;5707.429257074291;5339.466053394659;5525.0883604205255;5532.3637781655125;5562.610617256793;5605.626621477733;3442.4664177052596;4091.590840915914;4241;4268.210381079511;4288.1423715256915;4047.736897101679;4199.286121359376;4203.991042149877;4289.313709806432;4298.183345164424;3620.4026335654585;3907.921841563167;4126.401671757593;4228.5137209220875;4435.290353543433;3794.31702293587;4173.457450531427;4207.4109624652265;4222.9577704223175;4238.830446782125;2977.434287485371;3340.916477088068;3464.2378676691087;3602.9371335456103;3614.6023937366867;4673.7380907154875;4926.75366231687;5425.728713564306;5453.809116680937;5521.282233309684;3733.7224293181407;3868.7678739275734;4825.831095911626;4826.565609095163;4854.378635536841;4848.903021939576;5542.337298810341;5884.9108566459145;5893.528517718572;5960.612560183574;3320.57215397379;3828.693704503633;3976.6619837313865;3995.10110225197;4033.5361433435237;4883.389576302962;6090.355603986943;6195.5663103582765;6209.3790620938;6211.472024877846;3787.8295476703615;3987.5613682494904;4011.29802284599;4015.819288132041;4040.7373520721058;3596.424572068509;3959.5644479107277;4111.712180147361;4125.8968525787095;4152.252594532953;3789.6020917803785;4699.271612900006;4754.477007529169;4800.783964721596;4840.661153719206;4835.879103022453;5619.943800562024;5987.371326010793;5990.430909063632;6032.939670603326;1110.4392281897751;1219.7987332090283;1307.9934600327128;1330.6673331667084;1361.2649169448584;765.6669348833275;875.4090988582681;887.4409122252939;917.0142097245488;920.3741455810114;5057.8229761958155;5169.741512924339;5174.275601415805;5229.607779416483;5267.683938963674;4393.428854248928;5407.70257635826;5561.972190139104;5585.664860108325;5684.431556844283;4222.725522841006;4973.303737476756;5027.195648696191;5232.2674825524455;5272.947270527246;2546.59254519276;2600.8439493630444;2655.734426557329;3038.559408885699;3125.8280794556517;5126.6923984561045;5323.653962492503;5838.5329173666005;6042.6978651067275;6093;4577.741121191699;4847.95152048475;5960.612560183574;5977.204475158306;5981.398091830068;4998.900021999612;5542.642052697115;5757.560609847538;5977.671228082497;6047.274327080779;5353.839384818461;5365.892682146257;5794.449527294901;5807.315878395218;5874.971879920911;4498.077894031781;4607.815687372467;4719.528047195253;5300.893982120413;5759.798407055733;4753.429588449269;4803.495632958489;4871.830760617498;6002.859456703212;6014.609050411709;3713.4513107030557;3837.3668344723924;3839.865604703822;3882.1847412042657;3897.4153876917553;4403.14138742942;5520.723963801714;5751.33859606134;5754.741036653277;5798.362180160095;4829.830955916666;5639.91106447822;5957.927573036895;5959.364787479547;5960.940390596212;5299.887023724907;5805.622634528742;5877.94122058774;5896.823095307148;5943.58394912352;4414.404055452473;4933.339296273676;5778.508826749605;5844.6785426801935;5862.648241105548;4821.566059054702;5825.475707186373;5909.52723782105;5992.580519363602;6002.489788368038;4814;4861.732604706775;5671.85212207229;5898.439648233411;5920.940790592212 07:51:32 INFO - PROCESS | 1638 | |5;yui.html;715.6815217228335;760.604485667453;766.8274638206401;802.7712102050915;852.0882655558553;14626.04930679505;14974.401023959028;15358.232088395564;15486.380544778196;15560.922195389021;12047.879521204786;13625.591232263048;13912.026158168906;13974.720505589881;14014.019018668698;833.2375876073401;846.5217152308957;849.4478588917223;850.3367373448689;850.642730053376;686.9656517174121;699.8355386484172;700.4431476975791;719.7516856684435;864.5763575847852;740.3780824107748;905.8233644439337;905.8595917632778;907.2061945797427;907.2515174980634;716.8530451257479;873.2969959182886;878.1789027259468;900.8873890763655;902.8284625921087;649.3603800256743;658.4403257231363;668.9832754181135;671.2716702377926;671.5399951033557;14405.92797036008;14461.566153015427;14542.491012814606;14562.490312839105;14604.634884127876;14099.365528551138;14111.078547762992;14241.430342786276;14327;14494.855051449456;11290.153238507144;11512.194146409754;11559.075273978062;11714.121440891968;12190.2076365036;3738.5513738351574;3772.1701225730294;3794.165283637623;3814.950888505683;3951.1505026419172;92207.53896230475;92725.5363723177;93430;93855.53072234664;94383.52808235983;10441.895581044093;10655.30740501865;10698.625548105934;10971.50628221716;10978.066864316623;21579.67630485544;21643;21736.456588585097;21737.456563586038;21812.45468863291;7539.208383119803;7726.111497177896;7855.5286682799215;7860.764177074697;8064.193580641889;4426.734395936254;4440.33394990754;4463.687541872103;4516.81932722694;4609.032103258353;3039.604851369352;3450.413429716954;3514.5606799150105;3552.3073000764603;3561.127523756696;632.6836581709146;636.4812677667686;650.2522099585515;651.1079820645746;662.3939095727355;631.1475818472705;658.9308122647149;664.3854434647932;681.8465845184794;684.1960696181966;8474.983002039795;9076.500792456347;9303.302252330966;9474.215521161907;9491.952540237255;567.3305499510617;581.7614777941023;589.6727316339473;675.2403546010738;681.5774219983642;4530.783084145297;4628.5371462853445;4640.605548528345;4672.649551283599;4712.575868171881;101952;109460;111121.33318000236;111350;113620.29569556464;105974;107072.11103389389;107336;109766.90233097567;110027;59641.40358596445;60913.78172436527;86984.56507717421;89466;91389.08610913939;6394;7337.596432196067;8751.474911505331;9099.636014559343;9143.862842057508;41448.58551414447;51968.440946771654;59242;59854.10218846808;61177;58375;71425.6428717874;72877;75180.62409687917;79690;12703.555375561811;12709.428075736429;12768.936155319165;12862.485500579873;13095.803562946372;14386.856131438552;14447;14541;14641.414343426144;14675.19286439256;13448.99132565042;13471.865281347062;13580.253086079965;13802.723945521235;13951.511697090553;12107.394630268627;12329.568465103679;12479;13007.349632518526;14403;12830.615081547568;12917.870821291666;13538.661533461742;13665.590032299047;14078.788818167941;10885.45572721345;11004.920091947619;11043.116550676088;11195.033316171384;11269.549218031187;10335.741606459898;11353.205275630626;11531.769364612828;11536.59621913229;11576 07:51:32 INFO - PROCESS | 1638 | __end_tp_report 07:51:32 INFO - PROCESS | 1638 | __start_cc_report 07:51:32 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,371 07:51:32 INFO - PROCESS | 1638 | __end_cc_report 07:51:32 INFO - PROCESS | 1638 | __startTimestamp1487260292617__endTimestamp 07:51:32 INFO - PROCESS | 1638 | 07:51:32 INFO - PROCESS | 1638 | ------- Summary: start ------- 07:51:32 INFO - PROCESS | 1638 | Number of tests: 6 07:51:32 INFO - PROCESS | 1638 | 07:51:32 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5815.62 Median:6110.65 stddev:660.60 (10.8%) stddev-sans-first:622.25 07:51:32 INFO - PROCESS | 1638 | Values: 2916.5 3495.3 3524.7 3570.5 3603.8 5819.6 5934.4 5941.2 5941.4 5946.3 4472.4 5286.2 5456.5 5620.4 5855.1 5211.2 5242.2 5300.8 5322.2 5365.8 5343.7 5366.8 5371.5 5391.9 5413.5 5228.8 5262.8 5355.0 5355.8 5358.0 5253.1 5270.2 5316.3 5346.4 5387.9 5212.2 5280.2 5292.9 5299.6 5314.4 5718.7 5905.3 5954.6 5985.6 5999.8 6202.3 6215.8 6218.6 6235.9 6328.5 6287.6 6293.5 6293.9 6302.6 6316.7 5861.8 5884.2 5887.9 5901.7 5920.1 5240.4 6212.3 6221.9 6258.7 6266.6 6188.7 6258.7 6258.7 6267.4 6294.7 6272.1 6332.2 6332.9 6346.4 6346.9 6151.2 6191.8 6200.0 6213.2 6216.8 5219.9 6244.4 6288.3 6292.3 6300.7 5093.5 5095.9 5097.7 5104.7 5108.7 4430.0 4448.8 4502.3 4559.2 4592.0 4975.9 5972.3 6029.7 6091.5 6101.6 6295.1 6302.3 6304.6 6317.6 6330.4 5158.7 5839.4 5901.6 5913.1 5958.9 5076.4 5460.0 5582.2 5594.8 5606.7 6531.8 6543.0 6560.8 6578.1 6585.9 6133.2 6149.2 6153.5 6193.6 6286.6 6555.8 6571.3 6577.3 6582.1 6596.4 6218.5 6228.8 6244.2 6262.1 6264.4 6122.6 6147.5 6155.9 6261.2 6289.1 5169.8 6088.2 6110.6 6216.2 6286.5 6245.8 6245.9 6250.9 6252.0 6278.6 5955.9 6005.9 6078.7 6133.9 6204.0 6181.5 6221.5 6248.2 6273.8 6288.8 6153.7 6158.3 6165.0 6172.4 6172.6 07:51:32 INFO - PROCESS | 1638 | 07:51:32 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:73841.48 Median:41294.79 stddev:162892.22 (394.5%) stddev-sans-first:163256.16 07:51:32 INFO - PROCESS | 1638 | Values: 524.5 543.3 562.9 568.7 579.5 39033.0 41754.4 41857.0 41868.4 41967.0 43082.0 43413.6 43459.0 43545.0 43685.6 8267.0 9449.3 9673.7 9686.7 9691.0 9438.3 9449.1 9546.3 9551.8 9565.2 9410.8 9506.4 9557.4 9564.5 9567.9 9508.6 9511.7 9539.0 9549.6 9577.9 9563.6 9576.8 9607.0 9633.7 9722.8 40259.0 40783.2 40801.6 40888.8 40935.0 3251.8 3369.2 3416.6 3421.8 3537.0 29563.9 29832.1 31420.4 32071.6 32082.9 3235.3 3265.7 3288.2 3318.8 3323.7 500941.0 507668.0 508553.0 509716.0 513345.0 61322.7 61459.0 62001.7 62006.7 62103.7 65559.7 65925.0 65941.7 66027.7 66197.0 14181.5 16677.5 16812.3 16877.9 17103.6 11221.4 13124.0 13209.0 13332.4 13336.0 14163.0 15916.0 17236.6 17372.2 17460.6 3264.3 3349.4 6168.9 6264.7 6287.7 844.8 937.2 959.7 1020.8 1064.9 24050.8 31074.8 31417.2 32303.8 33164.0 999.0 1024.4 1032.8 1065.8 1117.9 14708.8 15666.8 15673.4 15710.5 15776.9 671695.0 684093.0 686418.0 686436.0 687202.0 56636.0 57102.7 57129.7 57283.7 57338.4 1331.0 1333.5 1337.2 1385.3 1411.8 29383.6 29896.7 30624.5 31073.2 32019.0 57974.7 58725.1 58868.7 58926.4 58982.0 618168.0 621659.0 622625.0 622721.0 625090.0 27212.0 28199.6 41654.6 41994.4 42183.0 46139.3 47213.1 47223.5 48175.0 48352.5 38078.0 39302.0 39347.8 40302.6 40865.2 33072.5 38417.6 39100.6 39820.8 39959.8 38322.4 38724.2 39168.0 39239.6 39314.6 32552.3 32819.0 34544.0 35314.8 35733.5 23238.4 25030.4 25943.6 25999.0 26070.9 07:51:32 INFO - PROCESS | 1638 | 07:51:32 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:34863.52 Median:2244.53 stddev:102489.71 (4566.2%) stddev-sans-first:102744.51 07:51:32 INFO - PROCESS | 1638 | Values: 378.6 387.6 391.8 395.4 410.4 17964.0 20414.4 20434.3 20463.5 20810.5 19142.7 19832.3 20004.3 20083.7 20401.0 5307.9 5506.8 6010.5 6070.8 6084.6 5945.6 6000.3 6016.5 6024.8 6042.0 4734.1 5349.9 5377.1 5405.4 5414.9 5189.1 5314.6 5375.3 5432.0 5436.0 5218.9 5241.0 5256.3 5261.7 5272.1 10967.6 13103.3 13220.6 13311.3 13408.0 2176.5 2233.7 2255.4 2279.6 2311.2 7381.5 9942.5 10112.7 10480.7 14864.2 1899.6 1908.4 1914.7 1960.8 1979.5 99608.0 112840.9 113752.4 114879.3 118614.8 11933.6 11988.2 12072.7 12215.8 12359.0 12228.9 14820.5 14954.0 14994.9 15000.7 8545.7 8745.3 8826.5 8998.2 9004.8 8543.2 8601.1 8800.1 8824.9 8895.6 4569.0 4583.3 4641.4 4733.8 4735.3 1976.7 2001.6 2012.7 2016.7 2026.3 845.8 850.9 893.9 897.0 915.9 16055.1 20369.7 21500.4 21599.1 21599.6 793.4 876.5 959.3 965.3 1018.0 10044.0 10680.9 10706.0 11146.4 11255.7 604315.0 608706.0 617086.0 620458.0 623899.0 120246.4 127469.7 128251.4 132984.0 134952.3 990.4 1005.5 1028.4 1029.0 1030.9 14567.8 18086.3 18249.0 18711.3 18909.4 34292.7 41210.0 54577.5 56522.0 56738.7 79036.8 81348.0 81605.2 82495.0 82989.0 6661.9 7965.5 8424.0 8500.2 8625.0 9199.1 12300.8 12619.0 13148.2 13241.7 10915.3 10916.7 11142.4 11234.4 11379.7 10854.6 11050.9 11191.7 11209.0 11315.7 10578.5 11356.8 11357.9 11465.8 11482.5 7139.6 7475.6 7585.6 7843.0 8653.2 8536.7 9455.9 10533.3 11062.6 11386.5 07:51:32 INFO - PROCESS | 1638 | 07:51:32 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5470.93 Median:5031.81 stddev:2983.41 (59.3%) stddev-sans-first:2976.89 07:51:32 INFO - PROCESS | 1638 | Values: 1505.3 1544.7 1708.6 1819.8 1829.4 7671.9 7863.2 8149.8 8548.4 8559.5 6750.4 7100.5 8326.3 8370.7 8440.5 2341.1 2360.2 2379.4 2382.2 2382.6 2360.5 2372.0 2399.1 2408.1 2417.0 2286.8 2291.8 2318.1 2337.1 2362.1 2318.4 2322.9 2337.3 2346.6 2348.6 2343.6 2351.2 2378.2 2379.6 2389.7 8419.5 8446.2 8452.5 8471.4 8498.5 5107.4 5163.2 5219.9 5257.8 5267.3 2797.7 3535.9 3571.5 3637.1 3660.8 1006.9 1052.9 1076.6 1109.4 1129.8 10845.6 10893.8 10902.6 10902.9 10912.3 4483.7 4633.1 4719.2 4739.0 4787.6 9544.8 9566.4 9568.8 9570.0 9592.4 5250.1 5287.8 5288.4 5290.0 5309.5 4144.7 4366.4 4797.7 4863.0 4885.7 3375.5 3879.7 3899.2 3978.1 4009.3 1153.7 1211.8 1303.8 1318.5 1325.1 863.5 881.5 908.0 923.3 930.5 4361.6 5638.2 5753.0 5870.1 5949.0 766.9 950.3 968.5 1035.6 1074.6 3392.3 3686.2 3962.5 3975.7 4038.9 9948.2 10298.0 10309.7 10315.4 10320.9 9818.7 10001.8 10006.7 10238.4 10293.7 6834.5 7938.4 7961.2 7999.8 8090.8 5553.6 5562.8 5699.6 5702.3 5733.1 9274.2 9387.6 9465.7 9470.4 9491.3 9412.4 9472.0 9515.9 9629.3 9647.2 6313.1 6511.4 6643.9 6761.1 6905.0 7810.0 7903.2 8001.2 8014.4 8024.5 7099.0 7223.0 7259.1 7303.4 7345.5 7023.0 7075.4 7108.6 7204.8 7221.4 6696.0 6699.5 6740.6 6741.3 6741.7 4817.7 4850.3 4871.0 4888.0 4956.2 4730.3 4736.1 4830.9 4901.0 4906.1 07:51:32 INFO - PROCESS | 1638 | 07:51:32 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4568.69 Median:4902.40 stddev:1425.88 (29.1%) stddev-sans-first:1393.56 07:51:32 INFO - PROCESS | 1638 | Values: 308.0 315.5 322.7 324.5 326.0 4615.6 4921.4 5303.3 5533.7 5707.4 5339.5 5525.1 5532.4 5562.6 5605.6 3442.5 4091.6 4241.0 4268.2 4288.1 4047.7 4199.3 4204.0 4289.3 4298.2 3620.4 3907.9 4126.4 4228.5 4435.3 3794.3 4173.5 4207.4 4223.0 4238.8 2977.4 3340.9 3464.2 3602.9 3614.6 4673.7 4926.8 5425.7 5453.8 5521.3 3733.7 3868.8 4825.8 4826.6 4854.4 4848.9 5542.3 5884.9 5893.5 5960.6 3320.6 3828.7 3976.7 3995.1 4033.5 4883.4 6090.4 6195.6 6209.4 6211.5 3787.8 3987.6 4011.3 4015.8 4040.7 3596.4 3959.6 4111.7 4125.9 4152.3 3789.6 4699.3 4754.5 4800.8 4840.7 4835.9 5619.9 5987.4 5990.4 6032.9 1110.4 1219.8 1308.0 1330.7 1361.3 765.7 875.4 887.4 917.0 920.4 5057.8 5169.7 5174.3 5229.6 5267.7 4393.4 5407.7 5562.0 5585.7 5684.4 4222.7 4973.3 5027.2 5232.3 5272.9 2546.6 2600.8 2655.7 3038.6 3125.8 5126.7 5323.7 5838.5 6042.7 6093.0 4577.7 4848.0 5960.6 5977.2 5981.4 4998.9 5542.6 5757.6 5977.7 6047.3 5353.8 5365.9 5794.4 5807.3 5875.0 4498.1 4607.8 4719.5 5300.9 5759.8 4753.4 4803.5 4871.8 6002.9 6014.6 3713.5 3837.4 3839.9 3882.2 3897.4 4403.1 5520.7 5751.3 5754.7 5798.4 4829.8 5639.9 5957.9 5959.4 5960.9 5299.9 5805.6 5877.9 5896.8 5943.6 4414.4 4933.3 5778.5 5844.7 5862.6 4821.6 5825.5 5909.5 5992.6 6002.5 4814.0 4861.7 5671.9 5898.4 5920.9 07:51:32 INFO - PROCESS | 1638 | 07:51:32 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20949.30 Median:22937.66 stddev:30835.04 (134.4%) stddev-sans-first:30884.12 07:51:32 INFO - PROCESS | 1638 | Values: 715.7 760.6 766.8 802.8 852.1 14626.0 14974.4 15358.2 15486.4 15560.9 12047.9 13625.6 13912.0 13974.7 14014.0 833.2 846.5 849.4 850.3 850.6 687.0 699.8 700.4 719.8 864.6 740.4 905.8 905.9 907.2 907.3 716.9 873.3 878.2 900.9 902.8 649.4 658.4 669.0 671.3 671.5 14405.9 14461.6 14542.5 14562.5 14604.6 14099.4 14111.1 14241.4 14327.0 14494.9 11290.2 11512.2 11559.1 11714.1 12190.2 3738.6 3772.2 3794.2 3815.0 3951.2 92207.5 92725.5 93430.0 93855.5 94383.5 10441.9 10655.3 10698.6 10971.5 10978.1 21579.7 21643.0 21736.5 21737.5 21812.5 7539.2 7726.1 7855.5 7860.8 8064.2 4426.7 4440.3 4463.7 4516.8 4609.0 3039.6 3450.4 3514.6 3552.3 3561.1 632.7 636.5 650.3 651.1 662.4 631.1 658.9 664.4 681.8 684.2 8475.0 9076.5 9303.3 9474.2 9492.0 567.3 581.8 589.7 675.2 681.6 4530.8 4628.5 4640.6 4672.6 4712.6 101952.0 109460.0 111121.3 111350.0 113620.3 105974.0 107072.1 107336.0 109766.9 110027.0 59641.4 60913.8 86984.6 89466.0 91389.1 6394.0 7337.6 8751.5 9099.6 9143.9 41448.6 51968.4 59242.0 59854.1 61177.0 58375.0 71425.6 72877.0 75180.6 79690.0 12703.6 12709.4 12768.9 12862.5 13095.8 14386.9 14447.0 14541.0 14641.4 14675.2 13449.0 13471.9 13580.3 13802.7 13951.5 12107.4 12329.6 12479.0 13007.3 14403.0 12830.6 12917.9 13538.7 13665.6 14078.8 10885.5 11004.9 11043.1 11195.0 11269.5 10335.7 11353.2 11531.8 11536.6 11576.0 07:51:32 INFO - PROCESS | 1638 | -------- Summary: end -------- 07:51:32 INFO - PROCESS | 1638 | 07:51:33 INFO - TEST-INFO | 1638: exit 0 07:51:33 INFO - TEST-OK | dromaeo_css | took 1114674ms 07:51:33 INFO - TEST-START | kraken 07:51:33 INFO - operating with platform_type : mac_ 07:51:33 INFO - Initialising browser for kraken test... 07:51:33 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp6fQDju/profile http://localhost:49244/getInfo.html) 07:51:36 INFO - PROCESS | 1649 | 2017-02-16 07:51:36.145 plugin-container[1651:10106] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8637, name = 'com.apple.tsm.portname' 07:51:36 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:36 INFO - PROCESS | 1649 | 2017-02-16 07:51:36.145 plugin-container[1651:10106] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 07:51:36 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:36 INFO - PROCESS | 1649 | 2017-02-16 07:51:36.146 plugin-container[1651:10106] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:51:36 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 07:51:36 INFO - PROCESS | 1649 | colorDepth:24 07:51:36 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 07:51:36 INFO - PROCESS | 1649 | __metrics 07:51:36 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 07:51:36 INFO - TEST-INFO | 1649: exit 0 07:51:36 INFO - Browser initialized. 07:51:36 INFO - Running cycle 1/1 for kraken test... 07:51:36 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp6fQDju/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:51:39 INFO - PROCESS | 1652 | 2017-02-16 07:51:39.281 plugin-container[1653:10236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 07:51:39 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:39 INFO - PROCESS | 1652 | 2017-02-16 07:51:39.282 plugin-container[1653:10236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 07:51:39 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:39 INFO - PROCESS | 1652 | 2017-02-16 07:51:39.282 plugin-container[1653:10236] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:51:49 INFO - PROCESS | 1652 | RSS: Main: 177479680 07:51:49 INFO - PROCESS | 1652 | 07:51:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 07:51:53 INFO - PROCESS | 1652 | RSS: Main: 190410752 07:51:53 INFO - PROCESS | 1652 | 07:51:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 07:51:56 INFO - PROCESS | 1652 | RSS: Main: 198852608 07:51:56 INFO - PROCESS | 1652 | 07:51:59 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 07:52:00 INFO - PROCESS | 1652 | RSS: Main: 202055680 07:52:00 INFO - PROCESS | 1652 | 07:52:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 07:52:02 INFO - PROCESS | 1652 | RSS: Main: 203456512 07:52:02 INFO - PROCESS | 1652 | 07:52:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:52:05 INFO - PROCESS | 1652 | RSS: Main: 203628544 07:52:05 INFO - PROCESS | 1652 | 07:52:08 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 07:52:09 INFO - PROCESS | 1652 | RSS: Main: 208031744 07:52:09 INFO - PROCESS | 1652 | 07:52:12 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 07:52:12 INFO - PROCESS | 1652 | RSS: Main: 207073280 07:52:12 INFO - PROCESS | 1652 | 07:52:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 07:52:16 INFO - PROCESS | 1652 | RSS: Main: 206946304 07:52:16 INFO - PROCESS | 1652 | 07:52:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:52:18 INFO - PROCESS | 1652 | RSS: Main: 206393344 07:52:18 INFO - PROCESS | 1652 | 07:52:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:52:20 INFO - PROCESS | 1652 | RSS: Main: 206462976 07:52:20 INFO - PROCESS | 1652 | 07:52:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:52:23 INFO - PROCESS | 1652 | RSS: Main: 206446592 07:52:23 INFO - PROCESS | 1652 | 07:52:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:52:27 INFO - PROCESS | 1652 | RSS: Main: 206725120 07:52:27 INFO - PROCESS | 1652 | 07:52:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:52:30 INFO - PROCESS | 1652 | RSS: Main: 206827520 07:52:30 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 07:52:32 INFO - PROCESS | 1652 | RSS: Main: 208400384 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | __start_tp_report 07:52:32 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 07:52:32 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 07:52:32 INFO - PROCESS | 1652 | |i|pagename|runs| 07:52:32 INFO - PROCESS | 1652 | |0;ai-astar;107;125;172;122;129;169;121;117;122;161 07:52:32 INFO - PROCESS | 1652 | |1;audio-beat-detection;141;112;111;143;115;139;113;98;135;137 07:52:32 INFO - PROCESS | 1652 | |2;audio-dft;201;194;194;188;196;198;205;270;188;274 07:52:32 INFO - PROCESS | 1652 | |3;audio-fft;81;59;60;87;59;61;74;74;99;50 07:52:32 INFO - PROCESS | 1652 | |4;audio-oscillator;107;55;84;81;98;100;118;93;70;86 07:52:32 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;77;134;89;86;90;108;115;131;122;87 07:52:32 INFO - PROCESS | 1652 | |6;imaging-darkroom;97;97;95;153;96;99;96;125;153;98 07:52:32 INFO - PROCESS | 1652 | |7;imaging-desaturate;90;89;85;88;110;121;91;102;119;87 07:52:32 INFO - PROCESS | 1652 | |8;json-parse-financial;57;54;57;56;59;59;61;60;59;60 07:52:32 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;72;68;68;82;73;74;66;68;69;68 07:52:32 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;81;82;85;81;81;85;83;88;84;86 07:52:32 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;142;123;127;158;156;159;139;155;194;133 07:52:32 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;166;166;167;168;167;162;165;181;226;165 07:52:32 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;67;70;65;66;66;74;84;68;67;66 07:52:32 INFO - PROCESS | 1652 | __end_tp_report 07:52:32 INFO - PROCESS | 1652 | __start_cc_report 07:52:32 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,948 07:52:32 INFO - PROCESS | 1652 | __end_cc_report 07:52:32 INFO - PROCESS | 1652 | __startTimestamp1487260352838__endTimestamp 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | ------- Summary: start ------- 07:52:32 INFO - PROCESS | 1652 | Number of tests: 14 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:134.50 Median:127.00 stddev:23.51 (18.5%) stddev-sans-first:22.74 07:52:32 INFO - PROCESS | 1652 | Values: 107.0 125.0 172.0 122.0 129.0 169.0 121.0 117.0 122.0 161.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:124.40 Median:138.00 stddev:16.17 (11.7%) stddev-sans-first:16.00 07:52:32 INFO - PROCESS | 1652 | Values: 141.0 112.0 111.0 143.0 115.0 139.0 113.0 98.0 135.0 137.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:210.80 Median:199.50 stddev:32.69 (16.4%) stddev-sans-first:34.48 07:52:32 INFO - PROCESS | 1652 | Values: 201.0 194.0 194.0 188.0 196.0 198.0 205.0 270.0 188.0 274.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:70.40 Median:74.00 stddev:15.29 (20.7%) stddev-sans-first:15.73 07:52:32 INFO - PROCESS | 1652 | Values: 81.0 59.0 60.0 87.0 59.0 61.0 74.0 74.0 99.0 50.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:82.50 stddev:18.25 (22.1%) stddev-sans-first:18.19 07:52:32 INFO - PROCESS | 1652 | Values: 107.0 55.0 84.0 81.0 98.0 100.0 118.0 93.0 70.0 86.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:103.90 Median:81.50 stddev:20.69 (25.4%) stddev-sans-first:19.52 07:52:32 INFO - PROCESS | 1652 | Values: 77.0 134.0 89.0 86.0 90.0 108.0 115.0 131.0 122.0 87.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:110.90 Median:96.50 stddev:23.89 (24.8%) stddev-sans-first:24.80 07:52:32 INFO - PROCESS | 1652 | Values: 97.0 97.0 95.0 153.0 96.0 99.0 96.0 125.0 153.0 98.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:98.20 Median:87.50 stddev:13.80 (15.8%) stddev-sans-first:14.31 07:52:32 INFO - PROCESS | 1652 | Values: 90.0 89.0 85.0 88.0 110.0 121.0 91.0 102.0 119.0 87.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:59.00 stddev:2.15 (3.6%) stddev-sans-first:2.24 07:52:32 INFO - PROCESS | 1652 | Values: 57.0 54.0 57.0 56.0 59.0 59.0 61.0 60.0 59.0 60.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:70.50 stddev:4.71 (6.7%) stddev-sans-first:4.97 07:52:32 INFO - PROCESS | 1652 | Values: 72.0 68.0 68.0 82.0 73.0 74.0 66.0 68.0 69.0 68.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.50 stddev:2.41 (2.9%) stddev-sans-first:2.37 07:52:32 INFO - PROCESS | 1652 | Values: 81.0 82.0 85.0 81.0 81.0 85.0 83.0 88.0 84.0 86.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:148.60 Median:155.50 stddev:20.69 (13.3%) stddev-sans-first:21.81 07:52:32 INFO - PROCESS | 1652 | Values: 142.0 123.0 127.0 158.0 156.0 159.0 139.0 155.0 194.0 133.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:167.00 stddev:19.20 (11.5%) stddev-sans-first:20.18 07:52:32 INFO - PROCESS | 1652 | Values: 166.0 166.0 167.0 168.0 167.0 162.0 165.0 181.0 226.0 165.0 07:52:32 INFO - PROCESS | 1652 | 07:52:32 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:67.50 stddev:5.79 (8.6%) stddev-sans-first:6.09 07:52:32 INFO - PROCESS | 1652 | Values: 67.0 70.0 65.0 66.0 66.0 74.0 84.0 68.0 67.0 66.0 07:52:32 INFO - PROCESS | 1652 | -------- Summary: end -------- 07:52:32 INFO - PROCESS | 1652 | 07:52:33 INFO - TEST-INFO | 1652: exit 0 07:52:33 INFO - TEST-OK | kraken | took 60545ms 07:52:33 INFO - SUITE-END | took 1175s 07:52:34 INFO - Completed test suite (00:19:35) 07:52:34 INFO - javascript benchmark 07:52:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7270.270268805545, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2916.4896143174938, 3495.318412909484, 3524.68277854993, 3570.482280069389, 3603.837827297771, 5819.563532735041, 5934.3768904265025, 5941.168236446895, 5941.405859414056, 5946.345901950782, 4472.44094488189, 5286.233496143057, 5456.508914197721, 5620.437956204388, 5855.092460668592, 5211.24436956643, 5242.2136679498, 5300.787968481275, 5322.20166974953, 5365.839024829262, 5343.7328133593355, 5366.758495867695, 5371.5165635092835, 5391.919121213185, 5413.53984911283, 5228.816991405301, 5262.81580144695, 5355.036093503168, 5355.812546560871, 5357.973210133963, 5253.0544501989625, 5270.235815806705, 5316.282301889234, 5346.385165705955, 5387.892242155174, 5212.192110222922, 5280.1815718563685, 5292.894142117175, 5299.6025298102795, 5314.441983591706, 5718.685472299022, 5905.320888097881, 5954.55340849438, 5985.6408615483215, 5999.76179605772, 6202.34875338092, 6215.782447614311, 6218.564700470969, 6235.875282494325, 6328.493720502349, 6287.622742635456, 6293.46505547029, 6293.905591416133, 6302.55882088254, 6316.715747791361, 5861.765529378819, 5884.176215329857, 5887.941120588825, 5901.73442195098, 5920.111983202554, 5240.4497527759795, 6212.285587157535, 6221.875562488815, 6258.655773932477, 6266.624002559861, 6188.721507532172, 6258.655773932477, 6258.687065646699, 6267.4045965633195, 6294.685265736786, 6272.09054687077, 6332.240131184288, 6332.873342533124, 6346.428821406003, 6346.936530634727, 6151.200343955346, 6191.84520386994, 6200.0, 6213.1922850029205, 6216.844578885473, 5219.869503262373, 6244.406781355764, 6288.308286088527, 6292.276388215414, 6300.71646775887, 5093.54158125763, 5095.8980820383385, 5097.745112744348, 5104.744762761921, 5108.7190204538365, 4429.9778501107285, 4448.822047118144, 4502.302143167814, 4559.202139625619, 4591.9540804591525, 4975.875603109951, 5972.343042265347, 6029.728662210211, 6091.48222401092, 6101.633901965896, 6295.055741638791, 6302.338254483305, 6304.5586808922935, 6317.557770956081, 6330.39861213184, 5158.736109653084, 5839.445252700986, 5901.5868889177345, 5913.142594323799, 5958.910616340759, 5076.44159142494, 5459.972700136554, 5582.218489411485, 5594.776208951678, 5606.747696353592, 6531.771388001397, 6542.967285163544, 6560.770373036921, 6578.144841170617, 6585.934140658532, 6133.1510485532735, 6149.2313460817395, 6153.538484613582, 6193.566450348612, 6286.5599408041, 6555.770548030796, 6571.277159512545, 6577.309382514958, 6582.144321238208, 6596.439302659328, 6218.471429928326, 6228.750849965951, 6244.1570387997035, 6262.060690896402, 6264.436200741955, 6122.550631083031, 6147.477464415575, 6155.876882462236, 6261.226073846991, 6289.119523266655, 5169.793208271627, 6088.178095956989, 6110.649890384107, 6216.191895053614, 6286.52851036165, 6245.750169993149, 6245.937540624535, 6250.906236406367, 6252.030935205051, 6278.591891527219, 5955.940440595538, 6005.87988240224, 6078.696065196811, 6133.938660613337, 6203.96898015507, 6181.474574661204, 6221.47117495018, 6248.218972628421, 6273.811785646438, 6288.8427789303805, 6153.723082461211, 6158.261008678808, 6165.013597824327, 6172.382761723792, 6172.598781079216], "value": 5774.854105409134, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.5200641413107, 543.2937181663839, 562.8649124210191, 568.6522940630704, 579.5276849367763, 39033.0, 41754.37368439472, 41857.0, 41868.371974420406, 41967.0, 43082.0, 43413.56586434135, 43459.0, 43545.0, 43685.56314436855, 8267.0, 9449.291303152257, 9673.70978870635, 9686.66096686616, 9691.0, 9438.292128090387, 9449.102335278138, 9546.331756777028, 9551.80896382072, 9565.186959108483, 9410.764730881749, 9506.382085164476, 9557.426554406724, 9564.473953932531, 9567.947525772159, 9508.572114254875, 9511.667091651794, 9539.04609539044, 9549.570269337826, 9577.856332155023, 9563.617455301779, 9576.760580985461, 9607.039296070407, 9633.710988670351, 9722.75693107678, 40259.0, 40783.184336313105, 40801.591984080376, 40888.79555602233, 40935.0, 3251.7561182911377, 3369.22507823202, 3416.6070901846356, 3421.7775844570024, 3536.982315088434, 29563.852180739173, 29832.10503684893, 31420.371592568248, 32071.55677994496, 32082.877099301644, 3235.288236587947, 3265.657106003859, 3288.19439237386, 3318.8174650394217, 3323.667633236681, 500941.0, 507668.0, 508553.0, 509716.0, 513345.0, 61322.693386533676, 61459.0, 62001.689991549756, 62006.68996654988, 62103.689481552305, 65559.67220163964, 65925.01112483317, 65941.67029164919, 66027.66986165039, 66197.0, 14181.503647372292, 16677.49967500977, 16812.327506899586, 16877.91561042187, 17103.572410689583, 11221.438928053565, 13123.950083993257, 13209.0, 13332.400041998133, 13335.999800014844, 14163.0, 15916.0, 17236.569085772957, 17372.218250178772, 17460.60315174783, 3264.281857991238, 3349.363620912019, 6168.938310616926, 6264.655443950626, 6287.717052732638, 844.8310337932438, 937.2221056523069, 959.7456673981401, 1020.7958408318366, 1064.8935106489444, 24050.759492405203, 31074.84462577673, 31417.172454479205, 32303.838480807444, 33164.0, 998.9600415983425, 1024.3700124423574, 1032.8037672842136, 1065.840123981409, 1117.944102794857, 14708.779368309484, 15666.764998525032, 15673.373065077269, 15710.450134245246, 15776.921115394349, 671695.0, 684093.0, 686418.0, 686436.0, 687202.0, 56636.0, 57102.7144864273, 57129.71435142881, 57283.71358143266, 57338.42661573414, 1330.9933450332687, 1333.4732780551433, 1337.2444568818587, 1385.3142694366354, 1411.7741161414124, 29383.55924661089, 29896.701032989393, 30624.540631890093, 31073.223169420944, 32019.0, 57974.7101264491, 58725.11912321404, 58868.705656471444, 58926.410735893805, 58982.0, 618168.0, 621659.0, 622625.0, 622721.0, 625090.0, 27212.0, 28199.57700634533, 41654.58345416507, 41994.37008444815, 42183.0, 46139.3079103807, 47213.055738884344, 47223.52776472191, 48175.0, 48352.5164748348, 38078.048048799, 39302.01744956399, 39347.803260984656, 40302.59697402988, 40865.1826963465, 33072.50391244085, 38417.61582384141, 39100.6089939097, 39820.80089599649, 39959.80020099881, 38322.42516362201, 38724.2255154901, 39168.0, 39239.60760392359, 39314.606853931095, 32552.348953020333, 32819.015429537154, 34544.0, 35314.82342588271, 35733.46399804057, 23238.419039524146, 25030.37424064413, 25943.610845837706, 25998.960041598126, 26070.86964565165], "value": 20196.576896760573, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.6024674092204, 387.62400471542605, 391.83542911976974, 395.3693858296017, 410.3557414858671, 17964.0, 20414.387568372935, 20434.284800032, 20463.48841278967, 20810.479738006557, 19142.71285930712, 19832.305869294578, 20004.29984950527, 20083.698744518842, 20401.0, 5307.893842123155, 5506.779728810843, 6010.459058684717, 6070.848228794272, 6084.574079814415, 5945.554083443737, 6000.279966404038, 6016.4885984691355, 6024.819255422344, 6042.0, 4734.147853386389, 5349.946500534984, 5377.085895397791, 5405.351357837065, 5414.945850541503, 5189.143791274435, 5314.601404894648, 5375.274337964363, 5431.972840135793, 5435.972820135874, 5218.895622087537, 5241.0, 5256.316678831728, 5261.6842989420375, 5272.077386457355, 10967.561297548089, 13103.279319637417, 13220.603381898558, 13311.334433278394, 13408.0, 2176.4994051368117, 2233.6761169630477, 2255.379770563092, 2279.5782780185555, 2311.15642790382, 7381.48329616927, 9942.502874856229, 10112.747181320452, 10480.737981550446, 14864.182469964147, 1899.6105798311312, 1908.3988543608718, 1914.6553620348332, 1960.8137226963415, 1979.4952287166825, 99608.0, 112840.87159128385, 113752.43123784328, 114879.2560371978, 118614.81385186124, 11933.641990740292, 11988.220765650205, 12072.698182545506, 12215.755684886253, 12359.0, 12228.877711222953, 14820.481283155037, 14954.0, 14994.925025374803, 15000.69998600022, 8545.74362769118, 8745.300375969908, 8826.51454170014, 8998.235150012322, 9004.819903602021, 8543.231109200202, 8601.096884827002, 8800.075992020873, 8824.911750882537, 8895.644174232959, 4569.0, 4583.289590113538, 4641.419822522184, 4733.83431579893, 4735.266033764703, 1976.6837306030966, 2001.5896741168026, 2012.6679097948675, 2016.7277417548737, 2026.2907982206405, 845.750503601439, 850.9021462531764, 893.9317515568963, 896.9910300897038, 915.9129882661136, 16055.116968566841, 20369.694454583194, 21500.354989350344, 21599.05309367683, 21599.568008640053, 793.3732351442296, 876.5222953490363, 959.2805395953035, 965.3435663748718, 1017.9949100254452, 10043.995600440043, 10680.946595267129, 10706.036456718934, 11146.386948717736, 11255.718607034725, 604315.0, 608706.0, 617086.0, 620458.0, 623899.0, 120246.3987680056, 127469.72530274764, 128251.35874320568, 132984.0, 134952.3252383732, 990.4008075114596, 1005.4922264256504, 1028.3521381529738, 1028.9897101029044, 1030.8711411073616, 14567.781483277548, 18086.276548938422, 18249.0, 18711.25154993785, 18909.43271701851, 34292.65707342895, 41210.0, 54577.45422545724, 56522.0, 56738.7163064182, 79036.81444778448, 81348.0, 81605.18394815976, 82495.0, 82989.0, 6661.866762664817, 7965.522068675898, 8424.0, 8500.234978851933, 8624.965004199285, 9199.126083022102, 12300.81548776787, 12618.9904807617, 13148.211107333591, 13241.668958276121, 10915.290506117077, 10916.727081823017, 11142.387168705474, 11234.382108984084, 11379.715507112389, 10854.620088296871, 11050.900328937476, 11191.66425007251, 11209.0, 11315.660530184108, 10578.523966421684, 11356.829647555129, 11357.943210283896, 11465.828012579652, 11482.483288251882, 7139.643017848983, 7475.588842613708, 7585.582792946441, 7842.960785196037, 8653.17794809492, 8536.701215457677, 9455.858162127435, 10533.262671612914, 11062.557497700001, 11386.487608057492], "value": 9292.731114387994, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.3000354834999, 1544.7065057639047, 1708.6070203853114, 1819.7725284339458, 1829.4420201838434, 7671.925930369753, 7863.213678632134, 8149.796255093618, 8548.444351117188, 8559.486430814148, 6750.426213771835, 7100.502964792467, 8326.250637442628, 8370.665173393058, 8440.493570385766, 2341.110378056345, 2360.1975328388344, 2379.357573455166, 2382.24959137871, 2382.618780995041, 2360.480694247263, 2371.976280237193, 2399.0763556030965, 2408.14510848648, 2416.9879150604224, 2286.8056215221727, 2291.7822806833324, 2318.13070098713, 2337.1002164166835, 2362.137819695816, 2318.3508617587104, 2322.93031209064, 2337.263761915008, 2346.636271377939, 2348.6495265222566, 2343.625019996792, 2351.2358483492762, 2378.1557547070715, 2379.58357287474, 2389.7371289158177, 8419.494830310201, 8446.197611226924, 8452.535110568917, 8471.449355791854, 8498.532580708059, 5107.438181799999, 5163.173892177234, 5219.921701174486, 5257.789688412459, 5267.26258323835, 2797.692253852075, 3535.8939231823088, 3571.499990001402, 3637.0725465006517, 3660.798656073915, 1006.8842083160457, 1052.9202083904624, 1076.6339444588878, 1109.4341885638303, 1129.790988667091, 10845.620403285926, 10893.782124357469, 10902.563897444094, 10902.945485272523, 10912.290701104403, 4483.708558943658, 4633.119707255611, 4719.197736384823, 4738.976305118453, 4787.593054590329, 9544.761380965532, 9566.426014439017, 9568.760780980392, 9570.0, 9592.424454532751, 5250.107481728039, 5287.788488460418, 5288.444713305047, 5289.994900968881, 5309.548688361532, 4144.689148313889, 4366.388705581221, 4797.736124513115, 4863.027394521039, 4885.731284779371, 3375.4599264118115, 3879.7090218233743, 3899.1811719539214, 3978.144698889753, 4009.258287216874, 1153.7366583590954, 1211.8424604801496, 1303.8044293355883, 1318.4857905416895, 1325.1121748428577, 863.5164307987548, 881.4579033894237, 907.9954600226956, 923.3351986569659, 930.4882314727008, 4361.5856493633055, 5638.154276858504, 5752.971235143881, 5870.148828419855, 5949.048152295699, 766.8849672549162, 950.3252690589759, 968.5109019945023, 1035.5754140802235, 1074.6131392698621, 3392.2876195999115, 3686.152184997412, 3962.4848769660334, 3975.7216994810665, 4038.9394159087637, 9948.204143668489, 10298.021687939634, 10309.74225644365, 10315.432651204255, 10320.948395258125, 9818.656347027963, 10001.79996400068, 10006.749831254278, 10238.385696858213, 10293.691189264184, 6834.4874134440115, 7938.444308898322, 7961.203879612109, 7999.840003199904, 8090.838183236302, 5553.555715542666, 5562.7774889005605, 5699.629524081088, 5702.287214098238, 5733.0540460823495, 9274.211692006254, 9387.624495020396, 9465.66870159541, 9470.431774093577, 9491.288153388385, 9412.388194767593, 9472.0, 9515.904840951778, 9629.32594718363, 9647.228221742384, 6313.084602732486, 6511.413972742476, 6643.867122657616, 6761.087253220949, 6904.998775177672, 7809.960950195213, 7903.209679032051, 8001.199880011952, 8014.398920080901, 8024.518528888286, 7099.0416293801745, 7223.0, 7259.056322678018, 7303.379212766762, 7345.485815993043, 7023.0, 7075.433965282868, 7108.644567771694, 7204.819879502845, 7221.4222862171955, 6696.0, 6699.464042876461, 6740.561863478858, 6741.258461569321, 6741.730330786713, 4817.710937343771, 4850.320955066364, 4870.975645121894, 4887.975560122319, 4956.182229932021, 4730.314104454904, 4736.147493451212, 4830.8792280193275, 4901.0, 4906.141425250496], "value": 4456.493842718453, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.0204948264518, 315.53458648493466, 322.65314786604404, 324.4597744754983, 325.9934801303974, 4615.561521655446, 4921.409430868296, 5303.310569625948, 5533.723313834303, 5707.429257074291, 5339.466053394659, 5525.0883604205255, 5532.3637781655125, 5562.610617256793, 5605.626621477733, 3442.4664177052596, 4091.590840915914, 4241.0, 4268.210381079511, 4288.1423715256915, 4047.736897101679, 4199.286121359376, 4203.991042149877, 4289.313709806432, 4298.183345164424, 3620.4026335654585, 3907.921841563167, 4126.401671757593, 4228.5137209220875, 4435.290353543433, 3794.31702293587, 4173.457450531427, 4207.4109624652265, 4222.9577704223175, 4238.830446782125, 2977.434287485371, 3340.916477088068, 3464.2378676691087, 3602.9371335456103, 3614.6023937366867, 4673.7380907154875, 4926.75366231687, 5425.728713564306, 5453.809116680937, 5521.282233309684, 3733.7224293181407, 3868.7678739275734, 4825.831095911626, 4826.565609095163, 4854.378635536841, 4848.903021939576, 5542.337298810341, 5884.9108566459145, 5893.528517718572, 5960.612560183574, 3320.57215397379, 3828.693704503633, 3976.6619837313865, 3995.10110225197, 4033.5361433435237, 4883.389576302962, 6090.355603986943, 6195.5663103582765, 6209.3790620938, 6211.472024877846, 3787.8295476703615, 3987.5613682494904, 4011.29802284599, 4015.819288132041, 4040.7373520721058, 3596.424572068509, 3959.5644479107277, 4111.712180147361, 4125.8968525787095, 4152.252594532953, 3789.6020917803785, 4699.271612900006, 4754.477007529169, 4800.783964721596, 4840.661153719206, 4835.879103022453, 5619.943800562024, 5987.371326010793, 5990.430909063632, 6032.939670603326, 1110.4392281897751, 1219.7987332090283, 1307.9934600327128, 1330.6673331667084, 1361.2649169448584, 765.6669348833275, 875.4090988582681, 887.4409122252939, 917.0142097245488, 920.3741455810114, 5057.8229761958155, 5169.741512924339, 5174.275601415805, 5229.607779416483, 5267.683938963674, 4393.428854248928, 5407.70257635826, 5561.972190139104, 5585.664860108325, 5684.431556844283, 4222.725522841006, 4973.303737476756, 5027.195648696191, 5232.2674825524455, 5272.947270527246, 2546.59254519276, 2600.8439493630444, 2655.734426557329, 3038.559408885699, 3125.8280794556517, 5126.6923984561045, 5323.653962492503, 5838.5329173666005, 6042.6978651067275, 6093.0, 4577.741121191699, 4847.95152048475, 5960.612560183574, 5977.204475158306, 5981.398091830068, 4998.900021999612, 5542.642052697115, 5757.560609847538, 5977.671228082497, 6047.274327080779, 5353.839384818461, 5365.892682146257, 5794.449527294901, 5807.315878395218, 5874.971879920911, 4498.077894031781, 4607.815687372467, 4719.528047195253, 5300.893982120413, 5759.798407055733, 4753.429588449269, 4803.495632958489, 4871.830760617498, 6002.859456703212, 6014.609050411709, 3713.4513107030557, 3837.3668344723924, 3839.865604703822, 3882.1847412042657, 3897.4153876917553, 4403.14138742942, 5520.723963801714, 5751.33859606134, 5754.741036653277, 5798.362180160095, 4829.830955916666, 5639.91106447822, 5957.927573036895, 5959.364787479547, 5960.940390596212, 5299.887023724907, 5805.622634528742, 5877.94122058774, 5896.823095307148, 5943.58394912352, 4414.404055452473, 4933.339296273676, 5778.508826749605, 5844.6785426801935, 5862.648241105548, 4821.566059054702, 5825.475707186373, 5909.52723782105, 5992.580519363602, 6002.489788368038, 4814.0, 4861.732604706775, 5671.85212207229, 5898.439648233411, 5920.940790592212], "value": 4119.425081446299, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.6815217228335, 760.604485667453, 766.8274638206401, 802.7712102050915, 852.0882655558553, 14626.04930679505, 14974.401023959028, 15358.232088395564, 15486.380544778196, 15560.922195389021, 12047.879521204786, 13625.591232263048, 13912.026158168906, 13974.720505589881, 14014.019018668698, 833.2375876073401, 846.5217152308957, 849.4478588917223, 850.3367373448689, 850.642730053376, 686.9656517174121, 699.8355386484172, 700.4431476975791, 719.7516856684435, 864.5763575847852, 740.3780824107748, 905.8233644439337, 905.8595917632778, 907.2061945797427, 907.2515174980634, 716.8530451257479, 873.2969959182886, 878.1789027259468, 900.8873890763655, 902.8284625921087, 649.3603800256743, 658.4403257231363, 668.9832754181135, 671.2716702377926, 671.5399951033557, 14405.92797036008, 14461.566153015427, 14542.491012814606, 14562.490312839105, 14604.634884127876, 14099.365528551138, 14111.078547762992, 14241.430342786276, 14327.0, 14494.855051449456, 11290.153238507144, 11512.194146409754, 11559.075273978062, 11714.121440891968, 12190.2076365036, 3738.5513738351574, 3772.1701225730294, 3794.165283637623, 3814.950888505683, 3951.1505026419172, 92207.53896230475, 92725.5363723177, 93430.0, 93855.53072234664, 94383.52808235983, 10441.895581044093, 10655.30740501865, 10698.625548105934, 10971.50628221716, 10978.066864316623, 21579.67630485544, 21643.0, 21736.456588585097, 21737.456563586038, 21812.45468863291, 7539.208383119803, 7726.111497177896, 7855.5286682799215, 7860.764177074697, 8064.193580641889, 4426.734395936254, 4440.33394990754, 4463.687541872103, 4516.81932722694, 4609.032103258353, 3039.604851369352, 3450.413429716954, 3514.5606799150105, 3552.3073000764603, 3561.127523756696, 632.6836581709146, 636.4812677667686, 650.2522099585515, 651.1079820645746, 662.3939095727355, 631.1475818472705, 658.9308122647149, 664.3854434647932, 681.8465845184794, 684.1960696181966, 8474.983002039795, 9076.500792456347, 9303.302252330966, 9474.215521161907, 9491.952540237255, 567.3305499510617, 581.7614777941023, 589.6727316339473, 675.2403546010738, 681.5774219983642, 4530.783084145297, 4628.5371462853445, 4640.605548528345, 4672.649551283599, 4712.575868171881, 101952.0, 109460.0, 111121.33318000236, 111350.0, 113620.29569556464, 105974.0, 107072.11103389389, 107336.0, 109766.90233097567, 110027.0, 59641.40358596445, 60913.78172436527, 86984.56507717421, 89466.0, 91389.08610913939, 6394.0, 7337.596432196067, 8751.474911505331, 9099.636014559343, 9143.862842057508, 41448.58551414447, 51968.440946771654, 59242.0, 59854.10218846808, 61177.0, 58375.0, 71425.6428717874, 72877.0, 75180.62409687917, 79690.0, 12703.555375561811, 12709.428075736429, 12768.936155319165, 12862.485500579873, 13095.803562946372, 14386.856131438552, 14447.0, 14541.0, 14641.414343426144, 14675.19286439256, 13448.99132565042, 13471.865281347062, 13580.253086079965, 13802.723945521235, 13951.511697090553, 12107.394630268627, 12329.568465103679, 12479.0, 13007.349632518526, 14403.0, 12830.615081547568, 12917.870821291666, 13538.661533461742, 13665.590032299047, 14078.788818167941, 10885.45572721345, 11004.920091947619, 11043.116550676088, 11195.033316171384, 11269.549218031187, 10335.741606459898, 11353.205275630626, 11531.769364612828, 11536.59621913229, 11576.0], "value": 7421.041222790919, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 125.0, 172.0, 122.0, 129.0, 169.0, 121.0, 117.0, 122.0, 161.0], "value": 134.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 112.0, 111.0, 143.0, 115.0, 139.0, 113.0, 98.0, 135.0, 137.0], "value": 124.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 194.0, 194.0, 188.0, 196.0, 198.0, 205.0, 270.0, 188.0, 274.0], "value": 210.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 87.0, 59.0, 61.0, 74.0, 74.0, 99.0, 50.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 55.0, 84.0, 81.0, 98.0, 100.0, 118.0, 93.0, 70.0, 86.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 134.0, 89.0, 86.0, 90.0, 108.0, 115.0, 131.0, 122.0, 87.0], "value": 103.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 95.0, 153.0, 96.0, 99.0, 96.0, 125.0, 153.0, 98.0], "value": 110.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 85.0, 88.0, 110.0, 121.0, 91.0, 102.0, 119.0, 87.0], "value": 98.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 57.0, 56.0, 59.0, 59.0, 61.0, 60.0, 59.0, 60.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 68.0, 82.0, 73.0, 74.0, 66.0, 68.0, 69.0, 68.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 85.0, 81.0, 81.0, 85.0, 83.0, 88.0, 84.0, 86.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 123.0, 127.0, 158.0, 156.0, 159.0, 139.0, 155.0, 194.0, 133.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 167.0, 168.0, 167.0, 162.0, 165.0, 181.0, 226.0, 165.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 65.0, 66.0, 66.0, 74.0, 84.0, 68.0, 67.0, 66.0], "value": 69.3, "unit": "score"}]}]} 07:52:34 INFO - Return code: 0 07:52:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:52:34 ERROR - # TBPL SUCCESS # 07:52:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:52:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:52:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:52:34 INFO - 2017-02-16 07:52:34.167 screenresolution[1657:10619] starting screenresolution argv=screenresolution get 07:52:34 INFO - 2017-02-16 07:52:34.170 screenresolution[1657:10619] Display 0: 1600x1200x32@60 07:52:34 INFO - 2017-02-16 07:52:34.176 screenresolution[1658:10623] starting screenresolution argv=screenresolution list 07:52:34 INFO - Available Modes on Display 0 07:52:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:52:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:52:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:52:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:52:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:52:34 INFO - Intel Iris: 07:52:34 INFO - Chipset Model: Intel Iris 07:52:34 INFO - Type: GPU 07:52:34 INFO - Bus: Built-In 07:52:34 INFO - VRAM (Dynamic, Max): 1536 MB 07:52:34 INFO - Vendor: Intel (0x8086) 07:52:34 INFO - Device ID: 0x0a2e 07:52:34 INFO - Revision ID: 0x0009 07:52:34 INFO - Displays: 07:52:34 INFO - Display: 07:52:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:52:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:52:34 INFO - Display Serial Number: 1600x1200 60 07:52:34 INFO - Main Display: Yes 07:52:34 INFO - Mirror: Off 07:52:34 INFO - Online: Yes 07:52:34 INFO - Rotation: Supported 07:52:34 INFO - Return code: 0 07:52:34 INFO - Running post-action listener: _resource_record_post_action 07:52:34 INFO - [mozharness: 2017-02-16 15:52:34.417061Z] Finished run-tests step (success) 07:52:34 INFO - Running post-run listener: _resource_record_post_run 07:52:34 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 39266304; Write bytes: 502939648; Read time: 253; Write time: 1619 07:52:34 INFO - TinderboxPrint: CPU usage
54.2% 07:52:34 INFO - TinderboxPrint: I/O read bytes / time
39,266,304 / 253 07:52:34 INFO - TinderboxPrint: I/O write bytes / time
502,939,648 / 1,619 07:52:34 INFO - TinderboxPrint: CPU idle
2,195.0 (45.8%) 07:52:34 INFO - TinderboxPrint: CPU system
963.3 (20.1%) 07:52:34 INFO - TinderboxPrint: CPU user
1,631.4 (34.1%) 07:52:34 INFO - TinderboxPrint: Swap in / out
231,161,856 / 0 07:52:34 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 185645568; Write bytes: 178065408; Read time: 16977; Write time: 395 07:52:34 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38332416; Write bytes: 310284288; Read time: 239; Write time: 1180 07:52:34 INFO - Running post-run listener: _upload_blobber_files 07:52:34 INFO - Blob upload gear active. 07:52:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:52:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:52:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:52:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:52:35 INFO - (blobuploader) - INFO - Open directory for files ... 07:52:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:52:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:52:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:36 INFO - (blobuploader) - INFO - Done attempting. 07:52:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:52:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:52:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:37 INFO - (blobuploader) - INFO - Done attempting. 07:52:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:52:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:52:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:39 INFO - (blobuploader) - INFO - Done attempting. 07:52:39 INFO - (blobuploader) - INFO - Iteration through files over. 07:52:39 INFO - Return code: 0 07:52:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:52:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:52:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac116cd049309b2bccb282046994f57733fadba85eb56030f7e3030cf28d37087874f613b9dc29d12d2956068d333945a608835af9a3f47930de338ac78b91b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e8bf9bcddd27ed8c9923d627f10359962ec14b3c1afdc9e8d64afa3d1454e718e034ea331e7915b4b51b92bbc2c214556d323c681c3ba84244a843bd8ae66ff9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/240f600c0a40148ac303ce0b11711be6f8c3b1df2c79d661833ee72a2fa02e2a3d3676576eb7841e595f5bba281f8b56b007f3704493cb1b40bcf972d617a895"} 07:52:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:52:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:52:39 INFO - Contents: 07:52:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac116cd049309b2bccb282046994f57733fadba85eb56030f7e3030cf28d37087874f613b9dc29d12d2956068d333945a608835af9a3f47930de338ac78b91b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e8bf9bcddd27ed8c9923d627f10359962ec14b3c1afdc9e8d64afa3d1454e718e034ea331e7915b4b51b92bbc2c214556d323c681c3ba84244a843bd8ae66ff9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/240f600c0a40148ac303ce0b11711be6f8c3b1df2c79d661833ee72a2fa02e2a3d3676576eb7841e595f5bba281f8b56b007f3704493cb1b40bcf972d617a895"} 07:52:39 INFO - Running post-run listener: copy_logs_to_upload_dir 07:52:39 INFO - Copying logs to upload dir... 07:52:39 INFO - mkdir: /builds/slave/test/build/upload/logs 07:52:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.374298 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-16 07:52:39.940773) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 07:52:39.943751) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac116cd049309b2bccb282046994f57733fadba85eb56030f7e3030cf28d37087874f613b9dc29d12d2956068d333945a608835af9a3f47930de338ac78b91b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e8bf9bcddd27ed8c9923d627f10359962ec14b3c1afdc9e8d64afa3d1454e718e034ea331e7915b4b51b92bbc2c214556d323c681c3ba84244a843bd8ae66ff9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/240f600c0a40148ac303ce0b11711be6f8c3b1df2c79d661833ee72a2fa02e2a3d3676576eb7841e595f5bba281f8b56b007f3704493cb1b40bcf972d617a895"} build_url:https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010993 build_url: 'https://queue.taskcluster.net/v1/task/ONT1kt7MQXOL7aZRZycmYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac116cd049309b2bccb282046994f57733fadba85eb56030f7e3030cf28d37087874f613b9dc29d12d2956068d333945a608835af9a3f47930de338ac78b91b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e8bf9bcddd27ed8c9923d627f10359962ec14b3c1afdc9e8d64afa3d1454e718e034ea331e7915b4b51b92bbc2c214556d323c681c3ba84244a843bd8ae66ff9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/240f600c0a40148ac303ce0b11711be6f8c3b1df2c79d661833ee72a2fa02e2a3d3676576eb7841e595f5bba281f8b56b007f3704493cb1b40bcf972d617a895"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 07:52:39.977684) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 07:52:39.977991) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZOaZnHyHfi/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.1i1ZbY6XF1/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005162 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 07:52:40.026157) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 07:52:40.026517) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 07:52:40.026883) ========= ========= Total master_lag: 0.17 =========