builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0223 starttime: 1480564518.85 results: success (0) buildid: 20161130170644 builduid: 37c9028061264b7cbe6277421475b483 revision: 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:18.852751) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:18.853406) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:18.853735) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:18.881020) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:18.881864) ========= 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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 --2016-11-30 19:55:18-- 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% 161M=0s 2016-11-30 19:55:19 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141042 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:19.046786) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:19.047174) ========= 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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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.023903 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:19.095813) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 19:55:19.096261) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 2016-11-30 19:55:19,195 truncating revision to first 12 chars 2016-11-30 19:55:19,195 Setting DEBUG logging. 2016-11-30 19:55:19,195 attempt 1/10 2016-11-30 19:55:19,195 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/0792dcc4e9a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-30 19:55:20,312 unpacking tar archive at: graphics-0792dcc4e9a6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.405138 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 19:55:20.525733) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:20.526195) ========= script_repo_revision: 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:20.526653) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:20.526963) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 19:55:20.547096) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-30 19:55:20.547470) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 19:55:20 INFO - MultiFileLogger online at 20161130 19:55:20 in /builds/slave/test 19:55:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 19:55:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:55:20 INFO - {'append_to_log': False, 19:55:20 INFO - 'base_work_dir': '/builds/slave/test', 19:55:20 INFO - 'blob_upload_branch': 'Graphics', 19:55:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:55:20 INFO - 'branch': 'Graphics', 19:55:20 INFO - 'buildbot_json_path': 'buildprops.json', 19:55:20 INFO - 'config_files': ('talos/mac_config.py',), 19:55:20 INFO - 'default_actions': ('clobber', 19:55:20 INFO - 'read-buildbot-config', 19:55:20 INFO - 'download-and-extract', 19:55:20 INFO - 'populate-webroot', 19:55:20 INFO - 'create-virtualenv', 19:55:20 INFO - 'install', 19:55:20 INFO - 'run-tests'), 19:55:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:55:20 INFO - 'download_minidump_stackwalk': True, 19:55:20 INFO - 'download_symbols': 'ondemand', 19:55:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:55:20 INFO - 'tooltool.py': '/tools/tooltool.py', 19:55:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:55:20 INFO - '/tools/misc-python/virtualenv.py')}, 19:55:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:55:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:55:20 INFO - 'installer_path': 'installer.exe', 19:55:20 INFO - 'log_level': 'info', 19:55:20 INFO - 'log_name': 'talos', 19:55:20 INFO - 'log_to_console': True, 19:55:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:55:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:55:20 INFO - 'opt_config_files': (), 19:55:20 INFO - 'pip_index': False, 19:55:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:55:20 INFO - 'cmd': ('bash', 19:55:20 INFO - '-c', 19:55:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:55:20 INFO - 'enabled': True, 19:55:20 INFO - 'halt_on_failure': False, 19:55:20 INFO - 'name': 'check_screen_resolution'},), 19:55:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:55:20 INFO - 'cmd': ('bash', 19:55:20 INFO - '-c', 19:55:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:55:20 INFO - 'enabled': True, 19:55:20 INFO - 'halt_on_failure': False, 19:55:20 INFO - 'name': 'check_screen_resolution'},), 19:55:20 INFO - 'run_cmd_checks_enabled': True, 19:55:20 INFO - 'sps_profile': False, 19:55:20 INFO - 'sps_profile_interval': 0, 19:55:20 INFO - 'suite': 'dromaeojs', 19:55:20 INFO - 'system_bits': '32', 19:55:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:55:20 INFO - 'title': 't-yosemite-r7-0223', 19:55:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:55:20 INFO - 'use_talos_json': True, 19:55:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:55:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:55:20 INFO - 'work_dir': 'build'} 19:55:20 INFO - [mozharness: 2016-12-01 03:55:20.749563Z] Running clobber step. 19:55:20 INFO - Running pre-action listener: _resource_record_pre_action 19:55:20 INFO - Running main action method: clobber 19:55:20 INFO - rmtree: /builds/slave/test/build 19:55:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:55:23 INFO - Running post-action listener: _resource_record_post_action 19:55:23 INFO - [mozharness: 2016-12-01 03:55:23.283689Z] Finished clobber step (success) 19:55:23 INFO - [mozharness: 2016-12-01 03:55:23.283953Z] Running read-buildbot-config step. 19:55:23 INFO - Running pre-action listener: _resource_record_pre_action 19:55:23 INFO - Running main action method: read_buildbot_config 19:55:23 INFO - Using buildbot properties: 19:55:23 INFO - { 19:55:23 INFO - "project": "", 19:55:23 INFO - "product": "firefox", 19:55:23 INFO - "script_repo_revision": "default", 19:55:23 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 19:55:23 INFO - "repository": "", 19:55:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 19:55:23 INFO - "buildid": "20161130170644", 19:55:23 INFO - "pgo_build": "False", 19:55:23 INFO - "basedir": "/builds/slave/test", 19:55:23 INFO - "buildnumber": 4, 19:55:23 INFO - "slavename": "t-yosemite-r7-0223", 19:55:23 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:55:23 INFO - "platform": "yosemite_r7", 19:55:23 INFO - "scheduler": "tests-graphics-macosx64-talos", 19:55:23 INFO - "branch": "graphics", 19:55:23 INFO - "repo_path": "projects/graphics", 19:55:23 INFO - "revision": "0792dcc4e9a6798f13963b6a23e85497f1fe90f2", 19:55:23 INFO - "stage_platform": "macosx64", 19:55:23 INFO - "builduid": "37c9028061264b7cbe6277421475b483", 19:55:23 INFO - "slavebuilddir": "test" 19:55:23 INFO - } 19:55:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 19:55:23 INFO - Running post-action listener: _resource_record_post_action 19:55:23 INFO - [mozharness: 2016-12-01 03:55:23.289587Z] Finished read-buildbot-config step (success) 19:55:23 INFO - [mozharness: 2016-12-01 03:55:23.289790Z] Running download-and-extract step. 19:55:23 INFO - Running pre-action listener: _resource_record_pre_action 19:55:23 INFO - Running main action method: download_and_extract 19:55:23 INFO - mkdir: /builds/slave/test/build/tests 19:55:23 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')]} 19:55:23 INFO - trying https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 19:55:23 INFO - Downloading https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:55:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 19:55:23 INFO - Downloaded 1199 bytes. 19:55:23 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:55:23 INFO - Using the following test package requirements: 19:55:23 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:55:23 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:23 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 19:55:23 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:23 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 19:55:23 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:23 INFO - u'jsshell-mac.zip'], 19:55:23 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:23 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 19:55:23 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:55:23 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:23 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 19:55:23 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:23 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 19:55:23 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:23 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 19:55:23 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:23 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 19:55:23 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:55:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:55:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:55:23 INFO - Fetch https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:55:24 INFO - Http code: 200 19:55:24 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 19:55:24 INFO - x-amz-cf-id: xChOIoJi8hULB9EAf4HjaNnsoIWrbxH1zQfV-OEo0XnM3iSa1StMfw== 19:55:24 INFO - x-amz-version-id: cLtbn7.Cj0cBKe.FlPDOVIRLfMn1Vsxw 19:55:24 INFO - Expected file size: 16282640 19:55:24 INFO - Obtained file size: 16282640 19:55:31 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:55:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:55:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:55:31 INFO - Fetch https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:55:31 INFO - Http code: 200 19:55:31 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 19:55:31 INFO - x-amz-cf-id: GdBOJQrWmht2jyzimBKG6vHYO5vEHlUpJM4e9XqungDmPFkzuX87cQ== 19:55:31 INFO - x-amz-version-id: cLtbn7.Cj0cBKe.FlPDOVIRLfMn1Vsxw 19:55:32 INFO - Expected file size: 16282640 19:55:32 INFO - Obtained file size: 16282640 19:55:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 19:55:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:55:38 INFO - Fetch https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 19:55:39 INFO - Http code: 200 19:55:39 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 19:55:39 INFO - x-amz-cf-id: MaYnHrPZE1x2lYd0lQfpSFejhmOYch8xkV17VSzJCKjj6IQx7NxJgw== 19:55:39 INFO - x-amz-version-id: WFDn5OcKSPgAniYE5vY8Un3SZkCmbgfM 19:55:39 INFO - Expected file size: 14409702 19:55:39 INFO - Obtained file size: 14409702 19:55:40 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')]} 19:55:40 INFO - trying https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:55:40 INFO - Downloading https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 19:55:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 19:55:42 INFO - Downloaded 107621464 bytes. 19:55:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:55:42 INFO - mkdir: /builds/slave/test/properties 19:55:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:55:42 INFO - Writing to file /builds/slave/test/properties/build_url 19:55:42 INFO - Contents: 19:55:42 INFO - build_url:https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:55:43 INFO - Running post-action listener: _resource_record_post_action 19:55:43 INFO - Running post-action listener: set_extra_try_arguments 19:55:43 INFO - [mozharness: 2016-12-01 03:55:43.819728Z] Finished download-and-extract step (success) 19:55:43 INFO - [mozharness: 2016-12-01 03:55:43.819882Z] Running populate-webroot step. 19:55:43 INFO - Running pre-action listener: _resource_record_pre_action 19:55:43 INFO - Running main action method: populate_webroot 19:55:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:55:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:55:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:55:43 INFO - '--tppagecycles', 19:55:43 INFO - '7'], 19:55:43 INFO - 'tests': ['tsvgm']}}, 19:55:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:55:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:55:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:55:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:55:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:55:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:55:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:55:43 INFO - 'talos_options': ['--disable-e10s'], 19:55:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:55:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:55:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:55:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:55:43 INFO - 'talos_options': ['--disable-e10s'], 19:55:43 INFO - 'tests': ['damp', 'tps']}, 19:55:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:55:43 INFO - 'tests': ['damp', 'tps']}, 19:55:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:55:43 INFO - 'tests': ['dromaeo_dom']}, 19:55:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:55:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:55:43 INFO - 'tests': ['basic_compositor_video']}, 19:55:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:55:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:55:43 INFO - 'tests': ['a11yr', 19:55:43 INFO - 'ts_paint', 19:55:43 INFO - 'tpaint', 19:55:43 INFO - 'sessionrestore', 19:55:43 INFO - 'sessionrestore_no_auto_restore', 19:55:43 INFO - 'tabpaint']}, 19:55:43 INFO - 'other-e10s': {'tests': ['a11yr', 19:55:43 INFO - 'ts_paint', 19:55:43 INFO - 'tpaint', 19:55:43 INFO - 'sessionrestore', 19:55:43 INFO - 'sessionrestore_no_auto_restore', 19:55:43 INFO - 'tabpaint']}, 19:55:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:55:43 INFO - 'ts_paint', 19:55:43 INFO - 'tpaint', 19:55:43 INFO - 'sessionrestore', 19:55:43 INFO - 'sessionrestore_no_auto_restore', 19:55:43 INFO - 'tabpaint']}, 19:55:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:55:43 INFO - 'ts_paint', 19:55:43 INFO - 'tpaint', 19:55:43 INFO - 'sessionrestore', 19:55:43 INFO - 'sessionrestore_no_auto_restore', 19:55:43 INFO - 'tabpaint']}, 19:55:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:55:43 INFO - 'tests': ['a11yr', 19:55:43 INFO - 'ts_paint', 19:55:43 INFO - 'tpaint', 19:55:43 INFO - 'sessionrestore', 19:55:43 INFO - 'sessionrestore_no_auto_restore', 19:55:43 INFO - 'tabpaint']}, 19:55:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:55:43 INFO - 'tests': ['a11yr', 19:55:43 INFO - 'ts_paint', 19:55:43 INFO - 'tpaint', 19:55:43 INFO - 'sessionrestore', 19:55:43 INFO - 'sessionrestore_no_auto_restore', 19:55:43 INFO - 'tabpaint']}, 19:55:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:55:43 INFO - 'tests': ['tsvgx', 19:55:43 INFO - 'tsvgr_opacity', 19:55:43 INFO - 'tart', 19:55:43 INFO - 'tscrollx', 19:55:43 INFO - 'cart', 19:55:43 INFO - 'tsvg_static']}, 19:55:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:55:43 INFO - 'tsvgr_opacity', 19:55:43 INFO - 'tart', 19:55:43 INFO - 'tscrollx', 19:55:43 INFO - 'cart', 19:55:43 INFO - 'tsvg_static']}, 19:55:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:55:43 INFO - 'talos_options': ['--disable-e10s'], 19:55:43 INFO - 'tests': ['tp5o']}, 19:55:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:55:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:55:43 INFO - 'talos_options': ['--disable-e10s', 19:55:43 INFO - '--xperf_path', 19:55:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:55:43 INFO - 'tests': ['tp5n']}, 19:55:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:55:43 INFO - 'talos_options': ['--xperf_path', 19:55:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:55:43 INFO - 'tests': ['tp5n']}}, 19:55:43 INFO - 'talos.zip': {'path': '', 19:55:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:55:43 INFO - Running post-action listener: _resource_record_post_action 19:55:43 INFO - [mozharness: 2016-12-01 03:55:43.838631Z] Finished populate-webroot step (success) 19:55:43 INFO - [mozharness: 2016-12-01 03:55:43.838717Z] Running create-virtualenv step. 19:55:43 INFO - Running pre-action listener: _resource_record_pre_action 19:55:43 INFO - Running main action method: create_virtualenv 19:55:43 INFO - Creating virtualenv /builds/slave/test/build/venv 19:55:43 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 19:55:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:55:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:55:43 INFO - Using real prefix '/tools/python27' 19:55:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:55:44 INFO - Installing distribute.............................................................................................................................................................................................done. 19:55:47 INFO - Installing pip.................done. 19:55:47 INFO - Return code: 0 19:55:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:55:47 INFO - Reading from file tmpfile_stdout 19:55:47 INFO - Output received: 19:55:47 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) 19:55:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:55:47 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')]} 19:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:47 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')]} 19:55:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:47 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 0x105ecb3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fad510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ce1d30>, '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 0x105b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cd9b20>, '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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 19:55:47 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 19:55:47 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 19:55:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzVKW0sVgv/Render', 19:55:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:47 INFO - 'HOME': '/Users/cltbld', 19:55:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:47 INFO - 'LOGNAME': 'cltbld', 19:55:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:47 INFO - 'MOZ_NO_REMOTE': '1', 19:55:47 INFO - 'NO_EM_RESTART': '1', 19:55:47 INFO - 'PAGER': '/bin/cat', 19:55:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:47 INFO - 'PWD': '/builds/slave/test', 19:55:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:47 INFO - 'SHELL': '/bin/bash', 19:55:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cbwcc9kWvn/Listeners', 19:55:47 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 19:55:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:47 INFO - 'USER': 'cltbld', 19:55:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:47 INFO - 'XPC_FLAGS': '0x0', 19:55:47 INFO - 'XPC_SERVICE_NAME': '0', 19:55:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:48 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 19:55:48 INFO - Cleaning up... 19:55:48 INFO - Return code: 0 19:55:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:55:48 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')]} 19:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:48 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')]} 19:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:48 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 0x105ecb3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fad510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ce1d30>, '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 0x105b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cd9b20>, '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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 19:55:48 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 19:55:48 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 19:55:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzVKW0sVgv/Render', 19:55:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:48 INFO - 'HOME': '/Users/cltbld', 19:55:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:48 INFO - 'LOGNAME': 'cltbld', 19:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:48 INFO - 'MOZ_NO_REMOTE': '1', 19:55:48 INFO - 'NO_EM_RESTART': '1', 19:55:48 INFO - 'PAGER': '/bin/cat', 19:55:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:48 INFO - 'PWD': '/builds/slave/test', 19:55:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:48 INFO - 'SHELL': '/bin/bash', 19:55:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cbwcc9kWvn/Listeners', 19:55:48 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 19:55:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:48 INFO - 'USER': 'cltbld', 19:55:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:48 INFO - 'XPC_FLAGS': '0x0', 19:55:48 INFO - 'XPC_SERVICE_NAME': '0', 19:55:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:48 INFO - Downloading/unpacking psutil>=3.1.1 19:55:48 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 19:55:48 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 19:55:48 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 19:55:48 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 19:55:48 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 19:55:48 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 19:55:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:55:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:55:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:55:51 INFO - Installing collected packages: psutil 19:55:51 INFO - Running setup.py install for psutil 19:55:52 INFO - building 'psutil._psutil_osx' extension 19:55:52 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 19:55:52 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 19:55:52 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 19:55:52 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 19:55:52 INFO - building 'psutil._psutil_posix' extension 19:55:52 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 19:55:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:55:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:55:52 INFO - ^ 19:55:52 INFO - 1 warning generated. 19:55:52 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 19:55:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:55:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:55:52 INFO - Successfully installed psutil 19:55:52 INFO - Cleaning up... 19:55:53 INFO - Return code: 0 19:55:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:55:53 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')]} 19:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:53 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')]} 19:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:53 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 0x105ecb3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fad510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ce1d30>, '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 0x105b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cd9b20>, '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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 19:55:53 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 19:55:53 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 19:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzVKW0sVgv/Render', 19:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:53 INFO - 'HOME': '/Users/cltbld', 19:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:53 INFO - 'LOGNAME': 'cltbld', 19:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:53 INFO - 'MOZ_NO_REMOTE': '1', 19:55:53 INFO - 'NO_EM_RESTART': '1', 19:55:53 INFO - 'PAGER': '/bin/cat', 19:55:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:53 INFO - 'PWD': '/builds/slave/test', 19:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:53 INFO - 'SHELL': '/bin/bash', 19:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cbwcc9kWvn/Listeners', 19:55:53 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 19:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:53 INFO - 'USER': 'cltbld', 19:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:53 INFO - 'XPC_FLAGS': '0x0', 19:55:53 INFO - 'XPC_SERVICE_NAME': '0', 19:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:55:53 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 19:55:53 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 19:55:53 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 19:55:53 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 19:55:53 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 19:55:53 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 19:55:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:55:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:55:55 INFO - Installing collected packages: mozsystemmonitor 19:55:55 INFO - Running setup.py install for mozsystemmonitor 19:55:56 INFO - Successfully installed mozsystemmonitor 19:55:56 INFO - Cleaning up... 19:55:56 INFO - Return code: 0 19:55:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:55:56 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')]} 19:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:56 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')]} 19:55:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:56 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 0x105ecb3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fad510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ce1d30>, '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 0x105b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cd9b20>, '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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 19:55:56 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 19:55:56 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 19:55:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzVKW0sVgv/Render', 19:55:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:56 INFO - 'HOME': '/Users/cltbld', 19:55:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:56 INFO - 'LOGNAME': 'cltbld', 19:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:56 INFO - 'MOZ_NO_REMOTE': '1', 19:55:56 INFO - 'NO_EM_RESTART': '1', 19:55:56 INFO - 'PAGER': '/bin/cat', 19:55:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:56 INFO - 'PWD': '/builds/slave/test', 19:55:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:56 INFO - 'SHELL': '/bin/bash', 19:55:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cbwcc9kWvn/Listeners', 19:55:56 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 19:55:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:56 INFO - 'USER': 'cltbld', 19:55:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:56 INFO - 'XPC_FLAGS': '0x0', 19:55:56 INFO - 'XPC_SERVICE_NAME': '0', 19:55:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:56 INFO - Downloading/unpacking jsonschema==2.5.1 19:55:56 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 19:55:56 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 19:55:56 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 19:55:56 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 19:55:56 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 19:55:56 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 19:55:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:55:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:55:59 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 19:55:59 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 19:55:59 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 19:55:59 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 19:55:59 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 19:55:59 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 19:55:59 INFO - Downloading functools32-3.2.3-2.tar.gz 19:55:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:55:59 INFO - warning: no files found matching '*.txt' 19:55:59 INFO - no previously-included directories found matching 'build' 19:55:59 INFO - no previously-included directories found matching 'dist' 19:55:59 INFO - no previously-included directories found matching '.git*' 19:55:59 INFO - Installing collected packages: jsonschema, functools32 19:55:59 INFO - Running setup.py install for functools32 19:55:59 INFO - warning: no files found matching '*.txt' 19:55:59 INFO - no previously-included directories found matching 'build' 19:55:59 INFO - no previously-included directories found matching 'dist' 19:55:59 INFO - no previously-included directories found matching '.git*' 19:55:59 INFO - Successfully installed jsonschema functools32 19:55:59 INFO - Cleaning up... 19:55:59 INFO - Return code: 0 19:55:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:55: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')]} 19:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55: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')]} 19:55:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:59 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 0x105ecb3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fad510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ce1d30>, '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 0x105b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cd9b20>, '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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 19:55:59 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 19:55:59 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 19:55:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzVKW0sVgv/Render', 19:55:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:59 INFO - 'HOME': '/Users/cltbld', 19:55:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:59 INFO - 'LOGNAME': 'cltbld', 19:55:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:59 INFO - 'MOZ_NO_REMOTE': '1', 19:55:59 INFO - 'NO_EM_RESTART': '1', 19:55:59 INFO - 'PAGER': '/bin/cat', 19:55:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:59 INFO - 'PWD': '/builds/slave/test', 19:55:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:59 INFO - 'SHELL': '/bin/bash', 19:55:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cbwcc9kWvn/Listeners', 19:55:59 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 19:55:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:59 INFO - 'USER': 'cltbld', 19:55:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:59 INFO - 'XPC_FLAGS': '0x0', 19:55:59 INFO - 'XPC_SERVICE_NAME': '0', 19:55:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:56:00 INFO - Cleaning up... 19:56:00 INFO - Return code: 0 19:56:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:56:00 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')]} 19:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:00 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')]} 19:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:00 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 0x105ecb3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fad510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ce1d30>, '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 0x105b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cd9b20>, '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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 19:56:00 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 19:56:00 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 19:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzVKW0sVgv/Render', 19:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:00 INFO - 'HOME': '/Users/cltbld', 19:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:00 INFO - 'LOGNAME': 'cltbld', 19:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:00 INFO - 'MOZ_NO_REMOTE': '1', 19:56:00 INFO - 'NO_EM_RESTART': '1', 19:56:00 INFO - 'PAGER': '/bin/cat', 19:56:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:00 INFO - 'PWD': '/builds/slave/test', 19:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:00 INFO - 'SHELL': '/bin/bash', 19:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cbwcc9kWvn/Listeners', 19:56:00 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 19:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:00 INFO - 'USER': 'cltbld', 19:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:00 INFO - 'XPC_FLAGS': '0x0', 19:56:00 INFO - 'XPC_SERVICE_NAME': '0', 19:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:00 INFO - Downloading/unpacking blobuploader==1.2.4 19:56:00 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 19:56:00 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 19:56:00 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 19:56:00 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 19:56:00 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 19:56:00 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 19:56:02 INFO - Downloading blobuploader-1.2.4.tar.gz 19:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:56:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:56:03 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 19:56:03 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 19:56:03 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 19:56:03 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 19:56:03 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 19:56:03 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 19:56:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:56:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:56:03 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 19:56:03 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 19:56:03 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 19:56:03 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 19:56:03 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 19:56:03 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 19:56:04 INFO - Downloading docopt-0.6.1.tar.gz 19:56:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:56:04 INFO - Installing collected packages: blobuploader, requests, docopt 19:56:04 INFO - Running setup.py install for blobuploader 19:56:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:56:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:56:04 INFO - Running setup.py install for requests 19:56:05 INFO - Running setup.py install for docopt 19:56:05 INFO - Successfully installed blobuploader requests docopt 19:56:05 INFO - Cleaning up... 19:56:05 INFO - Return code: 0 19:56:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56:05 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')]} 19:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:05 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')]} 19:56:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:05 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 0x105ecb3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fad510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ce1d30>, '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 0x105b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cd9b20>, '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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 19:56:05 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 19:56:05 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 19:56:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzVKW0sVgv/Render', 19:56:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:05 INFO - 'HOME': '/Users/cltbld', 19:56:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:05 INFO - 'LOGNAME': 'cltbld', 19:56:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:05 INFO - 'MOZ_NO_REMOTE': '1', 19:56:05 INFO - 'NO_EM_RESTART': '1', 19:56:05 INFO - 'PAGER': '/bin/cat', 19:56:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:05 INFO - 'PWD': '/builds/slave/test', 19:56:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:05 INFO - 'SHELL': '/bin/bash', 19:56:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cbwcc9kWvn/Listeners', 19:56:05 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 19:56:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:05 INFO - 'USER': 'cltbld', 19:56:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:05 INFO - 'XPC_FLAGS': '0x0', 19:56:05 INFO - 'XPC_SERVICE_NAME': '0', 19:56:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:56:05 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-fa6MKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:56:05 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-0NuaWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:56:05 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-tq2ym8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:56:05 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-_t4l11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:56:05 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-IOPxOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:56:06 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-VngJF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:56:06 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-ikXgbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:56:06 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-lQGWbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:56:06 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-wkdjko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:56:06 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-w5znOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:56:06 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Nlc0c0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:56:06 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-y9A20Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:56:06 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-7En6v2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:56:06 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-rDMfsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:06 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Ez4JK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:56:07 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-4gbPUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:56:07 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-1_Klgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:56:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:56:07 INFO - Running setup.py install for manifestparser 19:56:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:56:07 INFO - Running setup.py install for mozcrash 19:56:07 INFO - Running setup.py install for mozdebug 19:56:07 INFO - Running setup.py install for mozdevice 19:56:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:56:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:56:07 INFO - Running setup.py install for mozfile 19:56:08 INFO - Running setup.py install for mozhttpd 19:56:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:56:08 INFO - Running setup.py install for mozinfo 19:56:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:56:08 INFO - Running setup.py install for mozInstall 19:56:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:56:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:56:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:56:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:56:08 INFO - Running setup.py install for mozleak 19:56:08 INFO - Running setup.py install for mozlog 19:56:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:56:08 INFO - Running setup.py install for moznetwork 19:56:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:56:08 INFO - Running setup.py install for mozprocess 19:56:09 INFO - Running setup.py install for mozprofile 19:56:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:56:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:56:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:56:09 INFO - Running setup.py install for mozrunner 19:56:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:56:09 INFO - Running setup.py install for mozscreenshot 19:56:09 INFO - Running setup.py install for moztest 19:56:09 INFO - Running setup.py install for mozversion 19:56:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:56:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:56:09 INFO - Cleaning up... 19:56:09 INFO - Return code: 0 19:56:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56:09 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')]} 19:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:09 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')]} 19:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:09 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 0x105ecb3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fad510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ce1d30>, '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 0x105b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cd9b20>, '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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 19:56:09 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 19:56:09 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 19:56:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzVKW0sVgv/Render', 19:56:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:09 INFO - 'HOME': '/Users/cltbld', 19:56:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:09 INFO - 'LOGNAME': 'cltbld', 19:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:09 INFO - 'MOZ_NO_REMOTE': '1', 19:56:09 INFO - 'NO_EM_RESTART': '1', 19:56:09 INFO - 'PAGER': '/bin/cat', 19:56:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:09 INFO - 'PWD': '/builds/slave/test', 19:56:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:09 INFO - 'SHELL': '/bin/bash', 19:56:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cbwcc9kWvn/Listeners', 19:56:09 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 19:56:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:09 INFO - 'USER': 'cltbld', 19:56:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:09 INFO - 'XPC_FLAGS': '0x0', 19:56:09 INFO - 'XPC_SERVICE_NAME': '0', 19:56:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:56:10 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-GpFY9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:56:10 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)) 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:56:10 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-6922FY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:56:10 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)) 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:56:10 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-VCCfwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:56:10 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)) 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:56:10 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-neymIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:56:10 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-_y2Ecr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:56:10 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)) 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:56:10 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-z0_2ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:56:10 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)) 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:56:10 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Ou10Pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:56:10 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)) 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:56:10 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-nyBlz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:56:10 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)) 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:56:10 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-d29d5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:56:11 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)) 19:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:56:11 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Y9iyr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:56:11 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)) 19:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:56:11 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-9TK8kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:56:11 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)) 19:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:56:11 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-3piuIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:56:11 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)) 19:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:56:11 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-7sfPkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:56:11 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)) 19:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:56:11 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Db_HPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:56:11 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)) 19:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:11 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-6PDKYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:11 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)) 19:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:56:11 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Ysixzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 19:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:56:11 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-MN5A3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:56:11 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)) 19:56:11 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)) 19:56:11 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)) 19:56:11 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)) 19:56:11 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:56:11 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:56:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:56:11 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 19:56:11 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 19:56:11 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 19:56:11 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 19:56:11 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 19:56:11 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 19:56:14 INFO - Downloading blessings-1.6.tar.gz 19:56:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:56:14 INFO - Installing collected packages: blessings 19:56:14 INFO - Running setup.py install for blessings 19:56:14 INFO - Successfully installed blessings 19:56:14 INFO - Cleaning up... 19:56:14 INFO - Return code: 0 19:56:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:56:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:56:15 INFO - Reading from file tmpfile_stdout 19:56:15 INFO - Current package versions: 19:56:15 INFO - blessings == 1.6 19:56:15 INFO - blobuploader == 1.2.4 19:56:15 INFO - docopt == 0.6.1 19:56:15 INFO - functools32 == 3.2.3-2 19:56:15 INFO - jsonschema == 2.5.1 19:56:15 INFO - manifestparser == 1.1 19:56:15 INFO - mozInstall == 1.12 19:56:15 INFO - mozcrash == 1.0 19:56:15 INFO - mozdebug == 0.1 19:56:15 INFO - mozdevice == 0.49 19:56:15 INFO - mozfile == 1.2 19:56:15 INFO - mozhttpd == 0.7 19:56:15 INFO - mozinfo == 0.9 19:56:15 INFO - mozleak == 0.1 19:56:15 INFO - mozlog == 3.4 19:56:15 INFO - moznetwork == 0.27 19:56:15 INFO - mozprocess == 0.23 19:56:15 INFO - mozprofile == 0.28 19:56:15 INFO - mozrunner == 6.13 19:56:15 INFO - mozscreenshot == 0.1 19:56:15 INFO - mozsystemmonitor == 0.3 19:56:15 INFO - moztest == 0.7 19:56:15 INFO - mozversion == 1.4 19:56:15 INFO - psutil == 3.1.1 19:56:15 INFO - requests == 1.2.3 19:56:15 INFO - wsgiref == 0.1.2 19:56:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56: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')]} 19:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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')]} 19:56:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:16 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 0x105ecb3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fad510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ce1d30>, '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 0x105b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cd9b20>, '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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 19:56:16 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 19:56:16 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 19:56:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzVKW0sVgv/Render', 19:56:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:16 INFO - 'HOME': '/Users/cltbld', 19:56:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:16 INFO - 'LOGNAME': 'cltbld', 19:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:16 INFO - 'MOZ_NO_REMOTE': '1', 19:56:16 INFO - 'NO_EM_RESTART': '1', 19:56:16 INFO - 'PAGER': '/bin/cat', 19:56:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:16 INFO - 'PWD': '/builds/slave/test', 19:56:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:16 INFO - 'SHELL': '/bin/bash', 19:56:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cbwcc9kWvn/Listeners', 19:56:16 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 19:56:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:16 INFO - 'USER': 'cltbld', 19:56:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:16 INFO - 'XPC_FLAGS': '0x0', 19:56:16 INFO - 'XPC_SERVICE_NAME': '0', 19:56:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:16 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)) 19:56:16 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)) 19:56:16 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)) 19:56:16 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)) 19:56:16 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)) 19:56:16 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)) 19:56:16 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)) 19:56:16 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)) 19:56:16 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)) 19:56:16 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)) 19:56:16 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)) 19:56:16 INFO - Cleaning up... 19:56:16 INFO - Return code: 0 19:56:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:56:16 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')]} 19:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:16 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')]} 19:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:16 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 0x105ecb3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e41570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fad510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ce1d30>, '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 0x105b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cd9b20>, '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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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 19:56:16 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 19:56:16 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 19:56:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzVKW0sVgv/Render', 19:56:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:16 INFO - 'HOME': '/Users/cltbld', 19:56:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:16 INFO - 'LOGNAME': 'cltbld', 19:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:16 INFO - 'MOZ_NO_REMOTE': '1', 19:56:16 INFO - 'NO_EM_RESTART': '1', 19:56:16 INFO - 'PAGER': '/bin/cat', 19:56:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:16 INFO - 'PWD': '/builds/slave/test', 19:56:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:16 INFO - 'SHELL': '/bin/bash', 19:56:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cbwcc9kWvn/Listeners', 19:56:16 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 19:56:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:16 INFO - 'USER': 'cltbld', 19:56:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:16 INFO - 'XPC_FLAGS': '0x0', 19:56:16 INFO - 'XPC_SERVICE_NAME': '0', 19:56:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:56:17 INFO - Cleaning up... 19:56:17 INFO - Return code: 0 19:56:17 INFO - Running post-action listener: _resource_record_post_action 19:56:17 INFO - Running post-action listener: _start_resource_monitoring 19:56:17 INFO - Starting resource monitoring. 19:56:17 INFO - [mozharness: 2016-12-01 03:56:17.146805Z] Finished create-virtualenv step (success) 19:56:17 INFO - [mozharness: 2016-12-01 03:56:17.147770Z] Running install step. 19:56:17 INFO - Running pre-action listener: _resource_record_pre_action 19:56:17 INFO - Running main action method: install 19:56:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:56:17 INFO - Reading from file tmpfile_stdout 19:56:17 INFO - Detecting whether we're running mozinstall >=1.0... 19:56:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:56:17 INFO - Reading from file tmpfile_stdout 19:56:17 INFO - Output received: 19:56:17 INFO - Usage: mozinstall [options] installer 19:56:17 INFO - Options: 19:56:17 INFO - -h, --help show this help message and exit 19:56:17 INFO - -d DEST, --destination=DEST 19:56:17 INFO - Directory to install application into. [default: 19:56:17 INFO - "/builds/slave/test"] 19:56:17 INFO - --app=APP Application being installed. [default: firefox] 19:56:17 INFO - mkdir: /builds/slave/test/build/application 19:56:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:56:50 INFO - Reading from file tmpfile_stdout 19:56:50 INFO - Output received: 19:56:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:56:50 INFO - Running post-action listener: _resource_record_post_action 19:56:50 INFO - [mozharness: 2016-12-01 03:56:50.007996Z] Finished install step (success) 19:56:50 INFO - [mozharness: 2016-12-01 03:56:50.008149Z] Running run-tests step. 19:56:50 INFO - Running pre-action listener: _resource_record_pre_action 19:56:50 INFO - Running main action method: run_tests 19:56:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:56:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:56:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:56:50 INFO - 2016-11-30 19:56:50.022 screenresolution[1625:7324] starting screenresolution argv=screenresolution get 19:56:50 INFO - 2016-11-30 19:56:50.025 screenresolution[1625:7324] Display 0: 1600x1200x32@60 19:56:50 INFO - 2016-11-30 19:56:50.031 screenresolution[1626:7326] starting screenresolution argv=screenresolution list 19:56:50 INFO - Available Modes on Display 0 19:56:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:56:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:56:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:56:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:56:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:56:50 INFO - Intel Iris: 19:56:50 INFO - Chipset Model: Intel Iris 19:56:50 INFO - Type: GPU 19:56:50 INFO - Bus: Built-In 19:56:50 INFO - VRAM (Dynamic, Max): 1536 MB 19:56:50 INFO - Vendor: Intel (0x8086) 19:56:50 INFO - Device ID: 0x0a2e 19:56:50 INFO - Revision ID: 0x0009 19:56:50 INFO - Displays: 19:56:50 INFO - Display: 19:56:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:56:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:56:50 INFO - Display Serial Number: 1600x1200 60 19:56:50 INFO - Main Display: Yes 19:56:50 INFO - Mirror: Off 19:56:50 INFO - Online: Yes 19:56:50 INFO - Rotation: Supported 19:56:50 INFO - Return code: 0 19:56:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:56:50 INFO - Python 2.7.3 19:56:50 INFO - Return code: 0 19:56:50 INFO - grabbing minidump binary from tooltool 19:56:50 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')]} 19:56:50 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 0x105ce1d30>, '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 0x105b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cd9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:56:50 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 19:56:50 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 19:56:50 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 19:56:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:56:50 INFO - Return code: 0 19:56:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:56:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:56:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:56:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:56:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:56:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0223', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0223 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:56:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzVKW0sVgv/Render', 19:56:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:50 INFO - 'HOME': '/Users/cltbld', 19:56:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:50 INFO - 'LOGNAME': 'cltbld', 19:56:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:56:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:56:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:50 INFO - 'MOZ_NO_REMOTE': '1', 19:56:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:56:50 INFO - 'NO_EM_RESTART': '1', 19:56:50 INFO - 'PAGER': '/bin/cat', 19:56:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:50 INFO - 'PWD': '/builds/slave/test', 19:56:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:56:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:50 INFO - 'SHELL': '/bin/bash', 19:56:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cbwcc9kWvn/Listeners', 19:56:50 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 19:56:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:50 INFO - 'USER': 'cltbld', 19:56:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:50 INFO - 'XPC_FLAGS': '0x0', 19:56:50 INFO - 'XPC_SERVICE_NAME': '0', 19:56:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0223', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:56:50 INFO - mozversion application_buildid: 20161130170644 19:56:50 INFO - mozversion application_changeset: 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 19:56:50 INFO - mozversion application_display_name: Nightly 19:56:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:56:50 INFO - mozversion application_name: Firefox 19:56:50 INFO - mozversion application_remotingname: firefox 19:56:50 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 19:56:50 INFO - mozversion application_vendor: Mozilla 19:56:50 INFO - mozversion application_version: 53.0a1 19:56:50 INFO - mozversion platform_buildid: 20161130170644 19:56:50 INFO - mozversion platform_changeset: 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 19:56:50 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 19:56:50 INFO - mozversion platform_version: 53.0a1 19:56:50 INFO - using testdate: 1480564610 19:56:50 INFO - actual date: 1480564610 19:56:50 INFO - starting webserver on 'localhost:49236' 19:56:50 INFO - SUITE-START | Running 2 tests 19:56:50 INFO - TEST-START | dromaeo_css 19:56:50 INFO - operating with platform_type : mac_ 19:56:50 INFO - Initialising browser for dromaeo_css test... 19:56:50 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpzrdinI/profile http://localhost:49236/getInfo.html) 19:56:53 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 19:56:53 INFO - PROCESS | 1636 | colorDepth:24 19:56:53 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 19:56:53 INFO - PROCESS | 1636 | __metrics 19:56:53 INFO - PROCESS | 1636 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:56:53 INFO - PROCESS | 1636 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:56:54 INFO - TEST-INFO | 1636: exit 0 19:56:54 INFO - Browser initialized. 19:56:54 INFO - Running cycle 1/1 for dromaeo_css test... 19:56:54 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpzrdinI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:57:06 INFO - PROCESS | 1638 | RSS: Main: 245338112 19:57:06 INFO - PROCESS | 1638 | 19:57:57 INFO - PROCESS | 1638 | 1480564677771 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:00:10 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 20:00:13 INFO - PROCESS | 1638 | RSS: Main: 468086784 20:00:13 INFO - PROCESS | 1638 | 20:03:16 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 20:03:16 INFO - PROCESS | 1638 | RSS: Main: 371269632 20:03:16 INFO - PROCESS | 1638 | 20:06:19 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 20:06:19 INFO - PROCESS | 1638 | RSS: Main: 335486976 20:06:19 INFO - PROCESS | 1638 | 20:09:22 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 20:09:22 INFO - PROCESS | 1638 | RSS: Main: 346267648 20:09:22 INFO - PROCESS | 1638 | 20:12:26 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 20:12:26 INFO - PROCESS | 1638 | RSS: Main: 333537280 20:12:26 INFO - PROCESS | 1638 | 20:15:30 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 20:15:30 INFO - PROCESS | 1638 | RSS: Main: 383684608 20:15:30 INFO - PROCESS | 1638 | 20:15:30 INFO - PROCESS | 1638 | __start_tp_report 20:15:30 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 20:15:30 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 20:15:30 INFO - PROCESS | 1638 | |i|pagename|runs| 20:15:30 INFO - PROCESS | 1638 | |0;dojo.html;2783.624210731552;3252.625948015979;3275.9344813103726;3284.441644920363;3289.983550082249;5460.099083651203;5504.422035686255;5549.528290095347;5581.469760372763;5599.216109744634;5556.833295001156;5562.471565201309;5605.495505404513;5606.467385598372;5651.011073062208;4303.720258183223;5113.054084994269;5140.2546630738525;5174;5181.326427564431;5101.311322971407;5168.043911876296;5186.118359878829;5190.013897359489;5198;5038.093143234217;5153.81961631343;5173.094772597105;5194.480551944813;5253.343332083489;5043.117454445457;5078.390593128848;5100.285959965608;5128.615353848476;5139.203423469349;5044.092063428581;5089.8727531812;5102.719350435725;5105.361829771278;5164.870878228036;5521.1442226454565;5526.4197259288;5547.639403438763;5549.833504994856;5551.77792888284;5933.940660593382;6096.96951515244;6114.510839132903;6124.081387791823;6132;6088.299845517778;6105.1941342447435;6124.724387402578;6127.017204504793;6154.876902461926;5641.323041235078;5652.802151924745;5659.320881494247;5668.943310566924;5695.430456954313;6247.156633854463;6254.34329395416;6259.374062593795;6263.78076767311;6269.46709529686;5772.480476757028;5875.529957603381;5887.470127688529;5948.405159484104;5973.910391344133;6027.547933904974;6090.147379366892;6102.05418160177;6108.908366374508;6122.969385153135;6007.789727359522;6013.098035294741;6021.066734656135;6026.728797204137;6040.305364883006;6022.397760223943;6024.126501657235;6029.276486821609;6086.604370715978;6091.5735898487565;4771.427428708508;4841.370621819211;4853.6602437829715;4877.6341774367065;4895.902081958412;4267.893302667459;4316.978415107905;4332.090261045153;4346.217680817484;4355.259605867011;5483.862903427446;5541.113421852484;5551.500364967173;5615.382307946123;5635.464630860061;6120.571559990758;6129.35641757609;6130.080487926846;6137.232845894263;6143.723532441051;5567.777288908398;5568.99758043548;5570.359408667974;5579.776808927679;5590.8043218487155;5266.8419947401635;5272.420033796279;5275.472452754694;5287.7884884604955;5299.258103865462;6253.7185826637915;6265.122882796412;6275.592086514362;6296.464800492009;6296.905546416808;4521.909561808679;6153.815385538441;6169.290531588925;6172.321044684992;6193.040078787615;6148.200733904564;6195.628262304276;6200.875982480416;6306;6318.336574659595;5980.910286345796;5994.220751302477;6024.0662697282;6042.697865106815;6122.877542449215;6055.091736239599;6137.1101190328045;6141.1402403664415;6143.662098584621;6157.076438534255;5773.653580785166;5932.970335148297;5945.583809133492;5950.137230101524;5950.375210602824;5668.008098582814;5917.378675239038;5939.524838013035;5941.7623295067715;5946;5785.4214578541805;5790.507806836297;5791.82624521265;5801.9709901451915;5803.013487707184;5867.911981320198;5893.292804863272;5909.113632955091;5922.674252916131;5928.288605367384;5689.089745640677;5781.248437703071;5786.218860453785;5786.913196302143;5788.392218816964 20:15:30 INFO - PROCESS | 1638 | |1;ext.html;481.8289507224935;487.32505479910327;503.65751289123403;520.1002266079682;552.864548185694;28814.711852881464;30103.849480752593;37302.25395492089;38597.61402385975;38668.41997370042;36200.81899590505;37281.88154355367;40017.99910004495;40214.79892600533;40469.988250293754;9075.863862042075;9241.122093401116;9248.167664910157;9251.398659087137;9268.814623707522;8314.334853211758;8504.489730616151;8507.31941444686;8597.4411663242;9148.313876459259;8487.575621218944;10937.398443085627;11238.157138214625;11242;11393.145514086476;11044.834327485094;11107.278026928223;11110.277831940979;11125.332480051224;11130;8159.347252219808;10968.957948994832;11053.723656908562;11056.170787190993;11099.334039957628;29673.70326296731;37509.24981500382;37708.05729856776;37793;39325.016874578076;3344.866205351783;3860.903477413059;3880.9223815523533;3895.0846551060476;3922.9201630304733;28779.56830647542;28952;28984.565231521334;29193.70806291931;30701.078967630783;3119.313750974782;3150.9054728358187;3156.6054243219596;3205.8877939272247;3245.042712399848;421894;427857.7214227849;429349;433112;433486.4977025316;56925.71537142288;57427;57449.71275143681;57559.42440575541;57646.423535764945;60066;60150.79698406007;60200.69899650561;60203.39796601978;60381.39618603846;15236;15520.611984700472;15521.689566208612;15529.689406211812;15559.766603500957;11919.463624136935;11983.64049078529;11986.820197697041;11989.040876729841;11995.340256285988;15390.461333853262;15760;16700;17930.462086137435;17973.910130449265;6456.063870738717;7708.267714567107;7774.533527988338;7787.29914307715;7846.411519136088;865.9523726194993;993.1161266472845;1009.8131845608586;1038.8234000219982;1039.5477967084344;27259.727402726116;28487.71512284892;29024.7097529022;29192;29273.97541086052;956.5073986896754;961.6153538584622;1024.364893765855;1044.3211912257093;1094.9671509854718;12527.498900044078;14863.777043344358;14880.851191488164;15092.39630414771;15150.848491515164;603042;604580;610506;614918.2540872939;621050;46837.82905427391;47682;47865.76067119712;54226.64433389197;55241.1713824293;1238.349866320171;1255.5229012975194;1258.3959699344366;1315.8289422375221;1349.2511656030997;28950.84196632111;29191.124266272047;29498.410031798816;30034.098977030724;30077.548836767903;45619.54380456153;46181.30728039149;46205.76897115493;46225;46281.84295392642;414277;419425;419786;420840.89579551906;421159;24871;25145.748542514342;25853.09514166995;25960;26584.468310633292;43614.34578481262;43697.56302436935;44078.779606101765;44285.89285267894;44876.55123448724;30317.69682303149;35830.46254306239;36045.45931810972;36846.078848027944;37052.44421333736;35397.46903796497;35920.64079359173;36792.44811327886;36798.63201367952;37010.8149459251;35157.47263790994;35574.64425355817;35852.40050239041;36255.64932208152;36300.81849590841;31060.365053485362;31376.73745414673;32398.676013240507;32737;32831.50752738663;24027.879860600584;24153.51692966166;24581.63127553124;24848.881800318668;24913.003479861327 20:15:30 INFO - PROCESS | 1638 | |2;jquery.html;350.57055107493306;369.42738754929854;372.91422972716276;380.3477036881748;400.2115831811332;14529.927350363247;14791.26043697815;15226.766833611566;19785.901070494663;19901.701474477893;18898;19374.903125484387;20011.999400030014;20067;20164.092615832287;5289;5781.913271300934;6078.331383547807;6081.391860813906;6083.939160608382;4362.8909277268;6504.772332968347;6585.9012114818315;6617.106690596756;6626.5030122740745;6275.811725648238;6455.064015717718;6547.967260163692;6571.704273307689;6573.967130164367;6491.805245842609;6527.4778017758945;6537.803865884007;6550.7707230246715;6554.016897465371;4919.680220785637;5181.663191892515;5233.502817232356;5239.659422137549;5664.773409063674;23542.882285588632;23679.382520408526;23968.28095157148;24019;24495;2162.8702277863376;2193.824494040489;2254.830887683414;2257.7742225777456;2265.694131292271;8248.298894593967;9986.440137193915;10135.594576216943;13310.401031953585;13485.865141348657;1593.6573636668056;1747.309812624019;1748.6677531269145;1844;1844.2438600173864;105986.47006764993;114722;114724.42637786841;116067.41966290199;117072.41463792711;10965.122790176767;11086.279391839505;11161.553537858395;11181.273217240854;11454.026407755267;14174.929125354307;14208.715825683428;14240.572782816533;14250.429982800779;14261.64345891361;6487.416132548094;7453.590052547162;7508.587027713528;8085.878711819328;8143.226393492609;7709.882067100239;7764.029496312961;7778.688852445839;7877.212278772192;8014.278714915569;4387.780610969503;4389.297712366006;4412.205802955435;4413.558644135561;4492.73043617384;1701.825740239231;1789.1322708486373;1821.9817801821812;1863.8351030605872;1890.1305399516377;585.2450339062647;708.2492557888654;760.9315161635479;807.6859796682462;889.088684098791;17926.193321300572;19096.52258693516;20018;20122.597548048958;20289.289874854603;803.4135081390617;807.4267270238079;824.5547404401556;825.405707890318;900.9054049324858;8727.956360218286;10685;10716.196285278635;10725.30285531438;10738.355698658106;414626;418426;420135.8993205014;487537;527850;76275.8558621621;92443;104100;120026;141826.290868545;901.6979311930577;956.4357029352715;1000.2498126405196;1000.8949060348558;1001.7345403468087;18165.727514087033;18495.63008739791;18628.06859657039;18640;19149.617007660047;42533.25801016823;51714.741426292625;52422;54181.45818541764;55856.162157568484;57483.27550173334;75724.62137689277;75740;77685.61157194177;77879.61060194662;7759.68961241544;7789.64946577394;7952.204779522001;8010.439269251096;8214.424990250625;10048.191325561176;11665.00847427978;12307.815382769444;12483.75032499363;12616.369181540702;10695.893041069801;10700.946495267473;10871.402072886067;10911.508982095984;11073.50169242402;10443.007914248134;10482.266241363337;10627.574897004033;10827.350358978172;10935.45322733876;9662.9033709662;10861.891381086089;11116.722081948015;11333.773324533298;11337.886621134014;6834.897476537756;7235.493515453868;7253.1296244451005;7482;7834.412419068479;9667.661631842851;10907.400092994643;11187.888121118685;11384.089272858317;11399.943000284946 20:15:30 INFO - PROCESS | 1638 | |3;mootools.html;1449.6448370149315;1470.1179292424547;1633.8121116071654;1806.7741532308462;1836.348096425768;7838.451308408414;8198.795030124249;8201.425900186989;8227.465214761036;8253.83492330153;7291.92180228635;7525.4732168748205;7531.585762783045;7848.882266766003;8047.637856296466;2249.3926639807282;2292.9770702293013;2294.621387471065;2298.3564601911407;2301.6432452969734;2275.6927814745045;2298.4139044543704;2309.0648287443614;2312.629979203328;2317.629179331307;2258.0516183203013;2258.706368172135;2279.90880364786;2282.7260728712577;2290.0839664134314;2244.8540844845033;2246.3148739634403;2273.499830037389;2273.8863056847176;2277.7722227777253;2271.2164303315494;2288.8855557222073;2290.129750694742;2293.2203050962753;2293.6559516072557;8033.0360356757565;8055.63749631268;8129.4715843469985;8140.837183256361;8169.714060007931;4906.1414252505665;5068.898622027539;5070.188769796851;5070.949290507121;5073.416557095944;3131.8434078295995;3226.322472280824;3445.4831775233915;3459.377312083824;3461.44616861301;1031.9071283584512;1044.3211912257018;1060.7454210989383;1072.8176210044312;1072.9570817167305;10439.37363758177;10459.738506537398;10600.840987385196;10620.681379558626;10625.256232063759;4358.259095953696;4513.277875539963;4599.402077729874;4633.4208223972;4637.026224492827;9179.036201198778;9180.8622870657;9186.724398268063;9187.724368268962;9203.631854725736;4593.517680643551;4945.060438516671;5104.3619547556555;5109.284700141983;5110.48895110486;4201.432806571135;4361.280388735823;4371.191329604034;4648.65135114861;4672.159011377917;3613.2412193439145;3665.9266814663556;3670.2292518570866;3777.223740550693;3795.6204379561823;1219.8353222314875;1231.384307846077;1236.1841184817977;1246.1681328447169;1247.6319485751726;832.883396324515;848.1391387741447;885.9202671759573;891.1801142948503;924.8520236762085;5451.400345961941;5587;5674.404187560329;5769.365369809318;5849.122631605208;911.876896618948;919.7608621758258;931.9207867331353;938.2634631813993;983.8868530118984;3775.9433608495897;3787.147891724395;3791.658750712449;3897.2205558888336;3938.3501722215515;9428.62285508572;9849.015098490094;9857;9904.108630223314;9923.65267215644;9764;9812.313138080408;9824.705258842247;9829.459379734184;9835.16401105914;6052.727627256784;7568.318851303299;7574.242575742492;7699.384049276045;7718.421118416029;5533.833984980457;5707.971460142673;5742.397048309867;5755.079187330007;5826.533877289722;8823.470591764515;8888.288936885163;8964.3276754245;8976.461412315282;9061.365704400629;9180.403273787182;9199.494027828534;9235.26117910579;9237.861432078389;9269.814603707753;6493.610383376824;6715.664216789043;6719.596824190564;6884.793456196322;7087.043249161297;7157.57054576727;7285.344319011315;7375.446841486802;7718.151003389735;7757.650905709143;6864.897026544706;6961.37347638715;7006.544574602634;7009.088818453773;7138.535995160298;6022.66875321844;6808.319168083152;6861.382475577222;6897.068895699016;6929.7228110875;6433.228012638513;6455.064015717789;6528.118703974903;6565.376289252705;6579.374959378852;4667.673262871566;4734.384530011076;4769.76151192432;4773.880652983703;4887.826921538876;4029.879103626896;4846.079244943449;4853.587445067209;4892.461829198714;4894.583960363267 20:15:30 INFO - PROCESS | 1638 | |4;prototype.html;299.4011976047904;300.74887468963414;307.40670505923566;311.49071268476047;314.2614855090536;5255.527002569773;5320.521153096221;5358.383785864627;5360.4639536046425;5405.918911216335;4093.8976525586877;4138.2412474698285;5144.691318520891;5159.658488792913;5220.321358223426;3567.4470457079065;3973.0663294125857;4026.939595906064;4033.919321613566;4059.411385349137;3383.374075795986;3844.596317386676;4009.278329900617;4049.5950404959417;4070.8982275443204;3113.9603559668453;3670.054870918404;4020.778857162855;4021.9798901005456;4030.9596904030877;4006.659433948118;4027.9194416111513;4030.8186131624147;4043.777592232426;4063.9883257787287;3350.6984371406447;3510.6313837047255;3517.384457719889;3525.453554699026;3552.1119720069983;4525.049739554697;5132.332796736438;5135.483613029626;5145.845624631267;5179.223116532513;3960.7029472789654;4437.755923424211;4558.7264764113925;4559.772011399416;4588.197065513556;4832.299316599074;5533.944660553423;5537.307836520435;5543.251661025791;5567.49892509672;3395.589131716484;3533.922153743107;3858.6913046956174;3865.7100717446024;3889.3193691103943;5530.861728456781;5790.536757059425;5830.300363956352;5858.769658371704;5874.7943821966455;3913.9217215655526;3930.292547341506;3932.5477570079233;3936.4254298280916;3942;3381.8309084545676;3770.7171962102952;3806.314863324573;3979.3235150024566;3980.800959951991;4237.915241695149;4511.187986162457;4572.611328037154;4604.263317869125;4624.445066591963;5456.836294911159;5558.555315574744;5595.720213989365;5644.096944488862;5662.5753068520025;1136.579465597734;1152.5505053029326;1194.689380760991;1249.7315225046584;1276.334383629354;730.6346826586706;773.1958762886535;834.9457285276437;859.7162936231028;874.3267683883374;4657.697249678828;5021.648484606042;5260.28986086881;5272.92090618641;5296.655717378358;4917.795140190676;5761.337446193657;5768.625039372428;5777.220075289866;5780.71096445176;4845.854624361275;4868.610511159099;4996.227847974756;5046.7476626168545;5214.13966695491;2760.2823265950997;2835.447087817856;2841.3606938438684;2860;2891.6096326996008;4889.926651100236;5256.26412302278;5532.1425179097305;5617.494425501724;5638.633488823213;5014.423341315723;5418.211681556814;5495.472082841858;5531.3362396512675;5708.686022268815;5080.187170052774;5224.52979231871;5644.5484361251;5684.8294551163535;5696.686682232351;4734.656345109193;5080.872978175576;5144.922826157685;5310.495502927216;5531.778728850801;4809.350737650372;5316.946830531645;5326.455327955005;5359.785608575613;5423.29497165366;4896.975515122402;5158.0199762045095;5623.044082505902;5659.801906933238;5696.316442026818;3125.984370078226;3270.918227044343;3629.3830048891223;3643.5809881863925;3685.226102518501;5332.626716129834;5601.499063085572;5816.854578635568;5859.707014649336;5860.238169037998;5681;5682.715864206856;5683.460071293221;5697.800576979786;5723.370429252862;4768.546988036131;5285.947140528546;5525.502704756591;5681.829545113653;5727.8568035799435;5336.573074154136;5409.513143817076;5478.479544443271;5481.808136715196;5495.203195536705;4762.738049407316;4908.601048701245;5561.304836895388;5569.359523654832;5582.665040097607;5469.781208751605;5519.558435325084;5519.806806761744;5536.363318218457;5572.749226284908 20:15:30 INFO - PROCESS | 1638 | |5;yui.html;709.8367375503638;713.8322494213862;745.1133151549656;761.3604572159387;779.3531368963759;14714.852851471482;14981.400743970242;15275.694486110271;15286.84713152868;15307.846921530781;14003.509877154302;14165.645858853508;14171.716565668707;14174.645633859132;14176.149431034146;663.9634820084917;693.1128155960379;695.5305169010923;783.8158032862273;816.3713940265992;831.3598529132564;834.511810590802;843.0010437631427;843.0220943705303;845;893.8033632600817;894.9642014319452;896.0233345653237;896.0367604824806;897.161154320709;689.2246222999127;718.8167017410527;892.7455675132557;893.5621545442687;896.4173287363234;537.0252990821646;537.2183473046707;537.2585831552472;628.9654069026202;657.7895073576458;14443.638909027253;14507.274636268145;14510.637234069127;14514.927425362912;14519;13019.674508137277;13073.443572505515;13274.734505309936;13298.069135160542;13409.66475838112;10060.94969525155;11248.391632015322;11474.598389056342;11936.343501107436;12168.209066410654;4284.378765079077;4287.249731297042;4304.461942257218;4381.649468042581;4429.667774916862;108082.3787643186;108918;111034.12419888638;111122;111838.99999999919;10537.262391632512;10594.523246453928;10745.247832651796;10753.301035432829;10891.727706807393;20790.960451977342;20814.583708326052;20819.791802082087;20837.89581052085;20848.061837217363;7272.454565907578;7318.597477138808;7351.411887049023;7402.9629851850395;7438.66526006331;4145.357469592218;4187.036981494212;4212.30496968003;4218.501311463996;4230.809613567397;3282.3271229397915;3406.0803583032443;3416.538767266437;3434.9484757728655;3453.3783918894337;608.5527137553894;610.7068607068543;610.7831719739429;621.0559948877681;627.1783963008473;515.3120584020375;538.9164679474687;581.4795757796749;604.2778879239266;643.6009674002148;8130.3495720342235;8461.280791132822;8717.564121794012;8886.555672216364;8898.911010889808;539.9892002159935;543.5506384993653;549.3380476525776;603.4177019176516;617.2438762515972;3599.2441587266967;4164.979580002919;4256.702030857872;4331.112122014979;4365.432493775789;107987.46006270076;109604;110667;115386;117820;96026.51986740022;96259.03740962641;97680.51159744155;98020;99063;58789;84865.72701409484;85037.57481212678;85591;87729;7269.60017199038;7404.851902962018;8290.212429819157;8308.584570771558;8626.353023523387;34670.65329346674;47614.523854761006;54063.918721626134;57869;59587;73889;78820;80495;82265.58867205626;82414.58792705998;10939.288946218789;11087.778244434905;11132.721681957692;11560.94219528897;11620.186586939171;11896.643100706993;11911.702207444883;11914.76170476603;12086;12118.757624847278;11585.768284634092;11601.535938562363;11650.359230242419;11708.063354931755;11830.349330786888;9878.061584149495;11459.942700286445;11576.88423115758;11599.942000289944;11824.290542567473;11346.94326528362;11497.885021149681;11541.076713863038;11626.5349386027;12059.095567832272;9322.300827437834;9738.220942324739;9854.950725246328;9894.653687120915;9899.109080182818;7872.889061126007;9196.494192819458;9562;9658.3722058066;9703.951480242553 20:15:30 INFO - PROCESS | 1638 | __end_tp_report 20:15:30 INFO - PROCESS | 1638 | __start_cc_report 20:15:30 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,2989 20:15:30 INFO - PROCESS | 1638 | __end_cc_report 20:15:30 INFO - PROCESS | 1638 | __startTimestamp1480565730432__endTimestamp 20:15:30 INFO - PROCESS | 1638 | 20:15:30 INFO - PROCESS | 1638 | ------- Summary: start ------- 20:15:30 INFO - PROCESS | 1638 | Number of tests: 6 20:15:30 INFO - PROCESS | 1638 | 20:15:30 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5607.24 Median:5786.22 stddev:647.00 (11.2%) stddev-sans-first:609.89 20:15:30 INFO - PROCESS | 1638 | Values: 2783.6 3252.6 3275.9 3284.4 3290.0 5460.1 5504.4 5549.5 5581.5 5599.2 5556.8 5562.5 5605.5 5606.5 5651.0 4303.7 5113.1 5140.3 5174.0 5181.3 5101.3 5168.0 5186.1 5190.0 5198.0 5038.1 5153.8 5173.1 5194.5 5253.3 5043.1 5078.4 5100.3 5128.6 5139.2 5044.1 5089.9 5102.7 5105.4 5164.9 5521.1 5526.4 5547.6 5549.8 5551.8 5933.9 6097.0 6114.5 6124.1 6132.0 6088.3 6105.2 6124.7 6127.0 6154.9 5641.3 5652.8 5659.3 5668.9 5695.4 6247.2 6254.3 6259.4 6263.8 6269.5 5772.5 5875.5 5887.5 5948.4 5973.9 6027.5 6090.1 6102.1 6108.9 6123.0 6007.8 6013.1 6021.1 6026.7 6040.3 6022.4 6024.1 6029.3 6086.6 6091.6 4771.4 4841.4 4853.7 4877.6 4895.9 4267.9 4317.0 4332.1 4346.2 4355.3 5483.9 5541.1 5551.5 5615.4 5635.5 6120.6 6129.4 6130.1 6137.2 6143.7 5567.8 5569.0 5570.4 5579.8 5590.8 5266.8 5272.4 5275.5 5287.8 5299.3 6253.7 6265.1 6275.6 6296.5 6296.9 4521.9 6153.8 6169.3 6172.3 6193.0 6148.2 6195.6 6200.9 6306.0 6318.3 5980.9 5994.2 6024.1 6042.7 6122.9 6055.1 6137.1 6141.1 6143.7 6157.1 5773.7 5933.0 5945.6 5950.1 5950.4 5668.0 5917.4 5939.5 5941.8 5946.0 5785.4 5790.5 5791.8 5802.0 5803.0 5867.9 5893.3 5909.1 5922.7 5928.3 5689.1 5781.2 5786.2 5786.9 5788.4 20:15:30 INFO - PROCESS | 1638 | 20:15:30 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:61984.24 Median:36123.14 stddev:131858.14 (365.0%) stddev-sans-first:132147.18 20:15:30 INFO - PROCESS | 1638 | Values: 481.8 487.3 503.7 520.1 552.9 28814.7 30103.8 37302.3 38597.6 38668.4 36200.8 37281.9 40018.0 40214.8 40470.0 9075.9 9241.1 9248.2 9251.4 9268.8 8314.3 8504.5 8507.3 8597.4 9148.3 8487.6 10937.4 11238.2 11242.0 11393.1 11044.8 11107.3 11110.3 11125.3 11130.0 8159.3 10969.0 11053.7 11056.2 11099.3 29673.7 37509.2 37708.1 37793.0 39325.0 3344.9 3860.9 3880.9 3895.1 3922.9 28779.6 28952.0 28984.6 29193.7 30701.1 3119.3 3150.9 3156.6 3205.9 3245.0 421894.0 427857.7 429349.0 433112.0 433486.5 56925.7 57427.0 57449.7 57559.4 57646.4 60066.0 60150.8 60200.7 60203.4 60381.4 15236.0 15520.6 15521.7 15529.7 15559.8 11919.5 11983.6 11986.8 11989.0 11995.3 15390.5 15760.0 16700.0 17930.5 17973.9 6456.1 7708.3 7774.5 7787.3 7846.4 866.0 993.1 1009.8 1038.8 1039.5 27259.7 28487.7 29024.7 29192.0 29274.0 956.5 961.6 1024.4 1044.3 1095.0 12527.5 14863.8 14880.9 15092.4 15150.8 603042.0 604580.0 610506.0 614918.3 621050.0 46837.8 47682.0 47865.8 54226.6 55241.2 1238.3 1255.5 1258.4 1315.8 1349.3 28950.8 29191.1 29498.4 30034.1 30077.5 45619.5 46181.3 46205.8 46225.0 46281.8 414277.0 419425.0 419786.0 420840.9 421159.0 24871.0 25145.7 25853.1 25960.0 26584.5 43614.3 43697.6 44078.8 44285.9 44876.6 30317.7 35830.5 36045.5 36846.1 37052.4 35397.5 35920.6 36792.4 36798.6 37010.8 35157.5 35574.6 35852.4 36255.6 36300.8 31060.4 31376.7 32398.7 32737.0 32831.5 24027.9 24153.5 24581.6 24848.9 24913.0 20:15:30 INFO - PROCESS | 1638 | 20:15:30 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:29808.73 Median:12422.79 stddev:77044.54 (620.2%) stddev-sans-first:77228.92 20:15:30 INFO - PROCESS | 1638 | Values: 350.6 369.4 372.9 380.3 400.2 14529.9 14791.3 15226.8 19785.9 19901.7 18898.0 19374.9 20012.0 20067.0 20164.1 5289.0 5781.9 6078.3 6081.4 6083.9 4362.9 6504.8 6585.9 6617.1 6626.5 6275.8 6455.1 6548.0 6571.7 6574.0 6491.8 6527.5 6537.8 6550.8 6554.0 4919.7 5181.7 5233.5 5239.7 5664.8 23542.9 23679.4 23968.3 24019.0 24495.0 2162.9 2193.8 2254.8 2257.8 2265.7 8248.3 9986.4 10135.6 13310.4 13485.9 1593.7 1747.3 1748.7 1844.0 1844.2 105986.5 114722.0 114724.4 116067.4 117072.4 10965.1 11086.3 11161.6 11181.3 11454.0 14174.9 14208.7 14240.6 14250.4 14261.6 6487.4 7453.6 7508.6 8085.9 8143.2 7709.9 7764.0 7778.7 7877.2 8014.3 4387.8 4389.3 4412.2 4413.6 4492.7 1701.8 1789.1 1822.0 1863.8 1890.1 585.2 708.2 760.9 807.7 889.1 17926.2 19096.5 20018.0 20122.6 20289.3 803.4 807.4 824.6 825.4 900.9 8728.0 10685.0 10716.2 10725.3 10738.4 414626.0 418426.0 420135.9 487537.0 527850.0 76275.9 92443.0 104100.0 120026.0 141826.3 901.7 956.4 1000.2 1000.9 1001.7 18165.7 18495.6 18628.1 18640.0 19149.6 42533.3 51714.7 52422.0 54181.5 55856.2 57483.3 75724.6 75740.0 77685.6 77879.6 7759.7 7789.6 7952.2 8010.4 8214.4 10048.2 11665.0 12307.8 12483.8 12616.4 10695.9 10700.9 10871.4 10911.5 11073.5 10443.0 10482.3 10627.6 10827.4 10935.5 9662.9 10861.9 11116.7 11333.8 11337.9 6834.9 7235.5 7253.1 7482.0 7834.4 9667.7 10907.4 11187.9 11384.1 11399.9 20:15:30 INFO - PROCESS | 1638 | 20:15:30 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5308.04 Median:5492.62 stddev:2868.86 (52.2%) stddev-sans-first:2862.26 20:15:30 INFO - PROCESS | 1638 | Values: 1449.6 1470.1 1633.8 1806.8 1836.3 7838.5 8198.8 8201.4 8227.5 8253.8 7291.9 7525.5 7531.6 7848.9 8047.6 2249.4 2293.0 2294.6 2298.4 2301.6 2275.7 2298.4 2309.1 2312.6 2317.6 2258.1 2258.7 2279.9 2282.7 2290.1 2244.9 2246.3 2273.5 2273.9 2277.8 2271.2 2288.9 2290.1 2293.2 2293.7 8033.0 8055.6 8129.5 8140.8 8169.7 4906.1 5068.9 5070.2 5070.9 5073.4 3131.8 3226.3 3445.5 3459.4 3461.4 1031.9 1044.3 1060.7 1072.8 1073.0 10439.4 10459.7 10600.8 10620.7 10625.3 4358.3 4513.3 4599.4 4633.4 4637.0 9179.0 9180.9 9186.7 9187.7 9203.6 4593.5 4945.1 5104.4 5109.3 5110.5 4201.4 4361.3 4371.2 4648.7 4672.2 3613.2 3665.9 3670.2 3777.2 3795.6 1219.8 1231.4 1236.2 1246.2 1247.6 832.9 848.1 885.9 891.2 924.9 5451.4 5587.0 5674.4 5769.4 5849.1 911.9 919.8 931.9 938.3 983.9 3775.9 3787.1 3791.7 3897.2 3938.4 9428.6 9849.0 9857.0 9904.1 9923.7 9764.0 9812.3 9824.7 9829.5 9835.2 6052.7 7568.3 7574.2 7699.4 7718.4 5533.8 5708.0 5742.4 5755.1 5826.5 8823.5 8888.3 8964.3 8976.5 9061.4 9180.4 9199.5 9235.3 9237.9 9269.8 6493.6 6715.7 6719.6 6884.8 7087.0 7157.6 7285.3 7375.4 7718.2 7757.7 6864.9 6961.4 7006.5 7009.1 7138.5 6022.7 6808.3 6861.4 6897.1 6929.7 6433.2 6455.1 6528.1 6565.4 6579.4 4667.7 4734.4 4769.8 4773.9 4887.8 4029.9 4846.1 4853.6 4892.5 4894.6 20:15:30 INFO - PROCESS | 1638 | 20:15:30 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4466.43 Median:5106.60 stddev:1385.02 (27.1%) stddev-sans-first:1353.13 20:15:30 INFO - PROCESS | 1638 | Values: 299.4 300.7 307.4 311.5 314.3 5255.5 5320.5 5358.4 5360.5 5405.9 4093.9 4138.2 5144.7 5159.7 5220.3 3567.4 3973.1 4026.9 4033.9 4059.4 3383.4 3844.6 4009.3 4049.6 4070.9 3114.0 3670.1 4020.8 4022.0 4031.0 4006.7 4027.9 4030.8 4043.8 4064.0 3350.7 3510.6 3517.4 3525.5 3552.1 4525.0 5132.3 5135.5 5145.8 5179.2 3960.7 4437.8 4558.7 4559.8 4588.2 4832.3 5533.9 5537.3 5543.3 5567.5 3395.6 3533.9 3858.7 3865.7 3889.3 5530.9 5790.5 5830.3 5858.8 5874.8 3913.9 3930.3 3932.5 3936.4 3942.0 3381.8 3770.7 3806.3 3979.3 3980.8 4237.9 4511.2 4572.6 4604.3 4624.4 5456.8 5558.6 5595.7 5644.1 5662.6 1136.6 1152.6 1194.7 1249.7 1276.3 730.6 773.2 834.9 859.7 874.3 4657.7 5021.6 5260.3 5272.9 5296.7 4917.8 5761.3 5768.6 5777.2 5780.7 4845.9 4868.6 4996.2 5046.7 5214.1 2760.3 2835.4 2841.4 2860.0 2891.6 4889.9 5256.3 5532.1 5617.5 5638.6 5014.4 5418.2 5495.5 5531.3 5708.7 5080.2 5224.5 5644.5 5684.8 5696.7 4734.7 5080.9 5144.9 5310.5 5531.8 4809.4 5316.9 5326.5 5359.8 5423.3 4897.0 5158.0 5623.0 5659.8 5696.3 3126.0 3270.9 3629.4 3643.6 3685.2 5332.6 5601.5 5816.9 5859.7 5860.2 5681.0 5682.7 5683.5 5697.8 5723.4 4768.5 5285.9 5525.5 5681.8 5727.9 5336.6 5409.5 5478.5 5481.8 5495.2 4762.7 4908.6 5561.3 5569.4 5582.7 5469.8 5519.6 5519.8 5536.4 5572.7 20:15:30 INFO - PROCESS | 1638 | 20:15:30 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20935.18 Median:24064.92 stddev:32003.56 (133.0%) stddev-sans-first:32057.31 20:15:30 INFO - PROCESS | 1638 | Values: 709.8 713.8 745.1 761.4 779.4 14714.9 14981.4 15275.7 15286.8 15307.8 14003.5 14165.6 14171.7 14174.6 14176.1 664.0 693.1 695.5 783.8 816.4 831.4 834.5 843.0 843.0 845.0 893.8 895.0 896.0 896.0 897.2 689.2 718.8 892.7 893.6 896.4 537.0 537.2 537.3 629.0 657.8 14443.6 14507.3 14510.6 14514.9 14519.0 13019.7 13073.4 13274.7 13298.1 13409.7 10060.9 11248.4 11474.6 11936.3 12168.2 4284.4 4287.2 4304.5 4381.6 4429.7 108082.4 108918.0 111034.1 111122.0 111839.0 10537.3 10594.5 10745.2 10753.3 10891.7 20791.0 20814.6 20819.8 20837.9 20848.1 7272.5 7318.6 7351.4 7403.0 7438.7 4145.4 4187.0 4212.3 4218.5 4230.8 3282.3 3406.1 3416.5 3434.9 3453.4 608.6 610.7 610.8 621.1 627.2 515.3 538.9 581.5 604.3 643.6 8130.3 8461.3 8717.6 8886.6 8898.9 540.0 543.6 549.3 603.4 617.2 3599.2 4165.0 4256.7 4331.1 4365.4 107987.5 109604.0 110667.0 115386.0 117820.0 96026.5 96259.0 97680.5 98020.0 99063.0 58789.0 84865.7 85037.6 85591.0 87729.0 7269.6 7404.9 8290.2 8308.6 8626.4 34670.7 47614.5 54063.9 57869.0 59587.0 73889.0 78820.0 80495.0 82265.6 82414.6 10939.3 11087.8 11132.7 11560.9 11620.2 11896.6 11911.7 11914.8 12086.0 12118.8 11585.8 11601.5 11650.4 11708.1 11830.3 9878.1 11459.9 11576.9 11599.9 11824.3 11346.9 11497.9 11541.1 11626.5 12059.1 9322.3 9738.2 9855.0 9894.7 9899.1 7872.9 9196.5 9562.0 9658.4 9704.0 20:15:30 INFO - PROCESS | 1638 | -------- Summary: end -------- 20:15:30 INFO - PROCESS | 1638 | 20:15:31 INFO - TEST-INFO | 1638: exit 0 20:15:31 INFO - TEST-OK | dromaeo_css | took 1120453ms 20:15:31 INFO - TEST-START | kraken 20:15:31 INFO - operating with platform_type : mac_ 20:15:31 INFO - Initialising browser for kraken test... 20:15:31 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpmEoyVy/profile http://localhost:49236/getInfo.html) 20:15:33 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 20:15:33 INFO - PROCESS | 1647 | colorDepth:24 20:15:33 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 20:15:33 INFO - PROCESS | 1647 | __metrics 20:15:33 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:15:33 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:15:34 INFO - TEST-INFO | 1647: exit 0 20:15:34 INFO - Browser initialized. 20:15:34 INFO - Running cycle 1/1 for kraken test... 20:15:34 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpmEoyVy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:15:46 INFO - PROCESS | 1648 | RSS: Main: 237412352 20:15:46 INFO - PROCESS | 1648 | 20:15:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 20:15:50 INFO - PROCESS | 1648 | RSS: Main: 295936000 20:15:50 INFO - PROCESS | 1648 | 20:15:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 20:15:53 INFO - PROCESS | 1648 | RSS: Main: 299032576 20:15:53 INFO - PROCESS | 1648 | 20:15:56 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 20:15:57 INFO - PROCESS | 1648 | RSS: Main: 389406720 20:15:57 INFO - PROCESS | 1648 | 20:15:59 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 20:16:00 INFO - PROCESS | 1648 | RSS: Main: 292937728 20:16:00 INFO - PROCESS | 1648 | 20:16:02 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:16:02 INFO - PROCESS | 1648 | RSS: Main: 297111552 20:16:02 INFO - PROCESS | 1648 | 20:16:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 20:16:06 INFO - PROCESS | 1648 | RSS: Main: 338751488 20:16:06 INFO - PROCESS | 1648 | 20:16:09 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 20:16:09 INFO - PROCESS | 1648 | RSS: Main: 339517440 20:16:09 INFO - PROCESS | 1648 | 20:16:12 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 20:16:13 INFO - PROCESS | 1648 | RSS: Main: 338161664 20:16:13 INFO - PROCESS | 1648 | 20:16:14 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:16:15 INFO - PROCESS | 1648 | RSS: Main: 310677504 20:16:15 INFO - PROCESS | 1648 | 20:16:17 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:16:18 INFO - PROCESS | 1648 | RSS: Main: 315453440 20:16:18 INFO - PROCESS | 1648 | 20:16:20 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:16:20 INFO - PROCESS | 1648 | RSS: Main: 317534208 20:16:20 INFO - PROCESS | 1648 | 20:16:23 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:16:24 INFO - PROCESS | 1648 | RSS: Main: 334827520 20:16:24 INFO - PROCESS | 1648 | 20:16:27 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:16:28 INFO - PROCESS | 1648 | RSS: Main: 328441856 20:16:28 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 20:16:30 INFO - PROCESS | 1648 | RSS: Main: 372432896 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | __start_tp_report 20:16:30 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 20:16:30 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 20:16:30 INFO - PROCESS | 1648 | |i|pagename|runs| 20:16:30 INFO - PROCESS | 1648 | |0;ai-astar;101;114;114;113;112;112;114;111;115;113 20:16:30 INFO - PROCESS | 1648 | |1;audio-beat-detection;150;114;115;154;115;115;119;140;159;124 20:16:30 INFO - PROCESS | 1648 | |2;audio-dft;183;182;183;187;183;181;181;183;184;185 20:16:30 INFO - PROCESS | 1648 | |3;audio-fft;79;58;59;87;61;58;59;58;73;59 20:16:30 INFO - PROCESS | 1648 | |4;audio-oscillator;90;71;85;70;69;70;81;67;70;84 20:16:30 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;89;86;88;86;87;85;89;86;87;85 20:16:30 INFO - PROCESS | 1648 | |6;imaging-darkroom;98;96;97;97;98;108;156;105;98;97 20:16:30 INFO - PROCESS | 1648 | |7;imaging-desaturate;88;89;88;86;121;138;88;85;88;87 20:16:30 INFO - PROCESS | 1648 | |8;json-parse-financial;57;53;54;55;54;56;58;55;54;55 20:16:30 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;65;70;80;75;73;69;70;71;71;71 20:16:30 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;88;88;87;88;92;87;87;88;88;86 20:16:30 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;152;151;157;158;154;174;157;152;196;160 20:16:30 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;175;172;175;174;172;172;171;174;181;210 20:16:30 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;70;69;69;70;68;66;66;70;69;69 20:16:30 INFO - PROCESS | 1648 | __end_tp_report 20:16:30 INFO - PROCESS | 1648 | __start_cc_report 20:16:30 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2059 20:16:30 INFO - PROCESS | 1648 | __end_cc_report 20:16:30 INFO - PROCESS | 1648 | __startTimestamp1480565790274__endTimestamp 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | ------- Summary: start ------- 20:16:30 INFO - PROCESS | 1648 | Number of tests: 14 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.50 stddev:4.01 (3.5%) stddev-sans-first:1.27 20:16:30 INFO - PROCESS | 1648 | Values: 101.0 114.0 114.0 113.0 112.0 112.0 114.0 111.0 115.0 113.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:132.00 stddev:18.27 (13.8%) stddev-sans-first:17.96 20:16:30 INFO - PROCESS | 1648 | Values: 150.0 114.0 115.0 154.0 115.0 115.0 119.0 140.0 159.0 124.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.00 stddev:1.81 (1.0%) stddev-sans-first:1.92 20:16:30 INFO - PROCESS | 1648 | Values: 183.0 182.0 183.0 187.0 183.0 181.0 181.0 183.0 184.0 185.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:65.10 Median:60.00 stddev:10.62 (17.7%) stddev-sans-first:10.00 20:16:30 INFO - PROCESS | 1648 | Values: 79.0 58.0 59.0 87.0 61.0 58.0 59.0 58.0 73.0 59.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:76.00 stddev:8.35 (11.0%) stddev-sans-first:7.08 20:16:30 INFO - PROCESS | 1648 | Values: 90.0 71.0 85.0 70.0 69.0 70.0 81.0 67.0 70.0 84.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.00 stddev:1.48 (1.7%) stddev-sans-first:1.33 20:16:30 INFO - PROCESS | 1648 | Values: 89.0 86.0 88.0 86.0 87.0 85.0 89.0 86.0 87.0 85.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:97.00 stddev:18.35 (18.9%) stddev-sans-first:19.29 20:16:30 INFO - PROCESS | 1648 | Values: 98.0 96.0 97.0 97.0 98.0 108.0 156.0 105.0 98.0 97.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:88.00 stddev:18.24 (20.7%) stddev-sans-first:19.13 20:16:30 INFO - PROCESS | 1648 | Values: 88.0 89.0 88.0 86.0 121.0 138.0 88.0 85.0 88.0 87.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:1.52 (2.8%) stddev-sans-first:1.45 20:16:30 INFO - PROCESS | 1648 | Values: 57.0 53.0 54.0 55.0 54.0 56.0 58.0 55.0 54.0 55.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.00 stddev:3.95 (5.6%) stddev-sans-first:3.42 20:16:30 INFO - PROCESS | 1648 | Values: 65.0 70.0 80.0 75.0 73.0 69.0 70.0 71.0 71.0 71.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:88.00 stddev:1.60 (1.8%) stddev-sans-first:1.69 20:16:30 INFO - PROCESS | 1648 | Values: 88.0 88.0 87.0 88.0 92.0 87.0 87.0 88.0 88.0 86.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:161.10 Median:157.50 stddev:13.93 (8.8%) stddev-sans-first:14.38 20:16:30 INFO - PROCESS | 1648 | Values: 152.0 151.0 157.0 158.0 154.0 174.0 157.0 152.0 196.0 160.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:174.50 stddev:11.73 (6.7%) stddev-sans-first:12.40 20:16:30 INFO - PROCESS | 1648 | Values: 175.0 172.0 175.0 174.0 172.0 172.0 171.0 174.0 181.0 210.0 20:16:30 INFO - PROCESS | 1648 | 20:16:30 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.51 (2.2%) stddev-sans-first:1.51 20:16:30 INFO - PROCESS | 1648 | Values: 70.0 69.0 69.0 70.0 68.0 66.0 66.0 70.0 69.0 69.0 20:16:30 INFO - PROCESS | 1648 | -------- Summary: end -------- 20:16:30 INFO - PROCESS | 1648 | 20:16:31 INFO - TEST-INFO | 1648: exit 0 20:16:31 INFO - TEST-OK | kraken | took 60259ms 20:16:31 INFO - SUITE-END | took 1180s 20:16:31 INFO - Completed test suite (00:19:40) 20:16:31 INFO - javascript benchmark 20:16:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2783.624210731552, 3252.625948015979, 3275.9344813103726, 3284.441644920363, 3289.983550082249, 5460.099083651203, 5504.422035686255, 5549.528290095347, 5581.469760372763, 5599.216109744634, 5556.833295001156, 5562.471565201309, 5605.495505404513, 5606.467385598372, 5651.011073062208, 4303.720258183223, 5113.054084994269, 5140.2546630738525, 5174.0, 5181.326427564431, 5101.311322971407, 5168.043911876296, 5186.118359878829, 5190.013897359489, 5198.0, 5038.093143234217, 5153.81961631343, 5173.094772597105, 5194.480551944813, 5253.343332083489, 5043.117454445457, 5078.390593128848, 5100.285959965608, 5128.615353848476, 5139.203423469349, 5044.092063428581, 5089.8727531812, 5102.719350435725, 5105.361829771278, 5164.870878228036, 5521.1442226454565, 5526.4197259288, 5547.639403438763, 5549.833504994856, 5551.77792888284, 5933.940660593382, 6096.96951515244, 6114.510839132903, 6124.081387791823, 6132.0, 6088.299845517778, 6105.1941342447435, 6124.724387402578, 6127.017204504793, 6154.876902461926, 5641.323041235078, 5652.802151924745, 5659.320881494247, 5668.943310566924, 5695.430456954313, 6247.156633854463, 6254.34329395416, 6259.374062593795, 6263.78076767311, 6269.46709529686, 5772.480476757028, 5875.529957603381, 5887.470127688529, 5948.405159484104, 5973.910391344133, 6027.547933904974, 6090.147379366892, 6102.05418160177, 6108.908366374508, 6122.969385153135, 6007.789727359522, 6013.098035294741, 6021.066734656135, 6026.728797204137, 6040.305364883006, 6022.397760223943, 6024.126501657235, 6029.276486821609, 6086.604370715978, 6091.5735898487565, 4771.427428708508, 4841.370621819211, 4853.6602437829715, 4877.6341774367065, 4895.902081958412, 4267.893302667459, 4316.978415107905, 4332.090261045153, 4346.217680817484, 4355.259605867011, 5483.862903427446, 5541.113421852484, 5551.500364967173, 5615.382307946123, 5635.464630860061, 6120.571559990758, 6129.35641757609, 6130.080487926846, 6137.232845894263, 6143.723532441051, 5567.777288908398, 5568.99758043548, 5570.359408667974, 5579.776808927679, 5590.8043218487155, 5266.8419947401635, 5272.420033796279, 5275.472452754694, 5287.7884884604955, 5299.258103865462, 6253.7185826637915, 6265.122882796412, 6275.592086514362, 6296.464800492009, 6296.905546416808, 4521.909561808679, 6153.815385538441, 6169.290531588925, 6172.321044684992, 6193.040078787615, 6148.200733904564, 6195.628262304276, 6200.875982480416, 6306.0, 6318.336574659595, 5980.910286345796, 5994.220751302477, 6024.0662697282, 6042.697865106815, 6122.877542449215, 6055.091736239599, 6137.1101190328045, 6141.1402403664415, 6143.662098584621, 6157.076438534255, 5773.653580785166, 5932.970335148297, 5945.583809133492, 5950.137230101524, 5950.375210602824, 5668.008098582814, 5917.378675239038, 5939.524838013035, 5941.7623295067715, 5946.0, 5785.4214578541805, 5790.507806836297, 5791.82624521265, 5801.9709901451915, 5803.013487707184, 5867.911981320198, 5893.292804863272, 5909.113632955091, 5922.674252916131, 5928.288605367384, 5689.089745640677, 5781.248437703071, 5786.218860453785, 5786.913196302143, 5788.392218816964], "value": 5564.463633267397, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.8289507224935, 487.32505479910327, 503.65751289123403, 520.1002266079682, 552.864548185694, 28814.711852881464, 30103.849480752593, 37302.25395492089, 38597.61402385975, 38668.41997370042, 36200.81899590505, 37281.88154355367, 40017.99910004495, 40214.79892600533, 40469.988250293754, 9075.863862042075, 9241.122093401116, 9248.167664910157, 9251.398659087137, 9268.814623707522, 8314.334853211758, 8504.489730616151, 8507.31941444686, 8597.4411663242, 9148.313876459259, 8487.575621218944, 10937.398443085627, 11238.157138214625, 11242.0, 11393.145514086476, 11044.834327485094, 11107.278026928223, 11110.277831940979, 11125.332480051224, 11130.0, 8159.347252219808, 10968.957948994832, 11053.723656908562, 11056.170787190993, 11099.334039957628, 29673.70326296731, 37509.24981500382, 37708.05729856776, 37793.0, 39325.016874578076, 3344.866205351783, 3860.903477413059, 3880.9223815523533, 3895.0846551060476, 3922.9201630304733, 28779.56830647542, 28952.0, 28984.565231521334, 29193.70806291931, 30701.078967630783, 3119.313750974782, 3150.9054728358187, 3156.6054243219596, 3205.8877939272247, 3245.042712399848, 421894.0, 427857.7214227849, 429349.0, 433112.0, 433486.4977025316, 56925.71537142288, 57427.0, 57449.71275143681, 57559.42440575541, 57646.423535764945, 60066.0, 60150.79698406007, 60200.69899650561, 60203.39796601978, 60381.39618603846, 15236.0, 15520.611984700472, 15521.689566208612, 15529.689406211812, 15559.766603500957, 11919.463624136935, 11983.64049078529, 11986.820197697041, 11989.040876729841, 11995.340256285988, 15390.461333853262, 15760.0, 16700.0, 17930.462086137435, 17973.910130449265, 6456.063870738717, 7708.267714567107, 7774.533527988338, 7787.29914307715, 7846.411519136088, 865.9523726194993, 993.1161266472845, 1009.8131845608586, 1038.8234000219982, 1039.5477967084344, 27259.727402726116, 28487.71512284892, 29024.7097529022, 29192.0, 29273.97541086052, 956.5073986896754, 961.6153538584622, 1024.364893765855, 1044.3211912257093, 1094.9671509854718, 12527.498900044078, 14863.777043344358, 14880.851191488164, 15092.39630414771, 15150.848491515164, 603042.0, 604580.0, 610506.0, 614918.2540872939, 621050.0, 46837.82905427391, 47682.0, 47865.76067119712, 54226.64433389197, 55241.1713824293, 1238.349866320171, 1255.5229012975194, 1258.3959699344366, 1315.8289422375221, 1349.2511656030997, 28950.84196632111, 29191.124266272047, 29498.410031798816, 30034.098977030724, 30077.548836767903, 45619.54380456153, 46181.30728039149, 46205.76897115493, 46225.0, 46281.84295392642, 414277.0, 419425.0, 419786.0, 420840.89579551906, 421159.0, 24871.0, 25145.748542514342, 25853.09514166995, 25960.0, 26584.468310633292, 43614.34578481262, 43697.56302436935, 44078.779606101765, 44285.89285267894, 44876.55123448724, 30317.69682303149, 35830.46254306239, 36045.45931810972, 36846.078848027944, 37052.44421333736, 35397.46903796497, 35920.64079359173, 36792.44811327886, 36798.63201367952, 37010.8149459251, 35157.47263790994, 35574.64425355817, 35852.40050239041, 36255.64932208152, 36300.81849590841, 31060.365053485362, 31376.73745414673, 32398.676013240507, 32737.0, 32831.50752738663, 24027.879860600584, 24153.51692966166, 24581.63127553124, 24848.881800318668, 24913.003479861327], "value": 19034.723275680444, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.57055107493306, 369.42738754929854, 372.91422972716276, 380.3477036881748, 400.2115831811332, 14529.927350363247, 14791.26043697815, 15226.766833611566, 19785.901070494663, 19901.701474477893, 18898.0, 19374.903125484387, 20011.999400030014, 20067.0, 20164.092615832287, 5289.0, 5781.913271300934, 6078.331383547807, 6081.391860813906, 6083.939160608382, 4362.8909277268, 6504.772332968347, 6585.9012114818315, 6617.106690596756, 6626.5030122740745, 6275.811725648238, 6455.064015717718, 6547.967260163692, 6571.704273307689, 6573.967130164367, 6491.805245842609, 6527.4778017758945, 6537.803865884007, 6550.7707230246715, 6554.016897465371, 4919.680220785637, 5181.663191892515, 5233.502817232356, 5239.659422137549, 5664.773409063674, 23542.882285588632, 23679.382520408526, 23968.28095157148, 24019.0, 24495.0, 2162.8702277863376, 2193.824494040489, 2254.830887683414, 2257.7742225777456, 2265.694131292271, 8248.298894593967, 9986.440137193915, 10135.594576216943, 13310.401031953585, 13485.865141348657, 1593.6573636668056, 1747.309812624019, 1748.6677531269145, 1844.0, 1844.2438600173864, 105986.47006764993, 114722.0, 114724.42637786841, 116067.41966290199, 117072.41463792711, 10965.122790176767, 11086.279391839505, 11161.553537858395, 11181.273217240854, 11454.026407755267, 14174.929125354307, 14208.715825683428, 14240.572782816533, 14250.429982800779, 14261.64345891361, 6487.416132548094, 7453.590052547162, 7508.587027713528, 8085.878711819328, 8143.226393492609, 7709.882067100239, 7764.029496312961, 7778.688852445839, 7877.212278772192, 8014.278714915569, 4387.780610969503, 4389.297712366006, 4412.205802955435, 4413.558644135561, 4492.73043617384, 1701.825740239231, 1789.1322708486373, 1821.9817801821812, 1863.8351030605872, 1890.1305399516377, 585.2450339062647, 708.2492557888654, 760.9315161635479, 807.6859796682462, 889.088684098791, 17926.193321300572, 19096.52258693516, 20018.0, 20122.597548048958, 20289.289874854603, 803.4135081390617, 807.4267270238079, 824.5547404401556, 825.405707890318, 900.9054049324858, 8727.956360218286, 10685.0, 10716.196285278635, 10725.30285531438, 10738.355698658106, 414626.0, 418426.0, 420135.8993205014, 487537.0, 527850.0, 76275.8558621621, 92443.0, 104100.0, 120026.0, 141826.290868545, 901.6979311930577, 956.4357029352715, 1000.2498126405196, 1000.8949060348558, 1001.7345403468087, 18165.727514087033, 18495.63008739791, 18628.06859657039, 18640.0, 19149.617007660047, 42533.25801016823, 51714.741426292625, 52422.0, 54181.45818541764, 55856.162157568484, 57483.27550173334, 75724.62137689277, 75740.0, 77685.61157194177, 77879.61060194662, 7759.68961241544, 7789.64946577394, 7952.204779522001, 8010.439269251096, 8214.424990250625, 10048.191325561176, 11665.00847427978, 12307.815382769444, 12483.75032499363, 12616.369181540702, 10695.893041069801, 10700.946495267473, 10871.402072886067, 10911.508982095984, 11073.50169242402, 10443.007914248134, 10482.266241363337, 10627.574897004033, 10827.350358978172, 10935.45322733876, 9662.9033709662, 10861.891381086089, 11116.722081948015, 11333.773324533298, 11337.886621134014, 6834.897476537756, 7235.493515453868, 7253.1296244451005, 7482.0, 7834.412419068479, 9667.661631842851, 10907.400092994643, 11187.888121118685, 11384.089272858317, 11399.943000284946], "value": 9106.715379530231, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.6448370149315, 1470.1179292424547, 1633.8121116071654, 1806.7741532308462, 1836.348096425768, 7838.451308408414, 8198.795030124249, 8201.425900186989, 8227.465214761036, 8253.83492330153, 7291.92180228635, 7525.4732168748205, 7531.585762783045, 7848.882266766003, 8047.637856296466, 2249.3926639807282, 2292.9770702293013, 2294.621387471065, 2298.3564601911407, 2301.6432452969734, 2275.6927814745045, 2298.4139044543704, 2309.0648287443614, 2312.629979203328, 2317.629179331307, 2258.0516183203013, 2258.706368172135, 2279.90880364786, 2282.7260728712577, 2290.0839664134314, 2244.8540844845033, 2246.3148739634403, 2273.499830037389, 2273.8863056847176, 2277.7722227777253, 2271.2164303315494, 2288.8855557222073, 2290.129750694742, 2293.2203050962753, 2293.6559516072557, 8033.0360356757565, 8055.63749631268, 8129.4715843469985, 8140.837183256361, 8169.714060007931, 4906.1414252505665, 5068.898622027539, 5070.188769796851, 5070.949290507121, 5073.416557095944, 3131.8434078295995, 3226.322472280824, 3445.4831775233915, 3459.377312083824, 3461.44616861301, 1031.9071283584512, 1044.3211912257018, 1060.7454210989383, 1072.8176210044312, 1072.9570817167305, 10439.37363758177, 10459.738506537398, 10600.840987385196, 10620.681379558626, 10625.256232063759, 4358.259095953696, 4513.277875539963, 4599.402077729874, 4633.4208223972, 4637.026224492827, 9179.036201198778, 9180.8622870657, 9186.724398268063, 9187.724368268962, 9203.631854725736, 4593.517680643551, 4945.060438516671, 5104.3619547556555, 5109.284700141983, 5110.48895110486, 4201.432806571135, 4361.280388735823, 4371.191329604034, 4648.65135114861, 4672.159011377917, 3613.2412193439145, 3665.9266814663556, 3670.2292518570866, 3777.223740550693, 3795.6204379561823, 1219.8353222314875, 1231.384307846077, 1236.1841184817977, 1246.1681328447169, 1247.6319485751726, 832.883396324515, 848.1391387741447, 885.9202671759573, 891.1801142948503, 924.8520236762085, 5451.400345961941, 5587.0, 5674.404187560329, 5769.365369809318, 5849.122631605208, 911.876896618948, 919.7608621758258, 931.9207867331353, 938.2634631813993, 983.8868530118984, 3775.9433608495897, 3787.147891724395, 3791.658750712449, 3897.2205558888336, 3938.3501722215515, 9428.62285508572, 9849.015098490094, 9857.0, 9904.108630223314, 9923.65267215644, 9764.0, 9812.313138080408, 9824.705258842247, 9829.459379734184, 9835.16401105914, 6052.727627256784, 7568.318851303299, 7574.242575742492, 7699.384049276045, 7718.421118416029, 5533.833984980457, 5707.971460142673, 5742.397048309867, 5755.079187330007, 5826.533877289722, 8823.470591764515, 8888.288936885163, 8964.3276754245, 8976.461412315282, 9061.365704400629, 9180.403273787182, 9199.494027828534, 9235.26117910579, 9237.861432078389, 9269.814603707753, 6493.610383376824, 6715.664216789043, 6719.596824190564, 6884.793456196322, 7087.043249161297, 7157.57054576727, 7285.344319011315, 7375.446841486802, 7718.151003389735, 7757.650905709143, 6864.897026544706, 6961.37347638715, 7006.544574602634, 7009.088818453773, 7138.535995160298, 6022.66875321844, 6808.319168083152, 6861.382475577222, 6897.068895699016, 6929.7228110875, 6433.228012638513, 6455.064015717789, 6528.118703974903, 6565.376289252705, 6579.374959378852, 4667.673262871566, 4734.384530011076, 4769.76151192432, 4773.880652983703, 4887.826921538876, 4029.879103626896, 4846.079244943449, 4853.587445067209, 4892.461829198714, 4894.583960363267], "value": 4332.975428438936, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.4011976047904, 300.74887468963414, 307.40670505923566, 311.49071268476047, 314.2614855090536, 5255.527002569773, 5320.521153096221, 5358.383785864627, 5360.4639536046425, 5405.918911216335, 4093.8976525586877, 4138.2412474698285, 5144.691318520891, 5159.658488792913, 5220.321358223426, 3567.4470457079065, 3973.0663294125857, 4026.939595906064, 4033.919321613566, 4059.411385349137, 3383.374075795986, 3844.596317386676, 4009.278329900617, 4049.5950404959417, 4070.8982275443204, 3113.9603559668453, 3670.054870918404, 4020.778857162855, 4021.9798901005456, 4030.9596904030877, 4006.659433948118, 4027.9194416111513, 4030.8186131624147, 4043.777592232426, 4063.9883257787287, 3350.6984371406447, 3510.6313837047255, 3517.384457719889, 3525.453554699026, 3552.1119720069983, 4525.049739554697, 5132.332796736438, 5135.483613029626, 5145.845624631267, 5179.223116532513, 3960.7029472789654, 4437.755923424211, 4558.7264764113925, 4559.772011399416, 4588.197065513556, 4832.299316599074, 5533.944660553423, 5537.307836520435, 5543.251661025791, 5567.49892509672, 3395.589131716484, 3533.922153743107, 3858.6913046956174, 3865.7100717446024, 3889.3193691103943, 5530.861728456781, 5790.536757059425, 5830.300363956352, 5858.769658371704, 5874.7943821966455, 3913.9217215655526, 3930.292547341506, 3932.5477570079233, 3936.4254298280916, 3942.0, 3381.8309084545676, 3770.7171962102952, 3806.314863324573, 3979.3235150024566, 3980.800959951991, 4237.915241695149, 4511.187986162457, 4572.611328037154, 4604.263317869125, 4624.445066591963, 5456.836294911159, 5558.555315574744, 5595.720213989365, 5644.096944488862, 5662.5753068520025, 1136.579465597734, 1152.5505053029326, 1194.689380760991, 1249.7315225046584, 1276.334383629354, 730.6346826586706, 773.1958762886535, 834.9457285276437, 859.7162936231028, 874.3267683883374, 4657.697249678828, 5021.648484606042, 5260.28986086881, 5272.92090618641, 5296.655717378358, 4917.795140190676, 5761.337446193657, 5768.625039372428, 5777.220075289866, 5780.71096445176, 4845.854624361275, 4868.610511159099, 4996.227847974756, 5046.7476626168545, 5214.13966695491, 2760.2823265950997, 2835.447087817856, 2841.3606938438684, 2860.0, 2891.6096326996008, 4889.926651100236, 5256.26412302278, 5532.1425179097305, 5617.494425501724, 5638.633488823213, 5014.423341315723, 5418.211681556814, 5495.472082841858, 5531.3362396512675, 5708.686022268815, 5080.187170052774, 5224.52979231871, 5644.5484361251, 5684.8294551163535, 5696.686682232351, 4734.656345109193, 5080.872978175576, 5144.922826157685, 5310.495502927216, 5531.778728850801, 4809.350737650372, 5316.946830531645, 5326.455327955005, 5359.785608575613, 5423.29497165366, 4896.975515122402, 5158.0199762045095, 5623.044082505902, 5659.801906933238, 5696.316442026818, 3125.984370078226, 3270.918227044343, 3629.3830048891223, 3643.5809881863925, 3685.226102518501, 5332.626716129834, 5601.499063085572, 5816.854578635568, 5859.707014649336, 5860.238169037998, 5681.0, 5682.715864206856, 5683.460071293221, 5697.800576979786, 5723.370429252862, 4768.546988036131, 5285.947140528546, 5525.502704756591, 5681.829545113653, 5727.8568035799435, 5336.573074154136, 5409.513143817076, 5478.479544443271, 5481.808136715196, 5495.203195536705, 4762.738049407316, 4908.601048701245, 5561.304836895388, 5569.359523654832, 5582.665040097607, 5469.781208751605, 5519.558435325084, 5519.806806761744, 5536.363318218457, 5572.749226284908], "value": 4015.7645139191686, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.8367375503638, 713.8322494213862, 745.1133151549656, 761.3604572159387, 779.3531368963759, 14714.852851471482, 14981.400743970242, 15275.694486110271, 15286.84713152868, 15307.846921530781, 14003.509877154302, 14165.645858853508, 14171.716565668707, 14174.645633859132, 14176.149431034146, 663.9634820084917, 693.1128155960379, 695.5305169010923, 783.8158032862273, 816.3713940265992, 831.3598529132564, 834.511810590802, 843.0010437631427, 843.0220943705303, 845.0, 893.8033632600817, 894.9642014319452, 896.0233345653237, 896.0367604824806, 897.161154320709, 689.2246222999127, 718.8167017410527, 892.7455675132557, 893.5621545442687, 896.4173287363234, 537.0252990821646, 537.2183473046707, 537.2585831552472, 628.9654069026202, 657.7895073576458, 14443.638909027253, 14507.274636268145, 14510.637234069127, 14514.927425362912, 14519.0, 13019.674508137277, 13073.443572505515, 13274.734505309936, 13298.069135160542, 13409.66475838112, 10060.94969525155, 11248.391632015322, 11474.598389056342, 11936.343501107436, 12168.209066410654, 4284.378765079077, 4287.249731297042, 4304.461942257218, 4381.649468042581, 4429.667774916862, 108082.3787643186, 108918.0, 111034.12419888638, 111122.0, 111838.99999999919, 10537.262391632512, 10594.523246453928, 10745.247832651796, 10753.301035432829, 10891.727706807393, 20790.960451977342, 20814.583708326052, 20819.791802082087, 20837.89581052085, 20848.061837217363, 7272.454565907578, 7318.597477138808, 7351.411887049023, 7402.9629851850395, 7438.66526006331, 4145.357469592218, 4187.036981494212, 4212.30496968003, 4218.501311463996, 4230.809613567397, 3282.3271229397915, 3406.0803583032443, 3416.538767266437, 3434.9484757728655, 3453.3783918894337, 608.5527137553894, 610.7068607068543, 610.7831719739429, 621.0559948877681, 627.1783963008473, 515.3120584020375, 538.9164679474687, 581.4795757796749, 604.2778879239266, 643.6009674002148, 8130.3495720342235, 8461.280791132822, 8717.564121794012, 8886.555672216364, 8898.911010889808, 539.9892002159935, 543.5506384993653, 549.3380476525776, 603.4177019176516, 617.2438762515972, 3599.2441587266967, 4164.979580002919, 4256.702030857872, 4331.112122014979, 4365.432493775789, 107987.46006270076, 109604.0, 110667.0, 115386.0, 117820.0, 96026.51986740022, 96259.03740962641, 97680.51159744155, 98020.0, 99063.0, 58789.0, 84865.72701409484, 85037.57481212678, 85591.0, 87729.0, 7269.60017199038, 7404.851902962018, 8290.212429819157, 8308.584570771558, 8626.353023523387, 34670.65329346674, 47614.523854761006, 54063.918721626134, 57869.0, 59587.0, 73889.0, 78820.0, 80495.0, 82265.58867205626, 82414.58792705998, 10939.288946218789, 11087.778244434905, 11132.721681957692, 11560.94219528897, 11620.186586939171, 11896.643100706993, 11911.702207444883, 11914.76170476603, 12086.0, 12118.757624847278, 11585.768284634092, 11601.535938562363, 11650.359230242419, 11708.063354931755, 11830.349330786888, 9878.061584149495, 11459.942700286445, 11576.88423115758, 11599.942000289944, 11824.290542567473, 11346.94326528362, 11497.885021149681, 11541.076713863038, 11626.5349386027, 12059.095567832272, 9322.300827437834, 9738.220942324739, 9854.950725246328, 9894.653687120915, 9899.109080182818, 7872.889061126007, 9196.494192819458, 9562.0, 9658.3722058066, 9703.951480242553], "value": 7073.384716554477, "unit": "score"}], "name": "dromaeo_css", "value": 7010.67770610152, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 114.0, 113.0, 112.0, 112.0, 114.0, 111.0, 115.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 114.0, 115.0, 154.0, 115.0, 115.0, 119.0, 140.0, 159.0, 124.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 183.0, 187.0, 183.0, 181.0, 181.0, 183.0, 184.0, 185.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 59.0, 87.0, 61.0, 58.0, 59.0, 58.0, 73.0, 59.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 85.0, 70.0, 69.0, 70.0, 81.0, 67.0, 70.0, 84.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 86.0, 87.0, 85.0, 89.0, 86.0, 87.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 97.0, 97.0, 98.0, 108.0, 156.0, 105.0, 98.0, 97.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 86.0, 121.0, 138.0, 88.0, 85.0, 88.0, 87.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 54.0, 55.0, 54.0, 56.0, 58.0, 55.0, 54.0, 55.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 80.0, 75.0, 73.0, 69.0, 70.0, 71.0, 71.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 87.0, 88.0, 92.0, 87.0, 87.0, 88.0, 88.0, 86.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 157.0, 158.0, 154.0, 174.0, 157.0, 152.0, 196.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 172.0, 175.0, 174.0, 172.0, 172.0, 171.0, 174.0, 181.0, 210.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 70.0, 68.0, 66.0, 66.0, 70.0, 69.0, 69.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1475.7999999999997, "alertThreshold": 2.0}]} 20:16:31 INFO - Return code: 0 20:16:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:16:31 INFO - # TBPL SUCCESS # 20:16:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:16:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:16:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:16:31 INFO - 2016-11-30 20:16:31.586 screenresolution[1652:9631] starting screenresolution argv=screenresolution get 20:16:31 INFO - 2016-11-30 20:16:31.588 screenresolution[1652:9631] Display 0: 1600x1200x32@60 20:16:31 INFO - 2016-11-30 20:16:31.594 screenresolution[1653:9634] starting screenresolution argv=screenresolution list 20:16:31 INFO - Available Modes on Display 0 20:16:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:16:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:16:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:16:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:16:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:16:31 INFO - Intel Iris: 20:16:31 INFO - Chipset Model: Intel Iris 20:16:31 INFO - Type: GPU 20:16:31 INFO - Bus: Built-In 20:16:31 INFO - VRAM (Dynamic, Max): 1536 MB 20:16:31 INFO - Vendor: Intel (0x8086) 20:16:31 INFO - Device ID: 0x0a2e 20:16:31 INFO - Revision ID: 0x0009 20:16:31 INFO - Displays: 20:16:31 INFO - Display: 20:16:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:16:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:16:31 INFO - Display Serial Number: 1600x1200 60 20:16:31 INFO - Main Display: Yes 20:16:31 INFO - Mirror: Off 20:16:31 INFO - Online: Yes 20:16:31 INFO - Rotation: Supported 20:16:31 INFO - Return code: 0 20:16:31 INFO - Running post-action listener: _resource_record_post_action 20:16:31 INFO - [mozharness: 2016-12-01 04:16:31.846400Z] Finished run-tests step (success) 20:16:31 INFO - Running post-run listener: _resource_record_post_run 20:16:31 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 37626880; Write bytes: 693450752; Read time: 242; Write time: 1970 20:16:31 INFO - TinderboxPrint: CPU usage
53.8% 20:16:31 INFO - TinderboxPrint: I/O read bytes / time
37,626,880 / 242 20:16:31 INFO - TinderboxPrint: I/O write bytes / time
693,450,752 / 1,970 20:16:31 INFO - TinderboxPrint: CPU idle
2,246.4 (46.2%) 20:16:31 INFO - TinderboxPrint: CPU system
972.5 (20.0%) 20:16:31 INFO - TinderboxPrint: CPU user
1,638.8 (33.7%) 20:16:31 INFO - TinderboxPrint: Swap in / out
421,220,352 / 0 20:16:31 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 366778880; Write bytes: 367658496; Read time: 27652; Write time: 776 20:16:31 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 36660224; Write bytes: 307245568; Read time: 227; Write time: 1147 20:16:32 INFO - Running post-run listener: _upload_blobber_files 20:16:32 INFO - Blob upload gear active. 20:16:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:16:32 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 20:16:32 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:16:32 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:16:32 INFO - (blobuploader) - INFO - Open directory for files ... 20:16:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:16:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:16:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:33 INFO - (blobuploader) - INFO - Done attempting. 20:16:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:16:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:16:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:35 INFO - (blobuploader) - INFO - Done attempting. 20:16:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:16:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:16:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:37 INFO - (blobuploader) - INFO - Done attempting. 20:16:37 INFO - (blobuploader) - INFO - Iteration through files over. 20:16:37 INFO - Return code: 0 20:16:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:16:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:16:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7bca332da4bfe57d27f58cb2e54d61886dcc5db07d182de92e59b62de6fbc4afeb17f9d2b8bfff59104fe1e852b2c4570b97ff90a488dc660a31b34db63c9011", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8d9ab9c4619f52dc0ee248201618bbbd684483368055eafa524a8c0f46562841462e3fe33f798a4a59c1cc38e4a7dd834e238a8f2acba524645645a2eb120acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a393b2459fa2b26623ac7b1c18bae7b736877ac8b524d5499f9f54ec278e4311118a42795ba2e7c74cb277c207dc18526041feaef24315602b85ef83d037a103"} 20:16:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:16:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:16:37 INFO - Contents: 20:16:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7bca332da4bfe57d27f58cb2e54d61886dcc5db07d182de92e59b62de6fbc4afeb17f9d2b8bfff59104fe1e852b2c4570b97ff90a488dc660a31b34db63c9011", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8d9ab9c4619f52dc0ee248201618bbbd684483368055eafa524a8c0f46562841462e3fe33f798a4a59c1cc38e4a7dd834e238a8f2acba524645645a2eb120acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a393b2459fa2b26623ac7b1c18bae7b736877ac8b524d5499f9f54ec278e4311118a42795ba2e7c74cb277c207dc18526041feaef24315602b85ef83d037a103"} 20:16:37 INFO - Running post-run listener: copy_logs_to_upload_dir 20:16:37 INFO - Copying logs to upload dir... 20:16:37 INFO - mkdir: /builds/slave/test/build/upload/logs 20:16:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.155475 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-30 20:16:37.750257) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 20:16:37.753889) ========= 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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7bca332da4bfe57d27f58cb2e54d61886dcc5db07d182de92e59b62de6fbc4afeb17f9d2b8bfff59104fe1e852b2c4570b97ff90a488dc660a31b34db63c9011", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8d9ab9c4619f52dc0ee248201618bbbd684483368055eafa524a8c0f46562841462e3fe33f798a4a59c1cc38e4a7dd834e238a8f2acba524645645a2eb120acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a393b2459fa2b26623ac7b1c18bae7b736877ac8b524d5499f9f54ec278e4311118a42795ba2e7c74cb277c207dc18526041feaef24315602b85ef83d037a103"} build_url:https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/UuTWH-KKQueDGacjwhipJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7bca332da4bfe57d27f58cb2e54d61886dcc5db07d182de92e59b62de6fbc4afeb17f9d2b8bfff59104fe1e852b2c4570b97ff90a488dc660a31b34db63c9011", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8d9ab9c4619f52dc0ee248201618bbbd684483368055eafa524a8c0f46562841462e3fe33f798a4a59c1cc38e4a7dd834e238a8f2acba524645645a2eb120acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a393b2459fa2b26623ac7b1c18bae7b736877ac8b524d5499f9f54ec278e4311118a42795ba2e7c74cb277c207dc18526041feaef24315602b85ef83d037a103"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 20:16:37.789519) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 20:16:37.789941) ========= 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.kzVKW0sVgv/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.Cbwcc9kWvn/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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.004925 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 20:16:37.839099) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 20:16:37.839483) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 20:16:37.839901) ========= ========= Total master_lag: 0.19 =========