builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0281 starttime: 1483560440.29 results: success (0) buildid: 20170104094639 builduid: 9343ee82ee7647bca7e55bf4f6e6e44f revision: a51cdeb88cbfee1e51234a575ccb8aa75130a07d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:20.288298) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:20.289016) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:20.289543) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:20.318527) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:20.319060) ========= 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.lzanSV6RWb/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.Wy7eawhhkc/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-04 12:07:20-- 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% 159M=0s 2017-01-04 12:07:20 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093065 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:20.437005) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:20.437330) ========= 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.lzanSV6RWb/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.Wy7eawhhkc/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.025271 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:20.486181) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 12:07:20.486591) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a51cdeb88cbfee1e51234a575ccb8aa75130a07d --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 a51cdeb88cbfee1e51234a575ccb8aa75130a07d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lzanSV6RWb/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.Wy7eawhhkc/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-04 12:07:20,576 truncating revision to first 12 chars 2017-01-04 12:07:20,576 Setting DEBUG logging. 2017-01-04 12:07:20,576 attempt 1/10 2017-01-04 12:07:20,576 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a51cdeb88cbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 12:07:22,119 unpacking tar archive at: mozilla-aurora-a51cdeb88cbf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.817283 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 12:07:22.327365) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:22.327683) ========= script_repo_revision: a51cdeb88cbfee1e51234a575ccb8aa75130a07d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:22.328061) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:22.328344) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 12:07:22.347850) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-04 12:07:22.348180) ========= /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.lzanSV6RWb/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.Wy7eawhhkc/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:07:22 INFO - MultiFileLogger online at 20170104 12:07:22 in /builds/slave/test 12:07:22 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 12:07:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:07:22 INFO - {'append_to_log': False, 12:07:22 INFO - 'base_work_dir': '/builds/slave/test', 12:07:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:07:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:07:22 INFO - 'branch': 'Mozilla-Aurora', 12:07:22 INFO - 'buildbot_json_path': 'buildprops.json', 12:07:22 INFO - 'config_files': ('talos/mac_config.py',), 12:07:22 INFO - 'default_actions': ('clobber', 12:07:22 INFO - 'read-buildbot-config', 12:07:22 INFO - 'download-and-extract', 12:07:22 INFO - 'populate-webroot', 12:07:22 INFO - 'create-virtualenv', 12:07:22 INFO - 'install', 12:07:22 INFO - 'run-tests'), 12:07:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:07:22 INFO - 'download_minidump_stackwalk': True, 12:07:22 INFO - 'download_symbols': 'ondemand', 12:07:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:07:22 INFO - 'tooltool.py': '/tools/tooltool.py', 12:07:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:07:22 INFO - '/tools/misc-python/virtualenv.py')}, 12:07:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:07:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:07:22 INFO - 'installer_path': 'installer.exe', 12:07:22 INFO - 'log_level': 'info', 12:07:22 INFO - 'log_name': 'talos', 12:07:22 INFO - 'log_to_console': True, 12:07:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:07:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:07:22 INFO - 'opt_config_files': (), 12:07:22 INFO - 'pip_index': False, 12:07:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:22 INFO - 'cmd': ('bash', 12:07:22 INFO - '-c', 12:07:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:22 INFO - 'enabled': True, 12:07:22 INFO - 'halt_on_failure': False, 12:07:22 INFO - 'name': 'check_screen_resolution'},), 12:07:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:22 INFO - 'cmd': ('bash', 12:07:22 INFO - '-c', 12:07:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:22 INFO - 'enabled': True, 12:07:22 INFO - 'halt_on_failure': False, 12:07:22 INFO - 'name': 'check_screen_resolution'},), 12:07:22 INFO - 'run_cmd_checks_enabled': True, 12:07:22 INFO - 'sps_profile': False, 12:07:22 INFO - 'sps_profile_interval': 0, 12:07:22 INFO - 'suite': 'dromaeojs', 12:07:22 INFO - 'system_bits': '32', 12:07:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:07:22 INFO - 'title': 't-yosemite-r7-0281', 12:07:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:07:22 INFO - 'use_talos_json': True, 12:07:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:07:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:07:22 INFO - 'work_dir': 'build'} 12:07:22 INFO - [mozharness: 2017-01-04 20:07:22.546467Z] Running clobber step. 12:07:22 INFO - Running pre-action listener: _resource_record_pre_action 12:07:22 INFO - Running main action method: clobber 12:07:22 INFO - rmtree: /builds/slave/test/build 12:07:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:07:24 INFO - Running post-action listener: _resource_record_post_action 12:07:24 INFO - [mozharness: 2017-01-04 20:07:24.939874Z] Finished clobber step (success) 12:07:24 INFO - [mozharness: 2017-01-04 20:07:24.940034Z] Running read-buildbot-config step. 12:07:24 INFO - Running pre-action listener: _resource_record_pre_action 12:07:24 INFO - Running main action method: read_buildbot_config 12:07:24 INFO - Using buildbot properties: 12:07:24 INFO - { 12:07:24 INFO - "project": "", 12:07:24 INFO - "product": "firefox", 12:07:24 INFO - "script_repo_revision": "production", 12:07:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:07:24 INFO - "repository": "", 12:07:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:07:24 INFO - "buildid": "20170104094639", 12:07:24 INFO - "pgo_build": "False", 12:07:24 INFO - "basedir": "/builds/slave/test", 12:07:24 INFO - "buildnumber": 76, 12:07:24 INFO - "slavename": "t-yosemite-r7-0281", 12:07:24 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 12:07:24 INFO - "platform": "yosemite_r7", 12:07:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:07:24 INFO - "branch": "mozilla-aurora", 12:07:24 INFO - "repo_path": "releases/mozilla-aurora", 12:07:24 INFO - "revision": "a51cdeb88cbfee1e51234a575ccb8aa75130a07d", 12:07:24 INFO - "stage_platform": "macosx64", 12:07:24 INFO - "builduid": "9343ee82ee7647bca7e55bf4f6e6e44f", 12:07:24 INFO - "slavebuilddir": "test" 12:07:24 INFO - } 12:07:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 12:07:24 INFO - Running post-action listener: _resource_record_post_action 12:07:24 INFO - [mozharness: 2017-01-04 20:07:24.943468Z] Finished read-buildbot-config step (success) 12:07:24 INFO - [mozharness: 2017-01-04 20:07:24.943568Z] Running download-and-extract step. 12:07:24 INFO - Running pre-action listener: _resource_record_pre_action 12:07:24 INFO - Running main action method: download_and_extract 12:07:24 INFO - mkdir: /builds/slave/test/build/tests 12:07:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:24 INFO - trying https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 12:07:24 INFO - Downloading https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/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 12:07:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/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 12:07:25 INFO - Downloaded 1199 bytes. 12:07:25 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:07:25 INFO - Using the following test package requirements: 12:07:25 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:07:25 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:07:25 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 12:07:25 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:07:25 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 12:07:25 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:07:25 INFO - u'jsshell-mac.zip'], 12:07:25 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:07:25 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 12:07:25 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:07:25 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:07:25 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 12:07:25 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:07:25 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 12:07:25 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:07:25 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 12:07:25 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:07:25 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 12:07:25 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:07:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:07:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:07:25 INFO - Fetch https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:07:26 INFO - Http code: 200 12:07:26 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 12:07:26 INFO - x-amz-cf-id: QbQQw4mHEnS3s7E569vFCOnZGUAYwkGo06jg1qUlRIYOtvuq6QXK9g== 12:07:26 INFO - x-amz-version-id: 9YHk.4rMyWjt8uozL4uIU15WsnwCf_Wi 12:07:26 INFO - Expected file size: 16269729 12:07:26 INFO - Obtained file size: 16269729 12:07:33 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 12:07:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:07:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:07:33 INFO - Fetch https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:07:33 INFO - Http code: 200 12:07:33 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 12:07:33 INFO - x-amz-cf-id: dOGLlNMz3UG4s29IKSURKgx2ypTKscWFCPSOwAwALwORqcXEHV2wnQ== 12:07:33 INFO - x-amz-version-id: 9YHk.4rMyWjt8uozL4uIU15WsnwCf_Wi 12:07:34 INFO - Expected file size: 16269729 12:07:34 INFO - Obtained file size: 16269729 12:07:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 12:07:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:07:40 INFO - Fetch https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 12:07:41 INFO - Http code: 200 12:07:41 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 12:07:41 INFO - x-amz-cf-id: vq2S5JM6eQbCXbg9wRq3CT4mwdeNYR9__4kNnvw-lgQR4sfHfnk-Zg== 12:07:41 INFO - x-amz-version-id: jxZZtD1J6FldNcvzKMhjki6ey7RzgZit 12:07:41 INFO - Expected file size: 14409038 12:07:41 INFO - Obtained file size: 14409038 12:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:42 INFO - trying https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:07:42 INFO - Downloading https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 12:07:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/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 12:07:45 INFO - Downloaded 109835763 bytes. 12:07:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:07:45 INFO - mkdir: /builds/slave/test/properties 12:07:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:07:45 INFO - Writing to file /builds/slave/test/properties/build_url 12:07:45 INFO - Contents: 12:07:45 INFO - build_url:https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:07:45 INFO - Running post-action listener: _resource_record_post_action 12:07:45 INFO - Running post-action listener: set_extra_try_arguments 12:07:45 INFO - [mozharness: 2017-01-04 20:07:45.952126Z] Finished download-and-extract step (success) 12:07:45 INFO - [mozharness: 2017-01-04 20:07:45.952260Z] Running populate-webroot step. 12:07:45 INFO - Running pre-action listener: _resource_record_pre_action 12:07:45 INFO - Running main action method: populate_webroot 12:07:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:07:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:07:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:07:45 INFO - '--tppagecycles', 12:07:45 INFO - '7'], 12:07:45 INFO - 'tests': ['tsvgm']}}, 12:07:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:07:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:07:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:07:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:07:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:07:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:07:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:45 INFO - 'talos_options': ['--disable-e10s'], 12:07:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:45 INFO - 'talos_options': ['--disable-e10s'], 12:07:45 INFO - 'tests': ['damp', 'tps']}, 12:07:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:45 INFO - 'tests': ['damp', 'tps']}, 12:07:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:07:45 INFO - 'tests': ['dromaeo_dom']}, 12:07:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:07:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:07:45 INFO - 'tests': ['basic_compositor_video']}, 12:07:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:07:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:07:45 INFO - 'tests': ['a11yr', 12:07:45 INFO - 'ts_paint', 12:07:45 INFO - 'tpaint', 12:07:45 INFO - 'sessionrestore', 12:07:45 INFO - 'sessionrestore_no_auto_restore', 12:07:45 INFO - 'tabpaint']}, 12:07:45 INFO - 'other-e10s': {'tests': ['a11yr', 12:07:45 INFO - 'ts_paint', 12:07:45 INFO - 'tpaint', 12:07:45 INFO - 'sessionrestore', 12:07:45 INFO - 'sessionrestore_no_auto_restore', 12:07:45 INFO - 'tabpaint']}, 12:07:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:07:45 INFO - 'ts_paint', 12:07:45 INFO - 'tpaint', 12:07:45 INFO - 'sessionrestore', 12:07:45 INFO - 'sessionrestore_no_auto_restore', 12:07:45 INFO - 'tabpaint']}, 12:07:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:07:45 INFO - 'ts_paint', 12:07:45 INFO - 'tpaint', 12:07:45 INFO - 'sessionrestore', 12:07:45 INFO - 'sessionrestore_no_auto_restore', 12:07:45 INFO - 'tabpaint']}, 12:07:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:07:45 INFO - 'tests': ['a11yr', 12:07:45 INFO - 'ts_paint', 12:07:45 INFO - 'tpaint', 12:07:45 INFO - 'sessionrestore', 12:07:45 INFO - 'sessionrestore_no_auto_restore', 12:07:45 INFO - 'tabpaint']}, 12:07:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:07:45 INFO - 'tests': ['a11yr', 12:07:45 INFO - 'ts_paint', 12:07:45 INFO - 'tpaint', 12:07:45 INFO - 'sessionrestore', 12:07:45 INFO - 'sessionrestore_no_auto_restore', 12:07:45 INFO - 'tabpaint']}, 12:07:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:07:45 INFO - 'tests': ['tsvgx', 12:07:45 INFO - 'tsvgr_opacity', 12:07:45 INFO - 'tart', 12:07:45 INFO - 'tscrollx', 12:07:45 INFO - 'cart']}, 12:07:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:07:45 INFO - 'tsvgr_opacity', 12:07:45 INFO - 'tart', 12:07:45 INFO - 'tscrollx', 12:07:45 INFO - 'cart']}, 12:07:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:45 INFO - 'talos_options': ['--disable-e10s'], 12:07:45 INFO - 'tests': ['tp5o']}, 12:07:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:45 INFO - 'tests': ['tp5o']}, 12:07:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:45 INFO - 'talos_options': ['--disable-e10s', 12:07:45 INFO - '--xperf_path', 12:07:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:45 INFO - 'tests': ['tp5n']}, 12:07:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:07:45 INFO - 'talos_options': ['--xperf_path', 12:07:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:45 INFO - 'tests': ['tp5n']}}, 12:07:45 INFO - 'talos.zip': {'path': '', 12:07:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:07:45 INFO - Running post-action listener: _resource_record_post_action 12:07:45 INFO - [mozharness: 2017-01-04 20:07:45.971338Z] Finished populate-webroot step (success) 12:07:45 INFO - [mozharness: 2017-01-04 20:07:45.971428Z] Running create-virtualenv step. 12:07:45 INFO - Running pre-action listener: _resource_record_pre_action 12:07:45 INFO - Running main action method: create_virtualenv 12:07:45 INFO - Creating virtualenv /builds/slave/test/build/venv 12:07:45 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 12:07:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:07:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:07:46 INFO - Using real prefix '/tools/python27' 12:07:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:07:46 INFO - Installing distribute.............................................................................................................................................................................................done. 12:07:49 INFO - Installing pip.................done. 12:07:49 INFO - Return code: 0 12:07:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:07:49 INFO - Reading from file tmpfile_stdout 12:07:49 INFO - Output received: 12:07:49 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) 12:07:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:07:49 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')]} 12:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:50 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 0x10a3ea490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a360570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0549950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1fed30>, '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 0x10a064db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1f6b20>, '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.lzanSV6RWb/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.Wy7eawhhkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:07:50 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 12:07:50 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 12:07:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lzanSV6RWb/Render', 12:07:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:50 INFO - 'HOME': '/Users/cltbld', 12:07:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:50 INFO - 'LOGNAME': 'cltbld', 12:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:50 INFO - 'MOZ_NO_REMOTE': '1', 12:07:50 INFO - 'NO_EM_RESTART': '1', 12:07:50 INFO - 'PAGER': '/bin/cat', 12:07:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:50 INFO - 'PWD': '/builds/slave/test', 12:07:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:50 INFO - 'SHELL': '/bin/bash', 12:07:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wy7eawhhkc/Listeners', 12:07:50 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:07:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:50 INFO - 'USER': 'cltbld', 12:07:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:50 INFO - 'XPC_FLAGS': '0x0', 12:07:50 INFO - 'XPC_SERVICE_NAME': '0', 12:07:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:50 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 12:07:50 INFO - Cleaning up... 12:07:50 INFO - Return code: 0 12:07:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:50 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 0x10a3ea490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a360570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0549950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1fed30>, '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 0x10a064db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1f6b20>, '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.lzanSV6RWb/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.Wy7eawhhkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:07:50 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 12:07:50 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 12:07:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lzanSV6RWb/Render', 12:07:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:50 INFO - 'HOME': '/Users/cltbld', 12:07:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:50 INFO - 'LOGNAME': 'cltbld', 12:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:50 INFO - 'MOZ_NO_REMOTE': '1', 12:07:50 INFO - 'NO_EM_RESTART': '1', 12:07:50 INFO - 'PAGER': '/bin/cat', 12:07:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:50 INFO - 'PWD': '/builds/slave/test', 12:07:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:50 INFO - 'SHELL': '/bin/bash', 12:07:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wy7eawhhkc/Listeners', 12:07:50 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:07:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:50 INFO - 'USER': 'cltbld', 12:07:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:50 INFO - 'XPC_FLAGS': '0x0', 12:07:50 INFO - 'XPC_SERVICE_NAME': '0', 12:07:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:50 INFO - Downloading/unpacking psutil>=3.1.1 12:07:50 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 12:07:50 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 12:07:50 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 12:07:50 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 12:07:50 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 12:07:50 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 12:07:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:07:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:54 INFO - Installing collected packages: psutil 12:07:54 INFO - Running setup.py install for psutil 12:07:54 INFO - building 'psutil._psutil_osx' extension 12:07:54 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 12:07:54 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 12:07:54 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 12:07:54 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 12:07:54 INFO - building 'psutil._psutil_posix' extension 12:07:54 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 12:07:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:07:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:07:54 INFO - ^ 12:07:55 INFO - 1 warning generated. 12:07:55 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 12:07:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:55 INFO - Successfully installed psutil 12:07:55 INFO - Cleaning up... 12:07:55 INFO - Return code: 0 12:07:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:07: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')]} 12:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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')]} 12:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a3ea490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a360570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0549950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1fed30>, '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 0x10a064db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1f6b20>, '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.lzanSV6RWb/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.Wy7eawhhkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:07: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:07: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 mozsystemmonitor==0.3 12:07:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lzanSV6RWb/Render', 12:07:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:55 INFO - 'HOME': '/Users/cltbld', 12:07:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:55 INFO - 'LOGNAME': 'cltbld', 12:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:55 INFO - 'MOZ_NO_REMOTE': '1', 12:07:55 INFO - 'NO_EM_RESTART': '1', 12:07:55 INFO - 'PAGER': '/bin/cat', 12:07:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:55 INFO - 'PWD': '/builds/slave/test', 12:07:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:55 INFO - 'SHELL': '/bin/bash', 12:07:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wy7eawhhkc/Listeners', 12:07:55 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:07:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:55 INFO - 'USER': 'cltbld', 12:07:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:55 INFO - 'XPC_FLAGS': '0x0', 12:07:55 INFO - 'XPC_SERVICE_NAME': '0', 12:07:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:07:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:07:58 INFO - Installing collected packages: mozsystemmonitor 12:07:58 INFO - Running setup.py install for mozsystemmonitor 12:07:58 INFO - Successfully installed mozsystemmonitor 12:07:58 INFO - Cleaning up... 12:07:58 INFO - Return code: 0 12:07:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:58 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 0x10a3ea490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a360570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0549950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1fed30>, '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 0x10a064db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1f6b20>, '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.lzanSV6RWb/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.Wy7eawhhkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:07:58 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 12:07:58 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 12:07:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lzanSV6RWb/Render', 12:07:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:58 INFO - 'HOME': '/Users/cltbld', 12:07:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:58 INFO - 'LOGNAME': 'cltbld', 12:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:58 INFO - 'MOZ_NO_REMOTE': '1', 12:07:58 INFO - 'NO_EM_RESTART': '1', 12:07:58 INFO - 'PAGER': '/bin/cat', 12:07:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:58 INFO - 'PWD': '/builds/slave/test', 12:07:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:58 INFO - 'SHELL': '/bin/bash', 12:07:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wy7eawhhkc/Listeners', 12:07:58 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:07:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:58 INFO - 'USER': 'cltbld', 12:07:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:58 INFO - 'XPC_FLAGS': '0x0', 12:07:58 INFO - 'XPC_SERVICE_NAME': '0', 12:07:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:58 INFO - Downloading/unpacking jsonschema==2.5.1 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:08:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:08:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:08:01 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 12:08:01 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 12:08:01 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 12:08:01 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 12:08:01 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 12:08:01 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 12:08:01 INFO - Downloading functools32-3.2.3-2.tar.gz 12:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:08:01 INFO - warning: no files found matching '*.txt' 12:08:01 INFO - no previously-included directories found matching 'build' 12:08:01 INFO - no previously-included directories found matching 'dist' 12:08:01 INFO - no previously-included directories found matching '.git*' 12:08:01 INFO - Installing collected packages: jsonschema, functools32 12:08:02 INFO - Running setup.py install for functools32 12:08:02 INFO - warning: no files found matching '*.txt' 12:08:02 INFO - no previously-included directories found matching 'build' 12:08:02 INFO - no previously-included directories found matching 'dist' 12:08:02 INFO - no previously-included directories found matching '.git*' 12:08:02 INFO - Successfully installed jsonschema functools32 12:08:02 INFO - Cleaning up... 12:08:02 INFO - Return code: 0 12:08:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:08:02 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')]} 12:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:02 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')]} 12:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:02 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 0x10a3ea490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a360570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0549950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1fed30>, '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 0x10a064db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1f6b20>, '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.lzanSV6RWb/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.Wy7eawhhkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:02 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 12:08:02 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 12:08:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lzanSV6RWb/Render', 12:08:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:02 INFO - 'HOME': '/Users/cltbld', 12:08:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:02 INFO - 'LOGNAME': 'cltbld', 12:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:02 INFO - 'MOZ_NO_REMOTE': '1', 12:08:02 INFO - 'NO_EM_RESTART': '1', 12:08:02 INFO - 'PAGER': '/bin/cat', 12:08:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:02 INFO - 'PWD': '/builds/slave/test', 12:08:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:02 INFO - 'SHELL': '/bin/bash', 12:08:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wy7eawhhkc/Listeners', 12:08:02 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:08:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:02 INFO - 'USER': 'cltbld', 12:08:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:02 INFO - 'XPC_FLAGS': '0x0', 12:08:02 INFO - 'XPC_SERVICE_NAME': '0', 12:08:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:08:02 INFO - Cleaning up... 12:08:02 INFO - Return code: 0 12:08:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:08:02 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')]} 12:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:02 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')]} 12:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:02 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 0x10a3ea490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a360570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0549950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1fed30>, '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 0x10a064db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1f6b20>, '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.lzanSV6RWb/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.Wy7eawhhkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:02 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 12:08:02 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 12:08:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lzanSV6RWb/Render', 12:08:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:02 INFO - 'HOME': '/Users/cltbld', 12:08:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:02 INFO - 'LOGNAME': 'cltbld', 12:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:02 INFO - 'MOZ_NO_REMOTE': '1', 12:08:02 INFO - 'NO_EM_RESTART': '1', 12:08:02 INFO - 'PAGER': '/bin/cat', 12:08:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:02 INFO - 'PWD': '/builds/slave/test', 12:08:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:02 INFO - 'SHELL': '/bin/bash', 12:08:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wy7eawhhkc/Listeners', 12:08:02 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:08:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:02 INFO - 'USER': 'cltbld', 12:08:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:02 INFO - 'XPC_FLAGS': '0x0', 12:08:02 INFO - 'XPC_SERVICE_NAME': '0', 12:08:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:02 INFO - Downloading/unpacking blobuploader==1.2.4 12:08:02 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 12:08:02 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 12:08:02 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 12:08:02 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 12:08:02 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 12:08:02 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 12:08:05 INFO - Downloading blobuploader-1.2.4.tar.gz 12:08:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:08:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:08:05 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 12:08:05 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 12:08:05 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 12:08:05 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 12:08:05 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 12:08:05 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 12:08:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:08:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:08:06 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 12:08:06 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 12:08:06 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 12:08:06 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 12:08:06 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 12:08:06 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 12:08:06 INFO - Downloading docopt-0.6.1.tar.gz 12:08:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:08:06 INFO - Installing collected packages: blobuploader, requests, docopt 12:08:06 INFO - Running setup.py install for blobuploader 12:08:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:08:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:08:06 INFO - Running setup.py install for requests 12:08:07 INFO - Running setup.py install for docopt 12:08:07 INFO - Successfully installed blobuploader requests docopt 12:08:07 INFO - Cleaning up... 12:08:07 INFO - Return code: 0 12:08:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:07 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 0x10a3ea490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a360570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0549950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1fed30>, '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 0x10a064db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1f6b20>, '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.lzanSV6RWb/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.Wy7eawhhkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:07 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 12:08:07 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 12:08:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lzanSV6RWb/Render', 12:08:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:07 INFO - 'HOME': '/Users/cltbld', 12:08:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:07 INFO - 'LOGNAME': 'cltbld', 12:08:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:07 INFO - 'MOZ_NO_REMOTE': '1', 12:08:07 INFO - 'NO_EM_RESTART': '1', 12:08:07 INFO - 'PAGER': '/bin/cat', 12:08:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:07 INFO - 'PWD': '/builds/slave/test', 12:08:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:07 INFO - 'SHELL': '/bin/bash', 12:08:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wy7eawhhkc/Listeners', 12:08:07 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:08:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:07 INFO - 'USER': 'cltbld', 12:08:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:07 INFO - 'XPC_FLAGS': '0x0', 12:08:07 INFO - 'XPC_SERVICE_NAME': '0', 12:08:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:07 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-GApegH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:07 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-LwXag_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:08 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-KytgaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:08 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-eC2zaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:08 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-eDq86t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:08 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-u8sFP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:08 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-dYzDjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:08 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-jbmQba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:08 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Grf7Sb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:08 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-GKgej1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:08 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-HOfUEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:08 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-authO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-iENYoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-KOF3af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-wVjAsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-3YxxAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:09 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-osIIQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:08:09 INFO - Running setup.py install for manifestparser 12:08:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:08:09 INFO - Running setup.py install for mozcrash 12:08:09 INFO - Running setup.py install for mozdebug 12:08:09 INFO - Running setup.py install for mozdevice 12:08:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:08:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:08:10 INFO - Running setup.py install for mozfile 12:08:10 INFO - Running setup.py install for mozhttpd 12:08:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:08:10 INFO - Running setup.py install for mozinfo 12:08:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:08:10 INFO - Running setup.py install for mozInstall 12:08:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:08:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:08:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:08:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:08:10 INFO - Running setup.py install for mozleak 12:08:10 INFO - Running setup.py install for mozlog 12:08:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:08:11 INFO - Running setup.py install for moznetwork 12:08:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:08:11 INFO - Running setup.py install for mozprocess 12:08:11 INFO - Running setup.py install for mozprofile 12:08:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:08:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:08:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:08:11 INFO - Running setup.py install for mozrunner 12:08:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:08:11 INFO - Running setup.py install for mozscreenshot 12:08:11 INFO - Running setup.py install for moztest 12:08:12 INFO - Running setup.py install for mozversion 12:08:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:08:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:08:12 INFO - Cleaning up... 12:08:12 INFO - Return code: 0 12:08:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:12 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 0x10a3ea490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a360570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0549950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1fed30>, '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 0x10a064db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1f6b20>, '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.lzanSV6RWb/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.Wy7eawhhkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:12 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 12:08:12 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 12:08:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lzanSV6RWb/Render', 12:08:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:12 INFO - 'HOME': '/Users/cltbld', 12:08:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:12 INFO - 'LOGNAME': 'cltbld', 12:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:12 INFO - 'MOZ_NO_REMOTE': '1', 12:08:12 INFO - 'NO_EM_RESTART': '1', 12:08:12 INFO - 'PAGER': '/bin/cat', 12:08:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:12 INFO - 'PWD': '/builds/slave/test', 12:08:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:12 INFO - 'SHELL': '/bin/bash', 12:08:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wy7eawhhkc/Listeners', 12:08:12 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:08:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:12 INFO - 'USER': 'cltbld', 12:08:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:12 INFO - 'XPC_FLAGS': '0x0', 12:08:12 INFO - 'XPC_SERVICE_NAME': '0', 12:08:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:12 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-cq3LRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:12 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)) 12:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:12 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-lss0de-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:12 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)) 12:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:12 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-m5OhCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:12 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)) 12:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:12 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-FGQc9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:12 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)) 12:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:12 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-TKi19v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:12 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)) 12:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:12 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-UHs0Km-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:13 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)) 12:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-FBRcW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:13 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)) 12:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-g0IY0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:13 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)) 12:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-q9pdDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:13 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)) 12:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Blva_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:13 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)) 12:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-7VnUzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:13 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)) 12:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-JEovEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:13 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)) 12:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-cD3vaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:13 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)) 12:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-DnqIc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:13 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)) 12:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Ev81zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:14 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)) 12:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-f2qios-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Q0d3DH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:14 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)) 12:08:14 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)) 12:08:14 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)) 12:08:14 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)) 12:08:14 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)) 12:08:14 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)) 12:08:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:08:14 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 12:08:14 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 12:08:14 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 12:08:14 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 12:08:14 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 12:08:14 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 12:08:16 INFO - Downloading blessings-1.6.tar.gz 12:08:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:08:17 INFO - Installing collected packages: blessings 12:08:17 INFO - Running setup.py install for blessings 12:08:17 INFO - Successfully installed blessings 12:08:17 INFO - Cleaning up... 12:08:17 INFO - Return code: 0 12:08:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:08:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:08:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:08:17 INFO - Reading from file tmpfile_stdout 12:08:17 INFO - Current package versions: 12:08:17 INFO - blessings == 1.6 12:08:17 INFO - blobuploader == 1.2.4 12:08:17 INFO - docopt == 0.6.1 12:08:17 INFO - functools32 == 3.2.3-2 12:08:17 INFO - jsonschema == 2.5.1 12:08:17 INFO - manifestparser == 1.1 12:08:17 INFO - mozInstall == 1.12 12:08:17 INFO - mozcrash == 1.0 12:08:17 INFO - mozdebug == 0.1 12:08:17 INFO - mozdevice == 0.48 12:08:17 INFO - mozfile == 1.2 12:08:17 INFO - mozhttpd == 0.7 12:08:17 INFO - mozinfo == 0.9 12:08:17 INFO - mozleak == 0.1 12:08:17 INFO - mozlog == 3.4 12:08:17 INFO - moznetwork == 0.27 12:08:17 INFO - mozprocess == 0.23 12:08:17 INFO - mozprofile == 0.28 12:08:17 INFO - mozrunner == 6.13 12:08:17 INFO - mozscreenshot == 0.1 12:08:17 INFO - mozsystemmonitor == 0.3 12:08:17 INFO - moztest == 0.8 12:08:17 INFO - mozversion == 1.4 12:08:17 INFO - psutil == 3.1.1 12:08:17 INFO - requests == 1.2.3 12:08:17 INFO - wsgiref == 0.1.2 12:08:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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')]} 12:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:18 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 0x10a3ea490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a360570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0549950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1fed30>, '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 0x10a064db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1f6b20>, '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.lzanSV6RWb/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.Wy7eawhhkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:18 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 12:08:18 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 12:08:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lzanSV6RWb/Render', 12:08:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:18 INFO - 'HOME': '/Users/cltbld', 12:08:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:18 INFO - 'LOGNAME': 'cltbld', 12:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:18 INFO - 'MOZ_NO_REMOTE': '1', 12:08:18 INFO - 'NO_EM_RESTART': '1', 12:08:18 INFO - 'PAGER': '/bin/cat', 12:08:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:18 INFO - 'PWD': '/builds/slave/test', 12:08:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:18 INFO - 'SHELL': '/bin/bash', 12:08:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wy7eawhhkc/Listeners', 12:08:18 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:08:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:18 INFO - 'USER': 'cltbld', 12:08:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:18 INFO - 'XPC_FLAGS': '0x0', 12:08:18 INFO - 'XPC_SERVICE_NAME': '0', 12:08:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:18 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)) 12:08:18 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)) 12:08:18 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)) 12:08:18 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)) 12:08:18 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)) 12:08:18 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)) 12:08:18 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)) 12:08:18 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)) 12:08:18 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)) 12:08:18 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)) 12:08:18 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)) 12:08:18 INFO - Cleaning up... 12:08:18 INFO - Return code: 0 12:08:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a3ea490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a360570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0549950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1fed30>, '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 0x10a064db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1f6b20>, '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.lzanSV6RWb/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.Wy7eawhhkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:08:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:08:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lzanSV6RWb/Render', 12:08:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:18 INFO - 'HOME': '/Users/cltbld', 12:08:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:18 INFO - 'LOGNAME': 'cltbld', 12:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:18 INFO - 'MOZ_NO_REMOTE': '1', 12:08:18 INFO - 'NO_EM_RESTART': '1', 12:08:18 INFO - 'PAGER': '/bin/cat', 12:08:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:18 INFO - 'PWD': '/builds/slave/test', 12:08:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:18 INFO - 'SHELL': '/bin/bash', 12:08:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wy7eawhhkc/Listeners', 12:08:18 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:08:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:18 INFO - 'USER': 'cltbld', 12:08:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:18 INFO - 'XPC_FLAGS': '0x0', 12:08:18 INFO - 'XPC_SERVICE_NAME': '0', 12:08:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:08:19 INFO - Cleaning up... 12:08:19 INFO - Return code: 0 12:08:19 INFO - Running post-action listener: _resource_record_post_action 12:08:19 INFO - Running post-action listener: _start_resource_monitoring 12:08:19 INFO - Starting resource monitoring. 12:08:19 INFO - [mozharness: 2017-01-04 20:08:19.269389Z] Finished create-virtualenv step (success) 12:08:19 INFO - [mozharness: 2017-01-04 20:08:19.270384Z] Running install step. 12:08:19 INFO - Running pre-action listener: _resource_record_pre_action 12:08:19 INFO - Running main action method: install 12:08:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:08:19 INFO - Reading from file tmpfile_stdout 12:08:19 INFO - Detecting whether we're running mozinstall >=1.0... 12:08:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:08:19 INFO - Reading from file tmpfile_stdout 12:08:19 INFO - Output received: 12:08:19 INFO - Usage: mozinstall [options] installer 12:08:19 INFO - Options: 12:08:19 INFO - -h, --help show this help message and exit 12:08:19 INFO - -d DEST, --destination=DEST 12:08:19 INFO - Directory to install application into. [default: 12:08:19 INFO - "/builds/slave/test"] 12:08:19 INFO - --app=APP Application being installed. [default: firefox] 12:08:19 INFO - mkdir: /builds/slave/test/build/application 12:08:19 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'] 12:08:19 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 12:08:50 INFO - Reading from file tmpfile_stdout 12:08:50 INFO - Output received: 12:08:50 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:08:50 INFO - Running post-action listener: _resource_record_post_action 12:08:50 INFO - [mozharness: 2017-01-04 20:08:50.978984Z] Finished install step (success) 12:08:50 INFO - [mozharness: 2017-01-04 20:08:50.979171Z] Running run-tests step. 12:08:50 INFO - Running pre-action listener: _resource_record_pre_action 12:08:50 INFO - Running main action method: run_tests 12:08:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:08:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:08:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:08:50 INFO - 2017-01-04 12:08:50.993 screenresolution[1622:7793] starting screenresolution argv=screenresolution get 12:08:50 INFO - 2017-01-04 12:08:50.996 screenresolution[1622:7793] Display 0: 1600x1200x32@60 12:08:51 INFO - 2017-01-04 12:08:51.002 screenresolution[1623:7795] starting screenresolution argv=screenresolution list 12:08:51 INFO - Available Modes on Display 0 12:08:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:08:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:08:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:08:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:08:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:08:51 INFO - Intel Iris: 12:08:51 INFO - Chipset Model: Intel Iris 12:08:51 INFO - Type: GPU 12:08:51 INFO - Bus: Built-In 12:08:51 INFO - VRAM (Dynamic, Max): 1536 MB 12:08:51 INFO - Vendor: Intel (0x8086) 12:08:51 INFO - Device ID: 0x0a2e 12:08:51 INFO - Revision ID: 0x0009 12:08:51 INFO - Displays: 12:08:51 INFO - Display: 12:08:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:08:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:08:51 INFO - Display Serial Number: 1600x1200 60 12:08:51 INFO - Main Display: Yes 12:08:51 INFO - Mirror: Off 12:08:51 INFO - Online: Yes 12:08:51 INFO - Rotation: Supported 12:08:51 INFO - Return code: 0 12:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:08:51 INFO - Python 2.7.3 12:08:51 INFO - Return code: 0 12:08:51 INFO - grabbing minidump binary from tooltool 12:08: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')]} 12:08:51 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 0x10a1fed30>, '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 0x10a064db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1f6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:08:51 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 12:08:51 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 12:08:51 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 12:08:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:08:51 INFO - Return code: 0 12:08:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:08:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:08:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:08:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:08:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:08:51 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/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:08:51 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/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0281 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lzanSV6RWb/Render', 12:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:51 INFO - 'HOME': '/Users/cltbld', 12:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:51 INFO - 'LOGNAME': 'cltbld', 12:08:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:08:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:51 INFO - 'MOZ_NO_REMOTE': '1', 12:08:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:08:51 INFO - 'NO_EM_RESTART': '1', 12:08:51 INFO - 'PAGER': '/bin/cat', 12:08:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:51 INFO - 'PWD': '/builds/slave/test', 12:08:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:51 INFO - 'SHELL': '/bin/bash', 12:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Wy7eawhhkc/Listeners', 12:08:51 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 12:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:51 INFO - 'USER': 'cltbld', 12:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:51 INFO - 'XPC_FLAGS': '0x0', 12:08:51 INFO - 'XPC_SERVICE_NAME': '0', 12:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:51 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/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:08:51 INFO - mozversion application_buildid: 20170104094639 12:08:51 INFO - mozversion application_changeset: a51cdeb88cbfee1e51234a575ccb8aa75130a07d 12:08:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:08:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:08:51 INFO - mozversion application_name: Firefox 12:08:51 INFO - mozversion application_remotingname: firefox-dev 12:08:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:08:51 INFO - mozversion application_vendor: Mozilla 12:08:51 INFO - mozversion application_version: 52.0a2 12:08:51 INFO - mozversion platform_buildid: 20170104094639 12:08:51 INFO - mozversion platform_changeset: a51cdeb88cbfee1e51234a575ccb8aa75130a07d 12:08:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:08:51 INFO - mozversion platform_version: 52.0a2 12:08:51 INFO - using testdate: 1483560531 12:08:51 INFO - actual date: 1483560531 12:08:51 INFO - starting webserver on 'localhost:49241' 12:08:51 INFO - SUITE-START | Running 2 tests 12:08:51 INFO - TEST-START | dromaeo_css 12:08:51 INFO - operating with platform_type : mac_ 12:08:51 INFO - Initialising browser for dromaeo_css test... 12:08:51 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpIQRZHz/profile http://localhost:49241/getInfo.html) 12:08:54 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 12:08:54 INFO - PROCESS | 1633 | colorDepth:24 12:08:54 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 12:08:54 INFO - PROCESS | 1633 | __metrics 12:08:54 INFO - PROCESS | 1633 | 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" 12:08:54 INFO - PROCESS | 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:08:55 INFO - TEST-INFO | 1633: exit 0 12:08:55 INFO - Browser initialized. 12:08:55 INFO - Running cycle 1/1 for dromaeo_css test... 12:08:55 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpIQRZHz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:09:07 INFO - PROCESS | 1635 | RSS: Main: 223252480 12:09:07 INFO - PROCESS | 1635 | 12:12:09 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 12:12:13 INFO - PROCESS | 1635 | RSS: Main: 523960320 12:12:13 INFO - PROCESS | 1635 | 12:15:16 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 12:15:17 INFO - PROCESS | 1635 | RSS: Main: 346714112 12:15:17 INFO - PROCESS | 1635 | 12:18:19 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 12:18:20 INFO - PROCESS | 1635 | RSS: Main: 332681216 12:18:20 INFO - PROCESS | 1635 | 12:21:22 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 12:21:23 INFO - PROCESS | 1635 | RSS: Main: 325701632 12:21:23 INFO - PROCESS | 1635 | 12:24:26 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 12:24:27 INFO - PROCESS | 1635 | RSS: Main: 317919232 12:24:27 INFO - PROCESS | 1635 | 12:27:30 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 12:27:30 INFO - PROCESS | 1635 | RSS: Main: 364007424 12:27:30 INFO - PROCESS | 1635 | 12:27:30 INFO - PROCESS | 1635 | __start_tp_report 12:27:30 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 12:27:30 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 12:27:30 INFO - PROCESS | 1635 | |i|pagename|runs| 12:27:30 INFO - PROCESS | 1635 | |0;dojo.html;2871.9282017949563;3081.907542773717;3289.276359200975;3304.7356211503075;3363;5212.582993360532;5452.100403433429;5491.395946445888;5518.475744804237;5539.639923404986;5434.103372943469;5533.114701647737;5542.362628297747;5556.91664625031;5560.749766260518;5060.620453465986;5061.1143049966295;5088.338515992925;5158.974205128988;5184.326037615105;5010.549050585447;5038.345015148026;5051.924221136685;5066.544011039005;5099.541041306282;5088.720120393377;5143.922841157367;5171.301874246985;5186.299849520305;5193.298904647881;5160.200168973796;5162.948370516285;5163.251328557376;5164.6384753067305;5214.426413094557;4937.654364194508;4942.456329803718;5087.160618497944;5104.055749686283;5125.1287281162295;5445.237666726662;5455.809046683347;5480.150576660624;5498.615096943216;5507.944920550824;4947.480514545957;5865.472107510302;5949.25634295713;5950.732217050243;5968.910466343008;6052.152698622196;6060.272767267956;6062.302835173923;6063.393660633946;6076.544259180535;5550.5282051025715;5556.611037227396;5562.638428502175;5569.693666848362;5575.69333686647;6116.052011938156;6116.609690437402;6120.877582448326;6128.197586012551;6135.2326666699655;5920.526357891358;5941.732622032019;5949.1076338549565;5949.702514874239;5962.3739507351975;6095.451409373089;6109.572329936951;6119.051547010221;6122.173506576644;6128.754849806047;5889.4699477046415;5964.284285885721;5969.940300597025;5977.491913187341;6035.969820150871;5070.898582028339;5894.027485464936;5898.675572843535;5936.673482958392;6051.546134039877;4743.335932969318;4751.619870410359;4758.5241475852135;4777.163996300634;4796.064767370399;4164.3961625564125;4185.183889141649;4232.280512312914;4242.257604919188;4270.402143699885;5608.270924779834;5623.775048998076;5667.574931880043;5684.630499017633;5697.4302569742695;6034.607750496291;6125.908111378333;6130.601510901866;6149.3235744068115;6151.046587778901;5662.150677398423;5670.858228544319;5676.205331253628;5681.57388195887;5701.116326969326;5122.538971492583;5172.534471897472;5184.092783762826;5191.740412979336;5223.582113430916;4898.314236007033;6169.290531588925;6200.255969283715;6233.657148856675;6329.430351268408;5991.6405015697455;6052.727627256872;6066.18106555627;6070.817875463743;6086.087086936995;6180.6600636965395;6269.937300626935;6270.184875966095;6270.4670103039925;6295.370462953668;5835.153902684002;5969.462748352669;5969.850753731018;5977.581569290108;5978.760849565969;5895.115732640138;6058.7273572688455;6090.177825993612;6115.510759139169;6122.602030867979;5715;5758.884822303614;5766.596338256281;5822;5859.121131830259;5770.394108618702;5776.768929242783;5787.160861675117;5787.305523337226;5798.536117110705;5660.547156227574;5661.631993920382;5666.575006874418;5670.461306175906;5675.716214189357;5633.155026746021;5751.396103409082;5762.337331206797;5767.7981270657;5777.7977770777825;5631.1271752878365;5634.380218176079;5673.8297851064535;5685.317761868602;5716.28546431696 12:27:30 INFO - PROCESS | 1635 | |1;ext.html;467.6843130886651;504.42747481608365;526.918327659213;537.6263496869675;568.8293511946415;30008.549871751915;31508.369832603363;39023.80488097556;39723.40414893779;39934.400983985266;40951.59048409515;41321.58678413215;41395.17209655805;41397.37903931444;41611.79194104033;9154.313426493023;9331.486768227745;9333.53332333384;9337.71986840396;9352.251819854395;8424.705135320264;8604.39769216155;11014.39420831854;11188.440577971109;11220.214584979014;9197.724068277963;11177.66467005991;11261.493232804543;11273.943630281838;11275.54897804091;8405.411621186458;11092.001719845211;11174;11177.049950754195;11196.608118715847;8373.539455329954;10822.458877056195;11131.833022504668;11133.498992545356;11258.437078146142;38595.421068683994;38950;38992.02519936996;39024;39049.80475097635;3282.491213861855;3355.2953879685297;3442.8278586070596;3792.1278106035766;3835.4822099016833;28496.857515712498;28518.572221416696;29880;29883.850580747174;30597.388052239054;3139.183812208842;3189.6012998375204;3209.903702888917;3214.791038582485;3221.7261532769744;474301;479422;482337;482471;485965;32220;33160.834195828866;57972.710136449045;58458.415415846146;58475.122873156935;34497.655023449945;34876.30247395038;34887;34910.301793963976;34960.650393496246;14156.787648185285;14253.50112746049;14274.286285685881;14274.571762847132;14306.856931430553;10941.726456838644;12037.699057523632;12042.81935770964;12056.517739290506;12071.939640301742;7243.0946131733535;7546.660400282;7781.144074151839;7802.843943121105;7814.296713295831;3141.8429078545983;3150.401423729484;3206.903792886217;3212.3254116635767;3228.2090887732656;921.9262459003263;974;985.049427302642;1002.7493126718321;1003.814294355532;29169.124926252247;29273.85363073171;29616.407671846442;29747.256318592208;29836.701632983826;916.7753900294338;1053.1791048472867;1056.915446764257;1140.1391949078393;1179.1156632525606;15317.540473785804;15328.003679760779;15414.22928853553;15532.611684707746;15757;677674;682891;686936;687063;689723;56213.43786562082;56857.43142568604;56951;56973.71513142408;56988.71505642445;1205.4394706461537;1240.7332423528803;1241.30486927321;1293.6895145164904;1344.6907211341252;29120.126396208147;30170.84914575413;30327.92971166935;30428.543571845996;30830.84584577063;40668.1866362677;48030.03939921252;48315.999999998596;48458;48560.757196213795;517971;523207;524980;525573;527868;26045.348866277738;26114.955401784475;26459.470810584065;27329.180124596292;27391.17826465209;45043.549564503934;45124.548754512034;45417;45597.54402455933;45607.77196114131;36689.89930302095;36943.37548103418;37495.062623434635;38068.238635227695;38881.416778748906;37131.814340928126;37239.441408378356;37362.43956340711;37698.43452348162;37751.433728494645;36372.81813591021;36514.26971460609;37168.6283137176;37170.62829371672;37366.439503406924;31559.52660710045;32404.055756654678;33536;33738.3252334947;33917.982460526226;22373.77626223717;25024.49951001006;25090.372740680898;25432.254163961312;25521.36196595026 12:27:30 INFO - PROCESS | 1635 | |2;jquery.html;349.18117015598415;368.4841222288795;369.89827797355724;377.26056928420286;392.0668808236397;14329.211893345862;14726.852731472682;15598.298076586567;19761.40715778529;20171.899140504276;18855.43433696988;19246.42260732177;20281.69577456339;20500.384988450332;20534.794652053475;4499.61753250974;5566.165075238706;5574.777008919658;5579.804706835262;5585.413531579186;5473.726313684319;5547.417521160261;6030.788922387717;6090.543209259301;6092.390760923917;6049.1228771828055;6051.394860513936;6061.818145455643;6069.726862291208;6071.544634152434;5423.023855705971;5445.50990410861;5481.534069604048;5482.150266708666;5494.780208791643;5117.104506711348;5325.387580428262;5334.866628334284;5336.546393556552;5336.733163341856;13324.400401981935;13605.183688978694;13623.523176688868;13636.795448068287;13657.04400691952;2143.678448232762;2310.849794763335;2354.6468029795496;2366.7633236676365;2855.8286502809897;10349.430781307025;10405.323653962469;12621.927136193435;14339.71320573593;14811.777823332552;1841.815818418161;1845.2177446127582;1944.6110777844488;1951.717001034842;2052.958940821175;112121;121626.39186804098;123981;126369.73630263671;127115;10955.397453140155;11507.76984460306;11514.194006419637;11564;11758.05935525156;14613.78079328811;14654.974151809483;14662.926685366718;14663.19352435605;14679.119252844863;6650.933490665128;8407.243348098578;8451;8592.097829727914;8618.052014278424;8348.916510834935;8364.330853531703;8385.6226469809;8526.23263906239;8547.145285471403;3691.981540092336;4363;4453.799579018952;4520.344550040291;4523.163214805206;1855.461916044359;1908.59919416924;1912.7429633033155;1914.258025197064;1940.9708854367195;817.3665409307715;899.8605216191501;903.4875014256437;920.4385324951774;949.5299826585746;20723.58552828935;20744;21569.78430215709;21613.891930540245;21646.56706865854;846.8475674378548;902.8194361127669;912.8493798523169;928.9256859451363;951.1534734086669;10931.18016148776;11260.94369528147;11320.547178112947;11352.772944541062;11412.372319522507;561249;579554;604601;627064;628199.859000702;125070;130337;131965;133880;134417.65582344052;984.8178087053918;993.9701808945744;1020.132887046005;1022.0290723812408;1022.8670272864629;18715.532111697317;18904.621907562047;19019.42941711751;19096.23615055382;19116.66183367151;51803;54661.45338546563;55108;56155;56461;72639.63680181565;73407.53184936378;76519;77137;77788.2221177781;7703.460757746904;7989.961305030309;8123.268905798506;8420.284275836622;8424.368172387218;10521.263511554118;11625.069994400597;11852.881471185177;13054.477820887058;13154.40805163789;11047.226694131334;11272.83090753623;11412.543498259976;11527.308361498337;11533.192676512563;10273.229507787095;10525.578976840841;10621.628243011457;10803.243772935819;10994;11331.660050198507;11427.428628568705;11585.304881707125;11762.470688818852;11814.101346555444;8047.637856296597;8138.348932085537;8176.305014073871;8270.462419942685;8278.296344810518;8359;11176.217664763713;11267.26762760418;11443.942780286045;11451.083913287084 12:27:30 INFO - PROCESS | 1635 | |3;mootools.html;1308.2804457548345;1471.683588028574;1498.2059508460516;1667.0914351678334;1849.2325684840782;7442.739504117357;7758.991331126953;8114.634841432135;8354.832903341929;8409.159084091587;8145.0633177184645;8232.46488978216;8232.95883520583;8242.29940455062;8248.463849849755;2277.544491101778;2313.81489480842;2319.652052192176;2320.1995311617466;2323.9883800580974;2304.7349554801162;2333.53329334133;2365.3968238099346;2368.0882860098814;2372.8694921779297;2296.4488522754586;2305.4351683837485;2327.2203811723093;2328.6856274402994;2331.230693871027;2201.735791705006;2203.4711669199355;2224.0547767198877;2225.1321984426004;2227.3206671965045;2313.9190128345426;2334.8365614407;2337.39227800773;2340.6957095577636;2342.847714898526;8153.9184608153755;8156.225158609923;8352.958235208785;8362.540060296682;8374.330053595697;5010.5240002199735;5181.093308671006;5182;5191.273221748958;5196.038732834438;3386.8983930482127;3527.6295988921306;3545.1137215696076;3599.892003239907;3602.3155600435834;1064.7284942339732;1076.7792602516545;1084.3710647824244;1089.2320913755784;1104.0725790336155;10823.24237303389;10859.565617375296;10907.181961352851;10932.890671093266;10933.890661093366;4183.100633363781;4354.30182228931;4434.224010798098;4449.933251001237;4453.888652783641;8628.870566941501;8641.956790216009;8645.481271123752;8650.048494665582;8660.566971651393;4988.5260900214425;5060.645754797202;5061.493850614909;5063.620228482879;5090.643654944119;3819.413361978936;4269.4449721536;4391.231534481453;4620.491745907947;4762.857114286577;3552.289542091592;3596.4425514045365;3599.6760291573883;3649.361361761681;3668.596454390015;1100.1638754546602;1107.4407424250862;1107.4462768615692;1116.0792346314324;1117.0839911272678;821.6056292979404;877.2981614708208;894.552723638181;906.9410488318238;934.9859752103724;5473.8357849264585;5658.349289831722;5733.254676892061;5861.677607731531;5875.6474611523445;817.4550100310418;922.8833111934634;931.9114684104999;945.3193700535741;966.21253678252;3714.405695088773;3783.1109689223013;3802.5627052888717;3811.5616704079384;3946.625070618287;8425.578721064045;10179.84730229047;10302.124319432933;10318.43248621333;10337.9483102584;10246.53890574911;10249.231307651957;10257.769067711923;10263.332883362558;10298.3820970742;7534.095908490906;7782.338501227459;7888.053433588007;7919.762407127795;7972.3622110231045;5522;5696.515796157208;5827.94172058274;5848.239728835224;5880.794172203952;7827.608619569112;9144.63421463134;9208.355415120877;9229.907700923173;9383.483908385104;8794.824103518022;9470.668526601536;9492.905070949479;9524.190443812353;9660.758481037748;7132;7305.963470182615;7307.086614173229;7310.342069213796;7317.597532135784;7717.729879454192;7785.805354866174;7786.377089832913;7871.48835325702;7878.684852605831;5871.236739224044;7129.215786263403;7130.679119439741;7179.210286868336;7199.8560028800175;7010.894836577353;7046.013558101768;7075.398591119812;7107.928920710727;7167.677454514455;6573;6607.438367738796;6694.966525167343;6703.229128650268;6724.4284235840505;4742.810287588458;4813.109574728686;4850.442199147144;4868.318435419115;4994.650374473752;4804.567588917014;4892.584130348818;4893.755312234302;4935.851924442272;4962.727050012284 12:27:30 INFO - PROCESS | 1635 | |4;prototype.html;256.76377732486117;302.31374779251087;307.1522597630539;312.23969633941357;316.4414807864119;4777.450593181785;4978.701277923327;5013.298138260647;5032.144535428976;5110.923336149952;3475.669811367922;4195.685323600727;4214.915701685964;4678.766061696919;5012.5964729875695;3173.6667649896776;3858.344081506151;3866.516685414323;3910.3743401055835;3922.9604154898975;3226.677332266778;3844.8462061517644;3866.8098818474873;3879.7672139671567;3888.7666739995548;3352.8155951422664;3706.4625629283737;3820.7964491185326;3860.8069596520063;3879.1465877507044;3512.1570823002553;3514.086337552255;3887.086534664352;3896.279188350164;3901.804909754501;3372.9325413491592;3384.5430866832908;3386.881459148918;3391.3895498810202;3399.1502124468884;4435.445569303837;4444.444444444457;4739.64919997799;4951.356323677935;5009.674371165863;4198.727082739642;4337.71804832688;4383.298672212431;4393.121375724868;4393.51671316155;5305.681659100466;5315.734213289359;5317.038806089488;5319.2287118368;5341.43914888935;3405.9489107663403;3718.958691566366;3730.2166545025575;3743.756655817363;3750.306193354238;4784.928226076577;5252.36971563411;5502.477264659851;5587.608867379285;5593.231350481497;3600.621934696819;3769.1707824278615;3776.792276424823;3782.224643947984;3788.9431658525145;3190.7766456348295;3393.321335732863;3837;3858.607663248804;3861.517310336208;4088.3049881520215;4415.028693687384;4421.557844215553;4432.246518091933;4437.689361744711;4918.532739389752;4990.326305948651;5269.552088072557;5346.866328341823;5353.544948679328;1097.5609756097465;1111.3109871879487;1260.7226410189744;1265.9746805063846;1270.7394984028438;693.7398475571661;750.4521699159644;834.7770516193805;836.8368168207141;881.1849039638437;4322.481302243751;4497.797599108048;4667.533246675305;4703.482616912205;4713.622910167179;5088.77100530477;5134.974325128351;5141.357330333708;5141.794328226829;5183.377994640668;4243.851465198703;4363.992563420862;4641.651876109306;4754.286856971482;4764.928526072112;2432.603885173103;2605.205412349214;2667.4265033017996;2686.764088519303;2708.7291270872756;5353;5376.973115134478;5377.865553361197;5398.703071331114;5401.729913504388;4568.79440425175;4697;4929.383827021622;4996.975015124901;5190.636655434083;4768.7615619218905;5098.490150984871;5312.681239125664;5349.81275655358;5363.436839131835;4320.351947207818;4466.431801713962;4704.694194877322;4928.876778080433;5116.590672746097;4935.432425270996;4993.425756038102;5046.949530504795;5391.75737091824;5453.372862120748;5020.347354843993;5759.136129580596;5763.51010164124;5768;5777.8555536111935;3676.375016247191;3743.36362818327;3744.801663467664;3746.550413950343;3765.284595926765;4442.111577684541;4502.527234640315;4874.463808980939;5493.2034854946605;5525.972370138124;4870.806652370192;5310.867228319323;5313.698143954756;5327.840164795062;5346.609881430778;4929.9014019720125;5306.602004849575;5327.520523152934;5344.786208551614;5373.865653358697;4111.527174374985;4495.820167193276;5067.163917953644;5125.872449902841;5219.817306394258;5128.205128205134;5221.608379371486;5228.764705588182;5278.645117777772;5312.0969435196885;4516.645006498103;4890.779914903766;5103.948960510347;5230.901510682799;5239.318888544465 12:27:30 INFO - PROCESS | 1635 | |5;yui.html;655.13849288186;684.9897251541227;691.1809505735704;702.3748863511472;751.3163021650298;13938.372773225205;14559.344829482672;14588.635284117903;14591.489297874563;14615.415383384678;12840.61478155657;12919.224846509216;12923.224606523592;12931.224126552414;12986;643.8905386084377;667.4293479075393;815.4577206157912;821.8233079887823;822.9506229626212;660.7158921663682;661.8047675935586;663.7444583835234;666.1007639686409;797.5135167547792;844.5270648436895;874.4097734029536;879.1164879296298;886.1581497577328;888.3115585421285;836.0469065265602;839.8572242718722;878.2622597018471;880.8062226310202;880.9647614095428;507.2669991861761;528.1892295326655;656.701200953565;656.7635651165576;657;12978.935105324414;13466.730665386638;13515.32423378837;13849.723005539934;13856.445742170301;13331.533396331179;13658.317084145752;13739.450421983109;13789;13847.515336963157;9842.852357214648;11229.27009744364;11335.489902954305;11518.366489843056;11745.354005529693;3481.4330181010764;3558.804265765382;3570.767900086512;3685.1155722626645;3716.182439863253;89584.55207723984;90602.09397906068;92572.53713731456;92831.07168928292;93304;9922.305438619373;10137.2903896728;10533.262671613067;10620.309679870783;10632.362058276529;19959.401217963485;19978.20087196496;20025.899870500845;20029.998500074937;20057;7138.857222855618;7254.1295044593935;7363.48455608113;7663.118741344705;7677.884831727529;4123;4244.108737165168;4298.46269216348;4386.254336762758;4437.245668236407;3043.1631301391853;3099.4421004219457;3121.4225368306484;3125.390548842999;3130.9217269568444;546.6665334146168;643.6234802640507;644.0789670770843;646.612032780328;648.9772857949949;591.1398914579277;599.7465297528187;658.2890478283442;664.6064856084498;669.3039239191284;7166.892496612555;8757.430767000253;8879.978802437803;8917.063708310663;9106.225970792557;649.3474058571201;651.8174911024921;662.3939095727452;675.7972608217515;675.7972608217614;4137.13120244752;4421.801018954155;4464.4865840428765;4473.217186992263;4522.615577675869;72047;110124.4493777542;119302.81627356326;119695;120211;90210;103469.96530034754;106813;107041.46479267554;107560;93123.0687693128;94453.0554694458;94768.52615737016;95080;96617.51691241498;8416.87374689368;8743.256823170103;8785.736427907173;8793.736187914374;9052.818943621223;54858.61131109582;58390;58615;59635.70182149061;60861.39138608734;63391.683041584496;76833.84749228654;85340.57329713312;86315;88290.11709883076;10902.073323767569;11347.695015073372;11440.599579014695;11442.542298308308;11800.822987655363;12121.69695757613;12183.147179697336;12263.32551709629;12314.384280786104;12500;11597.826032609686;11617.882670396944;11719.858510361932;11727.46412626392;11884.821727674265;10634.89365106339;10757.1932105094;10991.61529346469;11100.500477478372;12911.289879056741;11454.484548195518;12665.87334126647;12697.87302127004;12747.235165890077;12924;10582.312149710244;11079.459300909439;11115.944420277847;11185.552577897119;11360.943195283971;10426.165906727596;10867.130629549774;11129;11261.380624065427;11266.380349080551 12:27:30 INFO - PROCESS | 1635 | __end_tp_report 12:27:30 INFO - PROCESS | 1635 | __start_cc_report 12:27:30 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,3606 12:27:30 INFO - PROCESS | 1635 | __end_cc_report 12:27:30 INFO - PROCESS | 1635 | __startTimestamp1483561650330__endTimestamp 12:27:30 INFO - PROCESS | 1635 | 12:27:30 INFO - PROCESS | 1635 | ------- Summary: start ------- 12:27:30 INFO - PROCESS | 1635 | Number of tests: 6 12:27:30 INFO - PROCESS | 1635 | 12:27:30 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5544.91 Median:5676.21 stddev:629.43 (11.1%) stddev-sans-first:595.41 12:27:30 INFO - PROCESS | 1635 | Values: 2871.9 3081.9 3289.3 3304.7 3363.0 5212.6 5452.1 5491.4 5518.5 5539.6 5434.1 5533.1 5542.4 5556.9 5560.7 5060.6 5061.1 5088.3 5159.0 5184.3 5010.5 5038.3 5051.9 5066.5 5099.5 5088.7 5143.9 5171.3 5186.3 5193.3 5160.2 5162.9 5163.3 5164.6 5214.4 4937.7 4942.5 5087.2 5104.1 5125.1 5445.2 5455.8 5480.2 5498.6 5507.9 4947.5 5865.5 5949.3 5950.7 5968.9 6052.2 6060.3 6062.3 6063.4 6076.5 5550.5 5556.6 5562.6 5569.7 5575.7 6116.1 6116.6 6120.9 6128.2 6135.2 5920.5 5941.7 5949.1 5949.7 5962.4 6095.5 6109.6 6119.1 6122.2 6128.8 5889.5 5964.3 5969.9 5977.5 6036.0 5070.9 5894.0 5898.7 5936.7 6051.5 4743.3 4751.6 4758.5 4777.2 4796.1 4164.4 4185.2 4232.3 4242.3 4270.4 5608.3 5623.8 5667.6 5684.6 5697.4 6034.6 6125.9 6130.6 6149.3 6151.0 5662.2 5670.9 5676.2 5681.6 5701.1 5122.5 5172.5 5184.1 5191.7 5223.6 4898.3 6169.3 6200.3 6233.7 6329.4 5991.6 6052.7 6066.2 6070.8 6086.1 6180.7 6269.9 6270.2 6270.5 6295.4 5835.2 5969.5 5969.9 5977.6 5978.8 5895.1 6058.7 6090.2 6115.5 6122.6 5715.0 5758.9 5766.6 5822.0 5859.1 5770.4 5776.8 5787.2 5787.3 5798.5 5660.5 5661.6 5666.6 5670.5 5675.7 5633.2 5751.4 5762.3 5767.8 5777.8 5631.1 5634.4 5673.8 5685.3 5716.3 12:27:30 INFO - PROCESS | 1635 | 12:27:30 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:67713.57 Median:34898.65 stddev:152807.00 (437.9%) stddev-sans-first:153152.26 12:27:30 INFO - PROCESS | 1635 | Values: 467.7 504.4 526.9 537.6 568.8 30008.5 31508.4 39023.8 39723.4 39934.4 40951.6 41321.6 41395.2 41397.4 41611.8 9154.3 9331.5 9333.5 9337.7 9352.3 8424.7 8604.4 11014.4 11188.4 11220.2 9197.7 11177.7 11261.5 11273.9 11275.5 8405.4 11092.0 11174.0 11177.0 11196.6 8373.5 10822.5 11131.8 11133.5 11258.4 38595.4 38950.0 38992.0 39024.0 39049.8 3282.5 3355.3 3442.8 3792.1 3835.5 28496.9 28518.6 29880.0 29883.9 30597.4 3139.2 3189.6 3209.9 3214.8 3221.7 474301.0 479422.0 482337.0 482471.0 485965.0 32220.0 33160.8 57972.7 58458.4 58475.1 34497.7 34876.3 34887.0 34910.3 34960.7 14156.8 14253.5 14274.3 14274.6 14306.9 10941.7 12037.7 12042.8 12056.5 12071.9 7243.1 7546.7 7781.1 7802.8 7814.3 3141.8 3150.4 3206.9 3212.3 3228.2 921.9 974.0 985.0 1002.7 1003.8 29169.1 29273.9 29616.4 29747.3 29836.7 916.8 1053.2 1056.9 1140.1 1179.1 15317.5 15328.0 15414.2 15532.6 15757.0 677674.0 682891.0 686936.0 687063.0 689723.0 56213.4 56857.4 56951.0 56973.7 56988.7 1205.4 1240.7 1241.3 1293.7 1344.7 29120.1 30170.8 30327.9 30428.5 30830.8 40668.2 48030.0 48316.0 48458.0 48560.8 517971.0 523207.0 524980.0 525573.0 527868.0 26045.3 26115.0 26459.5 27329.2 27391.2 45043.5 45124.5 45417.0 45597.5 45607.8 36689.9 36943.4 37495.1 38068.2 38881.4 37131.8 37239.4 37362.4 37698.4 37751.4 36372.8 36514.3 37168.6 37170.6 37366.4 31559.5 32404.1 33536.0 33738.3 33918.0 22373.8 25024.5 25090.4 25432.3 25521.4 12:27:30 INFO - PROCESS | 1635 | 12:27:30 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:34870.68 Median:21591.84 stddev:100472.89 (465.3%) stddev-sans-first:100721.31 12:27:30 INFO - PROCESS | 1635 | Values: 349.2 368.5 369.9 377.3 392.1 14329.2 14726.9 15598.3 19761.4 20171.9 18855.4 19246.4 20281.7 20500.4 20534.8 4499.6 5566.2 5574.8 5579.8 5585.4 5473.7 5547.4 6030.8 6090.5 6092.4 6049.1 6051.4 6061.8 6069.7 6071.5 5423.0 5445.5 5481.5 5482.2 5494.8 5117.1 5325.4 5334.9 5336.5 5336.7 13324.4 13605.2 13623.5 13636.8 13657.0 2143.7 2310.8 2354.6 2366.8 2855.8 10349.4 10405.3 12621.9 14339.7 14811.8 1841.8 1845.2 1944.6 1951.7 2053.0 112121.0 121626.4 123981.0 126369.7 127115.0 10955.4 11507.8 11514.2 11564.0 11758.1 14613.8 14655.0 14662.9 14663.2 14679.1 6650.9 8407.2 8451.0 8592.1 8618.1 8348.9 8364.3 8385.6 8526.2 8547.1 3692.0 4363.0 4453.8 4520.3 4523.2 1855.5 1908.6 1912.7 1914.3 1941.0 817.4 899.9 903.5 920.4 949.5 20723.6 20744.0 21569.8 21613.9 21646.6 846.8 902.8 912.8 928.9 951.2 10931.2 11260.9 11320.5 11352.8 11412.4 561249.0 579554.0 604601.0 627064.0 628199.9 125070.0 130337.0 131965.0 133880.0 134417.7 984.8 994.0 1020.1 1022.0 1022.9 18715.5 18904.6 19019.4 19096.2 19116.7 51803.0 54661.5 55108.0 56155.0 56461.0 72639.6 73407.5 76519.0 77137.0 77788.2 7703.5 7990.0 8123.3 8420.3 8424.4 10521.3 11625.1 11852.9 13054.5 13154.4 11047.2 11272.8 11412.5 11527.3 11533.2 10273.2 10525.6 10621.6 10803.2 10994.0 11331.7 11427.4 11585.3 11762.5 11814.1 8047.6 8138.3 8176.3 8270.5 8278.3 8359.0 11176.2 11267.3 11443.9 11451.1 12:27:30 INFO - PROCESS | 1635 | 12:27:30 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5398.46 Median:5035.58 stddev:2953.43 (58.7%) stddev-sans-first:2945.72 12:27:30 INFO - PROCESS | 1635 | Values: 1308.3 1471.7 1498.2 1667.1 1849.2 7442.7 7759.0 8114.6 8354.8 8409.2 8145.1 8232.5 8233.0 8242.3 8248.5 2277.5 2313.8 2319.7 2320.2 2324.0 2304.7 2333.5 2365.4 2368.1 2372.9 2296.4 2305.4 2327.2 2328.7 2331.2 2201.7 2203.5 2224.1 2225.1 2227.3 2313.9 2334.8 2337.4 2340.7 2342.8 8153.9 8156.2 8353.0 8362.5 8374.3 5010.5 5181.1 5182.0 5191.3 5196.0 3386.9 3527.6 3545.1 3599.9 3602.3 1064.7 1076.8 1084.4 1089.2 1104.1 10823.2 10859.6 10907.2 10932.9 10933.9 4183.1 4354.3 4434.2 4449.9 4453.9 8628.9 8642.0 8645.5 8650.0 8660.6 4988.5 5060.6 5061.5 5063.6 5090.6 3819.4 4269.4 4391.2 4620.5 4762.9 3552.3 3596.4 3599.7 3649.4 3668.6 1100.2 1107.4 1107.4 1116.1 1117.1 821.6 877.3 894.6 906.9 935.0 5473.8 5658.3 5733.3 5861.7 5875.6 817.5 922.9 931.9 945.3 966.2 3714.4 3783.1 3802.6 3811.6 3946.6 8425.6 10179.8 10302.1 10318.4 10337.9 10246.5 10249.2 10257.8 10263.3 10298.4 7534.1 7782.3 7888.1 7919.8 7972.4 5522.0 5696.5 5827.9 5848.2 5880.8 7827.6 9144.6 9208.4 9229.9 9383.5 8794.8 9470.7 9492.9 9524.2 9660.8 7132.0 7306.0 7307.1 7310.3 7317.6 7717.7 7785.8 7786.4 7871.5 7878.7 5871.2 7129.2 7130.7 7179.2 7199.9 7010.9 7046.0 7075.4 7107.9 7167.7 6573.0 6607.4 6695.0 6703.2 6724.4 4742.8 4813.1 4850.4 4868.3 4994.7 4804.6 4892.6 4893.8 4935.9 4962.7 12:27:30 INFO - PROCESS | 1635 | 12:27:30 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4230.09 Median:4700.24 stddev:1283.81 (27.3%) stddev-sans-first:1252.30 12:27:30 INFO - PROCESS | 1635 | Values: 256.8 302.3 307.2 312.2 316.4 4777.5 4978.7 5013.3 5032.1 5110.9 3475.7 4195.7 4214.9 4678.8 5012.6 3173.7 3858.3 3866.5 3910.4 3923.0 3226.7 3844.8 3866.8 3879.8 3888.8 3352.8 3706.5 3820.8 3860.8 3879.1 3512.2 3514.1 3887.1 3896.3 3901.8 3372.9 3384.5 3386.9 3391.4 3399.2 4435.4 4444.4 4739.6 4951.4 5009.7 4198.7 4337.7 4383.3 4393.1 4393.5 5305.7 5315.7 5317.0 5319.2 5341.4 3405.9 3719.0 3730.2 3743.8 3750.3 4784.9 5252.4 5502.5 5587.6 5593.2 3600.6 3769.2 3776.8 3782.2 3788.9 3190.8 3393.3 3837.0 3858.6 3861.5 4088.3 4415.0 4421.6 4432.2 4437.7 4918.5 4990.3 5269.6 5346.9 5353.5 1097.6 1111.3 1260.7 1266.0 1270.7 693.7 750.5 834.8 836.8 881.2 4322.5 4497.8 4667.5 4703.5 4713.6 5088.8 5135.0 5141.4 5141.8 5183.4 4243.9 4364.0 4641.7 4754.3 4764.9 2432.6 2605.2 2667.4 2686.8 2708.7 5353.0 5377.0 5377.9 5398.7 5401.7 4568.8 4697.0 4929.4 4997.0 5190.6 4768.8 5098.5 5312.7 5349.8 5363.4 4320.4 4466.4 4704.7 4928.9 5116.6 4935.4 4993.4 5046.9 5391.8 5453.4 5020.3 5759.1 5763.5 5768.0 5777.9 3676.4 3743.4 3744.8 3746.6 3765.3 4442.1 4502.5 4874.5 5493.2 5526.0 4870.8 5310.9 5313.7 5327.8 5346.6 4929.9 5306.6 5327.5 5344.8 5373.9 4111.5 4495.8 5067.2 5125.9 5219.8 5128.2 5221.6 5228.8 5278.6 5312.1 4516.6 4890.8 5103.9 5230.9 5239.3 12:27:30 INFO - PROCESS | 1635 | 12:27:30 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:21118.04 Median:4404.03 stddev:31969.86 (725.9%) stddev-sans-first:32022.62 12:27:30 INFO - PROCESS | 1635 | Values: 655.1 685.0 691.2 702.4 751.3 13938.4 14559.3 14588.6 14591.5 14615.4 12840.6 12919.2 12923.2 12931.2 12986.0 643.9 667.4 815.5 821.8 823.0 660.7 661.8 663.7 666.1 797.5 844.5 874.4 879.1 886.2 888.3 836.0 839.9 878.3 880.8 881.0 507.3 528.2 656.7 656.8 657.0 12978.9 13466.7 13515.3 13849.7 13856.4 13331.5 13658.3 13739.5 13789.0 13847.5 9842.9 11229.3 11335.5 11518.4 11745.4 3481.4 3558.8 3570.8 3685.1 3716.2 89584.6 90602.1 92572.5 92831.1 93304.0 9922.3 10137.3 10533.3 10620.3 10632.4 19959.4 19978.2 20025.9 20030.0 20057.0 7138.9 7254.1 7363.5 7663.1 7677.9 4123.0 4244.1 4298.5 4386.3 4437.2 3043.2 3099.4 3121.4 3125.4 3130.9 546.7 643.6 644.1 646.6 649.0 591.1 599.7 658.3 664.6 669.3 7166.9 8757.4 8880.0 8917.1 9106.2 649.3 651.8 662.4 675.8 675.8 4137.1 4421.8 4464.5 4473.2 4522.6 72047.0 110124.4 119302.8 119695.0 120211.0 90210.0 103470.0 106813.0 107041.5 107560.0 93123.1 94453.1 94768.5 95080.0 96617.5 8416.9 8743.3 8785.7 8793.7 9052.8 54858.6 58390.0 58615.0 59635.7 60861.4 63391.7 76833.8 85340.6 86315.0 88290.1 10902.1 11347.7 11440.6 11442.5 11800.8 12121.7 12183.1 12263.3 12314.4 12500.0 11597.8 11617.9 11719.9 11727.5 11884.8 10634.9 10757.2 10991.6 11100.5 12911.3 11454.5 12665.9 12697.9 12747.2 12924.0 10582.3 11079.5 11115.9 11185.6 11360.9 10426.2 10867.1 11129.0 11261.4 11266.4 12:27:30 INFO - PROCESS | 1635 | -------- Summary: end -------- 12:27:30 INFO - PROCESS | 1635 | 12:27:30 INFO - TEST-INFO | 1635: exit 0 12:27:30 INFO - TEST-OK | dromaeo_css | took 1119414ms 12:27:30 INFO - TEST-START | kraken 12:27:30 INFO - operating with platform_type : mac_ 12:27:30 INFO - Initialising browser for kraken test... 12:27:31 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmptguxPo/profile http://localhost:49241/getInfo.html) 12:27:33 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 12:27:33 INFO - PROCESS | 1645 | colorDepth:24 12:27:33 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 12:27:33 INFO - PROCESS | 1645 | __metrics 12:27:33 INFO - PROCESS | 1645 | 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" 12:27:33 INFO - PROCESS | 1645 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:27:34 INFO - TEST-INFO | 1645: exit 0 12:27:34 INFO - Browser initialized. 12:27:34 INFO - Running cycle 1/1 for kraken test... 12:27:34 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmptguxPo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:27:46 INFO - PROCESS | 1646 | RSS: Main: 223174656 12:27:46 INFO - PROCESS | 1646 | 12:27:49 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 12:27:50 INFO - PROCESS | 1646 | RSS: Main: 272506880 12:27:50 INFO - PROCESS | 1646 | 12:27:52 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 12:27:53 INFO - PROCESS | 1646 | RSS: Main: 285081600 12:27:53 INFO - PROCESS | 1646 | 12:27:56 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 12:27:57 INFO - PROCESS | 1646 | RSS: Main: 381239296 12:27:57 INFO - PROCESS | 1646 | 12:27:59 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 12:27:59 INFO - PROCESS | 1646 | RSS: Main: 282464256 12:27:59 INFO - PROCESS | 1646 | 12:28:01 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:28:02 INFO - PROCESS | 1646 | RSS: Main: 284798976 12:28:02 INFO - PROCESS | 1646 | 12:28:05 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 12:28:05 INFO - PROCESS | 1646 | RSS: Main: 325160960 12:28:05 INFO - PROCESS | 1646 | 12:28:08 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 12:28:09 INFO - PROCESS | 1646 | RSS: Main: 326807552 12:28:09 INFO - PROCESS | 1646 | 12:28:12 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 12:28:12 INFO - PROCESS | 1646 | RSS: Main: 324304896 12:28:12 INFO - PROCESS | 1646 | 12:28:14 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:28:14 INFO - PROCESS | 1646 | RSS: Main: 300396544 12:28:14 INFO - PROCESS | 1646 | 12:28:16 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:28:17 INFO - PROCESS | 1646 | RSS: Main: 301522944 12:28:17 INFO - PROCESS | 1646 | 12:28:19 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:28:20 INFO - PROCESS | 1646 | RSS: Main: 303521792 12:28:20 INFO - PROCESS | 1646 | 12:28:23 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:28:24 INFO - PROCESS | 1646 | RSS: Main: 320561152 12:28:24 INFO - PROCESS | 1646 | 12:28:26 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:28:27 INFO - PROCESS | 1646 | RSS: Main: 314236928 12:28:27 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 12:28:29 INFO - PROCESS | 1646 | RSS: Main: 358592512 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | __start_tp_report 12:28:29 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 12:28:29 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 12:28:29 INFO - PROCESS | 1646 | |i|pagename|runs| 12:28:29 INFO - PROCESS | 1646 | |0;ai-astar;115;115;115;114;115;116;112;115;116;117 12:28:29 INFO - PROCESS | 1646 | |1;audio-beat-detection;149;94;121;174;140;117;107;103;140;107 12:28:29 INFO - PROCESS | 1646 | |2;audio-dft;184;184;184;185;182;182;182;183;185;184 12:28:29 INFO - PROCESS | 1646 | |3;audio-fft;80;59;60;89;61;59;61;58;83;69 12:28:29 INFO - PROCESS | 1646 | |4;audio-oscillator;83;71;97;71;69;69;89;68;69;93 12:28:29 INFO - PROCESS | 1646 | |5;imaging-gaussian-blur;86;84;88;84;87;83;88;84;87;84 12:28:29 INFO - PROCESS | 1646 | |6;imaging-darkroom;93;93;93;93;92;93;94;93;93;101 12:28:29 INFO - PROCESS | 1646 | |7;imaging-desaturate;83;81;79;84;85;81;83;83;81;80 12:28:29 INFO - PROCESS | 1646 | |8;json-parse-financial;55;53;53;53;56;59;58;57;56;56 12:28:29 INFO - PROCESS | 1646 | |9;json-stringify-tinderbox;72;59;69;107;107;101;69;69;69;70 12:28:29 INFO - PROCESS | 1646 | |10;stanford-crypto-aes;84;81;84;83;85;84;83;80;87;82 12:28:29 INFO - PROCESS | 1646 | |11;stanford-crypto-ccm;162;144;155;151;139;142;147;156;212;153 12:28:29 INFO - PROCESS | 1646 | |12;stanford-crypto-pbkdf2;166;165;162;166;167;163;164;164;165;164 12:28:29 INFO - PROCESS | 1646 | |13;stanford-crypto-sha256-iterative;68;69;67;68;65;68;65;65;66;66 12:28:29 INFO - PROCESS | 1646 | __end_tp_report 12:28:29 INFO - PROCESS | 1646 | __start_cc_report 12:28:29 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,2048 12:28:29 INFO - PROCESS | 1646 | __end_cc_report 12:28:29 INFO - PROCESS | 1646 | __startTimestamp1483561709458__endTimestamp 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | ------- Summary: start ------- 12:28:29 INFO - PROCESS | 1646 | Number of tests: 14 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:1.33 (1.2%) stddev-sans-first:1.41 12:28:29 INFO - PROCESS | 1646 | Values: 115.0 115.0 115.0 114.0 115.0 116.0 112.0 115.0 116.0 117.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:140.00 stddev:24.94 (17.8%) stddev-sans-first:24.93 12:28:29 INFO - PROCESS | 1646 | Values: 149.0 94.0 121.0 174.0 140.0 117.0 107.0 103.0 140.0 107.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.18 (0.6%) stddev-sans-first:1.24 12:28:29 INFO - PROCESS | 1646 | Values: 184.0 184.0 184.0 185.0 182.0 182.0 182.0 183.0 185.0 184.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#3] audio-fft Cycles:10 Average:67.90 Median:65.00 stddev:11.71 (18.0%) stddev-sans-first:11.58 12:28:29 INFO - PROCESS | 1646 | Values: 80.0 59.0 60.0 89.0 61.0 59.0 61.0 58.0 83.0 69.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:77.00 stddev:11.42 (14.8%) stddev-sans-first:11.96 12:28:29 INFO - PROCESS | 1646 | Values: 83.0 71.0 97.0 71.0 69.0 69.0 89.0 68.0 69.0 93.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.50 stddev:1.90 (2.2%) stddev-sans-first:2.01 12:28:29 INFO - PROCESS | 1646 | Values: 86.0 84.0 88.0 84.0 87.0 83.0 88.0 84.0 87.0 84.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:93.00 stddev:2.57 (2.8%) stddev-sans-first:2.71 12:28:29 INFO - PROCESS | 1646 | Values: 93.0 93.0 93.0 93.0 92.0 93.0 94.0 93.0 93.0 101.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#7] imaging-desaturate Cycles:10 Average:82.00 Median:83.00 stddev:1.89 (2.3%) stddev-sans-first:1.96 12:28:29 INFO - PROCESS | 1646 | Values: 83.0 81.0 79.0 84.0 85.0 81.0 83.0 83.0 81.0 80.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:2.12 (3.8%) stddev-sans-first:2.24 12:28:29 INFO - PROCESS | 1646 | Values: 55.0 53.0 53.0 53.0 56.0 59.0 58.0 57.0 56.0 56.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:69.00 stddev:18.20 (26.4%) stddev-sans-first:19.12 12:28:29 INFO - PROCESS | 1646 | Values: 72.0 59.0 69.0 107.0 107.0 101.0 69.0 69.0 69.0 70.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:84.00 stddev:2.00 (2.4%) stddev-sans-first:2.11 12:28:29 INFO - PROCESS | 1646 | Values: 84.0 81.0 84.0 83.0 85.0 84.0 83.0 80.0 87.0 82.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:156.10 Median:154.00 stddev:20.86 (13.5%) stddev-sans-first:22.02 12:28:29 INFO - PROCESS | 1646 | Values: 162.0 144.0 155.0 151.0 139.0 142.0 147.0 156.0 212.0 153.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.60 Median:165.00 stddev:1.51 (0.9%) stddev-sans-first:1.51 12:28:29 INFO - PROCESS | 1646 | Values: 166.0 165.0 162.0 166.0 167.0 163.0 164.0 164.0 165.0 164.0 12:28:29 INFO - PROCESS | 1646 | 12:28:29 INFO - PROCESS | 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.50 stddev:1.49 (2.2%) stddev-sans-first:1.51 12:28:29 INFO - PROCESS | 1646 | Values: 68.0 69.0 67.0 68.0 65.0 68.0 65.0 65.0 66.0 66.0 12:28:29 INFO - PROCESS | 1646 | -------- Summary: end -------- 12:28:29 INFO - PROCESS | 1646 | 12:28:30 INFO - TEST-INFO | 1646: exit 0 12:28:30 INFO - TEST-OK | kraken | took 59532ms 12:28:30 INFO - SUITE-END | took 1178s 12:28:30 INFO - Completed test suite (00:19:39) 12:28:30 INFO - javascript benchmark 12:28:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2871.9282017949563, 3081.907542773717, 3289.276359200975, 3304.7356211503075, 3363.0, 5212.582993360532, 5452.100403433429, 5491.395946445888, 5518.475744804237, 5539.639923404986, 5434.103372943469, 5533.114701647737, 5542.362628297747, 5556.91664625031, 5560.749766260518, 5060.620453465986, 5061.1143049966295, 5088.338515992925, 5158.974205128988, 5184.326037615105, 5010.549050585447, 5038.345015148026, 5051.924221136685, 5066.544011039005, 5099.541041306282, 5088.720120393377, 5143.922841157367, 5171.301874246985, 5186.299849520305, 5193.298904647881, 5160.200168973796, 5162.948370516285, 5163.251328557376, 5164.6384753067305, 5214.426413094557, 4937.654364194508, 4942.456329803718, 5087.160618497944, 5104.055749686283, 5125.1287281162295, 5445.237666726662, 5455.809046683347, 5480.150576660624, 5498.615096943216, 5507.944920550824, 4947.480514545957, 5865.472107510302, 5949.25634295713, 5950.732217050243, 5968.910466343008, 6052.152698622196, 6060.272767267956, 6062.302835173923, 6063.393660633946, 6076.544259180535, 5550.5282051025715, 5556.611037227396, 5562.638428502175, 5569.693666848362, 5575.69333686647, 6116.052011938156, 6116.609690437402, 6120.877582448326, 6128.197586012551, 6135.2326666699655, 5920.526357891358, 5941.732622032019, 5949.1076338549565, 5949.702514874239, 5962.3739507351975, 6095.451409373089, 6109.572329936951, 6119.051547010221, 6122.173506576644, 6128.754849806047, 5889.4699477046415, 5964.284285885721, 5969.940300597025, 5977.491913187341, 6035.969820150871, 5070.898582028339, 5894.027485464936, 5898.675572843535, 5936.673482958392, 6051.546134039877, 4743.335932969318, 4751.619870410359, 4758.5241475852135, 4777.163996300634, 4796.064767370399, 4164.3961625564125, 4185.183889141649, 4232.280512312914, 4242.257604919188, 4270.402143699885, 5608.270924779834, 5623.775048998076, 5667.574931880043, 5684.630499017633, 5697.4302569742695, 6034.607750496291, 6125.908111378333, 6130.601510901866, 6149.3235744068115, 6151.046587778901, 5662.150677398423, 5670.858228544319, 5676.205331253628, 5681.57388195887, 5701.116326969326, 5122.538971492583, 5172.534471897472, 5184.092783762826, 5191.740412979336, 5223.582113430916, 4898.314236007033, 6169.290531588925, 6200.255969283715, 6233.657148856675, 6329.430351268408, 5991.6405015697455, 6052.727627256872, 6066.18106555627, 6070.817875463743, 6086.087086936995, 6180.6600636965395, 6269.937300626935, 6270.184875966095, 6270.4670103039925, 6295.370462953668, 5835.153902684002, 5969.462748352669, 5969.850753731018, 5977.581569290108, 5978.760849565969, 5895.115732640138, 6058.7273572688455, 6090.177825993612, 6115.510759139169, 6122.602030867979, 5715.0, 5758.884822303614, 5766.596338256281, 5822.0, 5859.121131830259, 5770.394108618702, 5776.768929242783, 5787.160861675117, 5787.305523337226, 5798.536117110705, 5660.547156227574, 5661.631993920382, 5666.575006874418, 5670.461306175906, 5675.716214189357, 5633.155026746021, 5751.396103409082, 5762.337331206797, 5767.7981270657, 5777.7977770777825, 5631.1271752878365, 5634.380218176079, 5673.8297851064535, 5685.317761868602, 5716.28546431696], "value": 5504.1441638851, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.6843130886651, 504.42747481608365, 526.918327659213, 537.6263496869675, 568.8293511946415, 30008.549871751915, 31508.369832603363, 39023.80488097556, 39723.40414893779, 39934.400983985266, 40951.59048409515, 41321.58678413215, 41395.17209655805, 41397.37903931444, 41611.79194104033, 9154.313426493023, 9331.486768227745, 9333.53332333384, 9337.71986840396, 9352.251819854395, 8424.705135320264, 8604.39769216155, 11014.39420831854, 11188.440577971109, 11220.214584979014, 9197.724068277963, 11177.66467005991, 11261.493232804543, 11273.943630281838, 11275.54897804091, 8405.411621186458, 11092.001719845211, 11174.0, 11177.049950754195, 11196.608118715847, 8373.539455329954, 10822.458877056195, 11131.833022504668, 11133.498992545356, 11258.437078146142, 38595.421068683994, 38950.0, 38992.02519936996, 39024.0, 39049.80475097635, 3282.491213861855, 3355.2953879685297, 3442.8278586070596, 3792.1278106035766, 3835.4822099016833, 28496.857515712498, 28518.572221416696, 29880.0, 29883.850580747174, 30597.388052239054, 3139.183812208842, 3189.6012998375204, 3209.903702888917, 3214.791038582485, 3221.7261532769744, 474301.0, 479422.0, 482337.0, 482471.0, 485965.0, 32220.0, 33160.834195828866, 57972.710136449045, 58458.415415846146, 58475.122873156935, 34497.655023449945, 34876.30247395038, 34887.0, 34910.301793963976, 34960.650393496246, 14156.787648185285, 14253.50112746049, 14274.286285685881, 14274.571762847132, 14306.856931430553, 10941.726456838644, 12037.699057523632, 12042.81935770964, 12056.517739290506, 12071.939640301742, 7243.0946131733535, 7546.660400282, 7781.144074151839, 7802.843943121105, 7814.296713295831, 3141.8429078545983, 3150.401423729484, 3206.903792886217, 3212.3254116635767, 3228.2090887732656, 921.9262459003263, 974.0, 985.049427302642, 1002.7493126718321, 1003.814294355532, 29169.124926252247, 29273.85363073171, 29616.407671846442, 29747.256318592208, 29836.701632983826, 916.7753900294338, 1053.1791048472867, 1056.915446764257, 1140.1391949078393, 1179.1156632525606, 15317.540473785804, 15328.003679760779, 15414.22928853553, 15532.611684707746, 15757.0, 677674.0, 682891.0, 686936.0, 687063.0, 689723.0, 56213.43786562082, 56857.43142568604, 56951.0, 56973.71513142408, 56988.71505642445, 1205.4394706461537, 1240.7332423528803, 1241.30486927321, 1293.6895145164904, 1344.6907211341252, 29120.126396208147, 30170.84914575413, 30327.92971166935, 30428.543571845996, 30830.84584577063, 40668.1866362677, 48030.03939921252, 48315.999999998596, 48458.0, 48560.757196213795, 517971.0, 523207.0, 524980.0, 525573.0, 527868.0, 26045.348866277738, 26114.955401784475, 26459.470810584065, 27329.180124596292, 27391.17826465209, 45043.549564503934, 45124.548754512034, 45417.0, 45597.54402455933, 45607.77196114131, 36689.89930302095, 36943.37548103418, 37495.062623434635, 38068.238635227695, 38881.416778748906, 37131.814340928126, 37239.441408378356, 37362.43956340711, 37698.43452348162, 37751.433728494645, 36372.81813591021, 36514.26971460609, 37168.6283137176, 37170.62829371672, 37366.439503406924, 31559.52660710045, 32404.055756654678, 33536.0, 33738.3252334947, 33917.982460526226, 22373.77626223717, 25024.49951001006, 25090.372740680898, 25432.254163961312, 25521.36196595026], "value": 18396.97699902291, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.18117015598415, 368.4841222288795, 369.89827797355724, 377.26056928420286, 392.0668808236397, 14329.211893345862, 14726.852731472682, 15598.298076586567, 19761.40715778529, 20171.899140504276, 18855.43433696988, 19246.42260732177, 20281.69577456339, 20500.384988450332, 20534.794652053475, 4499.61753250974, 5566.165075238706, 5574.777008919658, 5579.804706835262, 5585.413531579186, 5473.726313684319, 5547.417521160261, 6030.788922387717, 6090.543209259301, 6092.390760923917, 6049.1228771828055, 6051.394860513936, 6061.818145455643, 6069.726862291208, 6071.544634152434, 5423.023855705971, 5445.50990410861, 5481.534069604048, 5482.150266708666, 5494.780208791643, 5117.104506711348, 5325.387580428262, 5334.866628334284, 5336.546393556552, 5336.733163341856, 13324.400401981935, 13605.183688978694, 13623.523176688868, 13636.795448068287, 13657.04400691952, 2143.678448232762, 2310.849794763335, 2354.6468029795496, 2366.7633236676365, 2855.8286502809897, 10349.430781307025, 10405.323653962469, 12621.927136193435, 14339.71320573593, 14811.777823332552, 1841.815818418161, 1845.2177446127582, 1944.6110777844488, 1951.717001034842, 2052.958940821175, 112121.0, 121626.39186804098, 123981.0, 126369.73630263671, 127115.0, 10955.397453140155, 11507.76984460306, 11514.194006419637, 11564.0, 11758.05935525156, 14613.78079328811, 14654.974151809483, 14662.926685366718, 14663.19352435605, 14679.119252844863, 6650.933490665128, 8407.243348098578, 8451.0, 8592.097829727914, 8618.052014278424, 8348.916510834935, 8364.330853531703, 8385.6226469809, 8526.23263906239, 8547.145285471403, 3691.981540092336, 4363.0, 4453.799579018952, 4520.344550040291, 4523.163214805206, 1855.461916044359, 1908.59919416924, 1912.7429633033155, 1914.258025197064, 1940.9708854367195, 817.3665409307715, 899.8605216191501, 903.4875014256437, 920.4385324951774, 949.5299826585746, 20723.58552828935, 20744.0, 21569.78430215709, 21613.891930540245, 21646.56706865854, 846.8475674378548, 902.8194361127669, 912.8493798523169, 928.9256859451363, 951.1534734086669, 10931.18016148776, 11260.94369528147, 11320.547178112947, 11352.772944541062, 11412.372319522507, 561249.0, 579554.0, 604601.0, 627064.0, 628199.859000702, 125070.0, 130337.0, 131965.0, 133880.0, 134417.65582344052, 984.8178087053918, 993.9701808945744, 1020.132887046005, 1022.0290723812408, 1022.8670272864629, 18715.532111697317, 18904.621907562047, 19019.42941711751, 19096.23615055382, 19116.66183367151, 51803.0, 54661.45338546563, 55108.0, 56155.0, 56461.0, 72639.63680181565, 73407.53184936378, 76519.0, 77137.0, 77788.2221177781, 7703.460757746904, 7989.961305030309, 8123.268905798506, 8420.284275836622, 8424.368172387218, 10521.263511554118, 11625.069994400597, 11852.881471185177, 13054.477820887058, 13154.40805163789, 11047.226694131334, 11272.83090753623, 11412.543498259976, 11527.308361498337, 11533.192676512563, 10273.229507787095, 10525.578976840841, 10621.628243011457, 10803.243772935819, 10994.0, 11331.660050198507, 11427.428628568705, 11585.304881707125, 11762.470688818852, 11814.101346555444, 8047.637856296597, 8138.348932085537, 8176.305014073871, 8270.462419942685, 8278.296344810518, 8359.0, 11176.217664763713, 11267.26762760418, 11443.942780286045, 11451.083913287084], "value": 9359.804603130113, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1308.2804457548345, 1471.683588028574, 1498.2059508460516, 1667.0914351678334, 1849.2325684840782, 7442.739504117357, 7758.991331126953, 8114.634841432135, 8354.832903341929, 8409.159084091587, 8145.0633177184645, 8232.46488978216, 8232.95883520583, 8242.29940455062, 8248.463849849755, 2277.544491101778, 2313.81489480842, 2319.652052192176, 2320.1995311617466, 2323.9883800580974, 2304.7349554801162, 2333.53329334133, 2365.3968238099346, 2368.0882860098814, 2372.8694921779297, 2296.4488522754586, 2305.4351683837485, 2327.2203811723093, 2328.6856274402994, 2331.230693871027, 2201.735791705006, 2203.4711669199355, 2224.0547767198877, 2225.1321984426004, 2227.3206671965045, 2313.9190128345426, 2334.8365614407, 2337.39227800773, 2340.6957095577636, 2342.847714898526, 8153.9184608153755, 8156.225158609923, 8352.958235208785, 8362.540060296682, 8374.330053595697, 5010.5240002199735, 5181.093308671006, 5182.0, 5191.273221748958, 5196.038732834438, 3386.8983930482127, 3527.6295988921306, 3545.1137215696076, 3599.892003239907, 3602.3155600435834, 1064.7284942339732, 1076.7792602516545, 1084.3710647824244, 1089.2320913755784, 1104.0725790336155, 10823.24237303389, 10859.565617375296, 10907.181961352851, 10932.890671093266, 10933.890661093366, 4183.100633363781, 4354.30182228931, 4434.224010798098, 4449.933251001237, 4453.888652783641, 8628.870566941501, 8641.956790216009, 8645.481271123752, 8650.048494665582, 8660.566971651393, 4988.5260900214425, 5060.645754797202, 5061.493850614909, 5063.620228482879, 5090.643654944119, 3819.413361978936, 4269.4449721536, 4391.231534481453, 4620.491745907947, 4762.857114286577, 3552.289542091592, 3596.4425514045365, 3599.6760291573883, 3649.361361761681, 3668.596454390015, 1100.1638754546602, 1107.4407424250862, 1107.4462768615692, 1116.0792346314324, 1117.0839911272678, 821.6056292979404, 877.2981614708208, 894.552723638181, 906.9410488318238, 934.9859752103724, 5473.8357849264585, 5658.349289831722, 5733.254676892061, 5861.677607731531, 5875.6474611523445, 817.4550100310418, 922.8833111934634, 931.9114684104999, 945.3193700535741, 966.21253678252, 3714.405695088773, 3783.1109689223013, 3802.5627052888717, 3811.5616704079384, 3946.625070618287, 8425.578721064045, 10179.84730229047, 10302.124319432933, 10318.43248621333, 10337.9483102584, 10246.53890574911, 10249.231307651957, 10257.769067711923, 10263.332883362558, 10298.3820970742, 7534.095908490906, 7782.338501227459, 7888.053433588007, 7919.762407127795, 7972.3622110231045, 5522.0, 5696.515796157208, 5827.94172058274, 5848.239728835224, 5880.794172203952, 7827.608619569112, 9144.63421463134, 9208.355415120877, 9229.907700923173, 9383.483908385104, 8794.824103518022, 9470.668526601536, 9492.905070949479, 9524.190443812353, 9660.758481037748, 7132.0, 7305.963470182615, 7307.086614173229, 7310.342069213796, 7317.597532135784, 7717.729879454192, 7785.805354866174, 7786.377089832913, 7871.48835325702, 7878.684852605831, 5871.236739224044, 7129.215786263403, 7130.679119439741, 7179.210286868336, 7199.8560028800175, 7010.894836577353, 7046.013558101768, 7075.398591119812, 7107.928920710727, 7167.677454514455, 6573.0, 6607.438367738796, 6694.966525167343, 6703.229128650268, 6724.4284235840505, 4742.810287588458, 4813.109574728686, 4850.442199147144, 4868.318435419115, 4994.650374473752, 4804.567588917014, 4892.584130348818, 4893.755312234302, 4935.851924442272, 4962.727050012284], "value": 4376.077908046915, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.76377732486117, 302.31374779251087, 307.1522597630539, 312.23969633941357, 316.4414807864119, 4777.450593181785, 4978.701277923327, 5013.298138260647, 5032.144535428976, 5110.923336149952, 3475.669811367922, 4195.685323600727, 4214.915701685964, 4678.766061696919, 5012.5964729875695, 3173.6667649896776, 3858.344081506151, 3866.516685414323, 3910.3743401055835, 3922.9604154898975, 3226.677332266778, 3844.8462061517644, 3866.8098818474873, 3879.7672139671567, 3888.7666739995548, 3352.8155951422664, 3706.4625629283737, 3820.7964491185326, 3860.8069596520063, 3879.1465877507044, 3512.1570823002553, 3514.086337552255, 3887.086534664352, 3896.279188350164, 3901.804909754501, 3372.9325413491592, 3384.5430866832908, 3386.881459148918, 3391.3895498810202, 3399.1502124468884, 4435.445569303837, 4444.444444444457, 4739.64919997799, 4951.356323677935, 5009.674371165863, 4198.727082739642, 4337.71804832688, 4383.298672212431, 4393.121375724868, 4393.51671316155, 5305.681659100466, 5315.734213289359, 5317.038806089488, 5319.2287118368, 5341.43914888935, 3405.9489107663403, 3718.958691566366, 3730.2166545025575, 3743.756655817363, 3750.306193354238, 4784.928226076577, 5252.36971563411, 5502.477264659851, 5587.608867379285, 5593.231350481497, 3600.621934696819, 3769.1707824278615, 3776.792276424823, 3782.224643947984, 3788.9431658525145, 3190.7766456348295, 3393.321335732863, 3837.0, 3858.607663248804, 3861.517310336208, 4088.3049881520215, 4415.028693687384, 4421.557844215553, 4432.246518091933, 4437.689361744711, 4918.532739389752, 4990.326305948651, 5269.552088072557, 5346.866328341823, 5353.544948679328, 1097.5609756097465, 1111.3109871879487, 1260.7226410189744, 1265.9746805063846, 1270.7394984028438, 693.7398475571661, 750.4521699159644, 834.7770516193805, 836.8368168207141, 881.1849039638437, 4322.481302243751, 4497.797599108048, 4667.533246675305, 4703.482616912205, 4713.622910167179, 5088.77100530477, 5134.974325128351, 5141.357330333708, 5141.794328226829, 5183.377994640668, 4243.851465198703, 4363.992563420862, 4641.651876109306, 4754.286856971482, 4764.928526072112, 2432.603885173103, 2605.205412349214, 2667.4265033017996, 2686.764088519303, 2708.7291270872756, 5353.0, 5376.973115134478, 5377.865553361197, 5398.703071331114, 5401.729913504388, 4568.79440425175, 4697.0, 4929.383827021622, 4996.975015124901, 5190.636655434083, 4768.7615619218905, 5098.490150984871, 5312.681239125664, 5349.81275655358, 5363.436839131835, 4320.351947207818, 4466.431801713962, 4704.694194877322, 4928.876778080433, 5116.590672746097, 4935.432425270996, 4993.425756038102, 5046.949530504795, 5391.75737091824, 5453.372862120748, 5020.347354843993, 5759.136129580596, 5763.51010164124, 5768.0, 5777.8555536111935, 3676.375016247191, 3743.36362818327, 3744.801663467664, 3746.550413950343, 3765.284595926765, 4442.111577684541, 4502.527234640315, 4874.463808980939, 5493.2034854946605, 5525.972370138124, 4870.806652370192, 5310.867228319323, 5313.698143954756, 5327.840164795062, 5346.609881430778, 4929.9014019720125, 5306.602004849575, 5327.520523152934, 5344.786208551614, 5373.865653358697, 4111.527174374985, 4495.820167193276, 5067.163917953644, 5125.872449902841, 5219.817306394258, 5128.205128205134, 5221.608379371486, 5228.764705588182, 5278.645117777772, 5312.0969435196885, 4516.645006498103, 4890.779914903766, 5103.948960510347, 5230.901510682799, 5239.318888544465], "value": 3819.620379123744, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.13849288186, 684.9897251541227, 691.1809505735704, 702.3748863511472, 751.3163021650298, 13938.372773225205, 14559.344829482672, 14588.635284117903, 14591.489297874563, 14615.415383384678, 12840.61478155657, 12919.224846509216, 12923.224606523592, 12931.224126552414, 12986.0, 643.8905386084377, 667.4293479075393, 815.4577206157912, 821.8233079887823, 822.9506229626212, 660.7158921663682, 661.8047675935586, 663.7444583835234, 666.1007639686409, 797.5135167547792, 844.5270648436895, 874.4097734029536, 879.1164879296298, 886.1581497577328, 888.3115585421285, 836.0469065265602, 839.8572242718722, 878.2622597018471, 880.8062226310202, 880.9647614095428, 507.2669991861761, 528.1892295326655, 656.701200953565, 656.7635651165576, 657.0, 12978.935105324414, 13466.730665386638, 13515.32423378837, 13849.723005539934, 13856.445742170301, 13331.533396331179, 13658.317084145752, 13739.450421983109, 13789.0, 13847.515336963157, 9842.852357214648, 11229.27009744364, 11335.489902954305, 11518.366489843056, 11745.354005529693, 3481.4330181010764, 3558.804265765382, 3570.767900086512, 3685.1155722626645, 3716.182439863253, 89584.55207723984, 90602.09397906068, 92572.53713731456, 92831.07168928292, 93304.0, 9922.305438619373, 10137.2903896728, 10533.262671613067, 10620.309679870783, 10632.362058276529, 19959.401217963485, 19978.20087196496, 20025.899870500845, 20029.998500074937, 20057.0, 7138.857222855618, 7254.1295044593935, 7363.48455608113, 7663.118741344705, 7677.884831727529, 4123.0, 4244.108737165168, 4298.46269216348, 4386.254336762758, 4437.245668236407, 3043.1631301391853, 3099.4421004219457, 3121.4225368306484, 3125.390548842999, 3130.9217269568444, 546.6665334146168, 643.6234802640507, 644.0789670770843, 646.612032780328, 648.9772857949949, 591.1398914579277, 599.7465297528187, 658.2890478283442, 664.6064856084498, 669.3039239191284, 7166.892496612555, 8757.430767000253, 8879.978802437803, 8917.063708310663, 9106.225970792557, 649.3474058571201, 651.8174911024921, 662.3939095727452, 675.7972608217515, 675.7972608217614, 4137.13120244752, 4421.801018954155, 4464.4865840428765, 4473.217186992263, 4522.615577675869, 72047.0, 110124.4493777542, 119302.81627356326, 119695.0, 120211.0, 90210.0, 103469.96530034754, 106813.0, 107041.46479267554, 107560.0, 93123.0687693128, 94453.0554694458, 94768.52615737016, 95080.0, 96617.51691241498, 8416.87374689368, 8743.256823170103, 8785.736427907173, 8793.736187914374, 9052.818943621223, 54858.61131109582, 58390.0, 58615.0, 59635.70182149061, 60861.39138608734, 63391.683041584496, 76833.84749228654, 85340.57329713312, 86315.0, 88290.11709883076, 10902.073323767569, 11347.695015073372, 11440.599579014695, 11442.542298308308, 11800.822987655363, 12121.69695757613, 12183.147179697336, 12263.32551709629, 12314.384280786104, 12500.0, 11597.826032609686, 11617.882670396944, 11719.858510361932, 11727.46412626392, 11884.821727674265, 10634.89365106339, 10757.1932105094, 10991.61529346469, 11100.500477478372, 12911.289879056741, 11454.484548195518, 12665.87334126647, 12697.87302127004, 12747.235165890077, 12924.0, 10582.312149710244, 11079.459300909439, 11115.944420277847, 11185.552577897119, 11360.943195283971, 10426.165906727596, 10867.130629549774, 11129.0, 11261.380624065427, 11266.380349080551], "value": 7134.835673498304, "unit": "score"}], "name": "dromaeo_css", "value": 6953.552001065793, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 114.0, 115.0, 116.0, 112.0, 115.0, 116.0, 117.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 94.0, 121.0, 174.0, 140.0, 117.0, 107.0, 103.0, 140.0, 107.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 185.0, 182.0, 182.0, 182.0, 183.0, 185.0, 184.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 60.0, 89.0, 61.0, 59.0, 61.0, 58.0, 83.0, 69.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 97.0, 71.0, 69.0, 69.0, 89.0, 68.0, 69.0, 93.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 88.0, 84.0, 87.0, 83.0, 88.0, 84.0, 87.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 92.0, 93.0, 94.0, 93.0, 93.0, 101.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 79.0, 84.0, 85.0, 81.0, 83.0, 83.0, 81.0, 80.0], "value": 82.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 53.0, 56.0, 59.0, 58.0, 57.0, 56.0, 56.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 59.0, 69.0, 107.0, 107.0, 101.0, 69.0, 69.0, 69.0, 70.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 84.0, 83.0, 85.0, 84.0, 83.0, 80.0, 87.0, 82.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 144.0, 155.0, 151.0, 139.0, 142.0, 147.0, 156.0, 212.0, 153.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 162.0, 166.0, 167.0, 163.0, 164.0, 164.0, 165.0, 164.0], "value": 164.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 68.0, 65.0, 68.0, 65.0, 65.0, 66.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1436.3, "alertThreshold": 2.0}]} 12:28:30 INFO - Return code: 0 12:28:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:28:30 INFO - # TBPL SUCCESS # 12:28:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:28:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:28:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:28:30 INFO - 2017-01-04 12:28:30.783 screenresolution[1650:10382] starting screenresolution argv=screenresolution get 12:28:30 INFO - 2017-01-04 12:28:30.785 screenresolution[1650:10382] Display 0: 1600x1200x32@60 12:28:30 INFO - 2017-01-04 12:28:30.791 screenresolution[1651:10385] starting screenresolution argv=screenresolution list 12:28:30 INFO - Available Modes on Display 0 12:28:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:28:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:28:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:28:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:28:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:28:31 INFO - Intel Iris: 12:28:31 INFO - Chipset Model: Intel Iris 12:28:31 INFO - Type: GPU 12:28:31 INFO - Bus: Built-In 12:28:31 INFO - VRAM (Dynamic, Max): 1536 MB 12:28:31 INFO - Vendor: Intel (0x8086) 12:28:31 INFO - Device ID: 0x0a2e 12:28:31 INFO - Revision ID: 0x0009 12:28:31 INFO - Displays: 12:28:31 INFO - Display: 12:28:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:28:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:28:31 INFO - Display Serial Number: 1600x1200 60 12:28:31 INFO - Main Display: Yes 12:28:31 INFO - Mirror: Off 12:28:31 INFO - Online: Yes 12:28:31 INFO - Rotation: Supported 12:28:31 INFO - Return code: 0 12:28:31 INFO - Running post-action listener: _resource_record_post_action 12:28:31 INFO - [mozharness: 2017-01-04 20:28:31.043151Z] Finished run-tests step (success) 12:28:31 INFO - Running post-run listener: _resource_record_post_run 12:28:31 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37688320; Write bytes: 659359232; Read time: 239; Write time: 1907 12:28:31 INFO - TinderboxPrint: CPU usage
53.4% 12:28:31 INFO - TinderboxPrint: I/O read bytes / time
37,688,320 / 239 12:28:31 INFO - TinderboxPrint: I/O write bytes / time
659,359,232 / 1,907 12:28:31 INFO - TinderboxPrint: CPU idle
2,258.3 (46.6%) 12:28:31 INFO - TinderboxPrint: CPU system
961.8 (19.8%) 12:28:31 INFO - TinderboxPrint: CPU user
1,625.6 (33.5%) 12:28:31 INFO - TinderboxPrint: Swap in / out
386,695,168 / 0 12:28:31 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 340883968; Write bytes: 335742464; Read time: 26822; Write time: 684 12:28:31 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 36725760; Write bytes: 306970624; Read time: 226; Write time: 1169 12:28:31 INFO - Running post-run listener: _upload_blobber_files 12:28:31 INFO - Blob upload gear active. 12:28:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:28:31 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 12:28:31 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'] 12:28:31 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 12:28:31 INFO - (blobuploader) - INFO - Open directory for files ... 12:28:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:28:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:28:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:33 INFO - (blobuploader) - INFO - Done attempting. 12:28:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:28:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:28:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:34 INFO - (blobuploader) - INFO - Done attempting. 12:28:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:28:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:28:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:36 INFO - (blobuploader) - INFO - Done attempting. 12:28:36 INFO - (blobuploader) - INFO - Iteration through files over. 12:28:36 INFO - Return code: 0 12:28:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:28:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:28:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f83234aaa2e8b548cd7e7c49c515511751216232198f2006a5de2e2c6d512be7533cf06dca956cb4cc7f1129b2a3c91c947475e93fe95d3739b4f036eed529e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e57fcc1a1dfc0abf1fec89e064b2806e7b5b0213f5775d5dcc82da7c9bfc5baee30bcda8d7965b172b2e2ed9fb29332ba6d5284e58066a87d9aee421537ca16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6e60a7b255667f056e1c58b62807cf359e4b6b1f1510b80fa9fefb0b47345807b0c68b0a245e571fa7d115d03df63b4d6a4037f81c0336aa5ddeedd6ebd3193"} 12:28:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:28:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:28:36 INFO - Contents: 12:28:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f83234aaa2e8b548cd7e7c49c515511751216232198f2006a5de2e2c6d512be7533cf06dca956cb4cc7f1129b2a3c91c947475e93fe95d3739b4f036eed529e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e57fcc1a1dfc0abf1fec89e064b2806e7b5b0213f5775d5dcc82da7c9bfc5baee30bcda8d7965b172b2e2ed9fb29332ba6d5284e58066a87d9aee421537ca16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6e60a7b255667f056e1c58b62807cf359e4b6b1f1510b80fa9fefb0b47345807b0c68b0a245e571fa7d115d03df63b4d6a4037f81c0336aa5ddeedd6ebd3193"} 12:28:36 INFO - Running post-run listener: copy_logs_to_upload_dir 12:28:36 INFO - Copying logs to upload dir... 12:28:36 INFO - mkdir: /builds/slave/test/build/upload/logs 12:28:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.374278 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-04 12:28:36.757474) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 12:28:36.760699) ========= 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.lzanSV6RWb/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.Wy7eawhhkc/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f83234aaa2e8b548cd7e7c49c515511751216232198f2006a5de2e2c6d512be7533cf06dca956cb4cc7f1129b2a3c91c947475e93fe95d3739b4f036eed529e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e57fcc1a1dfc0abf1fec89e064b2806e7b5b0213f5775d5dcc82da7c9bfc5baee30bcda8d7965b172b2e2ed9fb29332ba6d5284e58066a87d9aee421537ca16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6e60a7b255667f056e1c58b62807cf359e4b6b1f1510b80fa9fefb0b47345807b0c68b0a245e571fa7d115d03df63b4d6a4037f81c0336aa5ddeedd6ebd3193"} build_url:https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011048 build_url: 'https://queue.taskcluster.net/v1/task/exmP-ycMRFOQfKewmrI0_Q/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/6f83234aaa2e8b548cd7e7c49c515511751216232198f2006a5de2e2c6d512be7533cf06dca956cb4cc7f1129b2a3c91c947475e93fe95d3739b4f036eed529e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e57fcc1a1dfc0abf1fec89e064b2806e7b5b0213f5775d5dcc82da7c9bfc5baee30bcda8d7965b172b2e2ed9fb29332ba6d5284e58066a87d9aee421537ca16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6e60a7b255667f056e1c58b62807cf359e4b6b1f1510b80fa9fefb0b47345807b0c68b0a245e571fa7d115d03df63b4d6a4037f81c0336aa5ddeedd6ebd3193"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 12:28:36.794843) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 12:28:36.795176) ========= 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.lzanSV6RWb/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.Wy7eawhhkc/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005096 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 12:28:36.845922) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 12:28:36.846499) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 12:28:36.847064) ========= ========= Total master_lag: 0.18 =========