builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0073 starttime: 1481716453.81 results: success (0) buildid: 20161214004021 builduid: 9fe8d74653e1433e8d233167a04cf4c1 revision: c7f071262fbc3862dd41c8bc7d89999e2926126b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:13.807064) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:13.807554) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:13.807837) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:13.859534) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:13.859914) ========= 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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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-12-14 03:54:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 149M=0s 2016-12-14 03:54:13 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078464 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:13.961966) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:13.962594) ========= 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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.020125 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:14.013276) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-14 03:54:14.013641) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c7f071262fbc3862dd41c8bc7d89999e2926126b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c7f071262fbc3862dd41c8bc7d89999e2926126b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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-12-14 03:54:14,092 truncating revision to first 12 chars 2016-12-14 03:54:14,092 Setting DEBUG logging. 2016-12-14 03:54:14,092 attempt 1/10 2016-12-14 03:54:14,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c7f071262fbc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 03:54:16,205 unpacking tar archive at: mozilla-aurora-c7f071262fbc/testing/mozharness/ program finished with exit code 0 elapsedTime=2.387183 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-14 03:54:16.423272) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:16.423613) ========= script_repo_revision: c7f071262fbc3862dd41c8bc7d89999e2926126b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:16.424033) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:16.424307) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:16.445302) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-14 03:54:16.445979) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:54:16 INFO - MultiFileLogger online at 20161214 03:54:16 in /builds/slave/test 03:54:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:54:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:54:16 INFO - {'append_to_log': False, 03:54:16 INFO - 'base_work_dir': '/builds/slave/test', 03:54:16 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:54:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:54:16 INFO - 'branch': 'Mozilla-Aurora', 03:54:16 INFO - 'buildbot_json_path': 'buildprops.json', 03:54:16 INFO - 'config_files': ('talos/mac_config.py',), 03:54:16 INFO - 'default_actions': ('clobber', 03:54:16 INFO - 'read-buildbot-config', 03:54:16 INFO - 'download-and-extract', 03:54:16 INFO - 'populate-webroot', 03:54:16 INFO - 'create-virtualenv', 03:54:16 INFO - 'install', 03:54:16 INFO - 'run-tests'), 03:54:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:54:16 INFO - 'download_minidump_stackwalk': True, 03:54:16 INFO - 'download_symbols': 'ondemand', 03:54:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:54:16 INFO - 'tooltool.py': '/tools/tooltool.py', 03:54:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:54:16 INFO - '/tools/misc-python/virtualenv.py')}, 03:54:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:54:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:54:16 INFO - 'installer_path': 'installer.exe', 03:54:16 INFO - 'log_level': 'info', 03:54:16 INFO - 'log_name': 'talos', 03:54:16 INFO - 'log_to_console': True, 03:54:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:54:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:54:16 INFO - 'opt_config_files': (), 03:54:16 INFO - 'pip_index': False, 03:54:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:54:16 INFO - 'cmd': ('bash', 03:54:16 INFO - '-c', 03:54:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:54:16 INFO - 'enabled': True, 03:54:16 INFO - 'halt_on_failure': False, 03:54:16 INFO - 'name': 'check_screen_resolution'},), 03:54:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:54:16 INFO - 'cmd': ('bash', 03:54:16 INFO - '-c', 03:54:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:54:16 INFO - 'enabled': True, 03:54:16 INFO - 'halt_on_failure': False, 03:54:16 INFO - 'name': 'check_screen_resolution'},), 03:54:16 INFO - 'run_cmd_checks_enabled': True, 03:54:16 INFO - 'sps_profile': False, 03:54:16 INFO - 'sps_profile_interval': 0, 03:54:16 INFO - 'suite': 'dromaeojs', 03:54:16 INFO - 'system_bits': '32', 03:54:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:54:16 INFO - 'title': 't-yosemite-r7-0073', 03:54:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:54:16 INFO - 'use_talos_json': True, 03:54:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:54:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:54:16 INFO - 'work_dir': 'build'} 03:54:16 INFO - [mozharness: 2016-12-14 11:54:16.656217Z] Running clobber step. 03:54:16 INFO - Running pre-action listener: _resource_record_pre_action 03:54:16 INFO - Running main action method: clobber 03:54:16 INFO - rmtree: /builds/slave/test/build 03:54:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:54:17 INFO - Running post-action listener: _resource_record_post_action 03:54:17 INFO - [mozharness: 2016-12-14 11:54:17.604054Z] Finished clobber step (success) 03:54:17 INFO - [mozharness: 2016-12-14 11:54:17.604231Z] Running read-buildbot-config step. 03:54:17 INFO - Running pre-action listener: _resource_record_pre_action 03:54:17 INFO - Running main action method: read_buildbot_config 03:54:17 INFO - Using buildbot properties: 03:54:17 INFO - { 03:54:17 INFO - "project": "", 03:54:17 INFO - "product": "firefox", 03:54:17 INFO - "script_repo_revision": "production", 03:54:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:54:17 INFO - "repository": "", 03:54:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:54:17 INFO - "buildid": "20161214004021", 03:54:17 INFO - "stage_platform": "macosx64", 03:54:17 INFO - "basedir": "/builds/slave/test", 03:54:17 INFO - "nightly_build": "True", 03:54:17 INFO - "buildnumber": 23, 03:54:17 INFO - "slavename": "t-yosemite-r7-0073", 03:54:17 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 03:54:17 INFO - "platform": "yosemite_r7", 03:54:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:54:17 INFO - "branch": "mozilla-aurora", 03:54:17 INFO - "repo_path": "releases/mozilla-aurora", 03:54:17 INFO - "revision": "c7f071262fbc3862dd41c8bc7d89999e2926126b", 03:54:17 INFO - "pgo_build": "False", 03:54:17 INFO - "builduid": "9fe8d74653e1433e8d233167a04cf4c1", 03:54:17 INFO - "slavebuilddir": "test" 03:54:17 INFO - } 03:54:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:54:17 INFO - Running post-action listener: _resource_record_post_action 03:54:17 INFO - [mozharness: 2016-12-14 11:54:17.607706Z] Finished read-buildbot-config step (success) 03:54:17 INFO - [mozharness: 2016-12-14 11:54:17.607837Z] Running download-and-extract step. 03:54:17 INFO - Running pre-action listener: _resource_record_pre_action 03:54:17 INFO - Running main action method: download_and_extract 03:54:17 INFO - mkdir: /builds/slave/test/build/tests 03:54:17 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')]} 03:54:17 INFO - trying https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:54:17 INFO - Downloading https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:54:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 03:54:19 INFO - Downloaded 1199 bytes. 03:54:19 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:54:19 INFO - Using the following test package requirements: 03:54:19 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:54:19 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:19 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:54:19 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:19 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:54:19 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:19 INFO - u'jsshell-mac.zip'], 03:54:19 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:19 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:54:19 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:54:19 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:19 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:54:19 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:19 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:54:19 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:19 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:54:19 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:19 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:54:19 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:54:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:54:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:54:19 INFO - Fetch https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:54:19 INFO - Http code: 200 03:54:19 INFO - via: 1.1 11512f00e812993b9daa35c376d7cb20.cloudfront.net (CloudFront) 03:54:19 INFO - x-amz-cf-id: -0PiHp-wYxyyLn7PLDpphIFfbc9-Ic_lVjGqEfeaLA-RyHv7yiMslA== 03:54:19 INFO - x-amz-version-id: jgRemnb9GCrPDaNzlYy1Pza9zt3trXi8 03:54:19 INFO - Expected file size: 16281142 03:54:19 INFO - Obtained file size: 16281142 03:54:26 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:54:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:54:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:54:26 INFO - Fetch https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:54:27 INFO - Http code: 200 03:54:27 INFO - via: 1.1 2239f0bfe6d7427183a4e375c4638619.cloudfront.net (CloudFront) 03:54:27 INFO - x-amz-cf-id: qC-RPVbCrvn-Ca-WMlzjH2nLIdpuzkw_l1J2prBHyVS2wrx5pUWE6w== 03:54:27 INFO - x-amz-version-id: jgRemnb9GCrPDaNzlYy1Pza9zt3trXi8 03:54:28 INFO - Expected file size: 16281142 03:54:28 INFO - Obtained file size: 16281142 03:54:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:54:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:54:34 INFO - Fetch https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:54:35 INFO - Http code: 200 03:54:35 INFO - via: 1.1 0576b942ae9f4fc9c0b62b0736e9bfd6.cloudfront.net (CloudFront) 03:54:35 INFO - x-amz-cf-id: aDAJXQnNUTwlbxx15gZke28b6CQL43nbdjluE5gG2bTKG_687L-xYQ== 03:54:35 INFO - x-amz-version-id: FSC_shg6F92Ud8C4p5PwLhNIt66Ky_In 03:54:35 INFO - Expected file size: 14409038 03:54:35 INFO - Obtained file size: 14409038 03:54:36 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')]} 03:54:36 INFO - trying https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:54:36 INFO - Downloading https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:54:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 03:54:38 INFO - Downloaded 109850234 bytes. 03:54:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:54:38 INFO - mkdir: /builds/slave/test/properties 03:54:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:54:38 INFO - Writing to file /builds/slave/test/properties/build_url 03:54:38 INFO - Contents: 03:54:38 INFO - build_url:https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:54:39 INFO - Running post-action listener: _resource_record_post_action 03:54:39 INFO - Running post-action listener: set_extra_try_arguments 03:54:39 INFO - [mozharness: 2016-12-14 11:54:39.603876Z] Finished download-and-extract step (success) 03:54:39 INFO - [mozharness: 2016-12-14 11:54:39.604008Z] Running populate-webroot step. 03:54:39 INFO - Running pre-action listener: _resource_record_pre_action 03:54:39 INFO - Running main action method: populate_webroot 03:54:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:54:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:54:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:54:39 INFO - '--tppagecycles', 03:54:39 INFO - '7'], 03:54:39 INFO - 'tests': ['tsvgm']}}, 03:54:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:54:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:54:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:54:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:54:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:54:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:54:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:39 INFO - 'talos_options': ['--disable-e10s'], 03:54:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:54:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:54:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:39 INFO - 'talos_options': ['--disable-e10s'], 03:54:39 INFO - 'tests': ['damp', 'tps']}, 03:54:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:39 INFO - 'tests': ['damp', 'tps']}, 03:54:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:54:39 INFO - 'tests': ['dromaeo_dom']}, 03:54:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:54:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:54:39 INFO - 'tests': ['basic_compositor_video']}, 03:54:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:54:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:54:39 INFO - 'tests': ['a11yr', 03:54:39 INFO - 'ts_paint', 03:54:39 INFO - 'tpaint', 03:54:39 INFO - 'sessionrestore', 03:54:39 INFO - 'sessionrestore_no_auto_restore', 03:54:39 INFO - 'tabpaint']}, 03:54:39 INFO - 'other-e10s': {'tests': ['a11yr', 03:54:39 INFO - 'ts_paint', 03:54:39 INFO - 'tpaint', 03:54:39 INFO - 'sessionrestore', 03:54:39 INFO - 'sessionrestore_no_auto_restore', 03:54:39 INFO - 'tabpaint']}, 03:54:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:54:39 INFO - 'ts_paint', 03:54:39 INFO - 'tpaint', 03:54:39 INFO - 'sessionrestore', 03:54:39 INFO - 'sessionrestore_no_auto_restore', 03:54:39 INFO - 'tabpaint']}, 03:54:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:54:39 INFO - 'ts_paint', 03:54:39 INFO - 'tpaint', 03:54:39 INFO - 'sessionrestore', 03:54:39 INFO - 'sessionrestore_no_auto_restore', 03:54:39 INFO - 'tabpaint']}, 03:54:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:54:39 INFO - 'tests': ['a11yr', 03:54:39 INFO - 'ts_paint', 03:54:39 INFO - 'tpaint', 03:54:39 INFO - 'sessionrestore', 03:54:39 INFO - 'sessionrestore_no_auto_restore', 03:54:39 INFO - 'tabpaint']}, 03:54:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:54:39 INFO - 'tests': ['a11yr', 03:54:39 INFO - 'ts_paint', 03:54:39 INFO - 'tpaint', 03:54:39 INFO - 'sessionrestore', 03:54:39 INFO - 'sessionrestore_no_auto_restore', 03:54:39 INFO - 'tabpaint']}, 03:54:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:54:39 INFO - 'tests': ['tsvgx', 03:54:39 INFO - 'tsvgr_opacity', 03:54:39 INFO - 'tart', 03:54:39 INFO - 'tscrollx', 03:54:39 INFO - 'cart']}, 03:54:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:54:39 INFO - 'tsvgr_opacity', 03:54:39 INFO - 'tart', 03:54:39 INFO - 'tscrollx', 03:54:39 INFO - 'cart']}, 03:54:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:39 INFO - 'talos_options': ['--disable-e10s'], 03:54:39 INFO - 'tests': ['tp5o']}, 03:54:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:39 INFO - 'tests': ['tp5o']}, 03:54:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:39 INFO - 'talos_options': ['--disable-e10s', 03:54:39 INFO - '--xperf_path', 03:54:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:54:39 INFO - 'tests': ['tp5n']}, 03:54:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:39 INFO - 'talos_options': ['--xperf_path', 03:54:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:54:39 INFO - 'tests': ['tp5n']}}, 03:54:39 INFO - 'talos.zip': {'path': '', 03:54:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:54:39 INFO - Running post-action listener: _resource_record_post_action 03:54:39 INFO - [mozharness: 2016-12-14 11:54:39.621203Z] Finished populate-webroot step (success) 03:54:39 INFO - [mozharness: 2016-12-14 11:54:39.621354Z] Running create-virtualenv step. 03:54:39 INFO - Running pre-action listener: _resource_record_pre_action 03:54:39 INFO - Running main action method: create_virtualenv 03:54:39 INFO - Creating virtualenv /builds/slave/test/build/venv 03:54:39 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 03:54:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:54:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:54:39 INFO - Using real prefix '/tools/python27' 03:54:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:54:40 INFO - Installing distribute.............................................................................................................................................................................................done. 03:54:43 INFO - Installing pip.................done. 03:54:43 INFO - Return code: 0 03:54:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:54:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:54:43 INFO - Reading from file tmpfile_stdout 03:54:43 INFO - Output received: 03:54:43 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) 03:54:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:54:43 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')]} 03:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:43 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')]} 03:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:43 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 0x102be9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7db4ddc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029fdd30>, '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 0x102863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029f5b20>, '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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:54:43 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 03:54:43 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 03:54:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDGxHlChYQ/Render', 03:54:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:43 INFO - 'HOME': '/Users/cltbld', 03:54:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:43 INFO - 'LOGNAME': 'cltbld', 03:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:43 INFO - 'MOZ_NO_REMOTE': '1', 03:54:43 INFO - 'NO_EM_RESTART': '1', 03:54:43 INFO - 'PAGER': '/bin/cat', 03:54:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:43 INFO - 'PWD': '/builds/slave/test', 03:54:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:43 INFO - 'SHELL': '/bin/bash', 03:54:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2RrW8j3UL/Listeners', 03:54:43 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:54:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:43 INFO - 'USER': 'cltbld', 03:54:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:43 INFO - 'XPC_FLAGS': '0x0', 03:54:43 INFO - 'XPC_SERVICE_NAME': '0', 03:54:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:43 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 03:54:43 INFO - Cleaning up... 03:54:43 INFO - Return code: 0 03:54:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:54:43 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')]} 03:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:43 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')]} 03:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:43 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 0x102be9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7db4ddc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029fdd30>, '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 0x102863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029f5b20>, '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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:54:43 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 03:54:43 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 03:54:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDGxHlChYQ/Render', 03:54:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:43 INFO - 'HOME': '/Users/cltbld', 03:54:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:43 INFO - 'LOGNAME': 'cltbld', 03:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:43 INFO - 'MOZ_NO_REMOTE': '1', 03:54:43 INFO - 'NO_EM_RESTART': '1', 03:54:43 INFO - 'PAGER': '/bin/cat', 03:54:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:43 INFO - 'PWD': '/builds/slave/test', 03:54:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:43 INFO - 'SHELL': '/bin/bash', 03:54:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2RrW8j3UL/Listeners', 03:54:43 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:54:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:43 INFO - 'USER': 'cltbld', 03:54:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:43 INFO - 'XPC_FLAGS': '0x0', 03:54:43 INFO - 'XPC_SERVICE_NAME': '0', 03:54:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:44 INFO - Downloading/unpacking psutil>=3.1.1 03:54:44 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 03:54:44 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 03:54:44 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 03:54:44 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 03:54:44 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 03:54:44 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 03:54:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:54:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:54:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:54:47 INFO - Installing collected packages: psutil 03:54:47 INFO - Running setup.py install for psutil 03:54:47 INFO - building 'psutil._psutil_osx' extension 03:54:47 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 03:54:48 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 03:54:48 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 03:54:48 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 03:54:48 INFO - building 'psutil._psutil_posix' extension 03:54:48 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 03:54:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:54:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:54:48 INFO - ^ 03:54:48 INFO - 1 warning generated. 03:54:48 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 03:54:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:54:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:54:48 INFO - Successfully installed psutil 03:54:48 INFO - Cleaning up... 03:54:48 INFO - Return code: 0 03:54:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:54: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')]} 03:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54: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')]} 03:54:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102be9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7db4ddc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029fdd30>, '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 0x102863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029f5b20>, '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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:54: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:54: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 mozsystemmonitor==0.3 03:54:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDGxHlChYQ/Render', 03:54:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:48 INFO - 'HOME': '/Users/cltbld', 03:54:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:48 INFO - 'LOGNAME': 'cltbld', 03:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:48 INFO - 'MOZ_NO_REMOTE': '1', 03:54:48 INFO - 'NO_EM_RESTART': '1', 03:54:48 INFO - 'PAGER': '/bin/cat', 03:54:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:48 INFO - 'PWD': '/builds/slave/test', 03:54:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:48 INFO - 'SHELL': '/bin/bash', 03:54:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2RrW8j3UL/Listeners', 03:54:48 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:54:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:48 INFO - 'USER': 'cltbld', 03:54:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:48 INFO - 'XPC_FLAGS': '0x0', 03:54:48 INFO - 'XPC_SERVICE_NAME': '0', 03:54:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:54: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 03:54: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 03:54: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 03:54: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 03:54: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 03:54: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 03:54:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:54:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:54:51 INFO - Installing collected packages: mozsystemmonitor 03:54:51 INFO - Running setup.py install for mozsystemmonitor 03:54:51 INFO - Successfully installed mozsystemmonitor 03:54:51 INFO - Cleaning up... 03:54:51 INFO - Return code: 0 03:54:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:54:51 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')]} 03:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:51 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')]} 03:54:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:51 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 0x102be9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7db4ddc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029fdd30>, '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 0x102863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029f5b20>, '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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:54:51 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 03:54:51 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 03:54:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDGxHlChYQ/Render', 03:54:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:51 INFO - 'HOME': '/Users/cltbld', 03:54:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:51 INFO - 'LOGNAME': 'cltbld', 03:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:51 INFO - 'MOZ_NO_REMOTE': '1', 03:54:51 INFO - 'NO_EM_RESTART': '1', 03:54:51 INFO - 'PAGER': '/bin/cat', 03:54:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:51 INFO - 'PWD': '/builds/slave/test', 03:54:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:51 INFO - 'SHELL': '/bin/bash', 03:54:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2RrW8j3UL/Listeners', 03:54:51 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:54:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:51 INFO - 'USER': 'cltbld', 03:54:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:51 INFO - 'XPC_FLAGS': '0x0', 03:54:51 INFO - 'XPC_SERVICE_NAME': '0', 03:54:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:51 INFO - Downloading/unpacking jsonschema==2.5.1 03:54:51 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 03:54:51 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 03:54:51 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 03:54:51 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 03:54:51 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 03:54:51 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 03:54:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:54:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:54:54 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 03:54:54 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 03:54:54 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 03:54:54 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 03:54:54 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 03:54:54 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 03:54:54 INFO - Downloading functools32-3.2.3-2.tar.gz 03:54:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:54:54 INFO - warning: no files found matching '*.txt' 03:54:54 INFO - no previously-included directories found matching 'build' 03:54:54 INFO - no previously-included directories found matching 'dist' 03:54:54 INFO - no previously-included directories found matching '.git*' 03:54:54 INFO - Installing collected packages: jsonschema, functools32 03:54:54 INFO - Running setup.py install for functools32 03:54:55 INFO - warning: no files found matching '*.txt' 03:54:55 INFO - no previously-included directories found matching 'build' 03:54:55 INFO - no previously-included directories found matching 'dist' 03:54:55 INFO - no previously-included directories found matching '.git*' 03:54:55 INFO - Successfully installed jsonschema functools32 03:54:55 INFO - Cleaning up... 03:54:55 INFO - Return code: 0 03:54:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:54:55 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')]} 03:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:55 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')]} 03:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:55 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 0x102be9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7db4ddc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029fdd30>, '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 0x102863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029f5b20>, '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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:54:55 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 03:54:55 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 03:54:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDGxHlChYQ/Render', 03:54:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:55 INFO - 'HOME': '/Users/cltbld', 03:54:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:55 INFO - 'LOGNAME': 'cltbld', 03:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:55 INFO - 'MOZ_NO_REMOTE': '1', 03:54:55 INFO - 'NO_EM_RESTART': '1', 03:54:55 INFO - 'PAGER': '/bin/cat', 03:54:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:55 INFO - 'PWD': '/builds/slave/test', 03:54:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:55 INFO - 'SHELL': '/bin/bash', 03:54:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2RrW8j3UL/Listeners', 03:54:55 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:54:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:55 INFO - 'USER': 'cltbld', 03:54:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:55 INFO - 'XPC_FLAGS': '0x0', 03:54:55 INFO - 'XPC_SERVICE_NAME': '0', 03:54:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:54:55 INFO - Cleaning up... 03:54:55 INFO - Return code: 0 03:54:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:54:55 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')]} 03:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:55 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')]} 03:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:55 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 0x102be9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7db4ddc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029fdd30>, '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 0x102863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029f5b20>, '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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:54:55 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 03:54:55 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 03:54:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDGxHlChYQ/Render', 03:54:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:55 INFO - 'HOME': '/Users/cltbld', 03:54:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:55 INFO - 'LOGNAME': 'cltbld', 03:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:55 INFO - 'MOZ_NO_REMOTE': '1', 03:54:55 INFO - 'NO_EM_RESTART': '1', 03:54:55 INFO - 'PAGER': '/bin/cat', 03:54:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:55 INFO - 'PWD': '/builds/slave/test', 03:54:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:55 INFO - 'SHELL': '/bin/bash', 03:54:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2RrW8j3UL/Listeners', 03:54:55 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:54:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:55 INFO - 'USER': 'cltbld', 03:54:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:55 INFO - 'XPC_FLAGS': '0x0', 03:54:55 INFO - 'XPC_SERVICE_NAME': '0', 03:54:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:55 INFO - Downloading/unpacking blobuploader==1.2.4 03:54:55 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 03:54:55 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 03:54:55 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 03:54:55 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 03:54:55 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 03:54:55 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 03:54:58 INFO - Downloading blobuploader-1.2.4.tar.gz 03:54:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:54:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:54:58 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 03:54:58 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 03:54:58 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 03:54:58 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 03:54:58 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 03:54:58 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 03:54:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:54:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:54: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 03:54: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 03:54: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 03:54: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 03:54: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 03:54: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 03:54:59 INFO - Downloading docopt-0.6.1.tar.gz 03:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:54:59 INFO - Installing collected packages: blobuploader, requests, docopt 03:54:59 INFO - Running setup.py install for blobuploader 03:54:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:54:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:54:59 INFO - Running setup.py install for requests 03:55:00 INFO - Running setup.py install for docopt 03:55:00 INFO - Successfully installed blobuploader requests docopt 03:55:00 INFO - Cleaning up... 03:55:00 INFO - Return code: 0 03:55:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55: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')]} 03:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55: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')]} 03:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:00 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 0x102be9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7db4ddc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029fdd30>, '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 0x102863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029f5b20>, '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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:55:00 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 03:55:00 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 03:55:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDGxHlChYQ/Render', 03:55:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:00 INFO - 'HOME': '/Users/cltbld', 03:55:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:00 INFO - 'LOGNAME': 'cltbld', 03:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:00 INFO - 'MOZ_NO_REMOTE': '1', 03:55:00 INFO - 'NO_EM_RESTART': '1', 03:55:00 INFO - 'PAGER': '/bin/cat', 03:55:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:00 INFO - 'PWD': '/builds/slave/test', 03:55:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:00 INFO - 'SHELL': '/bin/bash', 03:55:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2RrW8j3UL/Listeners', 03:55:00 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:55:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:00 INFO - 'USER': 'cltbld', 03:55:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:00 INFO - 'XPC_FLAGS': '0x0', 03:55:00 INFO - 'XPC_SERVICE_NAME': '0', 03:55:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:55:00 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-QDubje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:55:00 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-Lh2tTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:55:00 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-c40ENc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:55:01 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-CAXZaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:55:01 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-evNVJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:55:01 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-dzuzO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:55:01 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-Jbd41V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:55:01 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-MMkOYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:55:01 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-yM6K1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:55:01 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-KmUzgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:55:01 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-Akl4jS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:55:01 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-NEpkdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:55:02 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-jqHS54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:55:02 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-av8krp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:02 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-hz0i4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:55:02 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-UVZ3xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:55:02 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-M8PhW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:55:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:55:02 INFO - Running setup.py install for manifestparser 03:55:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:55:02 INFO - Running setup.py install for mozcrash 03:55:02 INFO - Running setup.py install for mozdebug 03:55:03 INFO - Running setup.py install for mozdevice 03:55:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:55:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:55:03 INFO - Running setup.py install for mozfile 03:55:03 INFO - Running setup.py install for mozhttpd 03:55:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:55:03 INFO - Running setup.py install for mozinfo 03:55:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:55:03 INFO - Running setup.py install for mozInstall 03:55:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:55:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:55:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:55:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:55:03 INFO - Running setup.py install for mozleak 03:55:03 INFO - Running setup.py install for mozlog 03:55:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:55:04 INFO - Running setup.py install for moznetwork 03:55:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:55:04 INFO - Running setup.py install for mozprocess 03:55:04 INFO - Running setup.py install for mozprofile 03:55:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:55:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:55:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:55:04 INFO - Running setup.py install for mozrunner 03:55:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:55:04 INFO - Running setup.py install for mozscreenshot 03:55:04 INFO - Running setup.py install for moztest 03:55:05 INFO - Running setup.py install for mozversion 03:55:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:55:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:55:05 INFO - Cleaning up... 03:55:05 INFO - Return code: 0 03:55:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55: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')]} 03:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55: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')]} 03:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:05 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 0x102be9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7db4ddc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029fdd30>, '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 0x102863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029f5b20>, '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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:55:05 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 03:55:05 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 03:55:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDGxHlChYQ/Render', 03:55:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:05 INFO - 'HOME': '/Users/cltbld', 03:55:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:05 INFO - 'LOGNAME': 'cltbld', 03:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:05 INFO - 'MOZ_NO_REMOTE': '1', 03:55:05 INFO - 'NO_EM_RESTART': '1', 03:55:05 INFO - 'PAGER': '/bin/cat', 03:55:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:05 INFO - 'PWD': '/builds/slave/test', 03:55:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:05 INFO - 'SHELL': '/bin/bash', 03:55:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2RrW8j3UL/Listeners', 03:55:05 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:55:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:05 INFO - 'USER': 'cltbld', 03:55:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:05 INFO - 'XPC_FLAGS': '0x0', 03:55:05 INFO - 'XPC_SERVICE_NAME': '0', 03:55:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:55:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-EOOirg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:55:05 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)) 03:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:55:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-FJIgsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:55:05 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)) 03:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:55:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-sTw67q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:55:05 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)) 03:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:55:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-FLWF3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:55:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:55:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-hLCXYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:55:06 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)) 03:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:55:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-T9IWYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:55:06 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)) 03:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:55:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-a9wuhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:55:06 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)) 03:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:55:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-3JLFQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:55:06 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)) 03:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:55:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-EzzAjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:55:06 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)) 03:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:55:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-1sx9bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:55:06 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)) 03:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:55:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-6qnor9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:55:06 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)) 03:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:55:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-0dBC4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:55:06 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)) 03:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:55:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-8P9dK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:55:06 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)) 03:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:55:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-75BavG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:55:07 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)) 03:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:07 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-I9BRXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:07 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)) 03:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:55:07 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-5Vc8Od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:55:07 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)) 03:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:55:07 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-SK47dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:55:07 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)) 03:55:07 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)) 03:55:07 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)) 03:55:07 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)) 03:55:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:55:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:55:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:09 INFO - Downloading blessings-1.6.tar.gz 03:55:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:55:10 INFO - Installing collected packages: blessings 03:55:10 INFO - Running setup.py install for blessings 03:55:10 INFO - Successfully installed blessings 03:55:10 INFO - Cleaning up... 03:55:10 INFO - Return code: 0 03:55:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:55:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:55:10 INFO - Reading from file tmpfile_stdout 03:55:10 INFO - Current package versions: 03:55:10 INFO - blessings == 1.6 03:55:10 INFO - blobuploader == 1.2.4 03:55:10 INFO - docopt == 0.6.1 03:55:10 INFO - functools32 == 3.2.3-2 03:55:10 INFO - jsonschema == 2.5.1 03:55:10 INFO - manifestparser == 1.1 03:55:10 INFO - mozInstall == 1.12 03:55:10 INFO - mozcrash == 1.0 03:55:10 INFO - mozdebug == 0.1 03:55:10 INFO - mozdevice == 0.48 03:55:10 INFO - mozfile == 1.2 03:55:10 INFO - mozhttpd == 0.7 03:55:10 INFO - mozinfo == 0.9 03:55:10 INFO - mozleak == 0.1 03:55:10 INFO - mozlog == 3.4 03:55:10 INFO - moznetwork == 0.27 03:55:10 INFO - mozprocess == 0.23 03:55:10 INFO - mozprofile == 0.28 03:55:10 INFO - mozrunner == 6.13 03:55:10 INFO - mozscreenshot == 0.1 03:55:10 INFO - mozsystemmonitor == 0.3 03:55:10 INFO - moztest == 0.7 03:55:10 INFO - mozversion == 1.4 03:55:10 INFO - psutil == 3.1.1 03:55:10 INFO - requests == 1.2.3 03:55:10 INFO - wsgiref == 0.1.2 03:55:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55:10 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')]} 03:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:10 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')]} 03:55:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:11 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 0x102be9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7db4ddc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029fdd30>, '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 0x102863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029f5b20>, '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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:55:11 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 03:55:11 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 03:55:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDGxHlChYQ/Render', 03:55:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:11 INFO - 'HOME': '/Users/cltbld', 03:55:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:11 INFO - 'LOGNAME': 'cltbld', 03:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:11 INFO - 'MOZ_NO_REMOTE': '1', 03:55:11 INFO - 'NO_EM_RESTART': '1', 03:55:11 INFO - 'PAGER': '/bin/cat', 03:55:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:11 INFO - 'PWD': '/builds/slave/test', 03:55:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:11 INFO - 'SHELL': '/bin/bash', 03:55:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2RrW8j3UL/Listeners', 03:55:11 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:55:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:11 INFO - 'USER': 'cltbld', 03:55:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:11 INFO - 'XPC_FLAGS': '0x0', 03:55:11 INFO - 'XPC_SERVICE_NAME': '0', 03:55:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:11 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)) 03:55:11 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)) 03:55:11 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)) 03:55:11 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)) 03:55:11 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)) 03:55:11 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)) 03:55:11 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)) 03:55:11 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)) 03:55:11 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)) 03:55:11 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)) 03:55:11 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)) 03:55:11 INFO - Cleaning up... 03:55:11 INFO - Return code: 0 03:55:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:55:11 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')]} 03:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:11 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')]} 03:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:11 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 0x102be9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b5f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7db4ddc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029fdd30>, '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 0x102863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029f5b20>, '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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:55:11 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 03:55:11 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 03:55:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDGxHlChYQ/Render', 03:55:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:11 INFO - 'HOME': '/Users/cltbld', 03:55:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:11 INFO - 'LOGNAME': 'cltbld', 03:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:11 INFO - 'MOZ_NO_REMOTE': '1', 03:55:11 INFO - 'NO_EM_RESTART': '1', 03:55:11 INFO - 'PAGER': '/bin/cat', 03:55:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:11 INFO - 'PWD': '/builds/slave/test', 03:55:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:11 INFO - 'SHELL': '/bin/bash', 03:55:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2RrW8j3UL/Listeners', 03:55:11 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:55:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:11 INFO - 'USER': 'cltbld', 03:55:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:11 INFO - 'XPC_FLAGS': '0x0', 03:55:11 INFO - 'XPC_SERVICE_NAME': '0', 03:55:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:55:12 INFO - Cleaning up... 03:55:12 INFO - Return code: 0 03:55:12 INFO - Running post-action listener: _resource_record_post_action 03:55:12 INFO - Running post-action listener: _start_resource_monitoring 03:55:12 INFO - Starting resource monitoring. 03:55:12 INFO - [mozharness: 2016-12-14 11:55:12.197627Z] Finished create-virtualenv step (success) 03:55:12 INFO - [mozharness: 2016-12-14 11:55:12.198643Z] Running install step. 03:55:12 INFO - Running pre-action listener: _resource_record_pre_action 03:55:12 INFO - Running main action method: install 03:55:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:55:12 INFO - Reading from file tmpfile_stdout 03:55:12 INFO - Detecting whether we're running mozinstall >=1.0... 03:55:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:55:12 INFO - Reading from file tmpfile_stdout 03:55:12 INFO - Output received: 03:55:12 INFO - Usage: mozinstall [options] installer 03:55:12 INFO - Options: 03:55:12 INFO - -h, --help show this help message and exit 03:55:12 INFO - -d DEST, --destination=DEST 03:55:12 INFO - Directory to install application into. [default: 03:55:12 INFO - "/builds/slave/test"] 03:55:12 INFO - --app=APP Application being installed. [default: firefox] 03:55:12 INFO - mkdir: /builds/slave/test/build/application 03:55:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:55:43 INFO - Reading from file tmpfile_stdout 03:55:43 INFO - Output received: 03:55:43 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:55:43 INFO - Running post-action listener: _resource_record_post_action 03:55:43 INFO - [mozharness: 2016-12-14 11:55:43.826114Z] Finished install step (success) 03:55:43 INFO - [mozharness: 2016-12-14 11:55:43.826273Z] Running run-tests step. 03:55:43 INFO - Running pre-action listener: _resource_record_pre_action 03:55:43 INFO - Running main action method: run_tests 03:55:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:55:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:55:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:55:43 INFO - 2016-12-14 03:55:43.841 screenresolution[2195:22980] starting screenresolution argv=screenresolution get 03:55:43 INFO - 2016-12-14 03:55:43.843 screenresolution[2195:22980] Display 0: 1600x1200x32@60 03:55:43 INFO - 2016-12-14 03:55:43.849 screenresolution[2196:22982] starting screenresolution argv=screenresolution list 03:55:43 INFO - Available Modes on Display 0 03:55:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:55:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:55:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:55:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:55:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:55:44 INFO - Intel Iris: 03:55:44 INFO - Chipset Model: Intel Iris 03:55:44 INFO - Type: GPU 03:55:44 INFO - Bus: Built-In 03:55:44 INFO - VRAM (Dynamic, Max): 1536 MB 03:55:44 INFO - Vendor: Intel (0x8086) 03:55:44 INFO - Device ID: 0x0a2e 03:55:44 INFO - Revision ID: 0x0009 03:55:44 INFO - Displays: 03:55:44 INFO - Display: 03:55:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:55:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:55:44 INFO - Display Serial Number: 1600x1200 60 03:55:44 INFO - Main Display: Yes 03:55:44 INFO - Mirror: Off 03:55:44 INFO - Online: Yes 03:55:44 INFO - Rotation: Supported 03:55:44 INFO - Return code: 0 03:55:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:55:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:55:44 INFO - Python 2.7.3 03:55:44 INFO - Return code: 0 03:55:44 INFO - grabbing minidump binary from tooltool 03:55:44 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')]} 03:55:44 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 0x1029fdd30>, '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 0x102863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1029f5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:55:44 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 03:55:44 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 03:55:44 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 03:55:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:55:44 INFO - Return code: 0 03:55:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:55:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:55:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:55:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:55:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:55:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--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 03:55:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0073 --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 03:55:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDGxHlChYQ/Render', 03:55:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:44 INFO - 'HOME': '/Users/cltbld', 03:55:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:44 INFO - 'LOGNAME': 'cltbld', 03:55:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:55:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:55:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:44 INFO - 'MOZ_NO_REMOTE': '1', 03:55:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:55:44 INFO - 'NO_EM_RESTART': '1', 03:55:44 INFO - 'PAGER': '/bin/cat', 03:55:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:44 INFO - 'PWD': '/builds/slave/test', 03:55:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:55:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:44 INFO - 'SHELL': '/bin/bash', 03:55:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z2RrW8j3UL/Listeners', 03:55:44 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:55:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:44 INFO - 'USER': 'cltbld', 03:55:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:44 INFO - 'XPC_FLAGS': '0x0', 03:55:44 INFO - 'XPC_SERVICE_NAME': '0', 03:55:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--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 03:55:44 INFO - mozversion application_buildid: 20161214004021 03:55:44 INFO - mozversion application_changeset: c7f071262fbc3862dd41c8bc7d89999e2926126b 03:55:44 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:55:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:55:44 INFO - mozversion application_name: Firefox 03:55:44 INFO - mozversion application_remotingname: firefox-dev 03:55:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:55:44 INFO - mozversion application_vendor: Mozilla 03:55:44 INFO - mozversion application_version: 52.0a2 03:55:44 INFO - mozversion platform_buildid: 20161214004021 03:55:44 INFO - mozversion platform_changeset: c7f071262fbc3862dd41c8bc7d89999e2926126b 03:55:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:55:44 INFO - mozversion platform_version: 52.0a2 03:55:44 INFO - using testdate: 1481716544 03:55:44 INFO - actual date: 1481716544 03:55:44 INFO - starting webserver on 'localhost:53665' 03:55:44 INFO - SUITE-START | Running 2 tests 03:55:44 INFO - TEST-START | dromaeo_css 03:55:44 INFO - operating with platform_type : mac_ 03:55:44 INFO - Initialising browser for dromaeo_css test... 03:55:44 INFO - TEST-INFO | started process 2206 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmp3uZsnp/profile http://localhost:53665/getInfo.html) 03:55:47 INFO - PROCESS | 2206 | __metrics Screen width/height:1600/1200 03:55:47 INFO - PROCESS | 2206 | colorDepth:24 03:55:47 INFO - PROCESS | 2206 | Browser inner width/height: 1024/689 03:55:47 INFO - PROCESS | 2206 | __metrics 03:55:47 INFO - PROCESS | 2206 | 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" 03:55:47 INFO - PROCESS | 2206 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:55:48 INFO - TEST-INFO | 2206: exit 0 03:55:48 INFO - Browser initialized. 03:55:48 INFO - Running cycle 1/1 for dromaeo_css test... 03:55:48 INFO - TEST-INFO | started process 2209 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmp3uZsnp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:56:01 INFO - PROCESS | 2209 | RSS: Main: 243167232 03:56:01 INFO - PROCESS | 2209 | 03:56:52 INFO - PROCESS | 2209 | 1481716612565 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] 03:59:05 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53665/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53665/tests/dromaeo/cssquery-ext.html) 03:59:07 INFO - PROCESS | 2209 | RSS: Main: 394674176 03:59:07 INFO - PROCESS | 2209 | 04:02:10 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53665/tests/dromaeo/cssquery-ext.html (next: http://localhost:53665/tests/dromaeo/cssquery-jquery.html) 04:02:10 INFO - PROCESS | 2209 | RSS: Main: 366620672 04:02:10 INFO - PROCESS | 2209 | 04:05:13 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53665/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53665/tests/dromaeo/cssquery-mootools.html) 04:05:13 INFO - PROCESS | 2209 | RSS: Main: 335814656 04:05:13 INFO - PROCESS | 2209 | 04:08:16 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53665/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53665/tests/dromaeo/cssquery-prototype.html) 04:08:16 INFO - PROCESS | 2209 | RSS: Main: 345374720 04:08:16 INFO - PROCESS | 2209 | 04:11:20 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53665/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53665/tests/dromaeo/cssquery-yui.html) 04:11:20 INFO - PROCESS | 2209 | RSS: Main: 330072064 04:11:20 INFO - PROCESS | 2209 | 04:14:24 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53665/tests/dromaeo/cssquery-yui.html (next: http://localhost:53665/tests/dromaeo/cssquery-dojo.html) 04:14:24 INFO - PROCESS | 2209 | RSS: Main: 509464576 04:14:24 INFO - PROCESS | 2209 | 04:14:24 INFO - PROCESS | 2209 | __start_tp_report 04:14:24 INFO - PROCESS | 2209 | _x_x_mozilla_page_load 04:14:24 INFO - PROCESS | 2209 | _x_x_mozilla_page_load_details 04:14:24 INFO - PROCESS | 2209 | |i|pagename|runs| 04:14:24 INFO - PROCESS | 2209 | |0;dojo.html;3168.6672899345563;3170.7780455368124;3225.5000474926396;3241.724453421459;3262.934741305174;5320.946790532098;5377.381601115872;5425.565954723623;5452;5459.208414779854;5417.133258678612;5431.375391829941;5433.483819037195;5439.891202175954;5440.564754819615;4941.2094064949615;4955.231939049453;5024.045431368047;5041.243813427996;5054.646174767768;4918;4924.261360795891;4941.703497790126;5029.647924645276;5086.3387759591205;4907.2639104134305;4960.379952505937;4980.327655766461;4986.551210391064;4988.451270360258;5000.049990501811;5007.349044624212;5061.670991385548;5076.949230507722;5133.512316329943;4858.392700912386;5018.5232402921665;5022.4224214215465;5026.773795179226;5038.143515602356;5248.8425347239645;5294.655847369947;5341.599380046473;5343.946560534423;5380.300560927094;5832.737526811304;5854.941450585482;5864.970675146597;5928.881422371528;5950.285965684102;6038.396160383926;6040.909386359208;6042.969785151046;6050.394960503959;6064.454199122056;5383.542398896059;5416.160495123262;5443.945560544423;5466.835994920159;5496.230527726121;6119.908201376983;6133.723982420713;6142.723577439026;6147.569670123048;6149.631022138686;5781.219535362757;5795.159701843209;5818.883622327529;5825.504832089235;5831.183634291203;5810.128480727924;5810.796622118206;5895.764169433261;5954.940450595439;5960.880782384328;5477.945220547823;5742.397048309867;5885.61743486672;5898.026825573732;5944.316403613553;5887.24331051649;5929.911051334234;5936.287645482484;5953.404659534012;5971.4327138921735;4566.292224705109;4739.194336962724;4740.360051393087;4742.857714268577;4781.163296423112;4177.289860723684;4217.831286748496;4237.110206856594;4247.3628955656895;4252.87241382759;4934.259861020804;5175.094358487249;5650.067738823131;5678.5741069419955;5701.458361455655;5887.322957859901;6034.547408944259;6037.0642550404755;6037.396260374016;6053.606515576474;5388.892222155535;5396.97457483077;5464.398916119224;5646.096624540054;5646.4353564644025;4342.891427714269;5201.193814958688;5205.323307969939;5208.114620514521;5274.868128296746;5211.348581427322;6315.36846315374;6340.017297318923;6353.237611486651;6355.30091689923;5782.10377391505;5816.069428891357;6018.518518518596;6027.276726792813;6032.668203248688;6134.865219626542;6218.81343559694;6231.3145553988115;6268.717907694074;6332.30344662096;5882.49998750111;5888.528917686489;5913.70431478433;5916.26046744157;5933.525317974465;5829.358770535323;5949.2266005419015;5959.255093113361;5977.193078934288;5984.700764961647;5572.275604171594;5675.801346952837;5749.683767392667;5762.567807414377;5781.190633311256;5787.8553036174435;5790.710464476843;5807.041838096836;5815.156802263564;5834.883302333845;5659.292588426447;5660.830175094754;5664.915026274527;5678.829635110953;5683.914741278967;5567.387587365468;5609.4390560943575;5685.033544297562;5717.1995920570325;5811.128330750421;5437.918431223456;5444.183372493999;5463.7814487420055;5468.644538105011;5469.0155771959835 04:14:24 INFO - PROCESS | 2209 | |1;ext.html;449.30806557900837;474.4259446070255;499.19629396671377;518.3054706693031;543.9673619582823;31148;31913.680863191392;39574.208515829705;40616;40984.38523422151;41751.95620109491;42154.94612634678;42177.15645687092;42205.577944220546;42221;9223.953880230589;9392.90607093927;9396.20132288753;9404.576794044284;9418.952905235465;8529.701460448885;8756.255718263956;11107.83338249927;11390;11407.201495895291;11275.492602832852;11318.094552435785;11328.886711132907;11351.035162011198;11352.545898164064;8513.489190648581;11277.154213433983;11356.772864542745;11368.943155284212;11381.317120972686;8590.742277731679;8657.610407531613;8663.480191188486;8665.17680820321;10942.288751231144;38440.615593843984;40073;40212.195756085006;40259;40424.79787601073;2717.836929784219;3220.114468521152;3280.557124788147;3302.93394132116;3348.866045358183;29530.114096577137;30013;30200.94296699605;30438.54342184869;30674.23314417135;3119.4073126106196;3200.6159260888608;3211.5985501812274;3242.302904875463;3296.604407471119;485707;486536;486613;487886;491193.54403228115;59207;60352;60652.393476065554;60844.39155608476;61033.69483152556;61119.69440152771;61940;63750.36249637537;63969.68015159894;63987.36012639907;16072.035677859365;16364;16384.83615163857;16406.425775097814;16415.671686566442;11039.613613523488;11390.886091139148;11622.6513204604;12266.754664906652;12663.873361266453;15043.473478428203;16774.74837877433;17164.828351716573;17307.653846922993;17611.647767044586;5183.2743415921805;5262.684238945675;6231.781887633911;6318.873622527523;6670.933290667128;830.8421399933993;833.5665453963904;850.3282406898481;887.6360692116331;1111.4165063341811;22347.77652223457;29141.531322505885;29143.85428072846;29596.260093497403;29996.55005174924;1036.7874585709912;1055.894410558938;1066.9573217071386;1077.8275475923815;1141.5547936304847;12347.135700501058;15076.39694412233;15305.540833775005;15535.922320388552;15620.609484762972;615554;622781;622990;623161;624052;57509.712451437474;57600.42399576034;57646.7117664409;57783.4221657778;57790.7110464445;1227.2329793878826;1264.1530174782918;1333.7132516508998;1345.4416417186758;1377.01543396472;29723.702762972094;30245.09264722062;30337.24156896095;30761.38477230398;31404.528932066496;49726.502734973634;50228.24657630212;50244.24633630572;50252.24621630752;50264.748676256386;525165;527537;529241;531122;772962;25561.488770224863;25752.871235643703;25764;25782.96868125254;25833.6124958122;43249.78375108104;44667;44932.550674494145;45102.32346514871;47990.28014579854;36786.08034799044;37131.752043053835;37320.81339593285;37395;38998;29546;30154.09537713872;37179.070523236056;37211;38372.6162738369;31809.840950795096;36051.91844244677;36626.63373366339;36788.63211367959;36859;31371.529427058154;33282.83358583192;33713.494297585064;33798.15504612404;33893.3221335567;24472.265832025067;24587.50824983526;24764.876175619007;24993.250202493953;25181.86681599369 04:14:24 INFO - PROCESS | 2209 | |2;jquery.html;350.0757998882948;361.11166530335373;371.9795411252382;374.2328227134374;401.2356460941905;14934.327955242014;14937.775933360994;16041.438549650764;20343.694844577305;20788.272410465637;20097.899510502426;20130;20573.485662858435;20840.853753043575;21078.051487683053;5147.867469156779;5734.885302293962;5981.820545383645;6002.249718785152;6002.549808764338;4962.255661650745;5302.151655735083;5808.535317174637;5861.384554621767;5923.14114453404;5705.743241554119;5804.635910561036;5908.822735317947;5910.61580997236;5918.704064796764;5782.335031471372;5870.823875283749;5889.941100588982;5893.145493903403;5911.615744976606;4916.164252077156;5096.847094587169;5158.329417175743;5165.251038599419;5168.638195326329;21055.894720526452;21060.894695526425;25720.614190787153;26028;26468;2082.03185518733;2449.3998970252223;2961.2300801791475;2996.640403151614;3078.4151011308004;9938.801223975552;10271.640492582725;10350.327228730108;10613.840792388042;10647;1684.4357140357995;1829.1494455078318;1864.4499872537633;1911.4361263427322;1920.5294702797764;81585.59207203986;85235.2952940944;132242;136590;138177;10978.231523793258;11395.088392928546;11426.485808138654;11491.827622585668;11819.468123934443;14383.496577619942;14415.78376324356;14840.18378989166;14972.1016738996;14995.700085998218;8331.708390206313;8353.33173346131;8508.74473765788;8521.147885211429;8601.870971935426;6703.698333575001;7418.96290518544;7466.402687784964;7630.8855367169535;8147.492713847957;4514.706544074625;4516.977415112903;4525.886852828639;4529.252673308933;4585.656075794262;1860.144333606529;1909.789923108457;1909.8281154695867;1910.3791267838008;1913.7129430585246;805.963731632078;885.8051228729798;904.7288559573809;905.9003509613938;940.1773448232797;20987.685184722242;21527.56944861094;21734.78265217359;21802.454938626342;21837.67243491349;801.7073768074611;892.5804871710286;895.7312806158127;898.3217670658611;914.3782228084833;10784.56861725538;11486.885131148581;11491;11520.790317016761;11529.423528823525;567232;583413;585676;603257;607108;113220;130794.9999999981;136072;138226.308868455;146247;939.8637197606308;944.9652630369734;947.6446332625266;953.8759961204998;960.1071003966239;16998;17968.281268749626;18103;18829.435116946515;19451.61096778028;45944.203756728035;54510.45489545054;55040.193569028765;55116;55551.56485389115;76081;77975.61012194902;78283;78723.21276787159;80883.10285868667;4994;5766.106253530709;6666.966620173881;7108.715651373887;7152.463565232524;12649.93675031619;13273.734525309634;13461.596152115451;13629.454821807016;13688.178709277476;10741.087007604128;10998.560057597606;11168.994790468896;11380.772384552098;11386.715332116763;11146.609868654883;11177.552897883994;11448.363412817822;11476.4261786912;11612.12909031809;11457.427128643702;11537.48081336325;11993.880061199625;12005;12101.636950891487;7465.29079737424;7553.848923021399;7590.7722768317035;7637.732679356196;7681.193474685301;8445.535495547803;11088.223824332219;11288;11296.661100167008;11778.705532361759 04:14:24 INFO - PROCESS | 2209 | |3;mootools.html;1102.39919244012;1434.1538492289546;1442.3581506229725;1588.9364425422984;1800.8019117897038;7456.888146677804;7833.843323133541;8097.068837083737;8385.287250583693;8401.453905496139;8276.751697449072;8339.791505212357;8372.874406883902;8380.245777880005;8385.496870187777;2297.7127859017583;2327.56939966106;2329.5573840970246;2335.9065637374483;2336.5210131923;2316.93049208524;2339.9649005264932;2378.131981826638;2379.881005949704;2380.8095352371856;2315.143396943133;2322.6980492535945;2343.5430091132234;2349.2012715676665;2353.1646265575732;2275.6131457652073;2290.2442194075998;2313.757055509181;2313.90744370225;2318.4551630366936;2329.8252631052737;2349.34218418843;2353.8940747666397;2354.7527509611414;2356.552255071531;8317.085120636724;8402.495850248943;8420.073791882887;8421.36839737016;8424.157584241588;5036;5222.895542089175;5224.059669259532;5256.159014557652;5262.210668399733;3252.7072563469164;3349.648286929886;3446.3796516627;3501.719862411001;3513.1568423578415;1039.8336266197448;1063.3991794636006;1070.1545778834711;1081.0324759340347;1082.796232027764;10973.23187376884;11025.944870275678;11102.444877756161;11107.222494425394;11132.833007504812;4279.914401711948;4431.512533621299;4501.549845015472;4501.639868810487;4505.819767209275;8684.78288042804;8698.608562614698;8708.782280442912;8714.128587141235;8737.737867863974;5016.871203979148;5040.092783299041;5063.797448102035;5107.080725469377;5107.668001579959;4043.2317859606583;4260.339647354665;4399.032212913154;4428.070105277863;4786.64100192487;3219.4365985952363;3642.508261384732;3675.981620091936;3719.9442008369897;3741.3639681254253;1023.9846402303822;1138.1577632552016;1140.4126874659557;1143.4568579924635;1149.6953307373553;767.9500832445873;788.9270591994882;882.8146089321186;902.9954850225838;925.8657494663236;4231.703780735319;5360.16241102565;5530.944690553123;5756.222909907193;5819.185314056035;870.9695160669346;927.5733162745213;932.4358762948318;941.9858702119474;978.1196922769565;2973.925651858721;3628.6552777486095;3739.3269211541638;3814.427835824593;3918.2359439908946;9455.432674039579;10016.298859079794;10144.391336519833;10306.896931030593;10321.277510574339;9957.352772069793;9988.151007164472;10038.247131465023;10212.948935255276;10274.743131421774;6993.545419547713;7789.260020298063;7835.529868207926;8054.8791768122355;8100.0279966404405;5346.438623944586;5477.1510415885605;5483;5517.862053448696;5637.4362563744935;9346.485943273185;9379.249660027317;9461.290403219647;9578.329516933747;9621.134097931219;9534.809303814023;9628.614855405986;9681.467519286507;9741.707748767549;9786.461744604116;6689.5986240825705;6701.865962680621;6788.117544719353;6830.59016459014;6848.760293389708;7827.882581761164;7934.603269836601;7938.5236885784725;7946.960265198637;7953.085395179628;6019.458248757633;7004.719811207495;7066.929330706627;7116.928830711834;7138.785836424916;6919.446444284345;6967.059446974797;6968.024476573182;7014.5440546364325;7020.333068358498;6627.4698024158915;6635.170603674541;6642.070110184674;6751.966240168767;6758.020087087247;4776.23580227162;4823.2282834746275;4854.417469903493;4918.213085906238;4963.03220871941;4634.374359461564;4826.444958829779;4846.079244943449;4986.426560945538;5023.271625614192 04:14:24 INFO - PROCESS | 2209 | |4;prototype.html;281.6451271398038;296.8797636957033;302.61870043744875;307.1553228621292;312.2568287475807;4116.752994820309;4837.274408838676;4844.491328410515;5041.420236672784;5210.322658054457;4377.730458167128;4574.078886867515;4907.656464047518;5086.46592107828;5091.9236211456855;3580.820958952055;3695.5956736440116;3987.8404863805413;3992.940105898414;4014.8193331300017;3516.839442983822;3774.0942173878348;3775.9811200944096;4010.9972506873282;4033.4151548025666;3911.2959667259884;3914.843406263746;3924.2543916655886;3949.2101579684177;3967.7222594418404;3872.845086196549;3881.8835434936714;3903.33643280643;3908.7459315144424;3963.663088637469;3417.8632854685784;3427.4516077427743;3436;3445.500402441657;3465;4289.6997210195295;4683.578477937002;4788.186008378584;5056.696598204083;5062.367204099488;3878.119112230566;4418.834770962206;4431.534688857656;4449.57729015743;4482.5069242383315;4589.154724071097;5017.774200161001;5416.647917885325;5430.972845135749;5466;3247.350529894031;3575.7103464812435;3581.5702115746017;3687.907802304937;3722.4788529605876;5348.486211480598;5581.246531718207;5651.784866253775;5698.458646428583;5719.485246327808;3381.4758712399616;3645.0158457216403;3698.9075273118387;3701.648898152155;3703.2408356286896;3107.813531188136;3414.4634914288667;3452.6202117767025;3710.870119545803;3793.3551296279147;3799.4680744695765;4000.4799376081505;4366.825326986885;4452.5992660660695;4477.2164871147415;5445.37378201504;5488.4785945335125;5493.351994401663;5495.477929596682;5504.522999615328;1115.576081089181;1122.3490375582144;1244.458660482693;1248.9250644961332;1257.3273298785107;742.985140297191;785.5718633344726;849.3120572336427;856.8518185631284;864.5072308783933;4562.794674239667;4867.955458707352;4915.311856340115;4928.088303663833;4929.901401971941;4237.491501019836;5141.53726164647;5168.519110826749;5210.837502436522;5275.7362131894015;4092.536463820773;4754.904901902012;4806.903861922742;4816;4824.758762061883;2414.0947144820693;2740.999070678416;2753.5406234695643;2762.7927905406773;2792.6928037915814;4858.5384388483035;4921.3356196912955;5028.64799464034;5285.286486324374;5604.234666080253;4515.5484451554585;4542.5911667949385;5006.249062640561;5185.370240831433;5360.91958620621;5503.926734286776;5529.101983101984;5532.944670553324;5614.410486898817;5652.834412728885;4391.7584532851;4568.520305368022;5220.269162317202;5234.476552344735;5264.684118952875;4662.9974555470735;4751.721312294506;4797.968436786107;5189.0607370722855;5278.917821846589;5233.764480598306;5428.7557059931605;5467.425920278472;5511.980283647538;5658.839937812821;3201.2637093468165;3274.6070471543567;3511.5259439975275;3583.408737558378;3624.293262813726;5152.510511501401;5279.419263881046;5323.680579165262;5355.4644535547695;5551.9722401387735;4938.209886418156;4956.306117143675;5013.724245166551;5409.945900541102;5494.752736126804;5297.682139071668;5375.865603359791;5381.892362152813;5407.405185429521;5425.376081750649;4833.879153021203;4859.857933385722;4977.676451030671;5242.318498595158;5413.323334583177;4439.48945871216;5451.7460983973115;5455.063452474473;5471.699056551769;5475.4524547544925;4532.7280363178315;5014.144377422985;5283.245079428391;5356.812511562077;5385.192221166856 04:14:24 INFO - PROCESS | 2209 | |5;yui.html;689.7103216649007;694.6353164588587;758.3667637522668;760.615888976067;789.6920201121562;14094.788578171323;14701;14721.92639036806;14736.96841221115;14755.631109222262;12826.358682065882;12869.09916305859;12874.485020599188;12911.354432278373;12994.935025324883;656.6454114778014;677.6171463123344;809.76516810125;820.3970081989718;833.2792134803382;823.9711610093648;829.7344849648115;832.7418500264937;836.5399030533213;837.5435387713711;863.9049704532497;865.6277800545763;866.5234121233296;867.3104881619128;867.4361664917791;685.9691313890837;856.0284077571938;860.423516244118;860.5396113079504;862.4782006885869;532.4648728028345;629.3014753623474;647.7603286783873;648.9286178520359;652.4063102576632;10652.946735266352;13055.608331750063;13717.931410342984;13735.862641373558;13749.862501374959;11346.432678366049;12375.257484550955;12542.623721288284;12579.496820127184;12698.873011269861;11298.661040168809;11663.59177428786;11715.297082175011;11983.161178717493;11985.700357491132;3495.370833250014;3577.5528058992627;3734.775913445202;3774.433834924756;4199.593136299343;92502;96153.9999999993;96526;97979;100040;10303.3817970922;10315.948420258;10360.481975901175;10408.063274305348;10451.634192803216;19217.51956201078;19226.03869806532;19358.903205484163;19394.612107757766;19436.319728809423;7211.89182162268;7306;7398.223186565441;7619.809504762426;7635.694572217049;3958.802059896994;4007.919841603152;4008.4187792769885;4145.191687620885;4445.622122119591;3432.1934345428804;3493.2314890724;3521.647835216509;3573.249617580337;3582.9104272393397;648.7729294746895;659.5976454362835;668.9096971908828;669.9430548403441;670.4368330602317;522.3418492699226;623.5977794322637;627.2535682537765;627.5262177056293;633.828866206131;9080.727578172666;9292.72121836346;9364.812703746024;9408;9474.289428292906;596.4095545410013;631.4632562322081;642.4282388674084;658.980230593083;675.1358261425383;4495.415595972503;4527.977360113178;4560.507899718775;4594.747288899142;4640.883977900512;73849;73888.63055684687;107917;110125.33096216188;111517;71346.28653713396;80128.79806802902;84554;96901.5154924235;98218;74209;74299.62850185715;76609;77082;77889;9125.543722813965;9741.853872191781;9852.704418867446;9865.506724663595;9865.654702085392;51675.74162129316;52169.739151304;54005.45994540004;54171.458285416644;56519.71740141273;71911.64044179746;73079;78165.60917195378;78371;78445.8233126515;10073.496325183858;11985.580504682293;12109.939450302692;12257.570985015482;12677.8098328527;14156.575302740934;14338.211398373189;14868.107913525224;14971.550853474413;15024.474143404928;11054;13099;13099.93450032782;13352.599422017354;13867.029307948347;12039.217450865666;12336.074794390277;13212.471501140231;13219.867801322249;13620.18278903269;12765;12788.552400665933;13544.729105418033;13557.25435101079;13605.319734013061;10528.315659482108;10770.24608277413;10788.190885683762;11211.943940280247;11709.531618735155;11139.387333696724;11216.495257713586;11377.54489820398;11404.48679809394;11453.255538389978 04:14:24 INFO - PROCESS | 2209 | __end_tp_report 04:14:24 INFO - PROCESS | 2209 | __start_cc_report 04:14:24 INFO - PROCESS | 2209 | _x_x_mozilla_cycle_collect,1874 04:14:24 INFO - PROCESS | 2209 | __end_cc_report 04:14:24 INFO - PROCESS | 2209 | __startTimestamp1481717664306__endTimestamp 04:14:24 INFO - PROCESS | 2209 | 04:14:24 INFO - PROCESS | 2209 | ------- Summary: start ------- 04:14:24 INFO - PROCESS | 2209 | Number of tests: 6 04:14:24 INFO - PROCESS | 2209 | 04:14:24 INFO - PROCESS | 2209 | [#0] dojo.html Cycles:165 Average:5475.62 Median:5650.07 stddev:625.06 (11.1%) stddev-sans-first:600.20 04:14:24 INFO - PROCESS | 2209 | Values: 3168.7 3170.8 3225.5 3241.7 3262.9 5320.9 5377.4 5425.6 5452.0 5459.2 5417.1 5431.4 5433.5 5439.9 5440.6 4941.2 4955.2 5024.0 5041.2 5054.6 4918.0 4924.3 4941.7 5029.6 5086.3 4907.3 4960.4 4980.3 4986.6 4988.5 5000.0 5007.3 5061.7 5076.9 5133.5 4858.4 5018.5 5022.4 5026.8 5038.1 5248.8 5294.7 5341.6 5343.9 5380.3 5832.7 5854.9 5865.0 5928.9 5950.3 6038.4 6040.9 6043.0 6050.4 6064.5 5383.5 5416.2 5443.9 5466.8 5496.2 6119.9 6133.7 6142.7 6147.6 6149.6 5781.2 5795.2 5818.9 5825.5 5831.2 5810.1 5810.8 5895.8 5954.9 5960.9 5477.9 5742.4 5885.6 5898.0 5944.3 5887.2 5929.9 5936.3 5953.4 5971.4 4566.3 4739.2 4740.4 4742.9 4781.2 4177.3 4217.8 4237.1 4247.4 4252.9 4934.3 5175.1 5650.1 5678.6 5701.5 5887.3 6034.5 6037.1 6037.4 6053.6 5388.9 5397.0 5464.4 5646.1 5646.4 4342.9 5201.2 5205.3 5208.1 5274.9 5211.3 6315.4 6340.0 6353.2 6355.3 5782.1 5816.1 6018.5 6027.3 6032.7 6134.9 6218.8 6231.3 6268.7 6332.3 5882.5 5888.5 5913.7 5916.3 5933.5 5829.4 5949.2 5959.3 5977.2 5984.7 5572.3 5675.8 5749.7 5762.6 5781.2 5787.9 5790.7 5807.0 5815.2 5834.9 5659.3 5660.8 5664.9 5678.8 5683.9 5567.4 5609.4 5685.0 5717.2 5811.1 5437.9 5444.2 5463.8 5468.6 5469.0 04:14:24 INFO - PROCESS | 2209 | 04:14:24 INFO - PROCESS | 2209 | [#1] ext.html Cycles:180 Average:69122.56 Median:36706.36 stddev:151865.03 (413.7%) stddev-sans-first:152203.52 04:14:24 INFO - PROCESS | 2209 | Values: 449.3 474.4 499.2 518.3 544.0 31148.0 31913.7 39574.2 40616.0 40984.4 41752.0 42154.9 42177.2 42205.6 42221.0 9224.0 9392.9 9396.2 9404.6 9419.0 8529.7 8756.3 11107.8 11390.0 11407.2 11275.5 11318.1 11328.9 11351.0 11352.5 8513.5 11277.2 11356.8 11368.9 11381.3 8590.7 8657.6 8663.5 8665.2 10942.3 38440.6 40073.0 40212.2 40259.0 40424.8 2717.8 3220.1 3280.6 3302.9 3348.9 29530.1 30013.0 30200.9 30438.5 30674.2 3119.4 3200.6 3211.6 3242.3 3296.6 485707.0 486536.0 486613.0 487886.0 491193.5 59207.0 60352.0 60652.4 60844.4 61033.7 61119.7 61940.0 63750.4 63969.7 63987.4 16072.0 16364.0 16384.8 16406.4 16415.7 11039.6 11390.9 11622.7 12266.8 12663.9 15043.5 16774.7 17164.8 17307.7 17611.6 5183.3 5262.7 6231.8 6318.9 6670.9 830.8 833.6 850.3 887.6 1111.4 22347.8 29141.5 29143.9 29596.3 29996.6 1036.8 1055.9 1067.0 1077.8 1141.6 12347.1 15076.4 15305.5 15535.9 15620.6 615554.0 622781.0 622990.0 623161.0 624052.0 57509.7 57600.4 57646.7 57783.4 57790.7 1227.2 1264.2 1333.7 1345.4 1377.0 29723.7 30245.1 30337.2 30761.4 31404.5 49726.5 50228.2 50244.2 50252.2 50264.7 525165.0 527537.0 529241.0 531122.0 772962.0 25561.5 25752.9 25764.0 25783.0 25833.6 43249.8 44667.0 44932.6 45102.3 47990.3 36786.1 37131.8 37320.8 37395.0 38998.0 29546.0 30154.1 37179.1 37211.0 38372.6 31809.8 36051.9 36626.6 36788.6 36859.0 31371.5 33282.8 33713.5 33798.2 33893.3 24472.3 24587.5 24764.9 24993.3 25181.9 04:14:24 INFO - PROCESS | 2209 | 04:14:24 INFO - PROCESS | 2209 | [#2] jquery.html Cycles:180 Average:34692.47 Median:1714.25 stddev:98682.14 (5756.6%) stddev-sans-first:98925.28 04:14:24 INFO - PROCESS | 2209 | Values: 350.1 361.1 372.0 374.2 401.2 14934.3 14937.8 16041.4 20343.7 20788.3 20097.9 20130.0 20573.5 20840.9 21078.1 5147.9 5734.9 5981.8 6002.2 6002.5 4962.3 5302.2 5808.5 5861.4 5923.1 5705.7 5804.6 5908.8 5910.6 5918.7 5782.3 5870.8 5889.9 5893.1 5911.6 4916.2 5096.8 5158.3 5165.3 5168.6 21055.9 21060.9 25720.6 26028.0 26468.0 2082.0 2449.4 2961.2 2996.6 3078.4 9938.8 10271.6 10350.3 10613.8 10647.0 1684.4 1829.1 1864.4 1911.4 1920.5 81585.6 85235.3 132242.0 136590.0 138177.0 10978.2 11395.1 11426.5 11491.8 11819.5 14383.5 14415.8 14840.2 14972.1 14995.7 8331.7 8353.3 8508.7 8521.1 8601.9 6703.7 7419.0 7466.4 7630.9 8147.5 4514.7 4517.0 4525.9 4529.3 4585.7 1860.1 1909.8 1909.8 1910.4 1913.7 806.0 885.8 904.7 905.9 940.2 20987.7 21527.6 21734.8 21802.5 21837.7 801.7 892.6 895.7 898.3 914.4 10784.6 11486.9 11491.0 11520.8 11529.4 567232.0 583413.0 585676.0 603257.0 607108.0 113220.0 130795.0 136072.0 138226.3 146247.0 939.9 945.0 947.6 953.9 960.1 16998.0 17968.3 18103.0 18829.4 19451.6 45944.2 54510.5 55040.2 55116.0 55551.6 76081.0 77975.6 78283.0 78723.2 80883.1 4994.0 5766.1 6667.0 7108.7 7152.5 12649.9 13273.7 13461.6 13629.5 13688.2 10741.1 10998.6 11169.0 11380.8 11386.7 11146.6 11177.6 11448.4 11476.4 11612.1 11457.4 11537.5 11993.9 12005.0 12101.6 7465.3 7553.8 7590.8 7637.7 7681.2 8445.5 11088.2 11288.0 11296.7 11778.7 04:14:24 INFO - PROCESS | 2209 | 04:14:24 INFO - PROCESS | 2209 | [#3] mootools.html Cycles:180 Average:5395.38 Median:5051.95 stddev:2993.56 (59.3%) stddev-sans-first:2984.57 04:14:24 INFO - PROCESS | 2209 | Values: 1102.4 1434.2 1442.4 1588.9 1800.8 7456.9 7833.8 8097.1 8385.3 8401.5 8276.8 8339.8 8372.9 8380.2 8385.5 2297.7 2327.6 2329.6 2335.9 2336.5 2316.9 2340.0 2378.1 2379.9 2380.8 2315.1 2322.7 2343.5 2349.2 2353.2 2275.6 2290.2 2313.8 2313.9 2318.5 2329.8 2349.3 2353.9 2354.8 2356.6 8317.1 8402.5 8420.1 8421.4 8424.2 5036.0 5222.9 5224.1 5256.2 5262.2 3252.7 3349.6 3446.4 3501.7 3513.2 1039.8 1063.4 1070.2 1081.0 1082.8 10973.2 11025.9 11102.4 11107.2 11132.8 4279.9 4431.5 4501.5 4501.6 4505.8 8684.8 8698.6 8708.8 8714.1 8737.7 5016.9 5040.1 5063.8 5107.1 5107.7 4043.2 4260.3 4399.0 4428.1 4786.6 3219.4 3642.5 3676.0 3719.9 3741.4 1024.0 1138.2 1140.4 1143.5 1149.7 768.0 788.9 882.8 903.0 925.9 4231.7 5360.2 5530.9 5756.2 5819.2 871.0 927.6 932.4 942.0 978.1 2973.9 3628.7 3739.3 3814.4 3918.2 9455.4 10016.3 10144.4 10306.9 10321.3 9957.4 9988.2 10038.2 10212.9 10274.7 6993.5 7789.3 7835.5 8054.9 8100.0 5346.4 5477.2 5483.0 5517.9 5637.4 9346.5 9379.2 9461.3 9578.3 9621.1 9534.8 9628.6 9681.5 9741.7 9786.5 6689.6 6701.9 6788.1 6830.6 6848.8 7827.9 7934.6 7938.5 7947.0 7953.1 6019.5 7004.7 7066.9 7116.9 7138.8 6919.4 6967.1 6968.0 7014.5 7020.3 6627.5 6635.2 6642.1 6752.0 6758.0 4776.2 4823.2 4854.4 4918.2 4963.0 4634.4 4826.4 4846.1 4986.4 5023.3 04:14:24 INFO - PROCESS | 2209 | 04:14:24 INFO - PROCESS | 2209 | [#4] prototype.html Cycles:180 Average:4280.06 Median:4811.45 stddev:1311.12 (27.2%) stddev-sans-first:1279.99 04:14:24 INFO - PROCESS | 2209 | Values: 281.6 296.9 302.6 307.2 312.3 4116.8 4837.3 4844.5 5041.4 5210.3 4377.7 4574.1 4907.7 5086.5 5091.9 3580.8 3695.6 3987.8 3992.9 4014.8 3516.8 3774.1 3776.0 4011.0 4033.4 3911.3 3914.8 3924.3 3949.2 3967.7 3872.8 3881.9 3903.3 3908.7 3963.7 3417.9 3427.5 3436.0 3445.5 3465.0 4289.7 4683.6 4788.2 5056.7 5062.4 3878.1 4418.8 4431.5 4449.6 4482.5 4589.2 5017.8 5416.6 5431.0 5466.0 3247.4 3575.7 3581.6 3687.9 3722.5 5348.5 5581.2 5651.8 5698.5 5719.5 3381.5 3645.0 3698.9 3701.6 3703.2 3107.8 3414.5 3452.6 3710.9 3793.4 3799.5 4000.5 4366.8 4452.6 4477.2 5445.4 5488.5 5493.4 5495.5 5504.5 1115.6 1122.3 1244.5 1248.9 1257.3 743.0 785.6 849.3 856.9 864.5 4562.8 4868.0 4915.3 4928.1 4929.9 4237.5 5141.5 5168.5 5210.8 5275.7 4092.5 4754.9 4806.9 4816.0 4824.8 2414.1 2741.0 2753.5 2762.8 2792.7 4858.5 4921.3 5028.6 5285.3 5604.2 4515.5 4542.6 5006.2 5185.4 5360.9 5503.9 5529.1 5532.9 5614.4 5652.8 4391.8 4568.5 5220.3 5234.5 5264.7 4663.0 4751.7 4798.0 5189.1 5278.9 5233.8 5428.8 5467.4 5512.0 5658.8 3201.3 3274.6 3511.5 3583.4 3624.3 5152.5 5279.4 5323.7 5355.5 5552.0 4938.2 4956.3 5013.7 5409.9 5494.8 5297.7 5375.9 5381.9 5407.4 5425.4 4833.9 4859.9 4977.7 5242.3 5413.3 4439.5 5451.7 5455.1 5471.7 5475.5 4532.7 5014.1 5283.2 5356.8 5385.2 04:14:24 INFO - PROCESS | 2209 | 04:14:24 INFO - PROCESS | 2209 | [#5] yui.html Cycles:180 Average:19862.91 Median:4544.24 stddev:28607.43 (629.5%) stddev-sans-first:28651.45 04:14:24 INFO - PROCESS | 2209 | Values: 689.7 694.6 758.4 760.6 789.7 14094.8 14701.0 14721.9 14737.0 14755.6 12826.4 12869.1 12874.5 12911.4 12994.9 656.6 677.6 809.8 820.4 833.3 824.0 829.7 832.7 836.5 837.5 863.9 865.6 866.5 867.3 867.4 686.0 856.0 860.4 860.5 862.5 532.5 629.3 647.8 648.9 652.4 10652.9 13055.6 13717.9 13735.9 13749.9 11346.4 12375.3 12542.6 12579.5 12698.9 11298.7 11663.6 11715.3 11983.2 11985.7 3495.4 3577.6 3734.8 3774.4 4199.6 92502.0 96154.0 96526.0 97979.0 100040.0 10303.4 10315.9 10360.5 10408.1 10451.6 19217.5 19226.0 19358.9 19394.6 19436.3 7211.9 7306.0 7398.2 7619.8 7635.7 3958.8 4007.9 4008.4 4145.2 4445.6 3432.2 3493.2 3521.6 3573.2 3582.9 648.8 659.6 668.9 669.9 670.4 522.3 623.6 627.3 627.5 633.8 9080.7 9292.7 9364.8 9408.0 9474.3 596.4 631.5 642.4 659.0 675.1 4495.4 4528.0 4560.5 4594.7 4640.9 73849.0 73888.6 107917.0 110125.3 111517.0 71346.3 80128.8 84554.0 96901.5 98218.0 74209.0 74299.6 76609.0 77082.0 77889.0 9125.5 9741.9 9852.7 9865.5 9865.7 51675.7 52169.7 54005.5 54171.5 56519.7 71911.6 73079.0 78165.6 78371.0 78445.8 10073.5 11985.6 12109.9 12257.6 12677.8 14156.6 14338.2 14868.1 14971.6 15024.5 11054.0 13099.0 13099.9 13352.6 13867.0 12039.2 12336.1 13212.5 13219.9 13620.2 12765.0 12788.6 13544.7 13557.3 13605.3 10528.3 10770.2 10788.2 11211.9 11709.5 11139.4 11216.5 11377.5 11404.5 11453.3 04:14:24 INFO - PROCESS | 2209 | -------- Summary: end -------- 04:14:24 INFO - PROCESS | 2209 | 04:14:24 INFO - TEST-INFO | 2209: exit 0 04:14:24 INFO - TEST-OK | dromaeo_css | took 1120560ms 04:14:24 INFO - TEST-START | kraken 04:14:24 INFO - operating with platform_type : mac_ 04:14:24 INFO - Initialising browser for kraken test... 04:14:25 INFO - TEST-INFO | started process 2211 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpHJmbxZ/profile http://localhost:53665/getInfo.html) 04:14:27 INFO - PROCESS | 2211 | __metrics Screen width/height:1600/1200 04:14:27 INFO - PROCESS | 2211 | colorDepth:24 04:14:27 INFO - PROCESS | 2211 | Browser inner width/height: 1024/689 04:14:27 INFO - PROCESS | 2211 | __metrics 04:14:27 INFO - PROCESS | 2211 | 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" 04:14:27 INFO - PROCESS | 2211 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:14:28 INFO - TEST-INFO | 2211: exit 0 04:14:28 INFO - Browser initialized. 04:14:28 INFO - Running cycle 1/1 for kraken test... 04:14:28 INFO - TEST-INFO | started process 2213 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpHJmbxZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:40 INFO - PROCESS | 2213 | RSS: Main: 242905088 04:14:40 INFO - PROCESS | 2213 | 04:14:43 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53665/tests/kraken/driver.html?testName=audio-beat-detection) 04:14:44 INFO - PROCESS | 2213 | RSS: Main: 289947648 04:14:44 INFO - PROCESS | 2213 | 04:14:47 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53665/tests/kraken/driver.html?testName=audio-dft) 04:14:47 INFO - PROCESS | 2213 | RSS: Main: 301449216 04:14:47 INFO - PROCESS | 2213 | 04:14:50 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53665/tests/kraken/driver.html?testName=audio-fft) 04:14:51 INFO - PROCESS | 2213 | RSS: Main: 395132928 04:14:51 INFO - PROCESS | 2213 | 04:14:53 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53665/tests/kraken/driver.html?testName=audio-oscillator) 04:14:54 INFO - PROCESS | 2213 | RSS: Main: 297390080 04:14:54 INFO - PROCESS | 2213 | 04:14:56 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53665/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:14:56 INFO - PROCESS | 2213 | RSS: Main: 298680320 04:14:56 INFO - PROCESS | 2213 | 04:14:59 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53665/tests/kraken/driver.html?testName=imaging-darkroom) 04:15:00 INFO - PROCESS | 2213 | RSS: Main: 339894272 04:15:00 INFO - PROCESS | 2213 | 04:15:03 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53665/tests/kraken/driver.html?testName=imaging-desaturate) 04:15:03 INFO - PROCESS | 2213 | RSS: Main: 340316160 04:15:03 INFO - PROCESS | 2213 | 04:15:06 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53665/tests/kraken/driver.html?testName=json-parse-financial) 04:15:07 INFO - PROCESS | 2213 | RSS: Main: 337260544 04:15:07 INFO - PROCESS | 2213 | 04:15:08 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53665/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:15:09 INFO - PROCESS | 2213 | RSS: Main: 313384960 04:15:09 INFO - PROCESS | 2213 | 04:15:11 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53665/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:15:12 INFO - PROCESS | 2213 | RSS: Main: 316805120 04:15:12 INFO - PROCESS | 2213 | 04:15:14 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53665/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:15:14 INFO - PROCESS | 2213 | RSS: Main: 320286720 04:15:14 INFO - PROCESS | 2213 | 04:15:17 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53665/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:15:18 INFO - PROCESS | 2213 | RSS: Main: 334594048 04:15:18 INFO - PROCESS | 2213 | 04:15:21 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53665/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:15:22 INFO - PROCESS | 2213 | RSS: Main: 327970816 04:15:22 INFO - PROCESS | 2213 | 04:15:23 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53665/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53665/tests/kraken/driver.html?testName=ai-astar) 04:15:24 INFO - PROCESS | 2213 | RSS: Main: 372858880 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | __start_tp_report 04:15:24 INFO - PROCESS | 2213 | _x_x_mozilla_page_load 04:15:24 INFO - PROCESS | 2213 | _x_x_mozilla_page_load_details 04:15:24 INFO - PROCESS | 2213 | |i|pagename|runs| 04:15:24 INFO - PROCESS | 2213 | |0;ai-astar;115;114;114;113;114;114;114;113;113;113 04:15:24 INFO - PROCESS | 2213 | |1;audio-beat-detection;146;112;127;176;143;109;112;113;140;110 04:15:24 INFO - PROCESS | 2213 | |2;audio-dft;184;187;182;188;184;185;184;185;186;183 04:15:24 INFO - PROCESS | 2213 | |3;audio-fft;81;59;59;104;60;59;59;59;83;58 04:15:24 INFO - PROCESS | 2213 | |4;audio-oscillator;86;70;95;70;68;69;89;67;69;92 04:15:24 INFO - PROCESS | 2213 | |5;imaging-gaussian-blur;89;86;88;87;89;85;88;86;88;85 04:15:24 INFO - PROCESS | 2213 | |6;imaging-darkroom;93;93;109;149;126;94;94;95;94;94 04:15:24 INFO - PROCESS | 2213 | |7;imaging-desaturate;83;85;78;78;85;84;82;83;80;81 04:15:24 INFO - PROCESS | 2213 | |8;json-parse-financial;56;55;56;55;57;57;62;59;57;58 04:15:24 INFO - PROCESS | 2213 | |9;json-stringify-tinderbox;67;79;79;116;110;84;71;73;71;72 04:15:24 INFO - PROCESS | 2213 | |10;stanford-crypto-aes;84;83;84;85;84;83;85;84;84;83 04:15:24 INFO - PROCESS | 2213 | |11;stanford-crypto-ccm;141;138;142;161;155;163;135;142;185;138 04:15:24 INFO - PROCESS | 2213 | |12;stanford-crypto-pbkdf2;160;165;165;169;229;181;164;165;165;165 04:15:24 INFO - PROCESS | 2213 | |13;stanford-crypto-sha256-iterative;68;68;69;66;67;66;67;65;65;66 04:15:24 INFO - PROCESS | 2213 | __end_tp_report 04:15:24 INFO - PROCESS | 2213 | __start_cc_report 04:15:24 INFO - PROCESS | 2213 | _x_x_mozilla_cycle_collect,1987 04:15:24 INFO - PROCESS | 2213 | __end_cc_report 04:15:24 INFO - PROCESS | 2213 | __startTimestamp1481717724020__endTimestamp 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | ------- Summary: start ------- 04:15:24 INFO - PROCESS | 2213 | Number of tests: 14 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.53 04:15:24 INFO - PROCESS | 2213 | Values: 115.0 114.0 114.0 113.0 114.0 114.0 114.0 113.0 113.0 113.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:133.50 stddev:22.14 (16.6%) stddev-sans-first:22.60 04:15:24 INFO - PROCESS | 2213 | Values: 146.0 112.0 127.0 176.0 143.0 109.0 112.0 113.0 140.0 110.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.00 stddev:1.81 (1.0%) stddev-sans-first:1.90 04:15:24 INFO - PROCESS | 2213 | Values: 184.0 187.0 182.0 188.0 184.0 185.0 184.0 185.0 186.0 183.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#3] audio-fft Cycles:10 Average:68.10 Median:59.00 stddev:15.84 (26.9%) stddev-sans-first:16.10 04:15:24 INFO - PROCESS | 2213 | Values: 81.0 59.0 59.0 104.0 60.0 59.0 59.0 59.0 83.0 58.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.00 stddev:11.44 (14.7%) stddev-sans-first:11.72 04:15:24 INFO - PROCESS | 2213 | Values: 86.0 70.0 95.0 70.0 68.0 69.0 89.0 67.0 69.0 92.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:88.00 stddev:1.52 (1.7%) stddev-sans-first:1.45 04:15:24 INFO - PROCESS | 2213 | Values: 89.0 86.0 88.0 87.0 89.0 85.0 88.0 86.0 88.0 85.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#6] imaging-darkroom Cycles:10 Average:104.10 Median:94.00 stddev:19.02 (20.2%) stddev-sans-first:19.75 04:15:24 INFO - PROCESS | 2213 | Values: 93.0 93.0 109.0 149.0 126.0 94.0 94.0 95.0 94.0 94.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#7] imaging-desaturate Cycles:10 Average:81.90 Median:83.00 stddev:2.60 (3.1%) stddev-sans-first:2.73 04:15:24 INFO - PROCESS | 2213 | Values: 83.0 85.0 78.0 78.0 85.0 84.0 82.0 83.0 80.0 81.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.00 stddev:2.10 (3.7%) stddev-sans-first:2.18 04:15:24 INFO - PROCESS | 2213 | Values: 56.0 55.0 56.0 55.0 57.0 57.0 62.0 59.0 57.0 58.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#9] json-stringify-tinderbox Cycles:10 Average:82.20 Median:72.50 stddev:17.03 (23.5%) stddev-sans-first:17.15 04:15:24 INFO - PROCESS | 2213 | Values: 67.0 79.0 79.0 116.0 110.0 84.0 71.0 73.0 71.0 72.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:0.74 (0.9%) stddev-sans-first:0.78 04:15:24 INFO - PROCESS | 2213 | Values: 84.0 83.0 84.0 85.0 84.0 83.0 85.0 84.0 84.0 83.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:148.50 stddev:15.85 (10.7%) stddev-sans-first:16.48 04:15:24 INFO - PROCESS | 2213 | Values: 141.0 138.0 142.0 161.0 155.0 163.0 135.0 142.0 185.0 138.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:165.00 stddev:20.51 (12.4%) stddev-sans-first:21.22 04:15:24 INFO - PROCESS | 2213 | Values: 160.0 165.0 165.0 169.0 229.0 181.0 164.0 165.0 165.0 165.0 04:15:24 INFO - PROCESS | 2213 | 04:15:24 INFO - PROCESS | 2213 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.34 (2.0%) stddev-sans-first:1.33 04:15:24 INFO - PROCESS | 2213 | Values: 68.0 68.0 69.0 66.0 67.0 66.0 67.0 65.0 65.0 66.0 04:15:24 INFO - PROCESS | 2213 | -------- Summary: end -------- 04:15:24 INFO - PROCESS | 2213 | 04:15:25 INFO - TEST-INFO | 2213: exit 0 04:15:25 INFO - TEST-OK | kraken | took 60105ms 04:15:25 INFO - SUITE-END | took 1180s 04:15:25 INFO - Completed test suite (00:19:40) 04:15:25 INFO - javascript benchmark 04:15:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3168.6672899345563, 3170.7780455368124, 3225.5000474926396, 3241.724453421459, 3262.934741305174, 5320.946790532098, 5377.381601115872, 5425.565954723623, 5452.0, 5459.208414779854, 5417.133258678612, 5431.375391829941, 5433.483819037195, 5439.891202175954, 5440.564754819615, 4941.2094064949615, 4955.231939049453, 5024.045431368047, 5041.243813427996, 5054.646174767768, 4918.0, 4924.261360795891, 4941.703497790126, 5029.647924645276, 5086.3387759591205, 4907.2639104134305, 4960.379952505937, 4980.327655766461, 4986.551210391064, 4988.451270360258, 5000.049990501811, 5007.349044624212, 5061.670991385548, 5076.949230507722, 5133.512316329943, 4858.392700912386, 5018.5232402921665, 5022.4224214215465, 5026.773795179226, 5038.143515602356, 5248.8425347239645, 5294.655847369947, 5341.599380046473, 5343.946560534423, 5380.300560927094, 5832.737526811304, 5854.941450585482, 5864.970675146597, 5928.881422371528, 5950.285965684102, 6038.396160383926, 6040.909386359208, 6042.969785151046, 6050.394960503959, 6064.454199122056, 5383.542398896059, 5416.160495123262, 5443.945560544423, 5466.835994920159, 5496.230527726121, 6119.908201376983, 6133.723982420713, 6142.723577439026, 6147.569670123048, 6149.631022138686, 5781.219535362757, 5795.159701843209, 5818.883622327529, 5825.504832089235, 5831.183634291203, 5810.128480727924, 5810.796622118206, 5895.764169433261, 5954.940450595439, 5960.880782384328, 5477.945220547823, 5742.397048309867, 5885.61743486672, 5898.026825573732, 5944.316403613553, 5887.24331051649, 5929.911051334234, 5936.287645482484, 5953.404659534012, 5971.4327138921735, 4566.292224705109, 4739.194336962724, 4740.360051393087, 4742.857714268577, 4781.163296423112, 4177.289860723684, 4217.831286748496, 4237.110206856594, 4247.3628955656895, 4252.87241382759, 4934.259861020804, 5175.094358487249, 5650.067738823131, 5678.5741069419955, 5701.458361455655, 5887.322957859901, 6034.547408944259, 6037.0642550404755, 6037.396260374016, 6053.606515576474, 5388.892222155535, 5396.97457483077, 5464.398916119224, 5646.096624540054, 5646.4353564644025, 4342.891427714269, 5201.193814958688, 5205.323307969939, 5208.114620514521, 5274.868128296746, 5211.348581427322, 6315.36846315374, 6340.017297318923, 6353.237611486651, 6355.30091689923, 5782.10377391505, 5816.069428891357, 6018.518518518596, 6027.276726792813, 6032.668203248688, 6134.865219626542, 6218.81343559694, 6231.3145553988115, 6268.717907694074, 6332.30344662096, 5882.49998750111, 5888.528917686489, 5913.70431478433, 5916.26046744157, 5933.525317974465, 5829.358770535323, 5949.2266005419015, 5959.255093113361, 5977.193078934288, 5984.700764961647, 5572.275604171594, 5675.801346952837, 5749.683767392667, 5762.567807414377, 5781.190633311256, 5787.8553036174435, 5790.710464476843, 5807.041838096836, 5815.156802263564, 5834.883302333845, 5659.292588426447, 5660.830175094754, 5664.915026274527, 5678.829635110953, 5683.914741278967, 5567.387587365468, 5609.4390560943575, 5685.033544297562, 5717.1995920570325, 5811.128330750421, 5437.918431223456, 5444.183372493999, 5463.7814487420055, 5468.644538105011, 5469.0155771959835], "value": 5435.537375980814, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.30806557900837, 474.4259446070255, 499.19629396671377, 518.3054706693031, 543.9673619582823, 31148.0, 31913.680863191392, 39574.208515829705, 40616.0, 40984.38523422151, 41751.95620109491, 42154.94612634678, 42177.15645687092, 42205.577944220546, 42221.0, 9223.953880230589, 9392.90607093927, 9396.20132288753, 9404.576794044284, 9418.952905235465, 8529.701460448885, 8756.255718263956, 11107.83338249927, 11390.0, 11407.201495895291, 11275.492602832852, 11318.094552435785, 11328.886711132907, 11351.035162011198, 11352.545898164064, 8513.489190648581, 11277.154213433983, 11356.772864542745, 11368.943155284212, 11381.317120972686, 8590.742277731679, 8657.610407531613, 8663.480191188486, 8665.17680820321, 10942.288751231144, 38440.615593843984, 40073.0, 40212.195756085006, 40259.0, 40424.79787601073, 2717.836929784219, 3220.114468521152, 3280.557124788147, 3302.93394132116, 3348.866045358183, 29530.114096577137, 30013.0, 30200.94296699605, 30438.54342184869, 30674.23314417135, 3119.4073126106196, 3200.6159260888608, 3211.5985501812274, 3242.302904875463, 3296.604407471119, 485707.0, 486536.0, 486613.0, 487886.0, 491193.54403228115, 59207.0, 60352.0, 60652.393476065554, 60844.39155608476, 61033.69483152556, 61119.69440152771, 61940.0, 63750.36249637537, 63969.68015159894, 63987.36012639907, 16072.035677859365, 16364.0, 16384.83615163857, 16406.425775097814, 16415.671686566442, 11039.613613523488, 11390.886091139148, 11622.6513204604, 12266.754664906652, 12663.873361266453, 15043.473478428203, 16774.74837877433, 17164.828351716573, 17307.653846922993, 17611.647767044586, 5183.2743415921805, 5262.684238945675, 6231.781887633911, 6318.873622527523, 6670.933290667128, 830.8421399933993, 833.5665453963904, 850.3282406898481, 887.6360692116331, 1111.4165063341811, 22347.77652223457, 29141.531322505885, 29143.85428072846, 29596.260093497403, 29996.55005174924, 1036.7874585709912, 1055.894410558938, 1066.9573217071386, 1077.8275475923815, 1141.5547936304847, 12347.135700501058, 15076.39694412233, 15305.540833775005, 15535.922320388552, 15620.609484762972, 615554.0, 622781.0, 622990.0, 623161.0, 624052.0, 57509.712451437474, 57600.42399576034, 57646.7117664409, 57783.4221657778, 57790.7110464445, 1227.2329793878826, 1264.1530174782918, 1333.7132516508998, 1345.4416417186758, 1377.01543396472, 29723.702762972094, 30245.09264722062, 30337.24156896095, 30761.38477230398, 31404.528932066496, 49726.502734973634, 50228.24657630212, 50244.24633630572, 50252.24621630752, 50264.748676256386, 525165.0, 527537.0, 529241.0, 531122.0, 772962.0, 25561.488770224863, 25752.871235643703, 25764.0, 25782.96868125254, 25833.6124958122, 43249.78375108104, 44667.0, 44932.550674494145, 45102.32346514871, 47990.28014579854, 36786.08034799044, 37131.752043053835, 37320.81339593285, 37395.0, 38998.0, 29546.0, 30154.09537713872, 37179.070523236056, 37211.0, 38372.6162738369, 31809.840950795096, 36051.91844244677, 36626.63373366339, 36788.63211367959, 36859.0, 31371.529427058154, 33282.83358583192, 33713.494297585064, 33798.15504612404, 33893.3221335567, 24472.265832025067, 24587.50824983526, 24764.876175619007, 24993.250202493953, 25181.86681599369], "value": 19479.03584388623, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.0757998882948, 361.11166530335373, 371.9795411252382, 374.2328227134374, 401.2356460941905, 14934.327955242014, 14937.775933360994, 16041.438549650764, 20343.694844577305, 20788.272410465637, 20097.899510502426, 20130.0, 20573.485662858435, 20840.853753043575, 21078.051487683053, 5147.867469156779, 5734.885302293962, 5981.820545383645, 6002.249718785152, 6002.549808764338, 4962.255661650745, 5302.151655735083, 5808.535317174637, 5861.384554621767, 5923.14114453404, 5705.743241554119, 5804.635910561036, 5908.822735317947, 5910.61580997236, 5918.704064796764, 5782.335031471372, 5870.823875283749, 5889.941100588982, 5893.145493903403, 5911.615744976606, 4916.164252077156, 5096.847094587169, 5158.329417175743, 5165.251038599419, 5168.638195326329, 21055.894720526452, 21060.894695526425, 25720.614190787153, 26028.0, 26468.0, 2082.03185518733, 2449.3998970252223, 2961.2300801791475, 2996.640403151614, 3078.4151011308004, 9938.801223975552, 10271.640492582725, 10350.327228730108, 10613.840792388042, 10647.0, 1684.4357140357995, 1829.1494455078318, 1864.4499872537633, 1911.4361263427322, 1920.5294702797764, 81585.59207203986, 85235.2952940944, 132242.0, 136590.0, 138177.0, 10978.231523793258, 11395.088392928546, 11426.485808138654, 11491.827622585668, 11819.468123934443, 14383.496577619942, 14415.78376324356, 14840.18378989166, 14972.1016738996, 14995.700085998218, 8331.708390206313, 8353.33173346131, 8508.74473765788, 8521.147885211429, 8601.870971935426, 6703.698333575001, 7418.96290518544, 7466.402687784964, 7630.8855367169535, 8147.492713847957, 4514.706544074625, 4516.977415112903, 4525.886852828639, 4529.252673308933, 4585.656075794262, 1860.144333606529, 1909.789923108457, 1909.8281154695867, 1910.3791267838008, 1913.7129430585246, 805.963731632078, 885.8051228729798, 904.7288559573809, 905.9003509613938, 940.1773448232797, 20987.685184722242, 21527.56944861094, 21734.78265217359, 21802.454938626342, 21837.67243491349, 801.7073768074611, 892.5804871710286, 895.7312806158127, 898.3217670658611, 914.3782228084833, 10784.56861725538, 11486.885131148581, 11491.0, 11520.790317016761, 11529.423528823525, 567232.0, 583413.0, 585676.0, 603257.0, 607108.0, 113220.0, 130794.9999999981, 136072.0, 138226.308868455, 146247.0, 939.8637197606308, 944.9652630369734, 947.6446332625266, 953.8759961204998, 960.1071003966239, 16998.0, 17968.281268749626, 18103.0, 18829.435116946515, 19451.61096778028, 45944.203756728035, 54510.45489545054, 55040.193569028765, 55116.0, 55551.56485389115, 76081.0, 77975.61012194902, 78283.0, 78723.21276787159, 80883.10285868667, 4994.0, 5766.106253530709, 6666.966620173881, 7108.715651373887, 7152.463565232524, 12649.93675031619, 13273.734525309634, 13461.596152115451, 13629.454821807016, 13688.178709277476, 10741.087007604128, 10998.560057597606, 11168.994790468896, 11380.772384552098, 11386.715332116763, 11146.609868654883, 11177.552897883994, 11448.363412817822, 11476.4261786912, 11612.12909031809, 11457.427128643702, 11537.48081336325, 11993.880061199625, 12005.0, 12101.636950891487, 7465.29079737424, 7553.848923021399, 7590.7722768317035, 7637.732679356196, 7681.193474685301, 8445.535495547803, 11088.223824332219, 11288.0, 11296.661100167008, 11778.705532361759], "value": 9408.255250682374, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1102.39919244012, 1434.1538492289546, 1442.3581506229725, 1588.9364425422984, 1800.8019117897038, 7456.888146677804, 7833.843323133541, 8097.068837083737, 8385.287250583693, 8401.453905496139, 8276.751697449072, 8339.791505212357, 8372.874406883902, 8380.245777880005, 8385.496870187777, 2297.7127859017583, 2327.56939966106, 2329.5573840970246, 2335.9065637374483, 2336.5210131923, 2316.93049208524, 2339.9649005264932, 2378.131981826638, 2379.881005949704, 2380.8095352371856, 2315.143396943133, 2322.6980492535945, 2343.5430091132234, 2349.2012715676665, 2353.1646265575732, 2275.6131457652073, 2290.2442194075998, 2313.757055509181, 2313.90744370225, 2318.4551630366936, 2329.8252631052737, 2349.34218418843, 2353.8940747666397, 2354.7527509611414, 2356.552255071531, 8317.085120636724, 8402.495850248943, 8420.073791882887, 8421.36839737016, 8424.157584241588, 5036.0, 5222.895542089175, 5224.059669259532, 5256.159014557652, 5262.210668399733, 3252.7072563469164, 3349.648286929886, 3446.3796516627, 3501.719862411001, 3513.1568423578415, 1039.8336266197448, 1063.3991794636006, 1070.1545778834711, 1081.0324759340347, 1082.796232027764, 10973.23187376884, 11025.944870275678, 11102.444877756161, 11107.222494425394, 11132.833007504812, 4279.914401711948, 4431.512533621299, 4501.549845015472, 4501.639868810487, 4505.819767209275, 8684.78288042804, 8698.608562614698, 8708.782280442912, 8714.128587141235, 8737.737867863974, 5016.871203979148, 5040.092783299041, 5063.797448102035, 5107.080725469377, 5107.668001579959, 4043.2317859606583, 4260.339647354665, 4399.032212913154, 4428.070105277863, 4786.64100192487, 3219.4365985952363, 3642.508261384732, 3675.981620091936, 3719.9442008369897, 3741.3639681254253, 1023.9846402303822, 1138.1577632552016, 1140.4126874659557, 1143.4568579924635, 1149.6953307373553, 767.9500832445873, 788.9270591994882, 882.8146089321186, 902.9954850225838, 925.8657494663236, 4231.703780735319, 5360.16241102565, 5530.944690553123, 5756.222909907193, 5819.185314056035, 870.9695160669346, 927.5733162745213, 932.4358762948318, 941.9858702119474, 978.1196922769565, 2973.925651858721, 3628.6552777486095, 3739.3269211541638, 3814.427835824593, 3918.2359439908946, 9455.432674039579, 10016.298859079794, 10144.391336519833, 10306.896931030593, 10321.277510574339, 9957.352772069793, 9988.151007164472, 10038.247131465023, 10212.948935255276, 10274.743131421774, 6993.545419547713, 7789.260020298063, 7835.529868207926, 8054.8791768122355, 8100.0279966404405, 5346.438623944586, 5477.1510415885605, 5483.0, 5517.862053448696, 5637.4362563744935, 9346.485943273185, 9379.249660027317, 9461.290403219647, 9578.329516933747, 9621.134097931219, 9534.809303814023, 9628.614855405986, 9681.467519286507, 9741.707748767549, 9786.461744604116, 6689.5986240825705, 6701.865962680621, 6788.117544719353, 6830.59016459014, 6848.760293389708, 7827.882581761164, 7934.603269836601, 7938.5236885784725, 7946.960265198637, 7953.085395179628, 6019.458248757633, 7004.719811207495, 7066.929330706627, 7116.928830711834, 7138.785836424916, 6919.446444284345, 6967.059446974797, 6968.024476573182, 7014.5440546364325, 7020.333068358498, 6627.4698024158915, 6635.170603674541, 6642.070110184674, 6751.966240168767, 6758.020087087247, 4776.23580227162, 4823.2282834746275, 4854.417469903493, 4918.213085906238, 4963.03220871941, 4634.374359461564, 4826.444958829779, 4846.079244943449, 4986.426560945538, 5023.271625614192], "value": 4358.2038013264955, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.6451271398038, 296.8797636957033, 302.61870043744875, 307.1553228621292, 312.2568287475807, 4116.752994820309, 4837.274408838676, 4844.491328410515, 5041.420236672784, 5210.322658054457, 4377.730458167128, 4574.078886867515, 4907.656464047518, 5086.46592107828, 5091.9236211456855, 3580.820958952055, 3695.5956736440116, 3987.8404863805413, 3992.940105898414, 4014.8193331300017, 3516.839442983822, 3774.0942173878348, 3775.9811200944096, 4010.9972506873282, 4033.4151548025666, 3911.2959667259884, 3914.843406263746, 3924.2543916655886, 3949.2101579684177, 3967.7222594418404, 3872.845086196549, 3881.8835434936714, 3903.33643280643, 3908.7459315144424, 3963.663088637469, 3417.8632854685784, 3427.4516077427743, 3436.0, 3445.500402441657, 3465.0, 4289.6997210195295, 4683.578477937002, 4788.186008378584, 5056.696598204083, 5062.367204099488, 3878.119112230566, 4418.834770962206, 4431.534688857656, 4449.57729015743, 4482.5069242383315, 4589.154724071097, 5017.774200161001, 5416.647917885325, 5430.972845135749, 5466.0, 3247.350529894031, 3575.7103464812435, 3581.5702115746017, 3687.907802304937, 3722.4788529605876, 5348.486211480598, 5581.246531718207, 5651.784866253775, 5698.458646428583, 5719.485246327808, 3381.4758712399616, 3645.0158457216403, 3698.9075273118387, 3701.648898152155, 3703.2408356286896, 3107.813531188136, 3414.4634914288667, 3452.6202117767025, 3710.870119545803, 3793.3551296279147, 3799.4680744695765, 4000.4799376081505, 4366.825326986885, 4452.5992660660695, 4477.2164871147415, 5445.37378201504, 5488.4785945335125, 5493.351994401663, 5495.477929596682, 5504.522999615328, 1115.576081089181, 1122.3490375582144, 1244.458660482693, 1248.9250644961332, 1257.3273298785107, 742.985140297191, 785.5718633344726, 849.3120572336427, 856.8518185631284, 864.5072308783933, 4562.794674239667, 4867.955458707352, 4915.311856340115, 4928.088303663833, 4929.901401971941, 4237.491501019836, 5141.53726164647, 5168.519110826749, 5210.837502436522, 5275.7362131894015, 4092.536463820773, 4754.904901902012, 4806.903861922742, 4816.0, 4824.758762061883, 2414.0947144820693, 2740.999070678416, 2753.5406234695643, 2762.7927905406773, 2792.6928037915814, 4858.5384388483035, 4921.3356196912955, 5028.64799464034, 5285.286486324374, 5604.234666080253, 4515.5484451554585, 4542.5911667949385, 5006.249062640561, 5185.370240831433, 5360.91958620621, 5503.926734286776, 5529.101983101984, 5532.944670553324, 5614.410486898817, 5652.834412728885, 4391.7584532851, 4568.520305368022, 5220.269162317202, 5234.476552344735, 5264.684118952875, 4662.9974555470735, 4751.721312294506, 4797.968436786107, 5189.0607370722855, 5278.917821846589, 5233.764480598306, 5428.7557059931605, 5467.425920278472, 5511.980283647538, 5658.839937812821, 3201.2637093468165, 3274.6070471543567, 3511.5259439975275, 3583.408737558378, 3624.293262813726, 5152.510511501401, 5279.419263881046, 5323.680579165262, 5355.4644535547695, 5551.9722401387735, 4938.209886418156, 4956.306117143675, 5013.724245166551, 5409.945900541102, 5494.752736126804, 5297.682139071668, 5375.865603359791, 5381.892362152813, 5407.405185429521, 5425.376081750649, 4833.879153021203, 4859.857933385722, 4977.676451030671, 5242.318498595158, 5413.323334583177, 4439.48945871216, 5451.7460983973115, 5455.063452474473, 5471.699056551769, 5475.4524547544925, 4532.7280363178315, 5014.144377422985, 5283.245079428391, 5356.812511562077, 5385.192221166856], "value": 3860.8852099800706, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.7103216649007, 694.6353164588587, 758.3667637522668, 760.615888976067, 789.6920201121562, 14094.788578171323, 14701.0, 14721.92639036806, 14736.96841221115, 14755.631109222262, 12826.358682065882, 12869.09916305859, 12874.485020599188, 12911.354432278373, 12994.935025324883, 656.6454114778014, 677.6171463123344, 809.76516810125, 820.3970081989718, 833.2792134803382, 823.9711610093648, 829.7344849648115, 832.7418500264937, 836.5399030533213, 837.5435387713711, 863.9049704532497, 865.6277800545763, 866.5234121233296, 867.3104881619128, 867.4361664917791, 685.9691313890837, 856.0284077571938, 860.423516244118, 860.5396113079504, 862.4782006885869, 532.4648728028345, 629.3014753623474, 647.7603286783873, 648.9286178520359, 652.4063102576632, 10652.946735266352, 13055.608331750063, 13717.931410342984, 13735.862641373558, 13749.862501374959, 11346.432678366049, 12375.257484550955, 12542.623721288284, 12579.496820127184, 12698.873011269861, 11298.661040168809, 11663.59177428786, 11715.297082175011, 11983.161178717493, 11985.700357491132, 3495.370833250014, 3577.5528058992627, 3734.775913445202, 3774.433834924756, 4199.593136299343, 92502.0, 96153.9999999993, 96526.0, 97979.0, 100040.0, 10303.3817970922, 10315.948420258, 10360.481975901175, 10408.063274305348, 10451.634192803216, 19217.51956201078, 19226.03869806532, 19358.903205484163, 19394.612107757766, 19436.319728809423, 7211.89182162268, 7306.0, 7398.223186565441, 7619.809504762426, 7635.694572217049, 3958.802059896994, 4007.919841603152, 4008.4187792769885, 4145.191687620885, 4445.622122119591, 3432.1934345428804, 3493.2314890724, 3521.647835216509, 3573.249617580337, 3582.9104272393397, 648.7729294746895, 659.5976454362835, 668.9096971908828, 669.9430548403441, 670.4368330602317, 522.3418492699226, 623.5977794322637, 627.2535682537765, 627.5262177056293, 633.828866206131, 9080.727578172666, 9292.72121836346, 9364.812703746024, 9408.0, 9474.289428292906, 596.4095545410013, 631.4632562322081, 642.4282388674084, 658.980230593083, 675.1358261425383, 4495.415595972503, 4527.977360113178, 4560.507899718775, 4594.747288899142, 4640.883977900512, 73849.0, 73888.63055684687, 107917.0, 110125.33096216188, 111517.0, 71346.28653713396, 80128.79806802902, 84554.0, 96901.5154924235, 98218.0, 74209.0, 74299.62850185715, 76609.0, 77082.0, 77889.0, 9125.543722813965, 9741.853872191781, 9852.704418867446, 9865.506724663595, 9865.654702085392, 51675.74162129316, 52169.739151304, 54005.45994540004, 54171.458285416644, 56519.71740141273, 71911.64044179746, 73079.0, 78165.60917195378, 78371.0, 78445.8233126515, 10073.496325183858, 11985.580504682293, 12109.939450302692, 12257.570985015482, 12677.8098328527, 14156.575302740934, 14338.211398373189, 14868.107913525224, 14971.550853474413, 15024.474143404928, 11054.0, 13099.0, 13099.93450032782, 13352.599422017354, 13867.029307948347, 12039.217450865666, 12336.074794390277, 13212.471501140231, 13219.867801322249, 13620.18278903269, 12765.0, 12788.552400665933, 13544.729105418033, 13557.25435101079, 13605.319734013061, 10528.315659482108, 10770.24608277413, 10788.190885683762, 11211.943940280247, 11709.531618735155, 11139.387333696724, 11216.495257713586, 11377.54489820398, 11404.48679809394, 11453.255538389978], "value": 7204.827846195347, "unit": "score"}], "name": "dromaeo_css", "value": 7030.688649889692, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 113.0, 113.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 127.0, 176.0, 143.0, 109.0, 112.0, 113.0, 140.0, 110.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 182.0, 188.0, 184.0, 185.0, 184.0, 185.0, 186.0, 183.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 104.0, 60.0, 59.0, 59.0, 59.0, 83.0, 58.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 95.0, 70.0, 68.0, 69.0, 89.0, 67.0, 69.0, 92.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 87.0, 89.0, 85.0, 88.0, 86.0, 88.0, 85.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 109.0, 149.0, 126.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 104.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 78.0, 78.0, 85.0, 84.0, 82.0, 83.0, 80.0, 81.0], "value": 81.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 55.0, 57.0, 57.0, 62.0, 59.0, 57.0, 58.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 79.0, 79.0, 116.0, 110.0, 84.0, 71.0, 73.0, 71.0, 72.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 85.0, 84.0, 83.0, 85.0, 84.0, 84.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 142.0, 161.0, 155.0, 163.0, 135.0, 142.0, 185.0, 138.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 165.0, 165.0, 169.0, 229.0, 181.0, 164.0, 165.0, 165.0, 165.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 66.0, 67.0, 66.0, 67.0, 65.0, 65.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1458.8000000000002, "alertThreshold": 2.0}]} 04:15:25 INFO - Return code: 0 04:15:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:15:25 INFO - # TBPL SUCCESS # 04:15:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:15:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:15:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:15:25 INFO - 2016-12-14 04:15:25.325 screenresolution[2217:25205] starting screenresolution argv=screenresolution get 04:15:25 INFO - 2016-12-14 04:15:25.328 screenresolution[2217:25205] Display 0: 1600x1200x32@60 04:15:25 INFO - 2016-12-14 04:15:25.335 screenresolution[2218:25208] starting screenresolution argv=screenresolution list 04:15:25 INFO - Available Modes on Display 0 04:15:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:15:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:15:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:15:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:15:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:15:25 INFO - Intel Iris: 04:15:25 INFO - Chipset Model: Intel Iris 04:15:25 INFO - Type: GPU 04:15:25 INFO - Bus: Built-In 04:15:25 INFO - VRAM (Dynamic, Max): 1536 MB 04:15:25 INFO - Vendor: Intel (0x8086) 04:15:25 INFO - Device ID: 0x0a2e 04:15:25 INFO - Revision ID: 0x0009 04:15:25 INFO - Displays: 04:15:25 INFO - Display: 04:15:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:15:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:15:25 INFO - Display Serial Number: 1600x1200 60 04:15:25 INFO - Main Display: Yes 04:15:25 INFO - Mirror: Off 04:15:25 INFO - Online: Yes 04:15:25 INFO - Rotation: Supported 04:15:25 INFO - Return code: 0 04:15:25 INFO - Running post-action listener: _resource_record_post_action 04:15:25 INFO - [mozharness: 2016-12-14 12:15:25.589529Z] Finished run-tests step (success) 04:15:25 INFO - Running post-run listener: _resource_record_post_run 04:15:25 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 622592; Write bytes: 654104064; Read time: 7; Write time: 1845 04:15:25 INFO - TinderboxPrint: CPU usage
53.5% 04:15:25 INFO - TinderboxPrint: I/O read bytes / time
622,592 / 7 04:15:25 INFO - TinderboxPrint: I/O write bytes / time
654,104,064 / 1,845 04:15:25 INFO - TinderboxPrint: CPU idle
2,253.0 (46.5%) 04:15:25 INFO - TinderboxPrint: CPU system
974.1 (20.1%) 04:15:25 INFO - TinderboxPrint: CPU user
1,622.7 (33.5%) 04:15:25 INFO - TinderboxPrint: Swap in / out
348,631,040 / 0 04:15:25 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342235648; Write bytes: 337393664; Read time: 26997; Write time: 672 04:15:25 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 622592; Write bytes: 301256192; Read time: 7; Write time: 1123 04:15:26 INFO - Running post-run listener: _upload_blobber_files 04:15:26 INFO - Blob upload gear active. 04:15:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:15:26 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 04:15:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:15:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:15:26 INFO - (blobuploader) - INFO - Open directory for files ... 04:15:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:15:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:15:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:28 INFO - (blobuploader) - INFO - Done attempting. 04:15:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:15:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:15:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:30 INFO - (blobuploader) - INFO - Done attempting. 04:15:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:15:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:15:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:33 INFO - (blobuploader) - INFO - Done attempting. 04:15:33 INFO - (blobuploader) - INFO - Iteration through files over. 04:15:33 INFO - Return code: 0 04:15:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:15:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:15:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/baf93a3910174ede203fee90293f3e7e262cb901c5d024820a08e8f2881f53a3e8d0b3aefe32d0dbb1aa06c3822e36ec93c732ad66ab399fd05cf266a1ef53cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c87d25b2639a92642e657e7b661d2b37bbbfccaaad37b60d4c8a9b04a6615594f4f76b017820cfebe98914b4b78514f6bcb10bfca8793c26f1241c7fd6995d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a093f93b638a75152d4eca777ab1b6c24ede8dd0818c4792accc931249092ccdd8e5b0e55e0562b915dc0a8efe3468d50d4f105358c05c87fbb08de72f986196"} 04:15:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:15:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:15:33 INFO - Contents: 04:15:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/baf93a3910174ede203fee90293f3e7e262cb901c5d024820a08e8f2881f53a3e8d0b3aefe32d0dbb1aa06c3822e36ec93c732ad66ab399fd05cf266a1ef53cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c87d25b2639a92642e657e7b661d2b37bbbfccaaad37b60d4c8a9b04a6615594f4f76b017820cfebe98914b4b78514f6bcb10bfca8793c26f1241c7fd6995d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a093f93b638a75152d4eca777ab1b6c24ede8dd0818c4792accc931249092ccdd8e5b0e55e0562b915dc0a8efe3468d50d4f105358c05c87fbb08de72f986196"} 04:15:33 INFO - Running post-run listener: copy_logs_to_upload_dir 04:15:33 INFO - Copying logs to upload dir... 04:15:33 INFO - mkdir: /builds/slave/test/build/upload/logs 04:15:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.832554 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-14 04:15:33.321571) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 04:15:33.325622) ========= 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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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/Mozilla-Aurora/sha512/baf93a3910174ede203fee90293f3e7e262cb901c5d024820a08e8f2881f53a3e8d0b3aefe32d0dbb1aa06c3822e36ec93c732ad66ab399fd05cf266a1ef53cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c87d25b2639a92642e657e7b661d2b37bbbfccaaad37b60d4c8a9b04a6615594f4f76b017820cfebe98914b4b78514f6bcb10bfca8793c26f1241c7fd6995d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a093f93b638a75152d4eca777ab1b6c24ede8dd0818c4792accc931249092ccdd8e5b0e55e0562b915dc0a8efe3468d50d4f105358c05c87fbb08de72f986196"} build_url:https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011425 build_url: 'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/baf93a3910174ede203fee90293f3e7e262cb901c5d024820a08e8f2881f53a3e8d0b3aefe32d0dbb1aa06c3822e36ec93c732ad66ab399fd05cf266a1ef53cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c87d25b2639a92642e657e7b661d2b37bbbfccaaad37b60d4c8a9b04a6615594f4f76b017820cfebe98914b4b78514f6bcb10bfca8793c26f1241c7fd6995d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a093f93b638a75152d4eca777ab1b6c24ede8dd0818c4792accc931249092ccdd8e5b0e55e0562b915dc0a8efe3468d50d4f105358c05c87fbb08de72f986196"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 04:15:33.364482) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 04:15:33.364828) ========= 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.sDGxHlChYQ/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.Z2RrW8j3UL/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.004838 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 04:15:33.414168) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 04:15:33.414559) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 04:15:33.414896) ========= ========= Total master_lag: 0.19 =========