builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0353 starttime: 1488561973.12 results: success (0) buildid: 20170303084451 builduid: 1e4d5eaed95844fc920d476769a716e8 revision: b946ece0c9d2bf34a925d13d08be2724f275dfd5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:13.124182) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:13.124696) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:13.124976) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:13.153103) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:13.153478) ========= 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.CH4eNN8kI9/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.r8JOcphMJK/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-03-03 09:26:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 40.5M=0s 2017-03-03 09:26:13 (40.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116513 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:13.294858) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:13.295209) ========= 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.CH4eNN8kI9/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.r8JOcphMJK/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.020029 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:13.345087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-03 09:26:13.345478) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b946ece0c9d2bf34a925d13d08be2724f275dfd5 --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 b946ece0c9d2bf34a925d13d08be2724f275dfd5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CH4eNN8kI9/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.r8JOcphMJK/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-03-03 09:26:13,437 truncating revision to first 12 chars 2017-03-03 09:26:13,437 Setting DEBUG logging. 2017-03-03 09:26:13,438 attempt 1/10 2017-03-03 09:26:13,438 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b946ece0c9d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-03 09:26:14,594 unpacking tar archive at: mozilla-aurora-b946ece0c9d2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.415437 ========= master_lag: 0.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-03 09:26:14.992751) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:14.993067) ========= script_repo_revision: b946ece0c9d2bf34a925d13d08be2724f275dfd5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:14.993444) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:14.993722) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-03 09:26:15.014047) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-03-03 09:26:15.014456) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.CH4eNN8kI9/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.r8JOcphMJK/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 09:26:15 INFO - MultiFileLogger online at 20170303 09:26:15 in /builds/slave/test 09:26:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:26:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:26:15 INFO - {'append_to_log': False, 09:26:15 INFO - 'base_work_dir': '/builds/slave/test', 09:26:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:26:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:26:15 INFO - 'branch': 'Mozilla-Aurora', 09:26:15 INFO - 'buildbot_json_path': 'buildprops.json', 09:26:15 INFO - 'config_files': ('talos/mac_config.py',), 09:26:15 INFO - 'default_actions': ('clobber', 09:26:15 INFO - 'read-buildbot-config', 09:26:15 INFO - 'download-and-extract', 09:26:15 INFO - 'populate-webroot', 09:26:15 INFO - 'create-virtualenv', 09:26:15 INFO - 'install', 09:26:15 INFO - 'run-tests'), 09:26:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:26:15 INFO - 'download_minidump_stackwalk': True, 09:26:15 INFO - 'download_symbols': 'ondemand', 09:26:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:26:15 INFO - 'tooltool.py': '/tools/tooltool.py', 09:26:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:26:15 INFO - '/tools/misc-python/virtualenv.py')}, 09:26:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:26:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:26:15 INFO - 'installer_path': 'installer.exe', 09:26:15 INFO - 'log_level': 'info', 09:26:15 INFO - 'log_name': 'talos', 09:26:15 INFO - 'log_to_console': True, 09:26:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:26:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:26:15 INFO - 'opt_config_files': (), 09:26:15 INFO - 'pip_index': False, 09:26:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:15 INFO - 'cmd': ('bash', 09:26:15 INFO - '-c', 09:26:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:15 INFO - 'enabled': True, 09:26:15 INFO - 'halt_on_failure': False, 09:26:15 INFO - 'name': 'check_screen_resolution'},), 09:26:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:15 INFO - 'cmd': ('bash', 09:26:15 INFO - '-c', 09:26:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:15 INFO - 'enabled': True, 09:26:15 INFO - 'halt_on_failure': False, 09:26:15 INFO - 'name': 'check_screen_resolution'},), 09:26:15 INFO - 'run_cmd_checks_enabled': True, 09:26:15 INFO - 'sps_profile': False, 09:26:15 INFO - 'sps_profile_interval': 0, 09:26:15 INFO - 'suite': 'dromaeojs-e10s', 09:26:15 INFO - 'system_bits': '32', 09:26:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:26:15 INFO - 'title': 't-yosemite-r7-0353', 09:26:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:26:15 INFO - 'use_talos_json': True, 09:26:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:26:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:26:15 INFO - 'work_dir': 'build'} 09:26:15 INFO - [mozharness: 2017-03-03 17:26:15.223878Z] Running clobber step. 09:26:15 INFO - Running pre-action listener: _resource_record_pre_action 09:26:15 INFO - Running main action method: clobber 09:26:15 INFO - rmtree: /builds/slave/test/build 09:26:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:26:15 INFO - Running post-action listener: _resource_record_post_action 09:26:15 INFO - [mozharness: 2017-03-03 17:26:15.827831Z] Finished clobber step (success) 09:26:15 INFO - [mozharness: 2017-03-03 17:26:15.827986Z] Running read-buildbot-config step. 09:26:15 INFO - Running pre-action listener: _resource_record_pre_action 09:26:15 INFO - Running main action method: read_buildbot_config 09:26:15 INFO - Using buildbot properties: 09:26:15 INFO - { 09:26:15 INFO - "project": "", 09:26:15 INFO - "product": "firefox", 09:26:15 INFO - "script_repo_revision": "production", 09:26:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:26:15 INFO - "repository": "", 09:26:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:26:15 INFO - "buildid": "20170303084451", 09:26:15 INFO - "pgo_build": "False", 09:26:15 INFO - "basedir": "/builds/slave/test", 09:26:15 INFO - "buildnumber": 3, 09:26:15 INFO - "slavename": "t-yosemite-r7-0353", 09:26:15 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:26:15 INFO - "platform": "yosemite_r7", 09:26:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:26:15 INFO - "branch": "mozilla-aurora", 09:26:15 INFO - "repo_path": "releases/mozilla-aurora", 09:26:15 INFO - "revision": "b946ece0c9d2bf34a925d13d08be2724f275dfd5", 09:26:15 INFO - "stage_platform": "macosx64", 09:26:15 INFO - "builduid": "1e4d5eaed95844fc920d476769a716e8", 09:26:15 INFO - "slavebuilddir": "test" 09:26:15 INFO - } 09:26:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 09:26:15 INFO - Running post-action listener: _resource_record_post_action 09:26:15 INFO - [mozharness: 2017-03-03 17:26:15.831163Z] Finished read-buildbot-config step (success) 09:26:15 INFO - [mozharness: 2017-03-03 17:26:15.831262Z] Running download-and-extract step. 09:26:15 INFO - Running pre-action listener: _resource_record_pre_action 09:26:15 INFO - Running main action method: download_and_extract 09:26:15 INFO - mkdir: /builds/slave/test/build/tests 09:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:15 INFO - trying https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 09:26:15 INFO - Downloading https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:26:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 09:26:17 INFO - Downloaded 1199 bytes. 09:26:17 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:26:17 INFO - Using the following test package requirements: 09:26:17 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:26:17 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:26:17 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 09:26:17 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:26:17 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 09:26:17 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:26:17 INFO - u'jsshell-mac.zip'], 09:26:17 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:26:17 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 09:26:17 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:26:17 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:26:17 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 09:26:17 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:26:17 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 09:26:17 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:26:17 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 09:26:17 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:26:17 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 09:26:17 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:26:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:26:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:26:17 INFO - Fetch https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:26:18 INFO - Expected file size: 16357779 09:26:18 INFO - Obtained file size: 16357779 09:26:24 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:26:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:26:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:26:24 INFO - Fetch https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:26:25 INFO - Expected file size: 16357779 09:26:25 INFO - Obtained file size: 16357779 09:26:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 09:26:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:26:31 INFO - Fetch https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 09:26:32 INFO - Expected file size: 14403650 09:26:32 INFO - Obtained file size: 14403650 09:26:33 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')]} 09:26:33 INFO - trying https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:26:33 INFO - Downloading https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 09:26:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 09:26:35 INFO - Downloaded 73900393 bytes. 09:26:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:26:35 INFO - mkdir: /builds/slave/test/properties 09:26:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:26:35 INFO - Writing to file /builds/slave/test/properties/build_url 09:26:35 INFO - Contents: 09:26:35 INFO - build_url:https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:26:35 INFO - Running post-action listener: _resource_record_post_action 09:26:35 INFO - Running post-action listener: set_extra_try_arguments 09:26:35 INFO - [mozharness: 2017-03-03 17:26:35.910554Z] Finished download-and-extract step (success) 09:26:35 INFO - [mozharness: 2017-03-03 17:26:35.910677Z] Running populate-webroot step. 09:26:35 INFO - Running pre-action listener: _resource_record_pre_action 09:26:35 INFO - Running main action method: populate_webroot 09:26:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:26:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:26:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:26:35 INFO - '--tppagecycles', 09:26:35 INFO - '7'], 09:26:35 INFO - 'tests': ['tsvgm']}}, 09:26:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:26:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:26:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:26:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:26:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:26:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:26:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:26:35 INFO - 'talos_options': ['--disable-e10s'], 09:26:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:26:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:26:35 INFO - 'talos_options': ['--disable-e10s'], 09:26:35 INFO - 'tests': ['damp', 'tps']}, 09:26:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:26:35 INFO - 'tests': ['damp', 'tps']}, 09:26:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:26:35 INFO - 'tests': ['dromaeo_dom']}, 09:26:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:26:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:26:35 INFO - 'tests': ['basic_compositor_video']}, 09:26:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:26:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:26:35 INFO - 'tests': ['a11yr', 09:26:35 INFO - 'ts_paint', 09:26:35 INFO - 'tpaint', 09:26:35 INFO - 'sessionrestore', 09:26:35 INFO - 'sessionrestore_no_auto_restore', 09:26:35 INFO - 'tabpaint']}, 09:26:35 INFO - 'other-e10s': {'tests': ['a11yr', 09:26:35 INFO - 'ts_paint', 09:26:35 INFO - 'tpaint', 09:26:35 INFO - 'sessionrestore', 09:26:35 INFO - 'sessionrestore_no_auto_restore', 09:26:35 INFO - 'tabpaint']}, 09:26:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:26:35 INFO - 'ts_paint', 09:26:35 INFO - 'tpaint', 09:26:35 INFO - 'sessionrestore', 09:26:35 INFO - 'sessionrestore_no_auto_restore', 09:26:35 INFO - 'tabpaint']}, 09:26:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:26:35 INFO - 'ts_paint', 09:26:35 INFO - 'tpaint', 09:26:35 INFO - 'sessionrestore', 09:26:35 INFO - 'sessionrestore_no_auto_restore', 09:26:35 INFO - 'tabpaint']}, 09:26:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:26:35 INFO - 'tests': ['a11yr', 09:26:35 INFO - 'ts_paint', 09:26:35 INFO - 'tpaint', 09:26:35 INFO - 'sessionrestore', 09:26:35 INFO - 'sessionrestore_no_auto_restore', 09:26:35 INFO - 'tabpaint']}, 09:26:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:26:35 INFO - 'tests': ['a11yr', 09:26:35 INFO - 'ts_paint', 09:26:35 INFO - 'tpaint', 09:26:35 INFO - 'sessionrestore', 09:26:35 INFO - 'sessionrestore_no_auto_restore', 09:26:35 INFO - 'tabpaint']}, 09:26:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:26:35 INFO - 'tests': ['tsvgx', 09:26:35 INFO - 'tsvgr_opacity', 09:26:35 INFO - 'tart', 09:26:35 INFO - 'tscrollx', 09:26:35 INFO - 'cart', 09:26:35 INFO - 'tsvg_static']}, 09:26:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:26:35 INFO - 'tsvgr_opacity', 09:26:35 INFO - 'tart', 09:26:35 INFO - 'tscrollx', 09:26:35 INFO - 'cart', 09:26:35 INFO - 'tsvg_static']}, 09:26:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:26:35 INFO - 'talos_options': ['--disable-e10s'], 09:26:35 INFO - 'tests': ['tp5o']}, 09:26:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:26:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:26:35 INFO - 'talos_options': ['--disable-e10s', 09:26:35 INFO - '--xperf_path', 09:26:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:35 INFO - 'tests': ['tp5n']}, 09:26:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:26:35 INFO - 'talos_options': ['--xperf_path', 09:26:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:35 INFO - 'tests': ['tp5n']}}, 09:26:35 INFO - 'talos.zip': {'path': '', 09:26:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:26:35 INFO - Running post-action listener: _resource_record_post_action 09:26:35 INFO - [mozharness: 2017-03-03 17:26:35.929525Z] Finished populate-webroot step (success) 09:26:35 INFO - [mozharness: 2017-03-03 17:26:35.929661Z] Running create-virtualenv step. 09:26:35 INFO - Running pre-action listener: _resource_record_pre_action 09:26:35 INFO - Running main action method: create_virtualenv 09:26:35 INFO - Creating virtualenv /builds/slave/test/build/venv 09:26:35 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 09:26:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:26:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:26:36 INFO - Using real prefix '/tools/python27' 09:26:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:26:36 INFO - Installing distribute.............................................................................................................................................................................................done. 09:26:39 INFO - Installing pip.................done. 09:26:39 INFO - Return code: 0 09:26:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:26:39 INFO - Reading from file tmpfile_stdout 09:26:39 INFO - Output received: 09:26:39 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) 09:26:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:26:39 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')]} 09:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:39 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')]} 09:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:39 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 0x108a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8a1d299f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108879d30>, '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 0x1087a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886ebe8>, '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.CH4eNN8kI9/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.r8JOcphMJK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 09:26:39 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 09:26:39 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 09:26:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CH4eNN8kI9/Render', 09:26:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:39 INFO - 'HOME': '/Users/cltbld', 09:26:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:39 INFO - 'LOGNAME': 'cltbld', 09:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:39 INFO - 'MOZ_NO_REMOTE': '1', 09:26:39 INFO - 'NO_EM_RESTART': '1', 09:26:39 INFO - 'PAGER': '/bin/cat', 09:26:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:39 INFO - 'PWD': '/builds/slave/test', 09:26:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:39 INFO - 'SHELL': '/bin/bash', 09:26:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8JOcphMJK/Listeners', 09:26:39 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:26:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:39 INFO - 'USER': 'cltbld', 09:26:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:39 INFO - 'XPC_FLAGS': '0x0', 09:26:39 INFO - 'XPC_SERVICE_NAME': '0', 09:26:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:40 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 09:26:40 INFO - Cleaning up... 09:26:40 INFO - Return code: 0 09:26:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:40 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 0x108a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8a1d299f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108879d30>, '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 0x1087a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886ebe8>, '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.CH4eNN8kI9/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.r8JOcphMJK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 09:26:40 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 09:26:40 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 09:26:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CH4eNN8kI9/Render', 09:26:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:40 INFO - 'HOME': '/Users/cltbld', 09:26:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:40 INFO - 'LOGNAME': 'cltbld', 09:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:40 INFO - 'MOZ_NO_REMOTE': '1', 09:26:40 INFO - 'NO_EM_RESTART': '1', 09:26:40 INFO - 'PAGER': '/bin/cat', 09:26:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:40 INFO - 'PWD': '/builds/slave/test', 09:26:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:40 INFO - 'SHELL': '/bin/bash', 09:26:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8JOcphMJK/Listeners', 09:26:40 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:26:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:40 INFO - 'USER': 'cltbld', 09:26:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:40 INFO - 'XPC_FLAGS': '0x0', 09:26:40 INFO - 'XPC_SERVICE_NAME': '0', 09:26:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:40 INFO - Downloading/unpacking psutil>=3.1.1 09:26:40 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 09:26:40 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 09:26:40 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 09:26:40 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 09:26:40 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 09:26:40 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 09:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:26:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:43 INFO - Installing collected packages: psutil 09:26:43 INFO - Running setup.py install for psutil 09:26:44 INFO - building 'psutil._psutil_osx' extension 09:26:44 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 09:26:44 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 09:26:44 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 09:26:44 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 09:26:44 INFO - building 'psutil._psutil_posix' extension 09:26:44 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 09:26:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:26:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:26:44 INFO - ^ 09:26:44 INFO - 1 warning generated. 09:26:44 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 09:26:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:44 INFO - Successfully installed psutil 09:26:44 INFO - Cleaning up... 09:26:44 INFO - Return code: 0 09:26:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:44 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 0x108a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8a1d299f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108879d30>, '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 0x1087a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886ebe8>, '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.CH4eNN8kI9/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.r8JOcphMJK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 09:26:44 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 09:26:44 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 09:26:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CH4eNN8kI9/Render', 09:26:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:44 INFO - 'HOME': '/Users/cltbld', 09:26:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:44 INFO - 'LOGNAME': 'cltbld', 09:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:44 INFO - 'MOZ_NO_REMOTE': '1', 09:26:44 INFO - 'NO_EM_RESTART': '1', 09:26:44 INFO - 'PAGER': '/bin/cat', 09:26:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:44 INFO - 'PWD': '/builds/slave/test', 09:26:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:44 INFO - 'SHELL': '/bin/bash', 09:26:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8JOcphMJK/Listeners', 09:26:44 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:26:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:44 INFO - 'USER': 'cltbld', 09:26:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:44 INFO - 'XPC_FLAGS': '0x0', 09:26:44 INFO - 'XPC_SERVICE_NAME': '0', 09:26:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:26:45 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 09:26:45 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 09:26:45 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 09:26:45 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 09:26:45 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 09:26:45 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 09:26:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:26:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:26:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:26:47 INFO - Installing collected packages: mozsystemmonitor 09:26:47 INFO - Running setup.py install for mozsystemmonitor 09:26:48 INFO - Successfully installed mozsystemmonitor 09:26:48 INFO - Cleaning up... 09:26:48 INFO - Return code: 0 09:26:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8a1d299f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108879d30>, '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 0x1087a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886ebe8>, '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.CH4eNN8kI9/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.r8JOcphMJK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 09:26:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:26:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:26:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CH4eNN8kI9/Render', 09:26:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:48 INFO - 'HOME': '/Users/cltbld', 09:26:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:48 INFO - 'LOGNAME': 'cltbld', 09:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:48 INFO - 'MOZ_NO_REMOTE': '1', 09:26:48 INFO - 'NO_EM_RESTART': '1', 09:26:48 INFO - 'PAGER': '/bin/cat', 09:26:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:48 INFO - 'PWD': '/builds/slave/test', 09:26:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:48 INFO - 'SHELL': '/bin/bash', 09:26:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8JOcphMJK/Listeners', 09:26:48 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:26:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:48 INFO - 'USER': 'cltbld', 09:26:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:48 INFO - 'XPC_FLAGS': '0x0', 09:26:48 INFO - 'XPC_SERVICE_NAME': '0', 09:26:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:48 INFO - Downloading/unpacking jsonschema==2.5.1 09:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:26:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26:51 INFO - Downloading functools32-3.2.3-2.tar.gz 09:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:26:51 INFO - warning: no files found matching '*.txt' 09:26:51 INFO - no previously-included directories found matching 'build' 09:26:51 INFO - no previously-included directories found matching 'dist' 09:26:51 INFO - no previously-included directories found matching '.git*' 09:26:51 INFO - Installing collected packages: jsonschema, functools32 09:26:51 INFO - Running setup.py install for functools32 09:26:51 INFO - warning: no files found matching '*.txt' 09:26:51 INFO - no previously-included directories found matching 'build' 09:26:51 INFO - no previously-included directories found matching 'dist' 09:26:51 INFO - no previously-included directories found matching '.git*' 09:26:51 INFO - Successfully installed jsonschema functools32 09:26:51 INFO - Cleaning up... 09:26:51 INFO - Return code: 0 09:26:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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')]} 09:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8a1d299f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108879d30>, '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 0x1087a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886ebe8>, '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.CH4eNN8kI9/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.r8JOcphMJK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 09:26:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:26:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:26:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CH4eNN8kI9/Render', 09:26:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:51 INFO - 'HOME': '/Users/cltbld', 09:26:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:51 INFO - 'LOGNAME': 'cltbld', 09:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:51 INFO - 'MOZ_NO_REMOTE': '1', 09:26:51 INFO - 'NO_EM_RESTART': '1', 09:26:51 INFO - 'PAGER': '/bin/cat', 09:26:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:51 INFO - 'PWD': '/builds/slave/test', 09:26:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:51 INFO - 'SHELL': '/bin/bash', 09:26:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8JOcphMJK/Listeners', 09:26:51 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:26:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:51 INFO - 'USER': 'cltbld', 09:26:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:51 INFO - 'XPC_FLAGS': '0x0', 09:26:51 INFO - 'XPC_SERVICE_NAME': '0', 09:26:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:26:51 INFO - Cleaning up... 09:26:52 INFO - Return code: 0 09:26:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:26:52 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')]} 09:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:52 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')]} 09:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:52 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 0x108a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8a1d299f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108879d30>, '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 0x1087a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886ebe8>, '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.CH4eNN8kI9/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.r8JOcphMJK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 09:26:52 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 09:26:52 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 09:26:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CH4eNN8kI9/Render', 09:26:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:52 INFO - 'HOME': '/Users/cltbld', 09:26:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:52 INFO - 'LOGNAME': 'cltbld', 09:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:52 INFO - 'MOZ_NO_REMOTE': '1', 09:26:52 INFO - 'NO_EM_RESTART': '1', 09:26:52 INFO - 'PAGER': '/bin/cat', 09:26:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:52 INFO - 'PWD': '/builds/slave/test', 09:26:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:52 INFO - 'SHELL': '/bin/bash', 09:26:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8JOcphMJK/Listeners', 09:26:52 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:26:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:52 INFO - 'USER': 'cltbld', 09:26:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:52 INFO - 'XPC_FLAGS': '0x0', 09:26:52 INFO - 'XPC_SERVICE_NAME': '0', 09:26:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:52 INFO - Downloading/unpacking blobuploader==1.2.4 09:26:52 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 09:26:52 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 09:26:52 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 09:26:52 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 09:26:52 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 09:26:52 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 09:26:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:26:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:26:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:26:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26:56 INFO - Downloading docopt-0.6.1.tar.gz 09:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:26:56 INFO - Installing collected packages: blobuploader, requests, docopt 09:26:56 INFO - Running setup.py install for blobuploader 09:26:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:26:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:26:56 INFO - Running setup.py install for requests 09:26:57 INFO - Running setup.py install for docopt 09:26:57 INFO - Successfully installed blobuploader requests docopt 09:26:57 INFO - Cleaning up... 09:26:57 INFO - Return code: 0 09:26:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:57 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')]} 09:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:57 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')]} 09:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:57 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 0x108a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8a1d299f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108879d30>, '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 0x1087a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886ebe8>, '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.CH4eNN8kI9/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.r8JOcphMJK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 09:26:57 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 09:26:57 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 09:26:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CH4eNN8kI9/Render', 09:26:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:57 INFO - 'HOME': '/Users/cltbld', 09:26:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:57 INFO - 'LOGNAME': 'cltbld', 09:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:57 INFO - 'MOZ_NO_REMOTE': '1', 09:26:57 INFO - 'NO_EM_RESTART': '1', 09:26:57 INFO - 'PAGER': '/bin/cat', 09:26:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:57 INFO - 'PWD': '/builds/slave/test', 09:26:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:57 INFO - 'SHELL': '/bin/bash', 09:26:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8JOcphMJK/Listeners', 09:26:57 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:26:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:57 INFO - 'USER': 'cltbld', 09:26:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:57 INFO - 'XPC_FLAGS': '0x0', 09:26:57 INFO - 'XPC_SERVICE_NAME': '0', 09:26:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:57 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-3TxaIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:57 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-cTQsP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:57 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-fZrbdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:57 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-yRSvuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:57 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Zle8B1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:58 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-1kjxFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:58 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-NolCwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:58 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-YHrer1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:58 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-1Dw9aF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:58 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-fiLjDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:58 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-OphAiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:58 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-H8zi5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:58 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-AjvhVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:58 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-umzn4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:59 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-7Kdgw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:59 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-fj1tEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:59 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-JCCzGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:26:59 INFO - Running setup.py install for manifestparser 09:26:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:26:59 INFO - Running setup.py install for mozcrash 09:26:59 INFO - Running setup.py install for mozdebug 09:26:59 INFO - Running setup.py install for mozdevice 09:26:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:26:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:26:59 INFO - Running setup.py install for mozfile 09:27:00 INFO - Running setup.py install for mozhttpd 09:27:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:00 INFO - Running setup.py install for mozinfo 09:27:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:00 INFO - Running setup.py install for mozInstall 09:27:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:00 INFO - Running setup.py install for mozleak 09:27:00 INFO - Running setup.py install for mozlog 09:27:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:00 INFO - Running setup.py install for moznetwork 09:27:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:00 INFO - Running setup.py install for mozprocess 09:27:01 INFO - Running setup.py install for mozprofile 09:27:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:01 INFO - Running setup.py install for mozrunner 09:27:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:01 INFO - Running setup.py install for mozscreenshot 09:27:01 INFO - Running setup.py install for moztest 09:27:01 INFO - Running setup.py install for mozversion 09:27:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:01 INFO - Cleaning up... 09:27:01 INFO - Return code: 0 09:27:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:01 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')]} 09:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:01 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')]} 09:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:01 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 0x108a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8a1d299f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108879d30>, '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 0x1087a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886ebe8>, '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.CH4eNN8kI9/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.r8JOcphMJK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 09:27:01 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 09:27:01 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 09:27:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CH4eNN8kI9/Render', 09:27:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:01 INFO - 'HOME': '/Users/cltbld', 09:27:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:01 INFO - 'LOGNAME': 'cltbld', 09:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:01 INFO - 'MOZ_NO_REMOTE': '1', 09:27:01 INFO - 'NO_EM_RESTART': '1', 09:27:01 INFO - 'PAGER': '/bin/cat', 09:27:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:01 INFO - 'PWD': '/builds/slave/test', 09:27:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:01 INFO - 'SHELL': '/bin/bash', 09:27:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8JOcphMJK/Listeners', 09:27:01 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:27:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:01 INFO - 'USER': 'cltbld', 09:27:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:01 INFO - 'XPC_FLAGS': '0x0', 09:27:01 INFO - 'XPC_SERVICE_NAME': '0', 09:27:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:02 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-raq05t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:02 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)) 09:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:02 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-YS9DJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:02 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)) 09:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:02 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-sYveJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:02 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)) 09:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:02 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-HpRGyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:02 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-gcFIj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:02 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)) 09:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:02 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-aJ9T2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:02 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)) 09:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:02 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-sfZTjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:02 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)) 09:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:02 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Kc57Hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:03 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:03 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-X4r2D9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:03 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:03 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-JeUWoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:03 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:03 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-roCjdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:03 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:03 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Q8Lswu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:03 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-W6Dfux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:03 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:03 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-aQ3mZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:03 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:03 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-cBF5mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:03 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:03 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Q7G8wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:03 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:03 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Z51Hpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:03 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)) 09:27:03 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)) 09:27:03 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)) 09:27:03 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)) 09:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:27:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:06 INFO - Downloading blessings-1.6.tar.gz 09:27:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:06 INFO - Installing collected packages: blessings 09:27:06 INFO - Running setup.py install for blessings 09:27:06 INFO - Successfully installed blessings 09:27:06 INFO - Cleaning up... 09:27:06 INFO - Return code: 0 09:27:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:07 INFO - Reading from file tmpfile_stdout 09:27:07 INFO - Current package versions: 09:27:07 INFO - blessings == 1.6 09:27:07 INFO - blobuploader == 1.2.4 09:27:07 INFO - docopt == 0.6.1 09:27:07 INFO - functools32 == 3.2.3-2 09:27:07 INFO - jsonschema == 2.5.1 09:27:07 INFO - manifestparser == 1.1 09:27:07 INFO - mozInstall == 1.12 09:27:07 INFO - mozcrash == 1.0 09:27:07 INFO - mozdebug == 0.1 09:27:07 INFO - mozdevice == 0.49 09:27:07 INFO - mozfile == 1.2 09:27:07 INFO - mozhttpd == 0.7 09:27:07 INFO - mozinfo == 0.9 09:27:07 INFO - mozleak == 0.1 09:27:07 INFO - mozlog == 3.4 09:27:07 INFO - moznetwork == 0.27 09:27:07 INFO - mozprocess == 0.24 09:27:07 INFO - mozprofile == 0.28 09:27:07 INFO - mozrunner == 6.13 09:27:07 INFO - mozscreenshot == 0.1 09:27:07 INFO - mozsystemmonitor == 0.3 09:27:07 INFO - moztest == 0.8 09:27:07 INFO - mozversion == 1.4 09:27:07 INFO - psutil == 3.1.1 09:27:07 INFO - requests == 1.2.3 09:27:07 INFO - wsgiref == 0.1.2 09:27:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:07 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 0x108a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8a1d299f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108879d30>, '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 0x1087a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886ebe8>, '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.CH4eNN8kI9/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.r8JOcphMJK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 09:27:07 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 09:27:07 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 09:27:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CH4eNN8kI9/Render', 09:27:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:07 INFO - 'HOME': '/Users/cltbld', 09:27:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:07 INFO - 'LOGNAME': 'cltbld', 09:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:07 INFO - 'MOZ_NO_REMOTE': '1', 09:27:07 INFO - 'NO_EM_RESTART': '1', 09:27:07 INFO - 'PAGER': '/bin/cat', 09:27:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:07 INFO - 'PWD': '/builds/slave/test', 09:27:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:07 INFO - 'SHELL': '/bin/bash', 09:27:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8JOcphMJK/Listeners', 09:27:07 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:27:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:07 INFO - 'USER': 'cltbld', 09:27:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:07 INFO - 'XPC_FLAGS': '0x0', 09:27:07 INFO - 'XPC_SERVICE_NAME': '0', 09:27:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 INFO - Cleaning up... 09:27:07 INFO - Return code: 0 09:27:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8a1d299f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108879d30>, '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 0x1087a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886ebe8>, '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.CH4eNN8kI9/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.r8JOcphMJK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 09:27:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:27:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:27:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CH4eNN8kI9/Render', 09:27:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:07 INFO - 'HOME': '/Users/cltbld', 09:27:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:07 INFO - 'LOGNAME': 'cltbld', 09:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:07 INFO - 'MOZ_NO_REMOTE': '1', 09:27:07 INFO - 'NO_EM_RESTART': '1', 09:27:07 INFO - 'PAGER': '/bin/cat', 09:27:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:07 INFO - 'PWD': '/builds/slave/test', 09:27:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:07 INFO - 'SHELL': '/bin/bash', 09:27:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8JOcphMJK/Listeners', 09:27:07 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:27:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:07 INFO - 'USER': 'cltbld', 09:27:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:07 INFO - 'XPC_FLAGS': '0x0', 09:27:07 INFO - 'XPC_SERVICE_NAME': '0', 09:27:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:27:07 INFO - Cleaning up... 09:27:07 INFO - Return code: 0 09:27:07 INFO - Running post-action listener: _resource_record_post_action 09:27:07 INFO - Running post-action listener: _start_resource_monitoring 09:27:07 INFO - Starting resource monitoring. 09:27:07 INFO - [mozharness: 2017-03-03 17:27:07.818537Z] Finished create-virtualenv step (success) 09:27:07 INFO - [mozharness: 2017-03-03 17:27:07.819480Z] Running install step. 09:27:07 INFO - Running pre-action listener: _resource_record_pre_action 09:27:07 INFO - Running main action method: install 09:27:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:08 INFO - Reading from file tmpfile_stdout 09:27:08 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:08 INFO - Reading from file tmpfile_stdout 09:27:08 INFO - Output received: 09:27:08 INFO - Usage: mozinstall [options] installer 09:27:08 INFO - Options: 09:27:08 INFO - -h, --help show this help message and exit 09:27:08 INFO - -d DEST, --destination=DEST 09:27:08 INFO - Directory to install application into. [default: 09:27:08 INFO - "/builds/slave/test"] 09:27:08 INFO - --app=APP Application being installed. [default: firefox] 09:27:08 INFO - mkdir: /builds/slave/test/build/application 09:27:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:27:28 INFO - Reading from file tmpfile_stdout 09:27:28 INFO - Output received: 09:27:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:27:28 INFO - Running post-action listener: _resource_record_post_action 09:27:28 INFO - [mozharness: 2017-03-03 17:27:28.174731Z] Finished install step (success) 09:27:28 INFO - [mozharness: 2017-03-03 17:27:28.174881Z] Running run-tests step. 09:27:28 INFO - Running pre-action listener: _resource_record_pre_action 09:27:28 INFO - Running main action method: run_tests 09:27:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:28 INFO - 2017-03-03 09:27:28.189 screenresolution[29898:276103] starting screenresolution argv=screenresolution get 09:27:28 INFO - 2017-03-03 09:27:28.192 screenresolution[29898:276103] Display 0: 1600x1200x32@60 09:27:28 INFO - 2017-03-03 09:27:28.197 screenresolution[29899:276106] starting screenresolution argv=screenresolution list 09:27:28 INFO - Available Modes on Display 0 09:27:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:28 INFO - Intel Iris: 09:27:28 INFO - Chipset Model: Intel Iris 09:27:28 INFO - Type: GPU 09:27:28 INFO - Bus: Built-In 09:27:28 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:28 INFO - Vendor: Intel (0x8086) 09:27:28 INFO - Device ID: 0x0a2e 09:27:28 INFO - Revision ID: 0x0009 09:27:28 INFO - Displays: 09:27:28 INFO - Display: 09:27:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:28 INFO - Display Serial Number: 1600x1200 60 09:27:28 INFO - Main Display: Yes 09:27:28 INFO - Mirror: Off 09:27:28 INFO - Online: Yes 09:27:28 INFO - Rotation: Supported 09:27:28 INFO - Return code: 0 09:27:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:27:28 INFO - Python 2.7.3 09:27:28 INFO - Return code: 0 09:27:28 INFO - grabbing minidump binary from tooltool 09:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:28 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 0x108879d30>, '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 0x1087a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:27:28 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 09:27:28 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 09:27:28 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 09:27:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:27:28 INFO - Return code: 0 09:27:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:27:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:27:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:27:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:27:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:27:28 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:27:28 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0353 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:27:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CH4eNN8kI9/Render', 09:27:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:28 INFO - 'HOME': '/Users/cltbld', 09:27:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:28 INFO - 'LOGNAME': 'cltbld', 09:27:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:27:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:28 INFO - 'MOZ_NO_REMOTE': '1', 09:27:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:27:28 INFO - 'NO_EM_RESTART': '1', 09:27:28 INFO - 'PAGER': '/bin/cat', 09:27:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:28 INFO - 'PWD': '/builds/slave/test', 09:27:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:27:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:28 INFO - 'SHELL': '/bin/bash', 09:27:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r8JOcphMJK/Listeners', 09:27:28 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:27:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:28 INFO - 'USER': 'cltbld', 09:27:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:28 INFO - 'XPC_FLAGS': '0x0', 09:27:28 INFO - 'XPC_SERVICE_NAME': '0', 09:27:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:27:28 INFO - mozversion application_buildid: 20170303084451 09:27:28 INFO - mozversion application_changeset: b946ece0c9d2bf34a925d13d08be2724f275dfd5 09:27:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:27:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:27:28 INFO - mozversion application_name: Firefox 09:27:28 INFO - mozversion application_remotingname: firefox-dev 09:27:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:27:28 INFO - mozversion application_vendor: Mozilla 09:27:28 INFO - mozversion application_version: 53.0a2 09:27:28 INFO - mozversion platform_buildid: 20170303084451 09:27:28 INFO - mozversion platform_changeset: b946ece0c9d2bf34a925d13d08be2724f275dfd5 09:27:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:27:28 INFO - mozversion platform_version: 53.0a2 09:27:28 INFO - using testdate: 1488562048 09:27:28 INFO - actual date: 1488562048 09:27:28 INFO - starting webserver on 'localhost:51345' 09:27:28 INFO - SUITE-START | Running 2 tests 09:27:28 INFO - TEST-START | dromaeo_css 09:27:28 INFO - operating with platform_type : mac_ 09:27:28 INFO - Initialising browser for dromaeo_css test... 09:27:28 INFO - TEST-INFO | started process 29909 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpGcIk23/profile http://localhost:51345/getInfo.html) 09:27:31 INFO - PROCESS | 29909 | 2017-03-03 09:27:31.428 plugin-container[29911:276277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 09:27:31 INFO - PROCESS | 29909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:31 INFO - PROCESS | 29909 | 2017-03-03 09:27:31.431 plugin-container[29911:276277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4923, name = 'com.apple.CFPasteboardClient' 09:27:31 INFO - PROCESS | 29909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:31 INFO - PROCESS | 29909 | 2017-03-03 09:27:31.431 plugin-container[29911:276277] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:27:31 INFO - PROCESS | 29909 | __metrics Screen width/height:1600/1200 09:27:31 INFO - PROCESS | 29909 | colorDepth:24 09:27:31 INFO - PROCESS | 29909 | Browser inner width/height: 1024/689 09:27:31 INFO - PROCESS | 29909 | __metrics 09:27:31 ERROR - PROCESS | 29909 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:27:32 INFO - TEST-INFO | 29909: exit 0 09:27:32 INFO - Browser initialized. 09:27:32 INFO - Running cycle 1/1 for dromaeo_css test... 09:27:32 INFO - TEST-INFO | started process 29912 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpGcIk23/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:34 INFO - PROCESS | 29912 | 2017-03-03 09:27:34.784 plugin-container[29913:276405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 09:27:34 INFO - PROCESS | 29912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:34 INFO - PROCESS | 29912 | 2017-03-03 09:27:34.785 plugin-container[29913:276405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 09:27:34 INFO - PROCESS | 29912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:34 INFO - PROCESS | 29912 | 2017-03-03 09:27:34.785 plugin-container[29913:276405] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:27:44 INFO - PROCESS | 29912 | RSS: Main: 218427392 09:27:44 INFO - PROCESS | 29912 | 09:30:46 INFO - PROCESS | 29912 | Cycle 1(1): loaded http://localhost:51345/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51345/tests/dromaeo/cssquery-ext.html) 09:30:48 INFO - PROCESS | 29912 | RSS: Main: 239427584 09:30:48 INFO - PROCESS | 29912 | 09:32:32 INFO - PROCESS | 29912 | ************************* 09:32:32 INFO - PROCESS | 29912 | A coding exception was thrown and uncaught in a Task. 09:32:32 INFO - PROCESS | 29912 | 09:32:32 ERROR - PROCESS | 29912 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:32:32 INFO - PROCESS | 29912 | Full stack: 09:32:32 INFO - PROCESS | 29912 | ************************* 09:33:50 INFO - PROCESS | 29912 | Cycle 1(1): loaded http://localhost:51345/tests/dromaeo/cssquery-ext.html (next: http://localhost:51345/tests/dromaeo/cssquery-jquery.html) 09:33:51 INFO - PROCESS | 29912 | RSS: Main: 248053760 09:33:51 INFO - PROCESS | 29912 | 09:36:53 INFO - PROCESS | 29912 | Cycle 1(1): loaded http://localhost:51345/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51345/tests/dromaeo/cssquery-mootools.html) 09:36:54 INFO - PROCESS | 29912 | RSS: Main: 255602688 09:36:54 INFO - PROCESS | 29912 | 09:39:56 INFO - PROCESS | 29912 | Cycle 1(1): loaded http://localhost:51345/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51345/tests/dromaeo/cssquery-prototype.html) 09:39:56 INFO - PROCESS | 29912 | RSS: Main: 252866560 09:39:56 INFO - PROCESS | 29912 | 09:42:59 INFO - PROCESS | 29912 | Cycle 1(1): loaded http://localhost:51345/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51345/tests/dromaeo/cssquery-yui.html) 09:43:00 INFO - PROCESS | 29912 | RSS: Main: 256131072 09:43:00 INFO - PROCESS | 29912 | 09:46:02 INFO - PROCESS | 29912 | Cycle 1(1): loaded http://localhost:51345/tests/dromaeo/cssquery-yui.html (next: http://localhost:51345/tests/dromaeo/cssquery-dojo.html) 09:46:03 INFO - PROCESS | 29912 | RSS: Main: 257822720 09:46:03 INFO - PROCESS | 29912 | 09:46:03 INFO - PROCESS | 29912 | __start_tp_report 09:46:03 INFO - PROCESS | 29912 | _x_x_mozilla_page_load 09:46:03 INFO - PROCESS | 29912 | _x_x_mozilla_page_load_details 09:46:03 INFO - PROCESS | 29912 | |i|pagename|runs| 09:46:03 INFO - PROCESS | 29912 | |0;dojo.html;3359.7312215022803;3390.3049874775656;3402.846871890765;3409.471531912553;3430.4511278195496;5746.712664366781;5758.481736643701;5840.182374467578;5847.386024467433;5872.1191821226785;5731.312242530902;5777.682227477487;5796.62321949073;5808.767649294024;5830.892130495214;5229.398619158807;5308.867278318054;5314.601404894629;5346.599005074616;5364.839054828341;5237.397699264595;5265.9473405266035;5275.446078161795;5342.519173274404;5360.463953604628;5083.720395378253;5119.360079990001;5137;5288.762005709733;5298.072837253504;5191.402988656315;5198.636095473318;5203.219517072469;5259.184826351921;5263.710495922723;5184.611154163453;5196;5235.6073294502685;5260.053190425722;5288.365396152448;4942.1351263529095;5045.4197767256865;5248.317718696583;5449.482299181572;5760.798372056958;6187.721552530147;6253.437190652863;6264.060390941349;6269.404406581368;6280.403361680633;6234;6266.87466250677;6283.717232724538;6286.49708023357;6292.307846136921;5861.531077513789;5891.675957822361;5892.204552385416;5911.7585307085565;5950.19672344228;6171.13604095427;6172.629642221481;6240.500759939194;6247.312795592481;6292.370762923671;6172.660503672341;6192.690365481708;6242.188515492957;6244.812655620339;6250.562460627712;6265.091561723524;6273.404026617555;6300.7479700811455;6307.211598550181;6313.810585682437;6121.452973575903;6161.291451483047;6181.041938499539;6183.84540386494;6206.937930620636;6211.440970312694;6249.406306400884;6262.906056409158;6275.058741188858;6319.146915166394;5050.49495050492;5071.264666623319;5080.339555857789;5083.034223497523;5084.8220312288895;4374.387585737999;4393.75834329115;4450.732956022649;4521.615662668644;4523.773811309487;5961.165436838846;6000.909986350208;6064.75740970365;6075.817725468243;6101.511879049665;5002.624803139779;6174.228221472316;6229.0967809667345;6240.5319601030105;6246.6876656167005;5851.063829787214;5936;5963.493103086286;6014.609050411709;6065.4541091302;5522.751476183501;5523.502884740393;5530.695811730394;5537.944620553743;5550.306211723535;6452.161219041683;6455.7740479083;6456.160699109086;6459.806205813833;6488.805335840027;5140.485951404829;6173.32093469709;6205;6229.127922091002;6274.403931626488;6384.776532821329;6390.936090639034;6408.679566021587;6446.903296450463;6450.548461607643;6255.31191568918;6270.341614130633;6273.404026617464;6275;6282.685865706788;6182.103594978613;6201.1938448003275;6204.968975155276;6238.594491358047;6291.685415729286;5935.7032148391545;6144.446999770043;6206.534509911684;6212;6217.409346112112;6178.320384757763;6182.474489668429;6182.845428864314;6197.101420293942;6219.937800622117;5184.896302073862;5854.765809367748;5967.254093238345;6023.30731965812;6065.181200537871;6118.314626315956;6235.563510554218;6239.96880015597;6242.032484964838;6265.906011409924;6099.75600975956;6112.8777424452155;6123.9693801532485;6127.3259941407305;6135.877282454415 09:46:03 INFO - PROCESS | 29912 | |1;ext.html;447.6553054148305;484.2953502653638;493.45966167047095;497.6640767481949;545.6398776807308;30922.69077309226;38533.22933541331;39158.65048097116;39917.4012389814;39924.80037599815;35184.64815351846;41932.58067419325;41944.58055419445;42108.78945605276;42158.10288537016;8277.048139463948;8479.024912135092;9034.186923176912;9340.813183736322;11093.611723589676;11219.943900280487;11372.545098196062;11411;11423.42882855858;11428.314301141958;11422.657320280405;11423.714407139805;11439.714007149805;11456.599019034335;11458.197926145172;11363.090952723762;11376.886231137665;11391.772164556745;11433.828492572618;11437.970582647602;11263.831042534368;11320.886791132065;11324.716882077932;11325.773484530264;11343.205975581712;40616.796916015526;40951.1809763803;41031.79484102561;41123.766287011436;41152.971175720544;3751.8874433767032;3977.76133431995;4012.578679238667;4019.658329042035;4035.1929614077007;29221.853890730625;29322;30254.54618180729;31156.909508167104;31738;3056.190109620952;3179.3959147761852;3238.3199528099126;3286.5398844161837;3411.1642647551507;484119;486328;491370;493659;495703;71162.64418677977;72402.27597723955;72556;72573.637131814;72603.27396726071;76349.6182519095;76805;76850.61574692202;77414.61292693613;77504.61247693725;15400.22998850053;15502.069875807261;15677.686446271238;15715.21423928799;15779.05325680463;10556.736081597868;10799.514021869034;11452.312861228353;11662.183647144619;11715.17993740447;22017.33947981531;22202.222922197645;22375.88812055929;22466.21368252136;22493.55012899733;7976.9202307976175;9370.203532699798;9405.717828465156;9423.717288481357;9487;856.5973992223645;858.411987788367;863.3266052479077;868.3877866104381;897.264243320484;30004;30025;30505.23736906595;30804.84597576998;30862.691373086433;1036.76154484469;1055.208593554834;1082.821334479802;1106.9335839849473;1173.9706507337385;12726.55457059017;15010;15489;15510.379584816706;15782.684346313239;659861;660562;662813;663642;664348;51772.74113629483;52532.21201681978;53064.73467632637;53097;53188.73405632947;1382.4539306973688;1404.2206575350779;1426.3795249066688;1429.942802287897;1433.9354729037218;27470;28599;28710.712892870804;28737.137885863456;28737.425251494435;43732.56267437412;44570.55429445664;44640.55359446364;44800.55199447964;44939.87650308768;470038;543849;546705;549215;549426;24858.751412485643;24935.75064249334;25603;25622.871885640452;26360.34099147537;40985.795071024455;41585.37621935734;42115.15769684528;42225.78887105668;42326;34525;34687.82656086703;34744.652553474145;34757.30485390227;35983;34908.65091349054;35118.946431607095;35688.82155589206;35730.821345893106;36162.638373615926;34338.96983090512;34364.62541498312;34592.82703586466;34909.82545087258;35002;29356.412871742872;30876.21418680108;30978.69021309758;31109.8444507776;31227.84386078146;32748.672513274563;32794.50808237831;32816.67183328136;34342.65657343494;34497.694843019664 09:46:03 INFO - PROCESS | 29912 | |2;jquery.html;378.14161852594594;381.22611099468054;398.3109221047588;405.750463464969;418.9853355132571;15983.520494385188;19914.60170796583;20501.48746281344;20855.582888342244;21192.046357913874;16775.580610484714;21004.789952100473;21011.894940525315;21390.679139812913;21527.354179374644;5551.8889622207635;5943.970280148615;5986.221791167164;5994.340622531518;6016.879662406749;5662.546996240291;5890.087036509327;5947.762089516415;5978.790742324019;6006;5934.940650593504;5935.169076329295;6082.178905847721;6090.512758979293;6095.939040609582;4532.002959348938;5639.971800140994;5869.295684517842;6024.246969128859;6072.271327440713;5237.188235823453;5648.830535083954;5818.185454036438;5898.911516327258;5902.055671092648;13220.6694832629;13258.337083145803;13326.400311985984;13334.733305333935;19646.01769911513;2554.361409647588;2623.4097328101025;2643.960340594893;2645.9206223813317;2651.668541432321;9867.802643947081;9869.60521579136;10054.64808731691;10413.53139108742;10430.895691043068;1778.5642517583146;1837.7702787151607;1849.805770394116;1856.907154642276;1872.2043131669122;130545.38909221857;130763;133169;134552;135316.32341838226;11544.191906566628;11838.822417663741;11934.462949167135;12045.759084818255;12117.27296362221;13609.523666671817;14315.57053288403;15339.463118791013;15416.997895137005;15426;6256.99888017915;8080.2727754502375;8127.796805079802;8211;8276.668933242723;7650.120236172911;7664.73173438927;7685.538867667958;7794.610269486617;7818.218178182136;4120.402541631506;4716.64625153108;4728.59806916416;4728.763561821896;4771.427428708508;2020.6564883969465;2062.8659137156037;2075.3981345409966;2075.6263872503096;2076.345951025422;985.2856678907734;999.4103478947311;1008.1984822066474;1008.5965613754557;1044.383813550009;20140.19439222444;20405.693914591295;20434.69347959782;20498.18007279722;20597.279095231595;984.2126298960802;989.2135752077116;1011.6712068577742;1015.1016350529686;1017.944013079273;10325.277230593787;10676.946615266874;10712.517936692868;10726.570937162425;10730.78538429227;713305;716009;722720;730187;742912;122619.77380226261;145851.5414845859;147999;154862.2256888731;168527;883.8674198870092;999.9650012249537;1034.374203606822;1035.7100011996654;1040;18498.260069597065;19010.52473688169;19457.61084778325;19475.805241947965;19490.22039118476;63048.05427918676;63987.040194398054;65209.673951629935;65533.37866485287;65834.34165658282;79420.60289698515;88933;93159.06840931776;97328.54007189756;97728.51135744275;7987.760367188994;8149.470284431493;8349.206825351583;8371.246587807127;8538.871916921375;12125.454354554242;12701.745965080832;12884.226946382872;13133.737325253633;13162.341882906008;10607.41659208751;10661.253712240068;10761.031507164393;10991.560337586407;11279.041281491165;10544.841827372442;10912.781744365227;11036.724081898017;11074.003339699144;11112.777744445228;10901.509432075734;11085.778284434105;11252;11369;11369.03363214103;8157.8776318354085;8363.916360836314;8364.749057528285;8507.617157228064;8518.78703032429;10372.58509659605;10631.627893023708;10743.570257189625;10743.731406714582;10959.890401095887 09:46:03 INFO - PROCESS | 29912 | |3;mootools.html;1282.6985658370284;1432.935517901695;1433.5699290212933;1440.762274224753;1450.2531196433836;7499.92500074999;7759.224077592252;8082.393820463467;8488.320934325255;8519.872201916969;6626.801195964117;7223.855522889552;8302.46034007789;8311;8436.451630644004;1936.786953435121;2292.4612716011766;2326.906923723049;2327.325075728037;2346.4251258441705;2072.8652637578584;2174.3259589527297;2290.358699564125;2330.4057465346314;2341.0518739910362;2277.7949984501392;2343.156463673085;2345.1088586337255;2347.389678683546;2354.376090336062;2301.3326135420707;2313.2366319114653;2331.557004169211;2333.918312859059;2344.9531009379716;2294.7590502997114;2311.110222564325;2366.5621859955963;2384.9165279215317;2389.259329607827;8464.576771161479;8495.683169108797;8497.872531912026;8507.276881465083;8522.914770852274;5089.440161582223;5253.185756207794;5255.185446255837;5261.316028916254;5261.710605916673;3292.0946739646547;3515.191505953645;3535.0101971448034;3565.732570057256;3582.140286331288;1139.7207684117445;1178.4225729392622;1221.8961388282007;1222.9877701223052;1247.7878760610718;10704.732381690443;10816.026557609774;10848.945755271174;10854.348739075602;10870.61952831655;4568.908621827612;4658.324542941255;4685.882852928636;4700.553447422558;4740.19416699162;9481.857772133424;9602.375845570015;9612.66355677548;9621.18219951298;9644.18024467914;5118.360204974379;5219.634625576173;5220.686758794484;5230.450802665741;5256.369235691742;4608.976955115203;4764.380630518011;4766.356541866804;4794.95205047952;4847.224444088929;3109.766767492402;3838.673712734449;3939.4090886366703;3968.8412463501713;4032.3145065338963;962.831504486712;1181.2381014245764;1188.3820413384992;1213.6662417835166;1252.0734656354232;780.7813812132612;837.9748607541784;903.6927444668713;911.3483859040686;929.3819609959437;5513.365962914236;5611.989841828428;5651.321841379061;5661.037623603997;5885.440883116097;945.8864936207561;993.8906720260766;1000.5847573257017;1015.5480811039017;1028.0079723067277;3717.2937141942944;3742.1954279829965;3782.167923056923;3885.339492286318;3895.8441662333757;9775.51122443875;9944.254180936314;9954.950225248827;10089.243306752023;10102.848457273147;10020.69937901864;10024.398536087858;10048.397096174253;10049.899501005042;10056.949715251378;7670.7698769037015;7682.308592226726;7807.6486558105025;7844.37245020397;7978.361731061501;5407.432219616883;5412.404635490014;5489.560835133259;5537.750801213874;5607.579431542732;9200.987891331813;9330.906690933003;9347.906520934703;9349.719508414759;9392.812143757223;9312.767180820534;9346.299027572823;9472.52637368115;9508.477033762932;9526.333156678966;6718;6799.626020568916;6828.078209441662;6834.555753875982;6855.451563874978;6488.837779055561;7750.224977502205;7901.051873775184;7925.881111783443;7937.523748575104;7037.753678621224;7166.53417527859;7177.354038136593;7224.74713385029;7346.33882950537;5967.343592204767;6805.795826125224;6895.689693963884;6997.475189360716;7015.824604384727;5285.709285989307;6305.495560355253;6437.903431448438;6466.256380516301;6474.028895665688;4920.384951881015;4923.655344125877;5016.899662006813;5069.670471419494;5093.796248150181;4960.751962401793;4971.204607262821;5064.417591976822;5133.79464821403;5178.886539394049 09:46:03 INFO - PROCESS | 29912 | |4;prototype.html;352.31474781549895;354.1359083835442;363.52378384316546;369.20989083361627;373.48645612283093;4337;4832.589229915453;5186.662866913657;5294.232336311242;5482.643628164172;4616.3767891334655;4882.048000639877;5208.7395630218525;5332.520073193412;5427.240186373911;3311.4701647736365;3466.358723636129;4010.6590939770153;4023.718339716221;4248.34150706641;3585.515955346034;3629.8548058077777;3772.6981841452757;3805.6574908258253;4302.634276086536;3933.3706606942897;4206.20082184384;4212.873613791591;4223.873283801476;4229.34445160999;4109.630133288018;4128.318827393491;4200.25689338924;4240.6395456385935;4242.242542347499;3522.84147213376;3639.854405823764;3769.491118698968;3780.2232950513217;3788.5264341957254;4949.034938187055;5035.320231768701;5036.546710796009;5172.896542069175;5313.362396512404;4243.045314804175;4721.787519561628;4865.610751139936;4899.485554016813;4909.509049095098;5112.3865136183895;5726.541876649858;5738.110592858114;5742;5780.364159942403;3397.133730898605;3547.8048707321086;3832.1952389998132;3868.7485313454536;4181.728187667823;4960.553550180501;5930.3773103823905;5956.255468066492;5958.374370691102;6009.338972712998;3181.8249996249965;3906.43356713275;3927.6857851371824;3954.5056867891512;3959.2279505496153;3305.966940330566;3857.765515035162;3892.065904182976;3997.380406037069;4028.1540876415693;4279.679024073207;4616.815327386934;4626.467956185014;4646.535346465326;4650.093231819763;5282.920756188661;5439.48324909133;5497.945020549823;5726.339361585094;5741.368449470555;1059.586761163147;1073.5598404654054;1125.7241975716001;1185.875483074282;1255.6923553729396;760.8592410404093;821.6631181215673;872.3152325424511;877.5436772878068;882.6645874567623;5021.297018417425;5083.923741143886;5087.804365973993;5092.668976516515;5100.846974590768;4516.893361126545;5144.794208231704;5305.814296499604;5582.637128586629;5590.636608620427;4494.842680506166;4570.428696412949;4880.560749532488;4977.32806071183;5027.748612569357;2534.3917459809513;2808.4804311202492;2907;2924.385878965441;2926.0541739743257;5115.514026167508;5148.8591958905035;5351.411344752074;5664.915026274609;5809.390014048464;4632.467266264355;4860.052289803525;5535;5702.0591602385975;5712.715463402448;5375.973120134296;5592.412796656374;5774.335951365562;5794.333651630032;5807.660595157717;4542.386777784956;5283.23403920647;5464.945350546603;5527.917081243704;5603.411641777718;4552.567506086916;4674.813007479663;5041.8487445376695;5478.616496845342;5529.972350138223;5154.402135338058;5298.81454149103;5767.163761254678;5795;5860.179574859609;3311.3873933321447;3557.697595704394;3740.85036598533;3747.756395834262;3752.5872154062486;4783.918995237083;4891.5353041461;5487.317755142832;5514.0373663702985;5519.558435325084;4570.771461426849;4830.840598256464;5082.593392528663;5154.845354639367;5360.276362690986;4369.69412141147;4591.563801438858;4719.923233777127;4884.145274577006;5288.894222115613;5257.449052529437;5269.419174247634;5281.075811733008;5336.893262134813;5426.918596220981;4805.327254184487;5640.420682208988;5671.461211184931;5672.943270567406;5713.828745076844;4500.4149460569915;5518.558515318685;5527.816512477469;5552.917181149637;5565 09:46:03 INFO - PROCESS | 29912 | |5;yui.html;685.7257097161138;708.7023450150936;721.433674565466;746.5819141280883;772.8175890886943;14758.262086895653;14845.628859278524;15580.84419155811;15762.921185394058;15823.050616962975;12204.877951220486;14200.857991420082;14391.920605954543;14472.927635361835;14619;769.9846003079936;782.679101568357;837.4096262135196;854.9786255343635;855.5123579559654;675.6925598852534;696.6656005117546;832.4006715165071;843.7932706486919;858.8325276571071;899.4827973914993;908.0465511213233;909.6179604566098;910.6994691751706;910.886139232596;901.7475106970026;903.575319599787;903.746950853762;905.9456432613998;909.9863502047441;664.727461740684;671.1979184874077;672.7746205021324;673.0207548017622;673.9561928474634;15092.39630414782;15223.771643425358;15228.847711522854;15232.162231077285;15695.68608627821;14123.717525649532;14448.783268250985;14477.276136193253;14478.058926169766;14508.637284067878;11401.258918170292;11695.252733433475;11705.239159454693;11789.64631061061;11840.526378944833;3576.7853928764357;3610.2779444111284;3666;3706.981465092684;3831.214601006787;98404.50797745965;99101.51347729789;99600;100673;102686.48656756744;10411.479426028669;10775.137988961022;10874.72813179661;10936.507857146447;10960;22675.546489070126;22882.65676014861;22922.42693932665;22933.853307334568;23001.884990575276;7243.9637801811705;7252.456065795086;7605.581693006938;7625.351845093118;7745.651445684957;4101.569335219759;4137.9861933826405;4172.561881002451;4203.516595591485;4274;3396.218869659943;3398.082517720251;3469.7571170017854;3470.687638112582;3471;560.4731552340787;570.093551253509;570.3270141233302;575.2291928815407;583.7023118209659;479.6115146731159;554.6866020698293;622.9314775374705;635.3297271378648;664.3987191591617;8896;9446.433214007182;9711.174550163174;9719.708408747749;9740.95129524362;607.5686262753494;625.1304425605488;640.2860810196597;641.4964253061324;655.6066360183852;4530.207213737583;4691.859244222679;4695.718256904596;4698.929516057262;4727.290906364073;79027;81726.59136704278;86116.56941715376;106960;109631;89769;91910;92742.07257927334;94007;95595;60664;82565.99999999879;85200.57399712961;85635.57182214173;86359;6895.310468953065;9153;9167.679131230641;9177.449353038839;9190.678326258549;43304;52485;53202.66993325198;54206;55008.724956374965;42567.3614895783;65652.68694625986;66136;70114;70784;10272.584586652856;10522.894771052192;10777.461126943464;10886.346819190558;11175.105991520497;11527.135464840003;11782.705432363917;11851.525938962344;11867.703307417038;11953.043756499284;11122.888771112186;11508.251963622337;11526.769464610828;11556.711082222675;11934.820977685515;11320.490577924178;11337.773244535229;11578.710532236424;11588.130890183102;11767.411629418324;11258.155638327322;11302.491387887729;11308.095352371625;11354.148438867283;11547.780746401728;9506.287028472754;9623.422594644344;9670.56482458305;9700.175485083846;9955.352902061342;6817.420519255918;8714.956425217833;9043.412178208395;9190.954045229731;9359.204467620057 09:46:03 INFO - PROCESS | 29912 | __end_tp_report 09:46:03 INFO - PROCESS | 29912 | __start_cc_report 09:46:03 INFO - PROCESS | 29912 | _x_x_mozilla_cycle_collect,394 09:46:03 INFO - PROCESS | 29912 | __end_cc_report 09:46:03 INFO - PROCESS | 29912 | __startTimestamp1488563163125__endTimestamp 09:46:03 INFO - PROCESS | 29912 | 09:46:03 INFO - PROCESS | 29912 | ------- Summary: start ------- 09:46:03 INFO - PROCESS | 29912 | Number of tests: 6 09:46:03 INFO - PROCESS | 29912 | 09:46:03 INFO - PROCESS | 29912 | [#0] dojo.html Cycles:165 Average:5789.59 Median:6099.76 stddev:652.27 (10.7%) stddev-sans-first:625.80 09:46:03 INFO - PROCESS | 29912 | Values: 3359.7 3390.3 3402.8 3409.5 3430.5 5746.7 5758.5 5840.2 5847.4 5872.1 5731.3 5777.7 5796.6 5808.8 5830.9 5229.4 5308.9 5314.6 5346.6 5364.8 5237.4 5265.9 5275.4 5342.5 5360.5 5083.7 5119.4 5137.0 5288.8 5298.1 5191.4 5198.6 5203.2 5259.2 5263.7 5184.6 5196.0 5235.6 5260.1 5288.4 4942.1 5045.4 5248.3 5449.5 5760.8 6187.7 6253.4 6264.1 6269.4 6280.4 6234.0 6266.9 6283.7 6286.5 6292.3 5861.5 5891.7 5892.2 5911.8 5950.2 6171.1 6172.6 6240.5 6247.3 6292.4 6172.7 6192.7 6242.2 6244.8 6250.6 6265.1 6273.4 6300.7 6307.2 6313.8 6121.5 6161.3 6181.0 6183.8 6206.9 6211.4 6249.4 6262.9 6275.1 6319.1 5050.5 5071.3 5080.3 5083.0 5084.8 4374.4 4393.8 4450.7 4521.6 4523.8 5961.2 6000.9 6064.8 6075.8 6101.5 5002.6 6174.2 6229.1 6240.5 6246.7 5851.1 5936.0 5963.5 6014.6 6065.5 5522.8 5523.5 5530.7 5537.9 5550.3 6452.2 6455.8 6456.2 6459.8 6488.8 5140.5 6173.3 6205.0 6229.1 6274.4 6384.8 6390.9 6408.7 6446.9 6450.5 6255.3 6270.3 6273.4 6275.0 6282.7 6182.1 6201.2 6205.0 6238.6 6291.7 5935.7 6144.4 6206.5 6212.0 6217.4 6178.3 6182.5 6182.8 6197.1 6219.9 5184.9 5854.8 5967.3 6023.3 6065.2 6118.3 6235.6 6240.0 6242.0 6265.9 6099.8 6112.9 6124.0 6127.3 6135.9 09:46:03 INFO - PROCESS | 29912 | 09:46:03 INFO - PROCESS | 29912 | [#1] ext.html Cycles:180 Average:69861.50 Median:34640.33 stddev:151375.42 (437.0%) stddev-sans-first:151710.35 09:46:03 INFO - PROCESS | 29912 | Values: 447.7 484.3 493.5 497.7 545.6 30922.7 38533.2 39158.7 39917.4 39924.8 35184.6 41932.6 41944.6 42108.8 42158.1 8277.0 8479.0 9034.2 9340.8 11093.6 11219.9 11372.5 11411.0 11423.4 11428.3 11422.7 11423.7 11439.7 11456.6 11458.2 11363.1 11376.9 11391.8 11433.8 11438.0 11263.8 11320.9 11324.7 11325.8 11343.2 40616.8 40951.2 41031.8 41123.8 41153.0 3751.9 3977.8 4012.6 4019.7 4035.2 29221.9 29322.0 30254.5 31156.9 31738.0 3056.2 3179.4 3238.3 3286.5 3411.2 484119.0 486328.0 491370.0 493659.0 495703.0 71162.6 72402.3 72556.0 72573.6 72603.3 76349.6 76805.0 76850.6 77414.6 77504.6 15400.2 15502.1 15677.7 15715.2 15779.1 10556.7 10799.5 11452.3 11662.2 11715.2 22017.3 22202.2 22375.9 22466.2 22493.6 7976.9 9370.2 9405.7 9423.7 9487.0 856.6 858.4 863.3 868.4 897.3 30004.0 30025.0 30505.2 30804.8 30862.7 1036.8 1055.2 1082.8 1106.9 1174.0 12726.6 15010.0 15489.0 15510.4 15782.7 659861.0 660562.0 662813.0 663642.0 664348.0 51772.7 52532.2 53064.7 53097.0 53188.7 1382.5 1404.2 1426.4 1429.9 1433.9 27470.0 28599.0 28710.7 28737.1 28737.4 43732.6 44570.6 44640.6 44800.6 44939.9 470038.0 543849.0 546705.0 549215.0 549426.0 24858.8 24935.8 25603.0 25622.9 26360.3 40985.8 41585.4 42115.2 42225.8 42326.0 34525.0 34687.8 34744.7 34757.3 35983.0 34908.7 35118.9 35688.8 35730.8 36162.6 34339.0 34364.6 34592.8 34909.8 35002.0 29356.4 30876.2 30978.7 31109.8 31227.8 32748.7 32794.5 32816.7 34342.7 34497.7 09:46:03 INFO - PROCESS | 29912 | 09:46:03 INFO - PROCESS | 29912 | [#2] jquery.html Cycles:180 Average:39886.97 Median:11465.96 stddev:121023.77 (1055.5%) stddev-sans-first:121326.91 09:46:03 INFO - PROCESS | 29912 | Values: 378.1 381.2 398.3 405.8 419.0 15983.5 19914.6 20501.5 20855.6 21192.0 16775.6 21004.8 21011.9 21390.7 21527.4 5551.9 5944.0 5986.2 5994.3 6016.9 5662.5 5890.1 5947.8 5978.8 6006.0 5934.9 5935.2 6082.2 6090.5 6095.9 4532.0 5640.0 5869.3 6024.2 6072.3 5237.2 5648.8 5818.2 5898.9 5902.1 13220.7 13258.3 13326.4 13334.7 19646.0 2554.4 2623.4 2644.0 2645.9 2651.7 9867.8 9869.6 10054.6 10413.5 10430.9 1778.6 1837.8 1849.8 1856.9 1872.2 130545.4 130763.0 133169.0 134552.0 135316.3 11544.2 11838.8 11934.5 12045.8 12117.3 13609.5 14315.6 15339.5 15417.0 15426.0 6257.0 8080.3 8127.8 8211.0 8276.7 7650.1 7664.7 7685.5 7794.6 7818.2 4120.4 4716.6 4728.6 4728.8 4771.4 2020.7 2062.9 2075.4 2075.6 2076.3 985.3 999.4 1008.2 1008.6 1044.4 20140.2 20405.7 20434.7 20498.2 20597.3 984.2 989.2 1011.7 1015.1 1017.9 10325.3 10676.9 10712.5 10726.6 10730.8 713305.0 716009.0 722720.0 730187.0 742912.0 122619.8 145851.5 147999.0 154862.2 168527.0 883.9 1000.0 1034.4 1035.7 1040.0 18498.3 19010.5 19457.6 19475.8 19490.2 63048.1 63987.0 65209.7 65533.4 65834.3 79420.6 88933.0 93159.1 97328.5 97728.5 7987.8 8149.5 8349.2 8371.2 8538.9 12125.5 12701.7 12884.2 13133.7 13162.3 10607.4 10661.3 10761.0 10991.6 11279.0 10544.8 10912.8 11036.7 11074.0 11112.8 10901.5 11085.8 11252.0 11369.0 11369.0 8157.9 8363.9 8364.7 8507.6 8518.8 10372.6 10631.6 10743.6 10743.7 10959.9 09:46:03 INFO - PROCESS | 29912 | 09:46:03 INFO - PROCESS | 29912 | [#3] mootools.html Cycles:180 Average:5420.94 Median:5199.26 stddev:2956.60 (56.9%) stddev-sans-first:2948.54 09:46:03 INFO - PROCESS | 29912 | Values: 1282.7 1432.9 1433.6 1440.8 1450.3 7499.9 7759.2 8082.4 8488.3 8519.9 6626.8 7223.9 8302.5 8311.0 8436.5 1936.8 2292.5 2326.9 2327.3 2346.4 2072.9 2174.3 2290.4 2330.4 2341.1 2277.8 2343.2 2345.1 2347.4 2354.4 2301.3 2313.2 2331.6 2333.9 2345.0 2294.8 2311.1 2366.6 2384.9 2389.3 8464.6 8495.7 8497.9 8507.3 8522.9 5089.4 5253.2 5255.2 5261.3 5261.7 3292.1 3515.2 3535.0 3565.7 3582.1 1139.7 1178.4 1221.9 1223.0 1247.8 10704.7 10816.0 10848.9 10854.3 10870.6 4568.9 4658.3 4685.9 4700.6 4740.2 9481.9 9602.4 9612.7 9621.2 9644.2 5118.4 5219.6 5220.7 5230.5 5256.4 4609.0 4764.4 4766.4 4795.0 4847.2 3109.8 3838.7 3939.4 3968.8 4032.3 962.8 1181.2 1188.4 1213.7 1252.1 780.8 838.0 903.7 911.3 929.4 5513.4 5612.0 5651.3 5661.0 5885.4 945.9 993.9 1000.6 1015.5 1028.0 3717.3 3742.2 3782.2 3885.3 3895.8 9775.5 9944.3 9955.0 10089.2 10102.8 10020.7 10024.4 10048.4 10049.9 10056.9 7670.8 7682.3 7807.6 7844.4 7978.4 5407.4 5412.4 5489.6 5537.8 5607.6 9201.0 9330.9 9347.9 9349.7 9392.8 9312.8 9346.3 9472.5 9508.5 9526.3 6718.0 6799.6 6828.1 6834.6 6855.5 6488.8 7750.2 7901.1 7925.9 7937.5 7037.8 7166.5 7177.4 7224.7 7346.3 5967.3 6805.8 6895.7 6997.5 7015.8 5285.7 6305.5 6437.9 6466.3 6474.0 4920.4 4923.7 5016.9 5069.7 5093.8 4960.8 4971.2 5064.4 5133.8 5178.9 09:46:03 INFO - PROCESS | 29912 | 09:46:03 INFO - PROCESS | 29912 | [#4] prototype.html Cycles:180 Average:4439.18 Median:4895.51 stddev:1349.72 (27.6%) stddev-sans-first:1318.19 09:46:03 INFO - PROCESS | 29912 | Values: 352.3 354.1 363.5 369.2 373.5 4337.0 4832.6 5186.7 5294.2 5482.6 4616.4 4882.0 5208.7 5332.5 5427.2 3311.5 3466.4 4010.7 4023.7 4248.3 3585.5 3629.9 3772.7 3805.7 4302.6 3933.4 4206.2 4212.9 4223.9 4229.3 4109.6 4128.3 4200.3 4240.6 4242.2 3522.8 3639.9 3769.5 3780.2 3788.5 4949.0 5035.3 5036.5 5172.9 5313.4 4243.0 4721.8 4865.6 4899.5 4909.5 5112.4 5726.5 5738.1 5742.0 5780.4 3397.1 3547.8 3832.2 3868.7 4181.7 4960.6 5930.4 5956.3 5958.4 6009.3 3181.8 3906.4 3927.7 3954.5 3959.2 3306.0 3857.8 3892.1 3997.4 4028.2 4279.7 4616.8 4626.5 4646.5 4650.1 5282.9 5439.5 5497.9 5726.3 5741.4 1059.6 1073.6 1125.7 1185.9 1255.7 760.9 821.7 872.3 877.5 882.7 5021.3 5083.9 5087.8 5092.7 5100.8 4516.9 5144.8 5305.8 5582.6 5590.6 4494.8 4570.4 4880.6 4977.3 5027.7 2534.4 2808.5 2907.0 2924.4 2926.1 5115.5 5148.9 5351.4 5664.9 5809.4 4632.5 4860.1 5535.0 5702.1 5712.7 5376.0 5592.4 5774.3 5794.3 5807.7 4542.4 5283.2 5464.9 5527.9 5603.4 4552.6 4674.8 5041.8 5478.6 5530.0 5154.4 5298.8 5767.2 5795.0 5860.2 3311.4 3557.7 3740.9 3747.8 3752.6 4783.9 4891.5 5487.3 5514.0 5519.6 4570.8 4830.8 5082.6 5154.8 5360.3 4369.7 4591.6 4719.9 4884.1 5288.9 5257.4 5269.4 5281.1 5336.9 5426.9 4805.3 5640.4 5671.5 5672.9 5713.8 4500.4 5518.6 5527.8 5552.9 5565.0 09:46:03 INFO - PROCESS | 29912 | 09:46:03 INFO - PROCESS | 29912 | [#5] yui.html Cycles:180 Average:19674.85 Median:27781.71 stddev:28707.00 (103.3%) stddev-sans-first:28752.12 09:46:03 INFO - PROCESS | 29912 | Values: 685.7 708.7 721.4 746.6 772.8 14758.3 14845.6 15580.8 15762.9 15823.1 12204.9 14200.9 14391.9 14472.9 14619.0 770.0 782.7 837.4 855.0 855.5 675.7 696.7 832.4 843.8 858.8 899.5 908.0 909.6 910.7 910.9 901.7 903.6 903.7 905.9 910.0 664.7 671.2 672.8 673.0 674.0 15092.4 15223.8 15228.8 15232.2 15695.7 14123.7 14448.8 14477.3 14478.1 14508.6 11401.3 11695.3 11705.2 11789.6 11840.5 3576.8 3610.3 3666.0 3707.0 3831.2 98404.5 99101.5 99600.0 100673.0 102686.5 10411.5 10775.1 10874.7 10936.5 10960.0 22675.5 22882.7 22922.4 22933.9 23001.9 7244.0 7252.5 7605.6 7625.4 7745.7 4101.6 4138.0 4172.6 4203.5 4274.0 3396.2 3398.1 3469.8 3470.7 3471.0 560.5 570.1 570.3 575.2 583.7 479.6 554.7 622.9 635.3 664.4 8896.0 9446.4 9711.2 9719.7 9741.0 607.6 625.1 640.3 641.5 655.6 4530.2 4691.9 4695.7 4698.9 4727.3 79027.0 81726.6 86116.6 106960.0 109631.0 89769.0 91910.0 92742.1 94007.0 95595.0 60664.0 82566.0 85200.6 85635.6 86359.0 6895.3 9153.0 9167.7 9177.4 9190.7 43304.0 52485.0 53202.7 54206.0 55008.7 42567.4 65652.7 66136.0 70114.0 70784.0 10272.6 10522.9 10777.5 10886.3 11175.1 11527.1 11782.7 11851.5 11867.7 11953.0 11122.9 11508.3 11526.8 11556.7 11934.8 11320.5 11337.8 11578.7 11588.1 11767.4 11258.2 11302.5 11308.1 11354.1 11547.8 9506.3 9623.4 9670.6 9700.2 9955.4 6817.4 8715.0 9043.4 9191.0 9359.2 09:46:03 INFO - PROCESS | 29912 | -------- Summary: end -------- 09:46:03 INFO - PROCESS | 29912 | 09:46:03 INFO - TEST-INFO | 29912: exit 0 09:46:03 INFO - TEST-OK | dromaeo_css | took 1114990ms 09:46:03 INFO - TEST-START | kraken 09:46:03 INFO - operating with platform_type : mac_ 09:46:03 INFO - Initialising browser for kraken test... 09:46:03 INFO - TEST-INFO | started process 29918 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpSOVUMZ/profile http://localhost:51345/getInfo.html) 09:46:06 INFO - PROCESS | 29918 | 2017-03-03 09:46:06.657 plugin-container[29919:278299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 09:46:06 INFO - PROCESS | 29918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:06 INFO - PROCESS | 29918 | 2017-03-03 09:46:06.662 plugin-container[29919:278299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x440f, name = 'com.apple.CFPasteboardClient' 09:46:06 INFO - PROCESS | 29918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:06 INFO - PROCESS | 29918 | 2017-03-03 09:46:06.662 plugin-container[29919:278299] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:46:06 INFO - PROCESS | 29918 | __metrics Screen width/height:1600/1200 09:46:06 INFO - PROCESS | 29918 | colorDepth:24 09:46:06 INFO - PROCESS | 29918 | Browser inner width/height: 1024/689 09:46:06 INFO - PROCESS | 29918 | __metrics 09:46:06 ERROR - PROCESS | 29918 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:46:07 INFO - TEST-INFO | 29918: exit 0 09:46:07 INFO - Browser initialized. 09:46:07 INFO - Running cycle 1/1 for kraken test... 09:46:07 INFO - TEST-INFO | started process 29920 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpSOVUMZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:10 INFO - PROCESS | 29920 | 2017-03-03 09:46:10.063 plugin-container[29921:278426] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 09:46:10 INFO - PROCESS | 29920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:10 INFO - PROCESS | 29920 | 2017-03-03 09:46:10.064 plugin-container[29921:278426] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b1b, name = 'com.apple.CFPasteboardClient' 09:46:10 INFO - PROCESS | 29920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:10 INFO - PROCESS | 29920 | 2017-03-03 09:46:10.064 plugin-container[29921:278426] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:46:19 INFO - PROCESS | 29920 | RSS: Main: 218677248 09:46:19 INFO - PROCESS | 29920 | 09:46:22 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51345/tests/kraken/driver.html?testName=audio-beat-detection) 09:46:23 INFO - PROCESS | 29920 | RSS: Main: 233086976 09:46:23 INFO - PROCESS | 29920 | 09:46:26 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51345/tests/kraken/driver.html?testName=audio-dft) 09:46:26 INFO - PROCESS | 29920 | RSS: Main: 241045504 09:46:26 INFO - PROCESS | 29920 | 09:46:30 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51345/tests/kraken/driver.html?testName=audio-fft) 09:46:30 INFO - PROCESS | 29920 | RSS: Main: 244748288 09:46:30 INFO - PROCESS | 29920 | 09:46:32 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51345/tests/kraken/driver.html?testName=audio-oscillator) 09:46:33 INFO - PROCESS | 29920 | RSS: Main: 246079488 09:46:33 INFO - PROCESS | 29920 | 09:46:35 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51345/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:46:36 INFO - PROCESS | 29920 | RSS: Main: 246083584 09:46:36 INFO - PROCESS | 29920 | 09:46:39 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51345/tests/kraken/driver.html?testName=imaging-darkroom) 09:46:39 INFO - PROCESS | 29920 | RSS: Main: 249901056 09:46:39 INFO - PROCESS | 29920 | 09:46:42 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51345/tests/kraken/driver.html?testName=imaging-desaturate) 09:46:43 INFO - PROCESS | 29920 | RSS: Main: 249217024 09:46:43 INFO - PROCESS | 29920 | 09:46:46 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51345/tests/kraken/driver.html?testName=json-parse-financial) 09:46:46 INFO - PROCESS | 29920 | RSS: Main: 249221120 09:46:46 INFO - PROCESS | 29920 | 09:46:48 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51345/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:46:49 INFO - PROCESS | 29920 | RSS: Main: 248832000 09:46:49 INFO - PROCESS | 29920 | 09:46:51 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51345/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:46:51 INFO - PROCESS | 29920 | RSS: Main: 248926208 09:46:51 INFO - PROCESS | 29920 | 09:46:53 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51345/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:46:54 INFO - PROCESS | 29920 | RSS: Main: 248893440 09:46:54 INFO - PROCESS | 29920 | 09:46:57 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51345/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:46:58 INFO - PROCESS | 29920 | RSS: Main: 249192448 09:46:58 INFO - PROCESS | 29920 | 09:47:01 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51345/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:47:01 INFO - PROCESS | 29920 | RSS: Main: 249348096 09:47:01 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | Cycle 1(1): loaded http://localhost:51345/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51345/tests/kraken/driver.html?testName=ai-astar) 09:47:03 INFO - PROCESS | 29920 | RSS: Main: 251400192 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | __start_tp_report 09:47:03 INFO - PROCESS | 29920 | _x_x_mozilla_page_load 09:47:03 INFO - PROCESS | 29920 | _x_x_mozilla_page_load_details 09:47:03 INFO - PROCESS | 29920 | |i|pagename|runs| 09:47:03 INFO - PROCESS | 29920 | |0;ai-astar;105;116;117;117;116;115;118;116;115;115 09:47:03 INFO - PROCESS | 29920 | |1;audio-beat-detection;186;113;113;146;115;114;112;112;132;122 09:47:03 INFO - PROCESS | 29920 | |2;audio-dft;195;209;198;201;199;195;234;283;199;195 09:47:03 INFO - PROCESS | 29920 | |3;audio-fft;84;50;72;78;52;74;86;53;86;85 09:47:03 INFO - PROCESS | 29920 | |4;audio-oscillator;88;73;115;104;81;72;82;78;106;116 09:47:03 INFO - PROCESS | 29920 | |5;imaging-gaussian-blur;109;74;137;132;101;86;91;88;90;87 09:47:03 INFO - PROCESS | 29920 | |6;imaging-darkroom;104;97;95;107;97;96;96;97;124;98 09:47:03 INFO - PROCESS | 29920 | |7;imaging-desaturate;92;92;93;92;109;121;93;92;93;95 09:47:03 INFO - PROCESS | 29920 | |8;json-parse-financial;60;60;60;62;63;62;63;64;64;62 09:47:03 INFO - PROCESS | 29920 | |9;json-stringify-tinderbox;67;69;74;81;80;76;71;69;70;74 09:47:03 INFO - PROCESS | 29920 | |10;stanford-crypto-aes;80;86;94;109;91;88;94;88;85;89 09:47:03 INFO - PROCESS | 29920 | |11;stanford-crypto-ccm;150;137;158;158;157;141;144;144;192;147 09:47:03 INFO - PROCESS | 29920 | |12;stanford-crypto-pbkdf2;170;174;232;183;172;169;170;170;172;174 09:47:03 INFO - PROCESS | 29920 | |13;stanford-crypto-sha256-iterative;69;69;65;67;67;68;64;79;73;87 09:47:03 INFO - PROCESS | 29920 | __end_tp_report 09:47:03 INFO - PROCESS | 29920 | __start_cc_report 09:47:03 INFO - PROCESS | 29920 | _x_x_mozilla_cycle_collect,971 09:47:03 INFO - PROCESS | 29920 | __end_cc_report 09:47:03 INFO - PROCESS | 29920 | __startTimestamp1488563223959__endTimestamp 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | ------- Summary: start ------- 09:47:03 INFO - PROCESS | 29920 | Number of tests: 14 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#0] ai-astar Cycles:10 Average:115.00 Median:116.00 stddev:3.65 (3.1%) stddev-sans-first:1.05 09:47:03 INFO - PROCESS | 29920 | Values: 105.0 116.0 117.0 117.0 116.0 115.0 118.0 116.0 115.0 115.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:118.50 stddev:23.67 (20.0%) stddev-sans-first:11.78 09:47:03 INFO - PROCESS | 29920 | Values: 186.0 113.0 113.0 146.0 115.0 114.0 112.0 112.0 132.0 122.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#2] audio-dft Cycles:10 Average:210.80 Median:200.00 stddev:27.97 (14.0%) stddev-sans-first:29.08 09:47:03 INFO - PROCESS | 29920 | Values: 195.0 209.0 198.0 201.0 199.0 195.0 234.0 283.0 199.0 195.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#3] audio-fft Cycles:10 Average:72.00 Median:81.00 stddev:14.87 (18.4%) stddev-sans-first:15.12 09:47:03 INFO - PROCESS | 29920 | Values: 84.0 50.0 72.0 78.0 52.0 74.0 86.0 53.0 86.0 85.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:75.50 stddev:17.12 (22.7%) stddev-sans-first:18.11 09:47:03 INFO - PROCESS | 29920 | Values: 88.0 73.0 115.0 104.0 81.0 72.0 82.0 78.0 106.0 116.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#5] imaging-gaussian-blur Cycles:10 Average:99.50 Median:86.50 stddev:20.65 (23.9%) stddev-sans-first:21.62 09:47:03 INFO - PROCESS | 29920 | Values: 109.0 74.0 137.0 132.0 101.0 86.0 91.0 88.0 90.0 87.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#6] imaging-darkroom Cycles:10 Average:101.10 Median:96.50 stddev:8.92 (9.2%) stddev-sans-first:9.40 09:47:03 INFO - PROCESS | 29920 | Values: 104.0 97.0 95.0 107.0 97.0 96.0 96.0 97.0 124.0 98.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#7] imaging-desaturate Cycles:10 Average:97.20 Median:92.50 stddev:9.84 (10.6%) stddev-sans-first:10.26 09:47:03 INFO - PROCESS | 29920 | Values: 92.0 92.0 93.0 92.0 109.0 121.0 93.0 92.0 93.0 95.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:62.50 stddev:1.56 (2.5%) stddev-sans-first:1.48 09:47:03 INFO - PROCESS | 29920 | Values: 60.0 60.0 60.0 62.0 63.0 62.0 63.0 64.0 64.0 62.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:74.00 stddev:4.77 (6.4%) stddev-sans-first:4.52 09:47:03 INFO - PROCESS | 29920 | Values: 67.0 69.0 74.0 81.0 80.0 76.0 71.0 69.0 70.0 74.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:88.50 stddev:7.76 (8.8%) stddev-sans-first:7.26 09:47:03 INFO - PROCESS | 29920 | Values: 80.0 86.0 94.0 109.0 91.0 88.0 94.0 88.0 85.0 89.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#11] stanford-crypto-ccm Cycles:10 Average:152.80 Median:153.50 stddev:15.61 (10.2%) stddev-sans-first:16.53 09:47:03 INFO - PROCESS | 29920 | Values: 150.0 137.0 158.0 158.0 157.0 141.0 144.0 144.0 192.0 147.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.60 Median:173.00 stddev:19.19 (11.1%) stddev-sans-first:20.10 09:47:03 INFO - PROCESS | 29920 | Values: 170.0 174.0 232.0 183.0 172.0 169.0 170.0 170.0 172.0 174.0 09:47:03 INFO - PROCESS | 29920 | 09:47:03 INFO - PROCESS | 29920 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:69.00 stddev:7.13 (10.3%) stddev-sans-first:7.53 09:47:03 INFO - PROCESS | 29920 | Values: 69.0 69.0 65.0 67.0 67.0 68.0 64.0 79.0 73.0 87.0 09:47:03 INFO - PROCESS | 29920 | -------- Summary: end -------- 09:47:03 INFO - PROCESS | 29920 | 09:47:04 INFO - TEST-INFO | 29920: exit 0 09:47:05 INFO - TEST-OK | kraken | took 61244ms 09:47:05 INFO - SUITE-END | took 1176s 09:47:05 INFO - Completed test suite (00:19:36) 09:47:05 INFO - javascript benchmark 09:47:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7226.494627724596, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3359.7312215022803, 3390.3049874775656, 3402.846871890765, 3409.471531912553, 3430.4511278195496, 5746.712664366781, 5758.481736643701, 5840.182374467578, 5847.386024467433, 5872.1191821226785, 5731.312242530902, 5777.682227477487, 5796.62321949073, 5808.767649294024, 5830.892130495214, 5229.398619158807, 5308.867278318054, 5314.601404894629, 5346.599005074616, 5364.839054828341, 5237.397699264595, 5265.9473405266035, 5275.446078161795, 5342.519173274404, 5360.463953604628, 5083.720395378253, 5119.360079990001, 5137.0, 5288.762005709733, 5298.072837253504, 5191.402988656315, 5198.636095473318, 5203.219517072469, 5259.184826351921, 5263.710495922723, 5184.611154163453, 5196.0, 5235.6073294502685, 5260.053190425722, 5288.365396152448, 4942.1351263529095, 5045.4197767256865, 5248.317718696583, 5449.482299181572, 5760.798372056958, 6187.721552530147, 6253.437190652863, 6264.060390941349, 6269.404406581368, 6280.403361680633, 6234.0, 6266.87466250677, 6283.717232724538, 6286.49708023357, 6292.307846136921, 5861.531077513789, 5891.675957822361, 5892.204552385416, 5911.7585307085565, 5950.19672344228, 6171.13604095427, 6172.629642221481, 6240.500759939194, 6247.312795592481, 6292.370762923671, 6172.660503672341, 6192.690365481708, 6242.188515492957, 6244.812655620339, 6250.562460627712, 6265.091561723524, 6273.404026617555, 6300.7479700811455, 6307.211598550181, 6313.810585682437, 6121.452973575903, 6161.291451483047, 6181.041938499539, 6183.84540386494, 6206.937930620636, 6211.440970312694, 6249.406306400884, 6262.906056409158, 6275.058741188858, 6319.146915166394, 5050.49495050492, 5071.264666623319, 5080.339555857789, 5083.034223497523, 5084.8220312288895, 4374.387585737999, 4393.75834329115, 4450.732956022649, 4521.615662668644, 4523.773811309487, 5961.165436838846, 6000.909986350208, 6064.75740970365, 6075.817725468243, 6101.511879049665, 5002.624803139779, 6174.228221472316, 6229.0967809667345, 6240.5319601030105, 6246.6876656167005, 5851.063829787214, 5936.0, 5963.493103086286, 6014.609050411709, 6065.4541091302, 5522.751476183501, 5523.502884740393, 5530.695811730394, 5537.944620553743, 5550.306211723535, 6452.161219041683, 6455.7740479083, 6456.160699109086, 6459.806205813833, 6488.805335840027, 5140.485951404829, 6173.32093469709, 6205.0, 6229.127922091002, 6274.403931626488, 6384.776532821329, 6390.936090639034, 6408.679566021587, 6446.903296450463, 6450.548461607643, 6255.31191568918, 6270.341614130633, 6273.404026617464, 6275.0, 6282.685865706788, 6182.103594978613, 6201.1938448003275, 6204.968975155276, 6238.594491358047, 6291.685415729286, 5935.7032148391545, 6144.446999770043, 6206.534509911684, 6212.0, 6217.409346112112, 6178.320384757763, 6182.474489668429, 6182.845428864314, 6197.101420293942, 6219.937800622117, 5184.896302073862, 5854.765809367748, 5967.254093238345, 6023.30731965812, 6065.181200537871, 6118.314626315956, 6235.563510554218, 6239.96880015597, 6242.032484964838, 6265.906011409924, 6099.75600975956, 6112.8777424452155, 6123.9693801532485, 6127.3259941407305, 6135.877282454415], "value": 5748.117478249525, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.6553054148305, 484.2953502653638, 493.45966167047095, 497.6640767481949, 545.6398776807308, 30922.69077309226, 38533.22933541331, 39158.65048097116, 39917.4012389814, 39924.80037599815, 35184.64815351846, 41932.58067419325, 41944.58055419445, 42108.78945605276, 42158.10288537016, 8277.048139463948, 8479.024912135092, 9034.186923176912, 9340.813183736322, 11093.611723589676, 11219.943900280487, 11372.545098196062, 11411.0, 11423.42882855858, 11428.314301141958, 11422.657320280405, 11423.714407139805, 11439.714007149805, 11456.599019034335, 11458.197926145172, 11363.090952723762, 11376.886231137665, 11391.772164556745, 11433.828492572618, 11437.970582647602, 11263.831042534368, 11320.886791132065, 11324.716882077932, 11325.773484530264, 11343.205975581712, 40616.796916015526, 40951.1809763803, 41031.79484102561, 41123.766287011436, 41152.971175720544, 3751.8874433767032, 3977.76133431995, 4012.578679238667, 4019.658329042035, 4035.1929614077007, 29221.853890730625, 29322.0, 30254.54618180729, 31156.909508167104, 31738.0, 3056.190109620952, 3179.3959147761852, 3238.3199528099126, 3286.5398844161837, 3411.1642647551507, 484119.0, 486328.0, 491370.0, 493659.0, 495703.0, 71162.64418677977, 72402.27597723955, 72556.0, 72573.637131814, 72603.27396726071, 76349.6182519095, 76805.0, 76850.61574692202, 77414.61292693613, 77504.61247693725, 15400.22998850053, 15502.069875807261, 15677.686446271238, 15715.21423928799, 15779.05325680463, 10556.736081597868, 10799.514021869034, 11452.312861228353, 11662.183647144619, 11715.17993740447, 22017.33947981531, 22202.222922197645, 22375.88812055929, 22466.21368252136, 22493.55012899733, 7976.9202307976175, 9370.203532699798, 9405.717828465156, 9423.717288481357, 9487.0, 856.5973992223645, 858.411987788367, 863.3266052479077, 868.3877866104381, 897.264243320484, 30004.0, 30025.0, 30505.23736906595, 30804.84597576998, 30862.691373086433, 1036.76154484469, 1055.208593554834, 1082.821334479802, 1106.9335839849473, 1173.9706507337385, 12726.55457059017, 15010.0, 15489.0, 15510.379584816706, 15782.684346313239, 659861.0, 660562.0, 662813.0, 663642.0, 664348.0, 51772.74113629483, 52532.21201681978, 53064.73467632637, 53097.0, 53188.73405632947, 1382.4539306973688, 1404.2206575350779, 1426.3795249066688, 1429.942802287897, 1433.9354729037218, 27470.0, 28599.0, 28710.712892870804, 28737.137885863456, 28737.425251494435, 43732.56267437412, 44570.55429445664, 44640.55359446364, 44800.55199447964, 44939.87650308768, 470038.0, 543849.0, 546705.0, 549215.0, 549426.0, 24858.751412485643, 24935.75064249334, 25603.0, 25622.871885640452, 26360.34099147537, 40985.795071024455, 41585.37621935734, 42115.15769684528, 42225.78887105668, 42326.0, 34525.0, 34687.82656086703, 34744.652553474145, 34757.30485390227, 35983.0, 34908.65091349054, 35118.946431607095, 35688.82155589206, 35730.821345893106, 36162.638373615926, 34338.96983090512, 34364.62541498312, 34592.82703586466, 34909.82545087258, 35002.0, 29356.412871742872, 30876.21418680108, 30978.69021309758, 31109.8444507776, 31227.84386078146, 32748.672513274563, 32794.50808237831, 32816.67183328136, 34342.65657343494, 34497.694843019664], "value": 20328.32994397824, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.14161852594594, 381.22611099468054, 398.3109221047588, 405.750463464969, 418.9853355132571, 15983.520494385188, 19914.60170796583, 20501.48746281344, 20855.582888342244, 21192.046357913874, 16775.580610484714, 21004.789952100473, 21011.894940525315, 21390.679139812913, 21527.354179374644, 5551.8889622207635, 5943.970280148615, 5986.221791167164, 5994.340622531518, 6016.879662406749, 5662.546996240291, 5890.087036509327, 5947.762089516415, 5978.790742324019, 6006.0, 5934.940650593504, 5935.169076329295, 6082.178905847721, 6090.512758979293, 6095.939040609582, 4532.002959348938, 5639.971800140994, 5869.295684517842, 6024.246969128859, 6072.271327440713, 5237.188235823453, 5648.830535083954, 5818.185454036438, 5898.911516327258, 5902.055671092648, 13220.6694832629, 13258.337083145803, 13326.400311985984, 13334.733305333935, 19646.01769911513, 2554.361409647588, 2623.4097328101025, 2643.960340594893, 2645.9206223813317, 2651.668541432321, 9867.802643947081, 9869.60521579136, 10054.64808731691, 10413.53139108742, 10430.895691043068, 1778.5642517583146, 1837.7702787151607, 1849.805770394116, 1856.907154642276, 1872.2043131669122, 130545.38909221857, 130763.0, 133169.0, 134552.0, 135316.32341838226, 11544.191906566628, 11838.822417663741, 11934.462949167135, 12045.759084818255, 12117.27296362221, 13609.523666671817, 14315.57053288403, 15339.463118791013, 15416.997895137005, 15426.0, 6256.99888017915, 8080.2727754502375, 8127.796805079802, 8211.0, 8276.668933242723, 7650.120236172911, 7664.73173438927, 7685.538867667958, 7794.610269486617, 7818.218178182136, 4120.402541631506, 4716.64625153108, 4728.59806916416, 4728.763561821896, 4771.427428708508, 2020.6564883969465, 2062.8659137156037, 2075.3981345409966, 2075.6263872503096, 2076.345951025422, 985.2856678907734, 999.4103478947311, 1008.1984822066474, 1008.5965613754557, 1044.383813550009, 20140.19439222444, 20405.693914591295, 20434.69347959782, 20498.18007279722, 20597.279095231595, 984.2126298960802, 989.2135752077116, 1011.6712068577742, 1015.1016350529686, 1017.944013079273, 10325.277230593787, 10676.946615266874, 10712.517936692868, 10726.570937162425, 10730.78538429227, 713305.0, 716009.0, 722720.0, 730187.0, 742912.0, 122619.77380226261, 145851.5414845859, 147999.0, 154862.2256888731, 168527.0, 883.8674198870092, 999.9650012249537, 1034.374203606822, 1035.7100011996654, 1040.0, 18498.260069597065, 19010.52473688169, 19457.61084778325, 19475.805241947965, 19490.22039118476, 63048.05427918676, 63987.040194398054, 65209.673951629935, 65533.37866485287, 65834.34165658282, 79420.60289698515, 88933.0, 93159.06840931776, 97328.54007189756, 97728.51135744275, 7987.760367188994, 8149.470284431493, 8349.206825351583, 8371.246587807127, 8538.871916921375, 12125.454354554242, 12701.745965080832, 12884.226946382872, 13133.737325253633, 13162.341882906008, 10607.41659208751, 10661.253712240068, 10761.031507164393, 10991.560337586407, 11279.041281491165, 10544.841827372442, 10912.781744365227, 11036.724081898017, 11074.003339699144, 11112.777744445228, 10901.509432075734, 11085.778284434105, 11252.0, 11369.0, 11369.03363214103, 8157.8776318354085, 8363.916360836314, 8364.749057528285, 8507.617157228064, 8518.78703032429, 10372.58509659605, 10631.627893023708, 10743.570257189625, 10743.731406714582, 10959.890401095887], "value": 9709.368073343487, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1282.6985658370284, 1432.935517901695, 1433.5699290212933, 1440.762274224753, 1450.2531196433836, 7499.92500074999, 7759.224077592252, 8082.393820463467, 8488.320934325255, 8519.872201916969, 6626.801195964117, 7223.855522889552, 8302.46034007789, 8311.0, 8436.451630644004, 1936.786953435121, 2292.4612716011766, 2326.906923723049, 2327.325075728037, 2346.4251258441705, 2072.8652637578584, 2174.3259589527297, 2290.358699564125, 2330.4057465346314, 2341.0518739910362, 2277.7949984501392, 2343.156463673085, 2345.1088586337255, 2347.389678683546, 2354.376090336062, 2301.3326135420707, 2313.2366319114653, 2331.557004169211, 2333.918312859059, 2344.9531009379716, 2294.7590502997114, 2311.110222564325, 2366.5621859955963, 2384.9165279215317, 2389.259329607827, 8464.576771161479, 8495.683169108797, 8497.872531912026, 8507.276881465083, 8522.914770852274, 5089.440161582223, 5253.185756207794, 5255.185446255837, 5261.316028916254, 5261.710605916673, 3292.0946739646547, 3515.191505953645, 3535.0101971448034, 3565.732570057256, 3582.140286331288, 1139.7207684117445, 1178.4225729392622, 1221.8961388282007, 1222.9877701223052, 1247.7878760610718, 10704.732381690443, 10816.026557609774, 10848.945755271174, 10854.348739075602, 10870.61952831655, 4568.908621827612, 4658.324542941255, 4685.882852928636, 4700.553447422558, 4740.19416699162, 9481.857772133424, 9602.375845570015, 9612.66355677548, 9621.18219951298, 9644.18024467914, 5118.360204974379, 5219.634625576173, 5220.686758794484, 5230.450802665741, 5256.369235691742, 4608.976955115203, 4764.380630518011, 4766.356541866804, 4794.95205047952, 4847.224444088929, 3109.766767492402, 3838.673712734449, 3939.4090886366703, 3968.8412463501713, 4032.3145065338963, 962.831504486712, 1181.2381014245764, 1188.3820413384992, 1213.6662417835166, 1252.0734656354232, 780.7813812132612, 837.9748607541784, 903.6927444668713, 911.3483859040686, 929.3819609959437, 5513.365962914236, 5611.989841828428, 5651.321841379061, 5661.037623603997, 5885.440883116097, 945.8864936207561, 993.8906720260766, 1000.5847573257017, 1015.5480811039017, 1028.0079723067277, 3717.2937141942944, 3742.1954279829965, 3782.167923056923, 3885.339492286318, 3895.8441662333757, 9775.51122443875, 9944.254180936314, 9954.950225248827, 10089.243306752023, 10102.848457273147, 10020.69937901864, 10024.398536087858, 10048.397096174253, 10049.899501005042, 10056.949715251378, 7670.7698769037015, 7682.308592226726, 7807.6486558105025, 7844.37245020397, 7978.361731061501, 5407.432219616883, 5412.404635490014, 5489.560835133259, 5537.750801213874, 5607.579431542732, 9200.987891331813, 9330.906690933003, 9347.906520934703, 9349.719508414759, 9392.812143757223, 9312.767180820534, 9346.299027572823, 9472.52637368115, 9508.477033762932, 9526.333156678966, 6718.0, 6799.626020568916, 6828.078209441662, 6834.555753875982, 6855.451563874978, 6488.837779055561, 7750.224977502205, 7901.051873775184, 7925.881111783443, 7937.523748575104, 7037.753678621224, 7166.53417527859, 7177.354038136593, 7224.74713385029, 7346.33882950537, 5967.343592204767, 6805.795826125224, 6895.689693963884, 6997.475189360716, 7015.824604384727, 5285.709285989307, 6305.495560355253, 6437.903431448438, 6466.256380516301, 6474.028895665688, 4920.384951881015, 4923.655344125877, 5016.899662006813, 5069.670471419494, 5093.796248150181, 4960.751962401793, 4971.204607262821, 5064.417591976822, 5133.79464821403, 5178.886539394049], "value": 4407.600160132281, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.31474781549895, 354.1359083835442, 363.52378384316546, 369.20989083361627, 373.48645612283093, 4337.0, 4832.589229915453, 5186.662866913657, 5294.232336311242, 5482.643628164172, 4616.3767891334655, 4882.048000639877, 5208.7395630218525, 5332.520073193412, 5427.240186373911, 3311.4701647736365, 3466.358723636129, 4010.6590939770153, 4023.718339716221, 4248.34150706641, 3585.515955346034, 3629.8548058077777, 3772.6981841452757, 3805.6574908258253, 4302.634276086536, 3933.3706606942897, 4206.20082184384, 4212.873613791591, 4223.873283801476, 4229.34445160999, 4109.630133288018, 4128.318827393491, 4200.25689338924, 4240.6395456385935, 4242.242542347499, 3522.84147213376, 3639.854405823764, 3769.491118698968, 3780.2232950513217, 3788.5264341957254, 4949.034938187055, 5035.320231768701, 5036.546710796009, 5172.896542069175, 5313.362396512404, 4243.045314804175, 4721.787519561628, 4865.610751139936, 4899.485554016813, 4909.509049095098, 5112.3865136183895, 5726.541876649858, 5738.110592858114, 5742.0, 5780.364159942403, 3397.133730898605, 3547.8048707321086, 3832.1952389998132, 3868.7485313454536, 4181.728187667823, 4960.553550180501, 5930.3773103823905, 5956.255468066492, 5958.374370691102, 6009.338972712998, 3181.8249996249965, 3906.43356713275, 3927.6857851371824, 3954.5056867891512, 3959.2279505496153, 3305.966940330566, 3857.765515035162, 3892.065904182976, 3997.380406037069, 4028.1540876415693, 4279.679024073207, 4616.815327386934, 4626.467956185014, 4646.535346465326, 4650.093231819763, 5282.920756188661, 5439.48324909133, 5497.945020549823, 5726.339361585094, 5741.368449470555, 1059.586761163147, 1073.5598404654054, 1125.7241975716001, 1185.875483074282, 1255.6923553729396, 760.8592410404093, 821.6631181215673, 872.3152325424511, 877.5436772878068, 882.6645874567623, 5021.297018417425, 5083.923741143886, 5087.804365973993, 5092.668976516515, 5100.846974590768, 4516.893361126545, 5144.794208231704, 5305.814296499604, 5582.637128586629, 5590.636608620427, 4494.842680506166, 4570.428696412949, 4880.560749532488, 4977.32806071183, 5027.748612569357, 2534.3917459809513, 2808.4804311202492, 2907.0, 2924.385878965441, 2926.0541739743257, 5115.514026167508, 5148.8591958905035, 5351.411344752074, 5664.915026274609, 5809.390014048464, 4632.467266264355, 4860.052289803525, 5535.0, 5702.0591602385975, 5712.715463402448, 5375.973120134296, 5592.412796656374, 5774.335951365562, 5794.333651630032, 5807.660595157717, 4542.386777784956, 5283.23403920647, 5464.945350546603, 5527.917081243704, 5603.411641777718, 4552.567506086916, 4674.813007479663, 5041.8487445376695, 5478.616496845342, 5529.972350138223, 5154.402135338058, 5298.81454149103, 5767.163761254678, 5795.0, 5860.179574859609, 3311.3873933321447, 3557.697595704394, 3740.85036598533, 3747.756395834262, 3752.5872154062486, 4783.918995237083, 4891.5353041461, 5487.317755142832, 5514.0373663702985, 5519.558435325084, 4570.771461426849, 4830.840598256464, 5082.593392528663, 5154.845354639367, 5360.276362690986, 4369.69412141147, 4591.563801438858, 4719.923233777127, 4884.145274577006, 5288.894222115613, 5257.449052529437, 5269.419174247634, 5281.075811733008, 5336.893262134813, 5426.918596220981, 4805.327254184487, 5640.420682208988, 5671.461211184931, 5672.943270567406, 5713.828745076844, 4500.4149460569915, 5518.558515318685, 5527.816512477469, 5552.917181149637, 5565.0], "value": 4017.349449014338, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.7257097161138, 708.7023450150936, 721.433674565466, 746.5819141280883, 772.8175890886943, 14758.262086895653, 14845.628859278524, 15580.84419155811, 15762.921185394058, 15823.050616962975, 12204.877951220486, 14200.857991420082, 14391.920605954543, 14472.927635361835, 14619.0, 769.9846003079936, 782.679101568357, 837.4096262135196, 854.9786255343635, 855.5123579559654, 675.6925598852534, 696.6656005117546, 832.4006715165071, 843.7932706486919, 858.8325276571071, 899.4827973914993, 908.0465511213233, 909.6179604566098, 910.6994691751706, 910.886139232596, 901.7475106970026, 903.575319599787, 903.746950853762, 905.9456432613998, 909.9863502047441, 664.727461740684, 671.1979184874077, 672.7746205021324, 673.0207548017622, 673.9561928474634, 15092.39630414782, 15223.771643425358, 15228.847711522854, 15232.162231077285, 15695.68608627821, 14123.717525649532, 14448.783268250985, 14477.276136193253, 14478.058926169766, 14508.637284067878, 11401.258918170292, 11695.252733433475, 11705.239159454693, 11789.64631061061, 11840.526378944833, 3576.7853928764357, 3610.2779444111284, 3666.0, 3706.981465092684, 3831.214601006787, 98404.50797745965, 99101.51347729789, 99600.0, 100673.0, 102686.48656756744, 10411.479426028669, 10775.137988961022, 10874.72813179661, 10936.507857146447, 10960.0, 22675.546489070126, 22882.65676014861, 22922.42693932665, 22933.853307334568, 23001.884990575276, 7243.9637801811705, 7252.456065795086, 7605.581693006938, 7625.351845093118, 7745.651445684957, 4101.569335219759, 4137.9861933826405, 4172.561881002451, 4203.516595591485, 4274.0, 3396.218869659943, 3398.082517720251, 3469.7571170017854, 3470.687638112582, 3471.0, 560.4731552340787, 570.093551253509, 570.3270141233302, 575.2291928815407, 583.7023118209659, 479.6115146731159, 554.6866020698293, 622.9314775374705, 635.3297271378648, 664.3987191591617, 8896.0, 9446.433214007182, 9711.174550163174, 9719.708408747749, 9740.95129524362, 607.5686262753494, 625.1304425605488, 640.2860810196597, 641.4964253061324, 655.6066360183852, 4530.207213737583, 4691.859244222679, 4695.718256904596, 4698.929516057262, 4727.290906364073, 79027.0, 81726.59136704278, 86116.56941715376, 106960.0, 109631.0, 89769.0, 91910.0, 92742.07257927334, 94007.0, 95595.0, 60664.0, 82565.99999999879, 85200.57399712961, 85635.57182214173, 86359.0, 6895.310468953065, 9153.0, 9167.679131230641, 9177.449353038839, 9190.678326258549, 43304.0, 52485.0, 53202.66993325198, 54206.0, 55008.724956374965, 42567.3614895783, 65652.68694625986, 66136.0, 70114.0, 70784.0, 10272.584586652856, 10522.894771052192, 10777.461126943464, 10886.346819190558, 11175.105991520497, 11527.135464840003, 11782.705432363917, 11851.525938962344, 11867.703307417038, 11953.043756499284, 11122.888771112186, 11508.251963622337, 11526.769464610828, 11556.711082222675, 11934.820977685515, 11320.490577924178, 11337.773244535229, 11578.710532236424, 11588.130890183102, 11767.411629418324, 11258.155638327322, 11302.491387887729, 11308.095352371625, 11354.148438867283, 11547.780746401728, 9506.287028472754, 9623.422594644344, 9670.56482458305, 9700.175485083846, 9955.352902061342, 6817.420519255918, 8714.956425217833, 9043.412178208395, 9190.954045229731, 9359.204467620057], "value": 7088.5441501128, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 117.0, 117.0, 116.0, 115.0, 118.0, 116.0, 115.0, 115.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 113.0, 113.0, 146.0, 115.0, 114.0, 112.0, 112.0, 132.0, 122.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 209.0, 198.0, 201.0, 199.0, 195.0, 234.0, 283.0, 199.0, 195.0], "value": 210.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 50.0, 72.0, 78.0, 52.0, 74.0, 86.0, 53.0, 86.0, 85.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 73.0, 115.0, 104.0, 81.0, 72.0, 82.0, 78.0, 106.0, 116.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 74.0, 137.0, 132.0, 101.0, 86.0, 91.0, 88.0, 90.0, 87.0], "value": 99.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 97.0, 95.0, 107.0, 97.0, 96.0, 96.0, 97.0, 124.0, 98.0], "value": 101.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 92.0, 109.0, 121.0, 93.0, 92.0, 93.0, 95.0], "value": 97.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 62.0, 63.0, 62.0, 63.0, 64.0, 64.0, 62.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 74.0, 81.0, 80.0, 76.0, 71.0, 69.0, 70.0, 74.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 86.0, 94.0, 109.0, 91.0, 88.0, 94.0, 88.0, 85.0, 89.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 137.0, 158.0, 158.0, 157.0, 141.0, 144.0, 144.0, 192.0, 147.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 174.0, 232.0, 183.0, 172.0, 169.0, 170.0, 170.0, 172.0, 174.0], "value": 178.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 65.0, 67.0, 67.0, 68.0, 64.0, 79.0, 73.0, 87.0], "value": 70.8, "unit": "score"}]}]} 09:47:05 INFO - Return code: 0 09:47:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:47:05 ERROR - # TBPL SUCCESS # 09:47:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:05 INFO - 2017-03-03 09:47:05.271 screenresolution[29925:278810] starting screenresolution argv=screenresolution get 09:47:05 INFO - 2017-03-03 09:47:05.274 screenresolution[29925:278810] Display 0: 1600x1200x32@60 09:47:05 INFO - 2017-03-03 09:47:05.280 screenresolution[29926:278813] starting screenresolution argv=screenresolution list 09:47:05 INFO - Available Modes on Display 0 09:47:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:05 INFO - Intel Iris: 09:47:05 INFO - Chipset Model: Intel Iris 09:47:05 INFO - Type: GPU 09:47:05 INFO - Bus: Built-In 09:47:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:05 INFO - Vendor: Intel (0x8086) 09:47:05 INFO - Device ID: 0x0a2e 09:47:05 INFO - Revision ID: 0x0009 09:47:05 INFO - Displays: 09:47:05 INFO - Display: 09:47:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:05 INFO - Display Serial Number: 1600x1200 60 09:47:05 INFO - Main Display: Yes 09:47:05 INFO - Mirror: Off 09:47:05 INFO - Online: Yes 09:47:05 INFO - Rotation: Supported 09:47:05 INFO - Return code: 0 09:47:05 INFO - Running post-action listener: _resource_record_post_action 09:47:05 INFO - [mozharness: 2017-03-03 17:47:05.524990Z] Finished run-tests step (success) 09:47:05 INFO - Running post-run listener: _resource_record_post_run 09:47:05 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 1226240; Write bytes: 508721152; Read time: 18; Write time: 1591 09:47:05 INFO - TinderboxPrint: CPU usage
54.4% 09:47:05 INFO - TinderboxPrint: I/O read bytes / time
1,226,240 / 18 09:47:05 INFO - TinderboxPrint: I/O write bytes / time
508,721,152 / 1,591 09:47:05 INFO - TinderboxPrint: CPU idle
2,185.0 (45.6%) 09:47:05 INFO - TinderboxPrint: CPU system
965.2 (20.2%) 09:47:05 INFO - TinderboxPrint: CPU user
1,639.6 (34.2%) 09:47:05 INFO - TinderboxPrint: Swap in / out
209,301,504 / 0 09:47:05 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205447680; Write bytes: 200046080; Read time: 15600; Write time: 427 09:47:05 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1226240; Write bytes: 297701888; Read time: 18; Write time: 1129 09:47:06 INFO - Running post-run listener: _upload_blobber_files 09:47:06 INFO - Blob upload gear active. 09:47:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:47:06 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 09:47:06 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'] 09:47:06 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 09:47:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:47:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:47:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:47:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:07 INFO - (blobuploader) - INFO - Done attempting. 09:47:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:47:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:47:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:08 INFO - (blobuploader) - INFO - Done attempting. 09:47:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:47:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:47:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:09 INFO - (blobuploader) - INFO - Done attempting. 09:47:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:47:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:47:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:11 INFO - (blobuploader) - INFO - Done attempting. 09:47:11 INFO - (blobuploader) - INFO - Iteration through files over. 09:47:11 INFO - Return code: 0 09:47:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:47:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:47:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/767887ef61c1f866f683aa34cf769ea5e1d661c3f83e15c6fd388949ac38fe460bf8a00e474f32339f0e248eed61224a02e5495120104b04912aaac2bf12f13f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f20e5ebbccac474c42b3832571f4208a16be6fcdc2e23ee07e6581ad3138d7822858576f6771f4bef1e35c6227259c7b58e466447da2a9b92238d0c0e13de08", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f616ee47355572a2ea7bab2e3111cb84da162b0d2f461e7326b6f4abd2a4b30260e62374e8535f67803e3e5ce44c94d2db6a4d96dac8b2d4363c727a6e3d17d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5fa1019c04a7db31a8c4df46ac18a2b1b9b91f27676c0423d3efb53371562164066b00ed8d86c3d4fc09d33da9f93928e0fb88f0b52ad8ea54a3755c7d201eda"} 09:47:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:47:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:47:11 INFO - Contents: 09:47:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/767887ef61c1f866f683aa34cf769ea5e1d661c3f83e15c6fd388949ac38fe460bf8a00e474f32339f0e248eed61224a02e5495120104b04912aaac2bf12f13f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f20e5ebbccac474c42b3832571f4208a16be6fcdc2e23ee07e6581ad3138d7822858576f6771f4bef1e35c6227259c7b58e466447da2a9b92238d0c0e13de08", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f616ee47355572a2ea7bab2e3111cb84da162b0d2f461e7326b6f4abd2a4b30260e62374e8535f67803e3e5ce44c94d2db6a4d96dac8b2d4363c727a6e3d17d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5fa1019c04a7db31a8c4df46ac18a2b1b9b91f27676c0423d3efb53371562164066b00ed8d86c3d4fc09d33da9f93928e0fb88f0b52ad8ea54a3755c7d201eda"} 09:47:11 INFO - Running post-run listener: copy_logs_to_upload_dir 09:47:11 INFO - Copying logs to upload dir... 09:47:11 INFO - mkdir: /builds/slave/test/build/upload/logs 09:47:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.315078 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-03-03 09:47:11.370154) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-03 09:47:11.374081) ========= 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.CH4eNN8kI9/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.r8JOcphMJK/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/767887ef61c1f866f683aa34cf769ea5e1d661c3f83e15c6fd388949ac38fe460bf8a00e474f32339f0e248eed61224a02e5495120104b04912aaac2bf12f13f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f20e5ebbccac474c42b3832571f4208a16be6fcdc2e23ee07e6581ad3138d7822858576f6771f4bef1e35c6227259c7b58e466447da2a9b92238d0c0e13de08", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f616ee47355572a2ea7bab2e3111cb84da162b0d2f461e7326b6f4abd2a4b30260e62374e8535f67803e3e5ce44c94d2db6a4d96dac8b2d4363c727a6e3d17d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5fa1019c04a7db31a8c4df46ac18a2b1b9b91f27676c0423d3efb53371562164066b00ed8d86c3d4fc09d33da9f93928e0fb88f0b52ad8ea54a3755c7d201eda"} build_url:https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011234 build_url: 'https://queue.taskcluster.net/v1/task/M_o_oOgpTpC3YE29EHfi4Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/767887ef61c1f866f683aa34cf769ea5e1d661c3f83e15c6fd388949ac38fe460bf8a00e474f32339f0e248eed61224a02e5495120104b04912aaac2bf12f13f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3f20e5ebbccac474c42b3832571f4208a16be6fcdc2e23ee07e6581ad3138d7822858576f6771f4bef1e35c6227259c7b58e466447da2a9b92238d0c0e13de08", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f616ee47355572a2ea7bab2e3111cb84da162b0d2f461e7326b6f4abd2a4b30260e62374e8535f67803e3e5ce44c94d2db6a4d96dac8b2d4363c727a6e3d17d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5fa1019c04a7db31a8c4df46ac18a2b1b9b91f27676c0423d3efb53371562164066b00ed8d86c3d4fc09d33da9f93928e0fb88f0b52ad8ea54a3755c7d201eda"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-03 09:47:11.408735) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:47:11.409062) ========= 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.CH4eNN8kI9/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.r8JOcphMJK/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.005448 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:47:11.457935) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-03 09:47:11.458241) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-03 09:47:11.458564) ========= ========= Total master_lag: 0.39 =========