builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0254 starttime: 1465117326.65 results: success (0) buildid: 20160605010644 builduid: a6b3154f0b2c4bd0ae1605cb13f7ab02 revision: 072ce9419eaaf6be1cdbd589176feec2b7d8ec07 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:06.648610) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:06.649144) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:06.649455) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:06.690431) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:06.690796) ========= 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.Z8vlDCJILh/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.m9QZFxhuua/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-05 02:02:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 59.1M=0s 2016-06-05 02:02:07 (59.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.472431 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:07.184065) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:07.184430) ========= 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.Z8vlDCJILh/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.m9QZFxhuua/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.019994 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:07.232535) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-05 02:02:07.232889) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 072ce9419eaaf6be1cdbd589176feec2b7d8ec07 --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 072ce9419eaaf6be1cdbd589176feec2b7d8ec07 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Z8vlDCJILh/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.m9QZFxhuua/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-05 02:02:07,315 truncating revision to first 12 chars 2016-06-05 02:02:07,315 Setting DEBUG logging. 2016-06-05 02:02:07,315 attempt 1/10 2016-06-05 02:02:07,315 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/072ce9419eaa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-05 02:02:08,526 unpacking tar archive at: mozilla-aurora-072ce9419eaa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.476871 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-05 02:02:08.731066) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:08.731353) ========= script_repo_revision: 072ce9419eaaf6be1cdbd589176feec2b7d8ec07 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:08.731705) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:08.732054) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 02:02:08.751827) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-06-05 02:02:08.752124) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Z8vlDCJILh/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.m9QZFxhuua/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 02:02:08 INFO - MultiFileLogger online at 20160605 02:02:08 in /builds/slave/test 02:02:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:02:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:02:08 INFO - {'append_to_log': False, 02:02:08 INFO - 'base_work_dir': '/builds/slave/test', 02:02:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:02:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:02:08 INFO - 'branch': 'Mozilla-Aurora', 02:02:08 INFO - 'buildbot_json_path': 'buildprops.json', 02:02:08 INFO - 'config_files': ('talos/mac_config.py',), 02:02:08 INFO - 'default_actions': ('clobber', 02:02:08 INFO - 'read-buildbot-config', 02:02:08 INFO - 'download-and-extract', 02:02:08 INFO - 'populate-webroot', 02:02:08 INFO - 'create-virtualenv', 02:02:08 INFO - 'install', 02:02:08 INFO - 'run-tests'), 02:02:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:02:08 INFO - 'download_minidump_stackwalk': True, 02:02:08 INFO - 'download_symbols': 'ondemand', 02:02:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:02:08 INFO - 'tooltool.py': '/tools/tooltool.py', 02:02:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:02:08 INFO - '/tools/misc-python/virtualenv.py')}, 02:02:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:02:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:02:08 INFO - 'installer_path': 'installer.exe', 02:02:08 INFO - 'log_level': 'info', 02:02:08 INFO - 'log_name': 'talos', 02:02:08 INFO - 'log_to_console': True, 02:02:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:02:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:02:08 INFO - 'opt_config_files': (), 02:02:08 INFO - 'pip_index': False, 02:02:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:02:08 INFO - 'cmd': ('bash', 02:02:08 INFO - '-c', 02:02:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:02:08 INFO - 'enabled': True, 02:02:08 INFO - 'halt_on_failure': False, 02:02:08 INFO - 'name': 'check_screen_resolution'},), 02:02:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:02:08 INFO - 'cmd': ('bash', 02:02:08 INFO - '-c', 02:02:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:02:08 INFO - 'enabled': True, 02:02:08 INFO - 'halt_on_failure': False, 02:02:08 INFO - 'name': 'check_screen_resolution'},), 02:02:08 INFO - 'run_cmd_checks_enabled': True, 02:02:08 INFO - 'sps_profile': False, 02:02:08 INFO - 'sps_profile_interval': 0, 02:02:08 INFO - 'suite': 'dromaeojs', 02:02:08 INFO - 'system_bits': '32', 02:02:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:02:08 INFO - 'title': 't-yosemite-r7-0254', 02:02:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:02:08 INFO - 'use_talos_json': True, 02:02:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:02:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:02:08 INFO - 'work_dir': 'build'} 02:02:08 INFO - ##### 02:02:08 INFO - ##### Running clobber step. 02:02:08 INFO - ##### 02:02:08 INFO - Running pre-action listener: _resource_record_pre_action 02:02:08 INFO - Running main action method: clobber 02:02:08 INFO - rmtree: /builds/slave/test/build 02:02:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:02:10 INFO - Running post-action listener: _resource_record_post_action 02:02:10 INFO - ##### 02:02:10 INFO - ##### Running read-buildbot-config step. 02:02:10 INFO - ##### 02:02:10 INFO - Running pre-action listener: _resource_record_pre_action 02:02:10 INFO - Running main action method: read_buildbot_config 02:02:10 INFO - Using buildbot properties: 02:02:10 INFO - { 02:02:10 INFO - "project": "", 02:02:10 INFO - "product": "firefox", 02:02:10 INFO - "script_repo_revision": "production", 02:02:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:02:10 INFO - "repository": "", 02:02:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:02:10 INFO - "buildid": "20160605010644", 02:02:10 INFO - "pgo_build": "False", 02:02:10 INFO - "basedir": "/builds/slave/test", 02:02:10 INFO - "buildnumber": 370, 02:02:10 INFO - "slavename": "t-yosemite-r7-0254", 02:02:10 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:02:10 INFO - "platform": "yosemite_r7", 02:02:10 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:02:10 INFO - "branch": "mozilla-aurora", 02:02:10 INFO - "repo_path": "releases/mozilla-aurora", 02:02:10 INFO - "revision": "072ce9419eaaf6be1cdbd589176feec2b7d8ec07", 02:02:10 INFO - "stage_platform": "macosx64", 02:02:10 INFO - "builduid": "a6b3154f0b2c4bd0ae1605cb13f7ab02", 02:02:10 INFO - "slavebuilddir": "test" 02:02:10 INFO - } 02:02:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:02:10 INFO - Running post-action listener: _resource_record_post_action 02:02:10 INFO - ##### 02:02:10 INFO - ##### Running download-and-extract step. 02:02:10 INFO - ##### 02:02:10 INFO - Running pre-action listener: _resource_record_pre_action 02:02:10 INFO - Running main action method: download_and_extract 02:02:10 INFO - mkdir: /builds/slave/test/build/tests 02:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:10 INFO - https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:02:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:02:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:02:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:02:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:02:15 INFO - Downloaded 1199 bytes. 02:02:15 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:02:15 INFO - Using the following test package requirements: 02:02:15 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:02:15 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:02:15 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:02:15 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:02:15 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:02:15 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:02:15 INFO - u'jsshell-mac.zip'], 02:02:15 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:02:15 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:02:15 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:02:15 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:02:15 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:02:15 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:02:15 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:02:15 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:02:15 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:02:15 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:02:15 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:02:15 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:15 INFO - https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:02:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:02:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:02:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:02:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:02:16 INFO - Downloaded 18466788 bytes. 02:02:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:02:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:02:19 INFO - Return code: 0 02:02:19 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:19 INFO - https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:02:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:02:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:02:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:02:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:02:23 INFO - Downloaded 18466788 bytes. 02:02:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:02:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:02:26 INFO - Return code: 0 02:02:26 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:26 INFO - https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:02:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:02:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:02:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:02:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:02:27 INFO - Downloaded 10840145 bytes. 02:02:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:02:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:02:27 INFO - Return code: 0 02:02:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:27 INFO - https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:02:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:02:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:02:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:02:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:02:31 INFO - Downloaded 114164171 bytes. 02:02:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:02:31 INFO - mkdir: /builds/slave/test/properties 02:02:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:02:31 INFO - Writing to file /builds/slave/test/properties/build_url 02:02:31 INFO - Contents: 02:02:31 INFO - build_url:https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:02:32 INFO - Running post-action listener: _resource_record_post_action 02:02:32 INFO - Running post-action listener: set_extra_try_arguments 02:02:32 INFO - ##### 02:02:32 INFO - ##### Running populate-webroot step. 02:02:32 INFO - ##### 02:02:32 INFO - Running pre-action listener: _resource_record_pre_action 02:02:32 INFO - Running main action method: populate_webroot 02:02:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:02:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:02:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:02:32 INFO - '--tppagecycles', 02:02:32 INFO - '7'], 02:02:32 INFO - 'tests': ['tsvgm']}}, 02:02:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:02:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:02:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:02:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:02:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:02:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:02:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:32 INFO - 'talos_options': ['--disable-e10s'], 02:02:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:02:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:02:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:32 INFO - 'talos_options': ['--disable-e10s'], 02:02:32 INFO - 'tests': ['damp', 'tps']}, 02:02:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:32 INFO - 'tests': ['damp', 'tps']}, 02:02:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:02:32 INFO - 'tests': ['dromaeo_dom']}, 02:02:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:02:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:02:32 INFO - 'tests': ['a11yr', 02:02:32 INFO - 'ts_paint', 02:02:32 INFO - 'tpaint', 02:02:32 INFO - 'sessionrestore', 02:02:32 INFO - 'sessionrestore_no_auto_restore', 02:02:32 INFO - 'tabpaint']}, 02:02:32 INFO - 'other-e10s': {'tests': ['a11yr', 02:02:32 INFO - 'ts_paint', 02:02:32 INFO - 'tpaint', 02:02:32 INFO - 'sessionrestore', 02:02:32 INFO - 'sessionrestore_no_auto_restore', 02:02:32 INFO - 'tabpaint']}, 02:02:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:02:32 INFO - 'ts_paint', 02:02:32 INFO - 'tpaint', 02:02:32 INFO - 'sessionrestore', 02:02:32 INFO - 'sessionrestore_no_auto_restore', 02:02:32 INFO - 'tabpaint']}, 02:02:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:02:32 INFO - 'ts_paint', 02:02:32 INFO - 'tpaint', 02:02:32 INFO - 'sessionrestore', 02:02:32 INFO - 'sessionrestore_no_auto_restore', 02:02:32 INFO - 'tabpaint']}, 02:02:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:02:32 INFO - 'tests': ['a11yr', 02:02:32 INFO - 'ts_paint', 02:02:32 INFO - 'tpaint', 02:02:32 INFO - 'sessionrestore', 02:02:32 INFO - 'sessionrestore_no_auto_restore', 02:02:32 INFO - 'tabpaint']}, 02:02:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:02:32 INFO - 'tests': ['a11yr', 02:02:32 INFO - 'ts_paint', 02:02:32 INFO - 'tpaint', 02:02:32 INFO - 'sessionrestore', 02:02:32 INFO - 'sessionrestore_no_auto_restore', 02:02:32 INFO - 'tabpaint']}, 02:02:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:02:32 INFO - 'tests': ['tsvgx', 02:02:32 INFO - 'tsvgr_opacity', 02:02:32 INFO - 'tart', 02:02:32 INFO - 'tscrollx', 02:02:32 INFO - 'cart']}, 02:02:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:02:32 INFO - 'tsvgr_opacity', 02:02:32 INFO - 'tart', 02:02:32 INFO - 'tscrollx', 02:02:32 INFO - 'cart']}, 02:02:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:32 INFO - 'talos_options': ['--disable-e10s'], 02:02:32 INFO - 'tests': ['tp5o']}, 02:02:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:32 INFO - 'tests': ['tp5o']}, 02:02:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:32 INFO - 'talos_options': ['--disable-e10s', 02:02:32 INFO - '--xperf_path', 02:02:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:02:32 INFO - 'tests': ['tp5n']}, 02:02:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:32 INFO - 'talos_options': ['--xperf_path', 02:02:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:02:32 INFO - 'tests': ['tp5n']}}, 02:02:32 INFO - 'talos.zip': {'path': '', 02:02:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:02:32 INFO - Running post-action listener: _resource_record_post_action 02:02:32 INFO - ##### 02:02:32 INFO - ##### Running create-virtualenv step. 02:02:32 INFO - ##### 02:02:32 INFO - Running pre-action listener: _resource_record_pre_action 02:02:32 INFO - Running main action method: create_virtualenv 02:02:32 INFO - Creating virtualenv /builds/slave/test/build/venv 02:02:32 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 02:02:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:02:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:02:33 INFO - Using real prefix '/tools/python27' 02:02:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:02:33 INFO - Installing distribute.............................................................................................................................................................................................done. 02:02:36 INFO - Installing pip.................done. 02:02:36 INFO - Return code: 0 02:02:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:36 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 0x103f92a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fa2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa45b4ad810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e31e00>, '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 0x103c9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e2cb20>, '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.Z8vlDCJILh/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.m9QZFxhuua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 02:02:36 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 02:02:36 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 02:02:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z8vlDCJILh/Render', 02:02:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:36 INFO - 'HOME': '/Users/cltbld', 02:02:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:36 INFO - 'LOGNAME': 'cltbld', 02:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:36 INFO - 'MOZ_NO_REMOTE': '1', 02:02:36 INFO - 'NO_EM_RESTART': '1', 02:02:36 INFO - 'PAGER': '/bin/cat', 02:02:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:36 INFO - 'PWD': '/builds/slave/test', 02:02:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:36 INFO - 'SHELL': '/bin/bash', 02:02:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m9QZFxhuua/Listeners', 02:02:36 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 02:02:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:36 INFO - 'USER': 'cltbld', 02:02:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:36 INFO - 'XPC_FLAGS': '0x0', 02:02:36 INFO - 'XPC_SERVICE_NAME': '0', 02:02:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:36 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 02:02:36 INFO - Cleaning up... 02:02:36 INFO - Return code: 0 02:02:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:36 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f92a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fa2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa45b4ad810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e31e00>, '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 0x103c9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e2cb20>, '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.Z8vlDCJILh/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.m9QZFxhuua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 02:02:36 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>=0.7.1'] in /builds/slave/test/build 02:02:36 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>=0.7.1 02:02:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z8vlDCJILh/Render', 02:02:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:36 INFO - 'HOME': '/Users/cltbld', 02:02:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:36 INFO - 'LOGNAME': 'cltbld', 02:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:36 INFO - 'MOZ_NO_REMOTE': '1', 02:02:36 INFO - 'NO_EM_RESTART': '1', 02:02:36 INFO - 'PAGER': '/bin/cat', 02:02:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:36 INFO - 'PWD': '/builds/slave/test', 02:02:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:36 INFO - 'SHELL': '/bin/bash', 02:02:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m9QZFxhuua/Listeners', 02:02:36 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 02:02:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:36 INFO - 'USER': 'cltbld', 02:02:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:36 INFO - 'XPC_FLAGS': '0x0', 02:02:36 INFO - 'XPC_SERVICE_NAME': '0', 02:02:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:37 INFO - Downloading/unpacking psutil>=0.7.1 02:02:37 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 02:02:37 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 02:02:37 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 02:02:37 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 02:02:37 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 02:02:37 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 02:02:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:02:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:02:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:02:40 INFO - Installing collected packages: psutil 02:02:40 INFO - Running setup.py install for psutil 02:02:40 INFO - building 'psutil._psutil_osx' extension 02:02:40 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 02:02:40 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 02:02:41 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 02:02:41 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 02:02:41 INFO - building 'psutil._psutil_posix' extension 02:02:41 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 02:02:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:02:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:02:41 INFO - ^ 02:02:41 INFO - 1 warning generated. 02:02:41 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 02:02:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:02:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:02:41 INFO - Successfully installed psutil 02:02:41 INFO - Cleaning up... 02:02:41 INFO - Return code: 0 02:02:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:02:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:41 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f92a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fa2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa45b4ad810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e31e00>, '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 0x103c9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e2cb20>, '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.Z8vlDCJILh/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.m9QZFxhuua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 02:02:41 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.0.0'] in /builds/slave/test/build 02:02:41 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.0.0 02:02:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z8vlDCJILh/Render', 02:02:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:41 INFO - 'HOME': '/Users/cltbld', 02:02:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:41 INFO - 'LOGNAME': 'cltbld', 02:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:41 INFO - 'MOZ_NO_REMOTE': '1', 02:02:41 INFO - 'NO_EM_RESTART': '1', 02:02:41 INFO - 'PAGER': '/bin/cat', 02:02:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:41 INFO - 'PWD': '/builds/slave/test', 02:02:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:41 INFO - 'SHELL': '/bin/bash', 02:02:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m9QZFxhuua/Listeners', 02:02:41 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 02:02:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:41 INFO - 'USER': 'cltbld', 02:02:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:41 INFO - 'XPC_FLAGS': '0x0', 02:02:41 INFO - 'XPC_SERVICE_NAME': '0', 02:02:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:02:41 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 02:02:41 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 02:02:41 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 02:02:41 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 02:02:41 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 02:02:41 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 02:02:44 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:02:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:02:44 INFO - Installing collected packages: mozsystemmonitor 02:02:44 INFO - Running setup.py install for mozsystemmonitor 02:02:44 INFO - Successfully installed mozsystemmonitor 02:02:44 INFO - Cleaning up... 02:02:44 INFO - Return code: 0 02:02:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02: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', '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 0x103f92a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fa2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa45b4ad810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e31e00>, '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 0x103c9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e2cb20>, '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.Z8vlDCJILh/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.m9QZFxhuua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 02:02: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:02: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 blobuploader==1.2.4 02:02:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z8vlDCJILh/Render', 02:02:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:44 INFO - 'HOME': '/Users/cltbld', 02:02:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:44 INFO - 'LOGNAME': 'cltbld', 02:02:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:44 INFO - 'MOZ_NO_REMOTE': '1', 02:02:44 INFO - 'NO_EM_RESTART': '1', 02:02:44 INFO - 'PAGER': '/bin/cat', 02:02:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:44 INFO - 'PWD': '/builds/slave/test', 02:02:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:44 INFO - 'SHELL': '/bin/bash', 02:02:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m9QZFxhuua/Listeners', 02:02:44 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 02:02:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:44 INFO - 'USER': 'cltbld', 02:02:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:44 INFO - 'XPC_FLAGS': '0x0', 02:02:44 INFO - 'XPC_SERVICE_NAME': '0', 02:02:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:44 INFO - Downloading/unpacking blobuploader==1.2.4 02:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:02:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:02:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:02:47 INFO - Downloading blobuploader-1.2.4.tar.gz 02:02:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:02:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:02:47 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 02:02:47 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 02:02:47 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 02:02:47 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 02:02:47 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 02:02:47 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 02:02:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:02:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:02:47 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 02:02:47 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 02:02:47 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 02:02:47 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 02:02:47 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 02:02:47 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 02:02:48 INFO - Downloading docopt-0.6.1.tar.gz 02:02:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:02:48 INFO - Installing collected packages: blobuploader, requests, docopt 02:02:48 INFO - Running setup.py install for blobuploader 02:02:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:02:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:02:48 INFO - Running setup.py install for requests 02:02:49 INFO - Running setup.py install for docopt 02:02:49 INFO - Successfully installed blobuploader requests docopt 02:02:49 INFO - Cleaning up... 02:02:49 INFO - Return code: 0 02:02:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:49 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 0x103f92a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fa2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa45b4ad810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e31e00>, '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 0x103c9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e2cb20>, '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.Z8vlDCJILh/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.m9QZFxhuua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 02:02:49 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 02:02:49 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 02:02:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z8vlDCJILh/Render', 02:02:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:49 INFO - 'HOME': '/Users/cltbld', 02:02:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:49 INFO - 'LOGNAME': 'cltbld', 02:02:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:49 INFO - 'MOZ_NO_REMOTE': '1', 02:02:49 INFO - 'NO_EM_RESTART': '1', 02:02:49 INFO - 'PAGER': '/bin/cat', 02:02:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:49 INFO - 'PWD': '/builds/slave/test', 02:02:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:49 INFO - 'SHELL': '/bin/bash', 02:02:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m9QZFxhuua/Listeners', 02:02:49 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 02:02:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:49 INFO - 'USER': 'cltbld', 02:02:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:49 INFO - 'XPC_FLAGS': '0x0', 02:02:49 INFO - 'XPC_SERVICE_NAME': '0', 02:02:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:02:49 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-V2Dtcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:02:49 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-v33ITH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:02:49 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-4kmQTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:02:49 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-iMLzOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:02:49 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-RCmsFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:02:50 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Kd3kN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:02:50 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-hDKGZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:02:50 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-qcuC0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:02:50 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-7bMeck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:02:50 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-3z8CWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:02:50 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-oZos6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:02:50 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-za5xLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:02:50 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-iYrieU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:02:50 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-hByV4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:02:50 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-CBAsar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:02:51 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Q1RNsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:02:51 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-wXUxwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:02:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:02:51 INFO - Running setup.py install for manifestparser 02:02:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:02:51 INFO - Running setup.py install for mozcrash 02:02:51 INFO - Running setup.py install for mozdebug 02:02:51 INFO - Running setup.py install for mozdevice 02:02:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:02:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:02:51 INFO - Running setup.py install for mozfile 02:02:51 INFO - Running setup.py install for mozhttpd 02:02:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Running setup.py install for mozinfo 02:02:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Running setup.py install for mozInstall 02:02:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Running setup.py install for mozleak 02:02:52 INFO - Running setup.py install for mozlog 02:02:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Running setup.py install for moznetwork 02:02:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Running setup.py install for mozprocess 02:02:53 INFO - Running setup.py install for mozprofile 02:02:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Running setup.py install for mozrunner 02:02:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Running setup.py install for mozscreenshot 02:02:53 INFO - Running setup.py install for moztest 02:02:53 INFO - Running setup.py install for mozversion 02:02:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:02:53 INFO - Cleaning up... 02:02:53 INFO - Return code: 0 02:02:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:53 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 0x103f92a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fa2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa45b4ad810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e31e00>, '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 0x103c9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e2cb20>, '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.Z8vlDCJILh/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.m9QZFxhuua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 02:02:53 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 02:02:53 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 02:02:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z8vlDCJILh/Render', 02:02:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:53 INFO - 'HOME': '/Users/cltbld', 02:02:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:53 INFO - 'LOGNAME': 'cltbld', 02:02:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:53 INFO - 'MOZ_NO_REMOTE': '1', 02:02:53 INFO - 'NO_EM_RESTART': '1', 02:02:53 INFO - 'PAGER': '/bin/cat', 02:02:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:53 INFO - 'PWD': '/builds/slave/test', 02:02:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:53 INFO - 'SHELL': '/bin/bash', 02:02:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m9QZFxhuua/Listeners', 02:02:53 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 02:02:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:53 INFO - 'USER': 'cltbld', 02:02:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:53 INFO - 'XPC_FLAGS': '0x0', 02:02:53 INFO - 'XPC_SERVICE_NAME': '0', 02:02:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:02:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-8sJgc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:02:54 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)) 02:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:02:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-EqXWCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 02:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:02:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-4tjvjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:02:54 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)) 02:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:02:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-mvSmIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:02:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-ciM94b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:02:54 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)) 02:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:02:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-tJMVL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:02:54 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)) 02:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:02:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-mzn8n5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:02:54 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)) 02:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:02:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-K9sVGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:02:54 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)) 02:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:02:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-NLJQDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:02:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-cJhljX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:02:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-kNcstH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:02:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-p82M0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:02:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-RSc044-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:02:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-nSKbnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:02:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-GPoeyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:02:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-N0tW5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:02:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-ef7i3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:02:55 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)) 02:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:02:55 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)) 02:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:02:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:02: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 02:02: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 02:02: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 02:02: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 02:02: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 02:02: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 02:02:58 INFO - Downloading blessings-1.6.tar.gz 02:02:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:02:58 INFO - Installing collected packages: blessings 02:02:58 INFO - Running setup.py install for blessings 02:02:58 INFO - Successfully installed blessings 02:02:58 INFO - Cleaning up... 02:02:58 INFO - Return code: 0 02:02:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:02:58 INFO - Reading from file tmpfile_stdout 02:02:58 INFO - Current package versions: 02:02:58 INFO - blessings == 1.6 02:02:58 INFO - blobuploader == 1.2.4 02:02:58 INFO - docopt == 0.6.1 02:02:58 INFO - manifestparser == 1.1 02:02:58 INFO - mozInstall == 1.12 02:02:58 INFO - mozcrash == 0.17 02:02:58 INFO - mozdebug == 0.1 02:02:58 INFO - mozdevice == 0.48 02:02:58 INFO - mozfile == 1.2 02:02:58 INFO - mozhttpd == 0.7 02:02:58 INFO - mozinfo == 0.9 02:02:58 INFO - mozleak == 0.1 02:02:58 INFO - mozlog == 3.1 02:02:58 INFO - moznetwork == 0.27 02:02:58 INFO - mozprocess == 0.22 02:02:58 INFO - mozprofile == 0.28 02:02:58 INFO - mozrunner == 6.11 02:02:58 INFO - mozscreenshot == 0.1 02:02:58 INFO - mozsystemmonitor == 0.0 02:02:58 INFO - moztest == 0.7 02:02:58 INFO - mozversion == 1.4 02:02:58 INFO - psutil == 3.1.1 02:02:58 INFO - requests == 1.2.3 02:02:58 INFO - wsgiref == 0.1.2 02:02:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:58 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 0x103f92a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fa2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa45b4ad810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e31e00>, '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 0x103c9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e2cb20>, '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.Z8vlDCJILh/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.m9QZFxhuua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 02:02:58 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 02:02:58 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 02:02:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z8vlDCJILh/Render', 02:02:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:58 INFO - 'HOME': '/Users/cltbld', 02:02:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:58 INFO - 'LOGNAME': 'cltbld', 02:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:58 INFO - 'MOZ_NO_REMOTE': '1', 02:02:58 INFO - 'NO_EM_RESTART': '1', 02:02:58 INFO - 'PAGER': '/bin/cat', 02:02:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:58 INFO - 'PWD': '/builds/slave/test', 02:02:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:58 INFO - 'SHELL': '/bin/bash', 02:02:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m9QZFxhuua/Listeners', 02:02:58 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 02:02:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:58 INFO - 'USER': 'cltbld', 02:02:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:58 INFO - 'XPC_FLAGS': '0x0', 02:02:58 INFO - 'XPC_SERVICE_NAME': '0', 02:02:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:59 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)) 02:02:59 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)) 02:02:59 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)) 02:02:59 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)) 02:02:59 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)) 02:02:59 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)) 02:02:59 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)) 02:02:59 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)) 02:02:59 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)) 02:02:59 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)) 02:02:59 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)) 02:02:59 INFO - Cleaning up... 02:02:59 INFO - Return code: 0 02:02:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f92a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fa2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa45b4ad810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e31e00>, '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 0x103c9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e2cb20>, '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.Z8vlDCJILh/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.m9QZFxhuua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 02:02:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:02:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z8vlDCJILh/Render', 02:02:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:59 INFO - 'HOME': '/Users/cltbld', 02:02:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:59 INFO - 'LOGNAME': 'cltbld', 02:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:59 INFO - 'MOZ_NO_REMOTE': '1', 02:02:59 INFO - 'NO_EM_RESTART': '1', 02:02:59 INFO - 'PAGER': '/bin/cat', 02:02:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:59 INFO - 'PWD': '/builds/slave/test', 02:02:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:59 INFO - 'SHELL': '/bin/bash', 02:02:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m9QZFxhuua/Listeners', 02:02:59 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 02:02:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:59 INFO - 'USER': 'cltbld', 02:02:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:59 INFO - 'XPC_FLAGS': '0x0', 02:02:59 INFO - 'XPC_SERVICE_NAME': '0', 02:02:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:59 INFO - Downloading/unpacking jsonschema 02:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:02:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:02:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:03:01 INFO - Downloading/unpacking functools32 (from jsonschema) 02:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:02 INFO - Downloading functools32-3.2.3-2.tar.gz 02:03:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:03:02 INFO - warning: no files found matching '*.txt' 02:03:02 INFO - no previously-included directories found matching 'build' 02:03:02 INFO - no previously-included directories found matching 'dist' 02:03:02 INFO - no previously-included directories found matching '.git*' 02:03:02 INFO - Installing collected packages: jsonschema, functools32 02:03:02 INFO - Running setup.py install for functools32 02:03:02 INFO - warning: no files found matching '*.txt' 02:03:02 INFO - no previously-included directories found matching 'build' 02:03:02 INFO - no previously-included directories found matching 'dist' 02:03:02 INFO - no previously-included directories found matching '.git*' 02:03:02 INFO - Successfully installed jsonschema functools32 02:03:02 INFO - Cleaning up... 02:03:02 INFO - Return code: 0 02:03:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f92a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fa2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa45b4ad810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e31e00>, '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 0x103c9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e2cb20>, '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.Z8vlDCJILh/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.m9QZFxhuua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 02:03:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:03:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z8vlDCJILh/Render', 02:03:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:02 INFO - 'HOME': '/Users/cltbld', 02:03:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:02 INFO - 'LOGNAME': 'cltbld', 02:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:02 INFO - 'MOZ_NO_REMOTE': '1', 02:03:02 INFO - 'NO_EM_RESTART': '1', 02:03:02 INFO - 'PAGER': '/bin/cat', 02:03:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:02 INFO - 'PWD': '/builds/slave/test', 02:03:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:02 INFO - 'SHELL': '/bin/bash', 02:03:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m9QZFxhuua/Listeners', 02:03:02 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 02:03:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:02 INFO - 'USER': 'cltbld', 02:03:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:02 INFO - 'XPC_FLAGS': '0x0', 02:03:02 INFO - 'XPC_SERVICE_NAME': '0', 02:03:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:02 INFO - Downloading/unpacking flake8 02:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:03:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:03:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:03:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:03:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:03:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:03:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:03:06 INFO - no previously-included directories found matching 'docs/_build' 02:03:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:03:06 INFO - Running setup.py install for pep8 02:03:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:03:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:03:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:03:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:03:06 INFO - no previously-included directories found matching 'docs/_build' 02:03:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:03:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:03:07 INFO - Cleaning up... 02:03:07 INFO - Return code: 0 02:03:07 INFO - Running post-action listener: _resource_record_post_action 02:03:07 INFO - Running post-action listener: _start_resource_monitoring 02:03:07 INFO - Starting resource monitoring. 02:03:07 INFO - ##### 02:03:07 INFO - ##### Running install step. 02:03:07 INFO - ##### 02:03:07 INFO - Running pre-action listener: _resource_record_pre_action 02:03:07 INFO - Running main action method: install 02:03:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:03:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:03:07 INFO - Reading from file tmpfile_stdout 02:03:07 INFO - Detecting whether we're running mozinstall >=1.0... 02:03:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:03:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:03:07 INFO - Reading from file tmpfile_stdout 02:03:07 INFO - Output received: 02:03:07 INFO - Usage: mozinstall [options] installer 02:03:07 INFO - Options: 02:03:07 INFO - -h, --help show this help message and exit 02:03:07 INFO - -d DEST, --destination=DEST 02:03:07 INFO - Directory to install application into. [default: 02:03:07 INFO - "/builds/slave/test"] 02:03:07 INFO - --app=APP Application being installed. [default: firefox] 02:03:07 INFO - mkdir: /builds/slave/test/build/application 02:03:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:03:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:03:39 INFO - Reading from file tmpfile_stdout 02:03:39 INFO - Output received: 02:03:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:03:39 INFO - Running post-action listener: _resource_record_post_action 02:03:39 INFO - ##### 02:03:39 INFO - ##### Running run-tests step. 02:03:39 INFO - ##### 02:03:39 INFO - Running pre-action listener: _resource_record_pre_action 02:03:39 INFO - Running main action method: run_tests 02:03:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:03:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:03:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:03:39 INFO - 2016-06-05 02:03:39.770 screenresolution[1926:18079] starting screenresolution argv=screenresolution get 02:03:39 INFO - 2016-06-05 02:03:39.773 screenresolution[1926:18079] Display 0: 1600x1200x32@60 02:03:39 INFO - 2016-06-05 02:03:39.779 screenresolution[1927:18081] starting screenresolution argv=screenresolution list 02:03:39 INFO - Available Modes on Display 0 02:03:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:03:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:03:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:03:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:03:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:03:40 INFO - Intel Iris: 02:03:40 INFO - Chipset Model: Intel Iris 02:03:40 INFO - Type: GPU 02:03:40 INFO - Bus: Built-In 02:03:40 INFO - VRAM (Dynamic, Max): 1536 MB 02:03:40 INFO - Vendor: Intel (0x8086) 02:03:40 INFO - Device ID: 0x0a2e 02:03:40 INFO - Revision ID: 0x0009 02:03:40 INFO - Displays: 02:03:40 INFO - Display: 02:03:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:03:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:03:40 INFO - Display Serial Number: 1600x1200 60 02:03:40 INFO - Main Display: Yes 02:03:40 INFO - Mirror: Off 02:03:40 INFO - Online: Yes 02:03:40 INFO - Rotation: Supported 02:03:40 INFO - Return code: 0 02:03:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:03:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:03:40 INFO - Python 2.7.3 02:03:40 INFO - Return code: 0 02:03:40 INFO - grabbing minidump binary from tooltool 02:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:40 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e31e00>, '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 0x103c9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e2cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:03:40 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 02:03:40 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 02:03:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:03:40 INFO - Return code: 0 02:03:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:03:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:03:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:03:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:03:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:03:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:03:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:03:41 INFO - Return code: 0 02:03:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:03:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0254 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:03:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z8vlDCJILh/Render', 02:03:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:41 INFO - 'HOME': '/Users/cltbld', 02:03:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:41 INFO - 'LOGNAME': 'cltbld', 02:03:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:03:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:41 INFO - 'MOZ_NO_REMOTE': '1', 02:03:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:03:41 INFO - 'NO_EM_RESTART': '1', 02:03:41 INFO - 'PAGER': '/bin/cat', 02:03:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:41 INFO - 'PWD': '/builds/slave/test', 02:03:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:03:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:41 INFO - 'SHELL': '/bin/bash', 02:03:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m9QZFxhuua/Listeners', 02:03:41 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 02:03:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:41 INFO - 'USER': 'cltbld', 02:03:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:41 INFO - 'XPC_FLAGS': '0x0', 02:03:41 INFO - 'XPC_SERVICE_NAME': '0', 02:03:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:03:41 INFO - mozversion application_buildid: 20160605010644 02:03:41 INFO - mozversion application_changeset: 072ce9419eaaf6be1cdbd589176feec2b7d8ec07 02:03:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:03:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:03:41 INFO - mozversion application_name: Firefox 02:03:41 INFO - mozversion application_remotingname: firefox-dev 02:03:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:03:41 INFO - mozversion application_vendor: Mozilla 02:03:41 INFO - mozversion application_version: 48.0a2 02:03:41 INFO - mozversion platform_buildid: 20160605010644 02:03:41 INFO - mozversion platform_changeset: 072ce9419eaaf6be1cdbd589176feec2b7d8ec07 02:03:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:03:41 INFO - mozversion platform_version: 48.0a2 02:03:41 INFO - using testdate: 1465117421 02:03:41 INFO - actual date: 1465117421 02:03:41 INFO - starting webserver on 'localhost:52294' 02:03:41 INFO - SUITE-START | Running 2 tests 02:03:41 INFO - TEST-START | dromaeo_css 02:03:41 INFO - operating with platform_type : mac_ 02:03:41 INFO - Initialising browser for dromaeo_css test... 02:03:41 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpJVZj8f/profile http://localhost:52294/getInfo.html) 02:03:43 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 02:03:43 INFO - PROCESS | 1945 | colorDepth:24 02:03:43 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 02:03:43 INFO - PROCESS | 1945 | __metrics 02:03:43 INFO - PROCESS | 1945 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:03:43 ERROR - PROCESS | 1945 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:03:44 INFO - TEST-INFO | 1945: exit 0 02:03:44 INFO - Browser initialized. 02:03:44 INFO - Running cycle 1/1 for dromaeo_css test... 02:03:44 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpJVZj8f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:03:56 INFO - PROCESS | 1947 | RSS: Main: 211431424 02:03:56 INFO - PROCESS | 1947 | 02:04:48 INFO - PROCESS | 1947 | 1465117488265 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:06:58 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52294/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52294/tests/dromaeo/cssquery-ext.html) 02:07:00 INFO - PROCESS | 1947 | RSS: Main: 414167040 02:07:00 INFO - PROCESS | 1947 | 02:10:03 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52294/tests/dromaeo/cssquery-ext.html (next: http://localhost:52294/tests/dromaeo/cssquery-jquery.html) 02:10:04 INFO - PROCESS | 1947 | RSS: Main: 356069376 02:10:04 INFO - PROCESS | 1947 | 02:13:06 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52294/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52294/tests/dromaeo/cssquery-mootools.html) 02:13:07 INFO - PROCESS | 1947 | RSS: Main: 360673280 02:13:07 INFO - PROCESS | 1947 | 02:16:09 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52294/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52294/tests/dromaeo/cssquery-prototype.html) 02:16:10 INFO - PROCESS | 1947 | RSS: Main: 360902656 02:16:10 INFO - PROCESS | 1947 | 02:19:13 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52294/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52294/tests/dromaeo/cssquery-yui.html) 02:19:13 INFO - PROCESS | 1947 | RSS: Main: 325832704 02:19:13 INFO - PROCESS | 1947 | 02:22:16 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52294/tests/dromaeo/cssquery-yui.html (next: http://localhost:52294/tests/dromaeo/cssquery-dojo.html) 02:22:16 INFO - PROCESS | 1947 | RSS: Main: 367030272 02:22:16 INFO - PROCESS | 1947 | 02:22:16 INFO - PROCESS | 1947 | __start_tp_report 02:22:16 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 02:22:16 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 02:22:16 INFO - PROCESS | 1947 | |i|pagename|runs| 02:22:16 INFO - PROCESS | 1947 | |0;dojo.html;3138.6390565085017;3329.1011426914733;3359.12662707696;3385.6275809660947;3397.473391624299;5541.085720856054;5541.390447050826;5548.583856210786;5549.056660367747;5568.916466253005;5546.556275497961;5570.470805273492;5583.199797587927;5610.438956104387;5639;5003.624728145385;5066.594672426216;5080.390353157626;5117.437081920985;5138.768755405997;4804.942912559231;5022.17134172861;5102.770375333119;5113.565346945496;5119.078565858144;4549.658775591827;5012.8496145115705;5086.084504789136;5093.490650934914;5110.872228194287;5088.872778180538;5124.820631277925;5134.871628209287;5139.434662187156;5170.431252562253;4953.182724850395;4988.401391833003;5050.116229659795;5060.139776238048;5079.517445842639;5342.652727572696;5359.1425371940695;5378.865528361822;5395.757190926418;5410.594205434608;6051.757929682807;6053.424924632197;6067.848303792396;6087.4825639820665;6109.847253818646;5945.910811337833;6137.693115344215;6156.230471191101;6163.291221509539;6166.8766624667705;5571.498565129158;5629.605927585111;5714.285714285715;5736.340320863154;5763.164341170507;6130.049842274455;6133.26400831903;6137.877242455126;6147.846303842351;6152.600080994811;5800.24596802413;5806;5813.389594092644;5820.068788993741;5844.298684157843;5975.342712301644;6013.609115407485;6074.088886666947;6076.574639775174;6086.330503644595;4936.432310284292;5911.379305172981;5938.61399009063;5945.851353716105;5946.286445626466;5961.761529538857;6057.97014507526;6116.3577824328695;6138.020573254751;6141.2098563120835;4848.903021939542;4854.611631069506;4857.101436234238;4870.853874383774;4894.853154405374;4378.671599630041;4399.032212913154;4403.933940990888;4407.228734971432;4433;5459.454054594588;5564.554835613141;5564.582656300713;5657.60396772222;5659.971700141473;6149.538784591174;6169.321374648785;6172.660503672341;6178.980468222783;6186.19579454668;5553.72231388437;5615.9157612635845;5625.46558076982;5628.831135065954;5635.408282130317;5191;5272.103742363807;5277.445868183785;5279.604029697711;5283.365996080495;6193.411625895533;6282.528810339151;6293.527985401021;6317.5893566918;6319;6119.908201376894;6146.78486252979;6157.014877619738;6181.227346581677;6184.474319682878;5335.932992459767;6096.695165241631;6234.594751341148;6281.089242059968;6295.905561416491;5867.589268751147;5987.730552125077;6048.243969503812;6100.755969761338;6109;5936.910946335722;6058.333583305921;6059.757609695563;6070.666113363807;6076.817695469143;5773.769049237983;5820.650760954356;5836.066229403275;5874.412558744091;5877.853053673522;5796.246487956539;5807.477327040587;5846.093855452412;5846.473817356288;5847.093700476433;5653.265075540154;5668.149777533403;5686.175504551899;5687.77248910039;5689.431056894277;4961.900761984668;5827.679477628772;5866.882662346815;5895.764169433346;5904.704764761831;5794.333651630117;5818.330891947311;5839.328477225174;5839.386864379179;5843.2403787507355 02:22:16 INFO - PROCESS | 1947 | |1;ext.html;504.7930348557091;517.2990597740061;522.7621432248327;535.296085647374;547.5072434808674;30720.846395768018;37125.25749485012;37911.43132853006;38116.42825357622;38218.04454888629;41261.793691031504;41714;41892.37161442581;41902.790486047605;41911.79044104783;8326.6253018614;9297.116773906502;9535;9536.998615145414;9538.856917146248;8399.706010289641;11305.547778088867;11517.596884109058;11519.65441036766;11523.48144333507;11252.831207531894;11354.261972971772;11356.829647555292;11358.886411135907;11365.829512557319;8431.241188293021;8664.046954834963;11234.887651123507;11323.547058117665;11363.090952723804;8356.874646880302;8471.788205294855;11140.108791296758;11402.543898244061;11405.828912566318;38201;38636.227275454614;38729.22541549181;38784.80607596972;38803.02992425184;3065.390002342672;3209.293955329824;3225.3388055448577;3292.8353582320788;3323.3187196624876;26899.596506052425;27308.726912731017;27412;27606.447871042466;27768.861155694296;2744.972550274492;2750.5049091163582;2770.432061427423;2860.256333353322;2901.4922388582127;486032;486969;488623;488810;489001;50898.74550627223;60961.69519152376;62105.37894621086;62157;62257;64608.676956614916;64783.02825457622;64795.67602161959;64880.351196487434;64927;15577.68844623124;16880.915595421946;17540.47378578619;17545.736813947802;17556.2099705511;12349;12652.43064062101;13401.329933503286;13523.120997135155;13611.659708507366;15811.841881581267;15891.28489217988;15893.920530397274;15912.443064492687;15934.442294519637;6035.486983606443;7071.7878463646175;7113.89329160063;7124.893126603105;7220.350168484756;831.2477208126655;942.4486675295029;997.1823105053786;999.4253304350028;1016.8068067067381;28944.710552894623;29426.264343391587;29533.26166845846;30116.69883301183;30387.2403189922;1100.7743412600398;1128.5090985421368;1155.5608868629868;1164.2665120973902;1246.8342100136401;14810.259487025822;15057.698846023019;15185.392584296504;15467.5359739208;15552.844471555365;617464;617596;624422;625039;625433;60320.79358412807;60591.39408605857;60599.69700151559;60614.696926515964;60721.78556428847;1312.9365214176548;1375.2985977151725;1420.6590418299327;1536.7541193409002;1541.0368519675203;28127;30703.69296307098;30770.692293076783;30825.921092761644;31463.21341966469;57559.42440575541;57977.710111449174;58393;58421.4157858416;58519;580700;592371;593364;593639;594490;36099.819500902326;36648.90053298405;37726.62273377231;43729.56270437255;43809.78095109504;43316.70049898508;43625;44924.17661512522;44935.77532112318;45699.85750356135;37974.2405151901;37990;38093.80953095217;38280.85157445281;39231;31250.687493124777;37302.626973731;38086.428703570025;38824.029399265244;39073.609263907;31326.373472529966;36859.52561897601;38342;38694;38720.80639596784;32001.519977199896;32894.34211315808;33210;33252.836150734605;33469.33061338808;24623.38441538976;24635.507289853744;25014.37464063413;25205.49589008173;25254.494910101326 02:22:16 INFO - PROCESS | 1947 | |2;jquery.html;386.5168539325843;398.7149188330345;401.5943896664369;402.24177425552836;405.49313358302123;13392.799108013363;14889.478868239614;19653.410397688058;20253.987300634966;20291.78249305043;19700.507487312825;20285.492862678442;20320.491987700316;20477.28329508464;21030.474238144056;5755.654660720349;5899.2036075129845;6059.303180134297;6062.363451837559;6117.113018612298;4191.392248124021;5952.077427998667;5973.193618861442;6001.369856165105;6236.438720515152;6473.28793832678;6481.416672499474;6481.902771458432;6482.351764823527;6482.416582507573;5702.800401985911;6049.304330002062;6058.242719660043;6456.515761317896;6466;5830.912536311958;5836.474717275466;5843.9707801461145;5852.092925596539;5860.443257890493;14162.36269367881;23795.643065354034;24946.875265623738;24989.375265618324;24989.750102498925;1785.9196336164773;2079.3242196286114;2199.0104452996056;2220.6668999650183;2230.141395562704;9736.853947190797;10156.441395723232;10197.643082492152;13307.667308317272;13775.793363099561;1438.3239877257777;1746.3974928649595;1749.7462867884085;1749.7550342951997;1793.2049064630978;100300.99699002989;116352;119712.80287197104;119760.40119799346;124812.00751969911;11288.604898828502;11441.084713222923;11535.538578456768;11557.364344960939;11678.532858685727;13155.934220329029;13709.520166794115;14602.977791554702;14652.487162949246;14656.706865862623;6403.263624683221;8049.55727434985;8667.263282621048;8715.302775777922;8880.689175878942;8869.423487473294;8894.243989260985;8895.866562001574;8987.910120898838;8993.23557497607;4453.908792345896;4523.434570678665;4601.7699115044115;4603.309503574491;4641.489436162019;1917.8807417803405;1988.2842176816334;2023.1199428248465;2024.8738824234495;2039.643062464063;895.1943251073956;954.0650162840456;992.1963209800085;1019.3852767650951;1023.370627064365;16313.265903034391;19683.901580492005;19724.70412943807;19944.3019494317;20077.899610501852;879.9045188740059;910.5720311453606;967.2358836519212;997.7705127820642;1001.3941565353002;8032.236937490929;10433.634822781167;10550.314229575053;10555.261131720707;10613;434936;439349;446022;553277;628336;129072.35463822621;137272;144521;150472;154979;450.5246964452534;463.170924045954;470.4660210660856;471.5850051954258;475.265714471136;10340.948295258777;10396.168306535308;10609.416482093558;11023.888071863104;11120.830563289424;52685;53393.733031334596;53910.65223369447;54285.35725889023;56038.71980640071;63659.681701591195;76957;78795.81806272797;81120.18879811362;81962;8138.633761480866;8164.673413063411;8202.58987050657;8342.541160236246;8369.079401265977;8953.373263871466;11716.882831171579;12263.754724905273;12620.684482887635;12636.368181591068;10667.946660266649;10940.288881222696;10968.780624387628;11132.332060076422;11140.610078647209;10493.527791249564;10533.789324213627;10753.784924301313;10841;11017.614383496539;10663.89336106629;10899.400532970763;10958.56165753361;11363.943180284046;11375.886241137483;7923.088844782924;8084.434089613906;8133.186681331819;8172.83654326922;8206.671733130846;8871;9370.437773733598;10359.792804143724;10450.634227802295;10532.157427405637 02:22:16 INFO - PROCESS | 1947 | |3;mootools.html;1330.9618497571898;1557.8364271751466;1584.690985257875;1842.7328037434572;1997.2909617085943;7376.446766492514;8031.91968080319;8473.44922580032;8656.913430865705;8686.522241276727;7413.295736904999;8517.574121293927;8578.356623253265;8683.696070637528;8707.91292087079;2418.8306818522756;2443.8289319747623;2445.3519817248443;2448.681671382718;2449.081594402099;2073.232903825579;2405.7594240575977;2461.520003599299;2466.432720474283;2469;2065.452655046414;2428.987855060731;2458.6803715516958;2463.7905778008803;2468.9753102469017;2412.2160297903074;2451.901923923041;2461.4092617771785;2462.741412151725;2464.642626819119;2029.9289524866558;2439.316991242455;2458.606622940321;2458.7172475165407;2462.987685061581;8375.078741338448;8733.475991440471;8773.692920747742;8792.252658523968;8799.824003519958;5311.840644780663;5431.967926094029;5462.8361149165585;5466.945330546683;5474.616776825624;3314.254292784128;3388.525606415104;3402.5236466894653;3444.8794292199655;3448.9655103448895;1044.7283706236433;1047.4919663962971;1081.000074930686;1611.3715650896042;1657.2542355939875;11600.651980440516;11692.590759323468;11710.882891171064;11721.355325457096;11733.237339572901;4488.528704486047;4575.908481830411;4651.534846515321;4660.627149828006;4692.671512994126;9139.131782480654;9152.496612686366;9176.44941303524;9188.95405522968;9191.080891910755;5292.894142117136;5328.600354973392;5337.839864804062;5366.651167674088;5371.543418809367;4279.5934386233885;4439.844605438794;4448.043670610834;4458.420405347284;4988.551030407209;3774.150816066363;3794.4498047782918;3887.3974533947285;3891.1828516011387;3893.377059670439;1177.5643012085584;1188.6137005473256;1192.9224600400946;1200.4117982188839;1206.7646808872187;853.1724227499316;853.3727710133047;872.9388942773945;913.4747520175792;954.3605784124653;5758.251427314506;5785.247917770663;5885.264341957255;5896.64620122794;6158.846028849225;916.1251005289932;970.8640790289365;977.3402953006693;991.7272749993809;1025.333533203409;3313.3042061167425;3846.442265871433;3853.672437842759;3921.431392448136;3999.5800440953863;10251.794964100825;10557.894421055844;10760.97770711781;10847.294925829796;10867.78264434707;10646.467676616137;10718.73203169927;10724.624638137628;10847.077998370069;10932.56269749217;7973.481723687942;8200.466969646954;8255.504669719836;8266.462679925786;8454.535000575028;5732.799352022659;5761.884762304814;5831.35855055935;5892.882142357214;5975.40245975399;9052.864207036768;9360.578773955325;9378.67174648884;9379.202767764817;9390;9427.15155635996;9448.433094014381;9455;9479.810403792024;9506.762330941781;6635.767748128792;6837.35045170708;7162.677679504539;7512.887306690294;7528.623568821427;8075.798105047421;8155.510669359857;8253.669853205805;8279.296259817742;8288.50268983863;7200.783976480714;7301.233370496021;7304.561726296227;7409.925900740924;7440.107187137578;7148.749793757193;7162.247963963917;7256.9274307256255;7278.871774874903;7284.125904891447;5069.822556210515;6426.325235850355;6904;6905;6925.68834402443;4749.311349854321;4822.132016237111;4822.565969062801;4941.925871111865;4976.576990955809;4898.387701537308;4936.679115857458;4960.925586116139;5009.0983622948215;5133.589312854888 02:22:16 INFO - PROCESS | 1947 | |4;prototype.html;322.4341281051754;328.30235749033307;330.7205411427345;335.0167259094556;339.11152779717116;6590.934090659092;6605;6612.23959244687;6623.635700036502;6625.933740662592;6161.630302181872;6574.934250657492;6618.139641846566;6618.801435956917;6630.701618427171;4198.6011328923705;4761.857144285677;4786.617070634358;4843.055604157191;4880.414350277972;3856.8842934712;4868.196747536652;4882.755862206894;4886;4895.510448955094;4839.4918533554;4846.151923413407;4849.127157111719;4850.539198776129;4854;4616.376789133475;4700.01299727056;4789.137955168068;4825.131476334259;4840.346553215306;4138.234426631053;4145.2952997990415;4150.522689890671;4151.27352713277;4154.833806647761;6156.7537298508005;6173;6174.166487524217;6187.84530386744;6193.907091393633;5659.8585035374035;5673.092305231144;5682.232898558725;5688.232088668018;5766.961946849566;6750.62871542065;6990.825229369256;6995.440364770806;7010.964945175292;7015.9298407015785;4743.383360163191;4760.452547956995;4762.857114286577;4763.237881938908;4763.737994410306;7341.1557670868;7409.110906691178;7417.072865891763;7430.665620047111;7432.219616940251;3998.280309544252;4822.156122678517;4823.287732854857;4845.951540484621;4851.302044284472;4899.1426500362295;4909.459959404463;4918.852434426973;4929.0388374267395;4935.506449355107;5779.653220806765;5798.797042103506;5814.418558144152;5833.649981001068;5837.9124313135335;5721;6786.694598743068;7067;7079.150501939697;7107.431405487549;1602.6634406774474;1642.031201591043;1643.3098098798532;1643.7780899578643;1644.8519633233066;960.706984369774;974.848898420746;1024.9641262555774;1029.4955471818905;1053.2416660004785;5214.348206474191;5432.701201433959;6466.773662921775;6476.708548115252;6482.57863238888;5967.224260846062;7149.678264478111;7285.526440781438;7305.086864141982;7317.304856038774;6646;6648.966755166193;6656.667166641649;6676.26561078281;6683.799486015428;3831.6934645228316;3872.6320999505;3874.0121269076767;3877.1470276539117;3879.9030024249055;7360.300771426706;7630.122535908331;7640.579768112807;7649.31156195945;7666.348360389429;7531.435142364345;7555.055618047744;7558.886616700754;7562.584057876759;7567.697292108364;7630.245043639994;7664.923350766532;7667.0416197975255;7668.156502784577;7675.385969122457;6996.510244282851;7321.853562928818;7334.3032411920785;7335.339819416278;7339.229380914927;7156.3917067047805;7191.460640452092;7195.568265904062;7212.170600381023;7220.963895180491;7590.316871481593;7595.696172153273;7599.772006839804;7603.0876294844975;7622.16156222804;3857.305684976619;4663.883402914954;4669.276262179367;4670.159371313196;4672.976635116803;7039;7289.307515785992;7293.161286452126;7298.343149116605;7304.342609165201;7033.683484243324;7043.436525077879;7067.858642827012;7069.893951590833;7073.610951397723;6995.440364770704;7046.753363632249;7059.435245180476;7062.929370706227;7064.258252883374;5623.959567480029;6173.81478555644;6426;6886.965565172142;6908.412784913338;6812.693428795816;7052.224255332012;7058.576485410892;7060.399866011447;7074.257202993817;5604;6589.80230593083;6803.557768745016;6808.217055038734;6919.896201557081 02:22:16 INFO - PROCESS | 1947 | |5;yui.html;682.3722175598449;736.1975446763028;742.5025233093827;749.6326799868062;793.2979313307721;12281;14529.92735036326;14913.403463861447;15032.624184395396;15065.6986860263;11765.352905590189;13841;13878.79181812271;13894.166350019006;13897.444102235899;738.8780851159552;748.8165399477108;917.2295271971543;929.0894922975488;948.6347756113877;908.2869947091534;934.9439033658002;936.7564433247337;936.9718908432724;937.723371605378;950.6102497975832;985.3841349156777;985.4038306824375;986.6053578568558;986.9654562090328;763.2291385700455;949.586929685589;964.6141543382703;969.9272554558436;974.546835721393;716.1334784910213;719.7768691705588;721.0338146883201;724.807925899637;735.1252010108008;14744.557663270012;14839.480618178311;14846.1834599097;14861.256937153206;14862.2568871556;12337.198082124723;12361.62915112548;12563.685907852285;12721.809172862415;12721.936390318082;10160.136388406994;10195.745106372326;10199.745006374826;10421.583136674524;10429.478526073666;3493.9126521837156;3497.440409534462;3508.08789714676;3555.982220088909;3754.455603937414;70084.6495767523;70840.29159708388;80469.59765201136;80693;80811.19188808095;9486;9582.377145485521;9808.852867206997;9946.403215807075;10079.445630490394;20997.845118518628;21150;21182.682259766116;21184;21228;7498.234831985896;7641.732539361095;7678.731244406419;7793;7888.053433587891;4188.643965262987;4236.216299984513;4240.5123410808155;4338.240807858612;4376.934345984812;3510.982445087758;3529.6646818552194;3564.483149943244;3569.303985722759;3569.7679650822615;579.9739011744482;655.1254075808754;656.0127008851648;658.5883822610868;668.044696084594;451.82378872239855;580.3471095018105;582.356496071843;602.7347966894553;604.0365616841132;7975.920240797634;8024.438289319692;8040.557769322626;8223.588820559067;8237.8352432951;535.8821059366933;579.5334755521845;590.8286596886941;613.2303958532013;616.4144063139945;3749.1939233065023;3766.6871432210514;3960.7227494075137;4083.2854250506043;4146.034661768198;89490;90969.54515227381;91092.54453727689;91966.54016729874;92332;73982.26017739861;74564.62717686377;75160;76188;76742;66708.33291667118;66944.6652766733;67709.32290677128;67940.3205967944;69515.26211844644;8233.50598964064;8304.83390332202;8331.583420829056;8402.747917562483;8417.831643366975;38200.04499887525;47381.28928066013;48126.278105829144;48489.5151048485;49086.75456622694;61291;61841.45396364947;65067.69864602776;66367;66708.6664566674;9444.858327125236;10862.728431789268;11082.556697732;11190.160737944849;11497.647959741716;11822.113341499595;12028.819567706667;12077.57728479499;12263.570775023189;12328.630141095782;8311.875321870297;10919.890801091888;11625.360605166798;11803.409829508319;12043.036557075588;11307.886921130683;11407.828882566933;11468.770624587294;11503.48234329474;11751.353675547585;10977.176711746491;11011.504482298476;11044.171687123659;11325.546978120783;11461.541538338373;9394.577244024173;9497.810043799223;9787.51062446889;9791.069848364394;10092.34399764013;7480.925190748023;9716.805663886542;9815.754606134904;9934.45360505179;9935.354201976848 02:22:16 INFO - PROCESS | 1947 | __end_tp_report 02:22:16 INFO - PROCESS | 1947 | __start_cc_report 02:22:16 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,2130 02:22:16 INFO - PROCESS | 1947 | __end_cc_report 02:22:16 INFO - PROCESS | 1947 | __startTimestamp1465118536762__endTimestamp 02:22:16 INFO - PROCESS | 1947 | 02:22:16 INFO - PROCESS | 1947 | ------- Summary: start ------- 02:22:16 INFO - PROCESS | 1947 | Number of tests: 6 02:22:16 INFO - PROCESS | 1947 | 02:22:16 INFO - PROCESS | 1947 | [#0] dojo.html Cycles:165 Average:5578.18 Median:5763.16 stddev:617.97 (10.7%) stddev-sans-first:589.48 02:22:16 INFO - PROCESS | 1947 | Values: 3138.6 3329.1 3359.1 3385.6 3397.5 5541.1 5541.4 5548.6 5549.1 5568.9 5546.6 5570.5 5583.2 5610.4 5639.0 5003.6 5066.6 5080.4 5117.4 5138.8 4804.9 5022.2 5102.8 5113.6 5119.1 4549.7 5012.8 5086.1 5093.5 5110.9 5088.9 5124.8 5134.9 5139.4 5170.4 4953.2 4988.4 5050.1 5060.1 5079.5 5342.7 5359.1 5378.9 5395.8 5410.6 6051.8 6053.4 6067.8 6087.5 6109.8 5945.9 6137.7 6156.2 6163.3 6166.9 5571.5 5629.6 5714.3 5736.3 5763.2 6130.0 6133.3 6137.9 6147.8 6152.6 5800.2 5806.0 5813.4 5820.1 5844.3 5975.3 6013.6 6074.1 6076.6 6086.3 4936.4 5911.4 5938.6 5945.9 5946.3 5961.8 6058.0 6116.4 6138.0 6141.2 4848.9 4854.6 4857.1 4870.9 4894.9 4378.7 4399.0 4403.9 4407.2 4433.0 5459.5 5564.6 5564.6 5657.6 5660.0 6149.5 6169.3 6172.7 6179.0 6186.2 5553.7 5615.9 5625.5 5628.8 5635.4 5191.0 5272.1 5277.4 5279.6 5283.4 6193.4 6282.5 6293.5 6317.6 6319.0 6119.9 6146.8 6157.0 6181.2 6184.5 5335.9 6096.7 6234.6 6281.1 6295.9 5867.6 5987.7 6048.2 6100.8 6109.0 5936.9 6058.3 6059.8 6070.7 6076.8 5773.8 5820.7 5836.1 5874.4 5877.9 5796.2 5807.5 5846.1 5846.5 5847.1 5653.3 5668.1 5686.2 5687.8 5689.4 4961.9 5827.7 5866.9 5895.8 5904.7 5794.3 5818.3 5839.3 5839.4 5843.2 02:22:16 INFO - PROCESS | 1947 | 02:22:16 INFO - PROCESS | 1947 | [#1] ext.html Cycles:180 Average:70342.47 Median:37942.84 stddev:152171.05 (401.1%) stddev-sans-first:152507.59 02:22:16 INFO - PROCESS | 1947 | Values: 504.8 517.3 522.8 535.3 547.5 30720.8 37125.3 37911.4 38116.4 38218.0 41261.8 41714.0 41892.4 41902.8 41911.8 8326.6 9297.1 9535.0 9537.0 9538.9 8399.7 11305.5 11517.6 11519.7 11523.5 11252.8 11354.3 11356.8 11358.9 11365.8 8431.2 8664.0 11234.9 11323.5 11363.1 8356.9 8471.8 11140.1 11402.5 11405.8 38201.0 38636.2 38729.2 38784.8 38803.0 3065.4 3209.3 3225.3 3292.8 3323.3 26899.6 27308.7 27412.0 27606.4 27768.9 2745.0 2750.5 2770.4 2860.3 2901.5 486032.0 486969.0 488623.0 488810.0 489001.0 50898.7 60961.7 62105.4 62157.0 62257.0 64608.7 64783.0 64795.7 64880.4 64927.0 15577.7 16880.9 17540.5 17545.7 17556.2 12349.0 12652.4 13401.3 13523.1 13611.7 15811.8 15891.3 15893.9 15912.4 15934.4 6035.5 7071.8 7113.9 7124.9 7220.4 831.2 942.4 997.2 999.4 1016.8 28944.7 29426.3 29533.3 30116.7 30387.2 1100.8 1128.5 1155.6 1164.3 1246.8 14810.3 15057.7 15185.4 15467.5 15552.8 617464.0 617596.0 624422.0 625039.0 625433.0 60320.8 60591.4 60599.7 60614.7 60721.8 1312.9 1375.3 1420.7 1536.8 1541.0 28127.0 30703.7 30770.7 30825.9 31463.2 57559.4 57977.7 58393.0 58421.4 58519.0 580700.0 592371.0 593364.0 593639.0 594490.0 36099.8 36648.9 37726.6 43729.6 43809.8 43316.7 43625.0 44924.2 44935.8 45699.9 37974.2 37990.0 38093.8 38280.9 39231.0 31250.7 37302.6 38086.4 38824.0 39073.6 31326.4 36859.5 38342.0 38694.0 38720.8 32001.5 32894.3 33210.0 33252.8 33469.3 24623.4 24635.5 25014.4 25205.5 25254.5 02:22:16 INFO - PROCESS | 1947 | 02:22:16 INFO - PROCESS | 1947 | [#2] jquery.html Cycles:180 Average:32194.66 Median:24968.13 stddev:86118.14 (344.9%) stddev-sans-first:86326.61 02:22:16 INFO - PROCESS | 1947 | Values: 386.5 398.7 401.6 402.2 405.5 13392.8 14889.5 19653.4 20254.0 20291.8 19700.5 20285.5 20320.5 20477.3 21030.5 5755.7 5899.2 6059.3 6062.4 6117.1 4191.4 5952.1 5973.2 6001.4 6236.4 6473.3 6481.4 6481.9 6482.4 6482.4 5702.8 6049.3 6058.2 6456.5 6466.0 5830.9 5836.5 5844.0 5852.1 5860.4 14162.4 23795.6 24946.9 24989.4 24989.8 1785.9 2079.3 2199.0 2220.7 2230.1 9736.9 10156.4 10197.6 13307.7 13775.8 1438.3 1746.4 1749.7 1749.8 1793.2 100301.0 116352.0 119712.8 119760.4 124812.0 11288.6 11441.1 11535.5 11557.4 11678.5 13155.9 13709.5 14603.0 14652.5 14656.7 6403.3 8049.6 8667.3 8715.3 8880.7 8869.4 8894.2 8895.9 8987.9 8993.2 4453.9 4523.4 4601.8 4603.3 4641.5 1917.9 1988.3 2023.1 2024.9 2039.6 895.2 954.1 992.2 1019.4 1023.4 16313.3 19683.9 19724.7 19944.3 20077.9 879.9 910.6 967.2 997.8 1001.4 8032.2 10433.6 10550.3 10555.3 10613.0 434936.0 439349.0 446022.0 553277.0 628336.0 129072.4 137272.0 144521.0 150472.0 154979.0 450.5 463.2 470.5 471.6 475.3 10340.9 10396.2 10609.4 11023.9 11120.8 52685.0 53393.7 53910.7 54285.4 56038.7 63659.7 76957.0 78795.8 81120.2 81962.0 8138.6 8164.7 8202.6 8342.5 8369.1 8953.4 11716.9 12263.8 12620.7 12636.4 10667.9 10940.3 10968.8 11132.3 11140.6 10493.5 10533.8 10753.8 10841.0 11017.6 10663.9 10899.4 10958.6 11363.9 11375.9 7923.1 8084.4 8133.2 8172.8 8206.7 8871.0 9370.4 10359.8 10450.6 10532.2 02:22:16 INFO - PROCESS | 1947 | 02:22:16 INFO - PROCESS | 1947 | [#3] mootools.html Cycles:180 Average:5597.74 Median:5101.71 stddev:3090.80 (60.6%) stddev-sans-first:3082.84 02:22:16 INFO - PROCESS | 1947 | Values: 1331.0 1557.8 1584.7 1842.7 1997.3 7376.4 8031.9 8473.4 8656.9 8686.5 7413.3 8517.6 8578.4 8683.7 8707.9 2418.8 2443.8 2445.4 2448.7 2449.1 2073.2 2405.8 2461.5 2466.4 2469.0 2065.5 2429.0 2458.7 2463.8 2469.0 2412.2 2451.9 2461.4 2462.7 2464.6 2029.9 2439.3 2458.6 2458.7 2463.0 8375.1 8733.5 8773.7 8792.3 8799.8 5311.8 5432.0 5462.8 5466.9 5474.6 3314.3 3388.5 3402.5 3444.9 3449.0 1044.7 1047.5 1081.0 1611.4 1657.3 11600.7 11692.6 11710.9 11721.4 11733.2 4488.5 4575.9 4651.5 4660.6 4692.7 9139.1 9152.5 9176.4 9189.0 9191.1 5292.9 5328.6 5337.8 5366.7 5371.5 4279.6 4439.8 4448.0 4458.4 4988.6 3774.2 3794.4 3887.4 3891.2 3893.4 1177.6 1188.6 1192.9 1200.4 1206.8 853.2 853.4 872.9 913.5 954.4 5758.3 5785.2 5885.3 5896.6 6158.8 916.1 970.9 977.3 991.7 1025.3 3313.3 3846.4 3853.7 3921.4 3999.6 10251.8 10557.9 10761.0 10847.3 10867.8 10646.5 10718.7 10724.6 10847.1 10932.6 7973.5 8200.5 8255.5 8266.5 8454.5 5732.8 5761.9 5831.4 5892.9 5975.4 9052.9 9360.6 9378.7 9379.2 9390.0 9427.2 9448.4 9455.0 9479.8 9506.8 6635.8 6837.4 7162.7 7512.9 7528.6 8075.8 8155.5 8253.7 8279.3 8288.5 7200.8 7301.2 7304.6 7409.9 7440.1 7148.7 7162.2 7256.9 7278.9 7284.1 5069.8 6426.3 6904.0 6905.0 6925.7 4749.3 4822.1 4822.6 4941.9 4976.6 4898.4 4936.7 4960.9 5009.1 5133.6 02:22:16 INFO - PROCESS | 1947 | 02:22:16 INFO - PROCESS | 1947 | [#4] prototype.html Cycles:180 Average:5738.98 Median:6582.37 stddev:1837.10 (27.9%) stddev-sans-first:1796.70 02:22:16 INFO - PROCESS | 1947 | Values: 322.4 328.3 330.7 335.0 339.1 6590.9 6605.0 6612.2 6623.6 6625.9 6161.6 6574.9 6618.1 6618.8 6630.7 4198.6 4761.9 4786.6 4843.1 4880.4 3856.9 4868.2 4882.8 4886.0 4895.5 4839.5 4846.2 4849.1 4850.5 4854.0 4616.4 4700.0 4789.1 4825.1 4840.3 4138.2 4145.3 4150.5 4151.3 4154.8 6156.8 6173.0 6174.2 6187.8 6193.9 5659.9 5673.1 5682.2 5688.2 5767.0 6750.6 6990.8 6995.4 7011.0 7015.9 4743.4 4760.5 4762.9 4763.2 4763.7 7341.2 7409.1 7417.1 7430.7 7432.2 3998.3 4822.2 4823.3 4846.0 4851.3 4899.1 4909.5 4918.9 4929.0 4935.5 5779.7 5798.8 5814.4 5833.6 5837.9 5721.0 6786.7 7067.0 7079.2 7107.4 1602.7 1642.0 1643.3 1643.8 1644.9 960.7 974.8 1025.0 1029.5 1053.2 5214.3 5432.7 6466.8 6476.7 6482.6 5967.2 7149.7 7285.5 7305.1 7317.3 6646.0 6649.0 6656.7 6676.3 6683.8 3831.7 3872.6 3874.0 3877.1 3879.9 7360.3 7630.1 7640.6 7649.3 7666.3 7531.4 7555.1 7558.9 7562.6 7567.7 7630.2 7664.9 7667.0 7668.2 7675.4 6996.5 7321.9 7334.3 7335.3 7339.2 7156.4 7191.5 7195.6 7212.2 7221.0 7590.3 7595.7 7599.8 7603.1 7622.2 3857.3 4663.9 4669.3 4670.2 4673.0 7039.0 7289.3 7293.2 7298.3 7304.3 7033.7 7043.4 7067.9 7069.9 7073.6 6995.4 7046.8 7059.4 7062.9 7064.3 5624.0 6173.8 6426.0 6887.0 6908.4 6812.7 7052.2 7058.6 7060.4 7074.3 5604.0 6589.8 6803.6 6808.2 6919.9 02:22:16 INFO - PROCESS | 1947 | 02:22:16 INFO - PROCESS | 1947 | [#5] yui.html Cycles:180 Average:17771.48 Median:614.82 stddev:24728.02 (4022.0%) stddev-sans-first:24764.09 02:22:16 INFO - PROCESS | 1947 | Values: 682.4 736.2 742.5 749.6 793.3 12281.0 14529.9 14913.4 15032.6 15065.7 11765.4 13841.0 13878.8 13894.2 13897.4 738.9 748.8 917.2 929.1 948.6 908.3 934.9 936.8 937.0 937.7 950.6 985.4 985.4 986.6 987.0 763.2 949.6 964.6 969.9 974.5 716.1 719.8 721.0 724.8 735.1 14744.6 14839.5 14846.2 14861.3 14862.3 12337.2 12361.6 12563.7 12721.8 12721.9 10160.1 10195.7 10199.7 10421.6 10429.5 3493.9 3497.4 3508.1 3556.0 3754.5 70084.6 70840.3 80469.6 80693.0 80811.2 9486.0 9582.4 9808.9 9946.4 10079.4 20997.8 21150.0 21182.7 21184.0 21228.0 7498.2 7641.7 7678.7 7793.0 7888.1 4188.6 4236.2 4240.5 4338.2 4376.9 3511.0 3529.7 3564.5 3569.3 3569.8 580.0 655.1 656.0 658.6 668.0 451.8 580.3 582.4 602.7 604.0 7975.9 8024.4 8040.6 8223.6 8237.8 535.9 579.5 590.8 613.2 616.4 3749.2 3766.7 3960.7 4083.3 4146.0 89490.0 90969.5 91092.5 91966.5 92332.0 73982.3 74564.6 75160.0 76188.0 76742.0 66708.3 66944.7 67709.3 67940.3 69515.3 8233.5 8304.8 8331.6 8402.7 8417.8 38200.0 47381.3 48126.3 48489.5 49086.8 61291.0 61841.5 65067.7 66367.0 66708.7 9444.9 10862.7 11082.6 11190.2 11497.6 11822.1 12028.8 12077.6 12263.6 12328.6 8311.9 10919.9 11625.4 11803.4 12043.0 11307.9 11407.8 11468.8 11503.5 11751.4 10977.2 11011.5 11044.2 11325.5 11461.5 9394.6 9497.8 9787.5 9791.1 10092.3 7480.9 9716.8 9815.8 9934.5 9935.4 02:22:16 INFO - PROCESS | 1947 | -------- Summary: end -------- 02:22:16 INFO - PROCESS | 1947 | 02:22:17 INFO - TEST-INFO | 1947: exit 0 02:22:17 INFO - TEST-OK | dromaeo_css | took 1115937ms 02:22:17 INFO - TEST-START | kraken 02:22:17 INFO - operating with platform_type : mac_ 02:22:17 INFO - Initialising browser for kraken test... 02:22:17 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpou_q3T/profile http://localhost:52294/getInfo.html) 02:22:19 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 02:22:19 INFO - PROCESS | 1953 | colorDepth:24 02:22:19 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 02:22:19 INFO - PROCESS | 1953 | __metrics 02:22:19 INFO - PROCESS | 1953 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:22:19 ERROR - PROCESS | 1953 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:22:20 INFO - TEST-INFO | 1953: exit 0 02:22:20 INFO - Browser initialized. 02:22:20 INFO - Running cycle 1/1 for kraken test... 02:22:20 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpou_q3T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:22:32 INFO - PROCESS | 1955 | RSS: Main: 211259392 02:22:32 INFO - PROCESS | 1955 | 02:22:35 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52294/tests/kraken/driver.html?testName=audio-beat-detection) 02:22:36 INFO - PROCESS | 1955 | RSS: Main: 274870272 02:22:36 INFO - PROCESS | 1955 | 02:22:38 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52294/tests/kraken/driver.html?testName=audio-dft) 02:22:39 INFO - PROCESS | 1955 | RSS: Main: 288309248 02:22:39 INFO - PROCESS | 1955 | 02:22:42 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52294/tests/kraken/driver.html?testName=audio-fft) 02:22:43 INFO - PROCESS | 1955 | RSS: Main: 375332864 02:22:43 INFO - PROCESS | 1955 | 02:22:45 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52294/tests/kraken/driver.html?testName=audio-oscillator) 02:22:45 INFO - PROCESS | 1955 | RSS: Main: 279924736 02:22:45 INFO - PROCESS | 1955 | 02:22:47 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52294/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:22:48 INFO - PROCESS | 1955 | RSS: Main: 280276992 02:22:48 INFO - PROCESS | 1955 | 02:22:51 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52294/tests/kraken/driver.html?testName=imaging-darkroom) 02:22:52 INFO - PROCESS | 1955 | RSS: Main: 318869504 02:22:52 INFO - PROCESS | 1955 | 02:22:54 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52294/tests/kraken/driver.html?testName=imaging-desaturate) 02:22:55 INFO - PROCESS | 1955 | RSS: Main: 318697472 02:22:55 INFO - PROCESS | 1955 | 02:22:58 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52294/tests/kraken/driver.html?testName=json-parse-financial) 02:22:58 INFO - PROCESS | 1955 | RSS: Main: 318717952 02:22:58 INFO - PROCESS | 1955 | 02:23:00 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52294/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:23:01 INFO - PROCESS | 1955 | RSS: Main: 309637120 02:23:01 INFO - PROCESS | 1955 | 02:23:03 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52294/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:23:03 INFO - PROCESS | 1955 | RSS: Main: 316387328 02:23:03 INFO - PROCESS | 1955 | 02:23:05 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52294/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:23:06 INFO - PROCESS | 1955 | RSS: Main: 318828544 02:23:06 INFO - PROCESS | 1955 | 02:23:09 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52294/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:23:10 INFO - PROCESS | 1955 | RSS: Main: 324161536 02:23:10 INFO - PROCESS | 1955 | 02:23:12 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52294/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:23:13 INFO - PROCESS | 1955 | RSS: Main: 322052096 02:23:13 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52294/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52294/tests/kraken/driver.html?testName=ai-astar) 02:23:15 INFO - PROCESS | 1955 | RSS: Main: 386478080 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | __start_tp_report 02:23:15 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 02:23:15 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 02:23:15 INFO - PROCESS | 1955 | |i|pagename|runs| 02:23:15 INFO - PROCESS | 1955 | |0;ai-astar;114;116;116;119;164;163;115;114;113;133 02:23:15 INFO - PROCESS | 1955 | |1;audio-beat-detection;155;122;146;196;152;137;118;149;158;119 02:23:15 INFO - PROCESS | 1955 | |2;audio-dft;185;187;183;186;182;186;185;184;186;187 02:23:15 INFO - PROCESS | 1955 | |3;audio-fft;70;79;74;154;56;55;59;63;136;57 02:23:15 INFO - PROCESS | 1955 | |4;audio-oscillator;95;90;140;78;79;85;124;79;77;153 02:23:15 INFO - PROCESS | 1955 | |5;imaging-gaussian-blur;117;130;89;85;87;83;87;85;88;84 02:23:15 INFO - PROCESS | 1955 | |6;imaging-darkroom;94;94;93;94;93;93;93;94;96;93 02:23:15 INFO - PROCESS | 1955 | |7;imaging-desaturate;91;88;92;90;90;87;91;90;89;84 02:23:15 INFO - PROCESS | 1955 | |8;json-parse-financial;60;65;64;65;65;64;65;82;65;60 02:23:15 INFO - PROCESS | 1955 | |9;json-stringify-tinderbox;63;67;68;79;70;70;57;66;92;101 02:23:15 INFO - PROCESS | 1955 | |10;stanford-crypto-aes;81;80;81;81;79;94;105;80;81;84 02:23:15 INFO - PROCESS | 1955 | |11;stanford-crypto-ccm;127;130;130;143;149;126;126;124;130;155 02:23:15 INFO - PROCESS | 1955 | |12;stanford-crypto-pbkdf2;208;219;180;166;166;170;173;164;173;170 02:23:15 INFO - PROCESS | 1955 | |13;stanford-crypto-sha256-iterative;68;67;69;67;68;67;67;70;67;69 02:23:15 INFO - PROCESS | 1955 | __end_tp_report 02:23:15 INFO - PROCESS | 1955 | __start_cc_report 02:23:15 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,2037 02:23:15 INFO - PROCESS | 1955 | __end_cc_report 02:23:15 INFO - PROCESS | 1955 | __startTimestamp1465118595545__endTimestamp 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | ------- Summary: start ------- 02:23:15 INFO - PROCESS | 1955 | Number of tests: 14 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#0] ai-astar Cycles:10 Average:126.70 Median:117.50 stddev:20.23 (17.2%) stddev-sans-first:20.93 02:23:15 INFO - PROCESS | 1955 | Values: 114.0 116.0 116.0 119.0 164.0 163.0 115.0 114.0 113.0 133.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:150.50 stddev:23.41 (15.6%) stddev-sans-first:24.56 02:23:15 INFO - PROCESS | 1955 | Values: 155.0 122.0 146.0 196.0 152.0 137.0 118.0 149.0 158.0 119.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#2] audio-dft Cycles:10 Average:185.10 Median:186.00 stddev:1.66 (0.9%) stddev-sans-first:1.76 02:23:15 INFO - PROCESS | 1955 | Values: 185.0 187.0 183.0 186.0 182.0 186.0 185.0 184.0 186.0 187.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#3] audio-fft Cycles:10 Average:80.30 Median:61.00 stddev:35.29 (57.9%) stddev-sans-first:37.23 02:23:15 INFO - PROCESS | 1955 | Values: 70.0 79.0 74.0 154.0 56.0 55.0 59.0 63.0 136.0 57.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#4] audio-oscillator Cycles:10 Average:100.00 Median:79.00 stddev:28.34 (35.9%) stddev-sans-first:30.00 02:23:15 INFO - PROCESS | 1955 | Values: 95.0 90.0 140.0 78.0 79.0 85.0 124.0 79.0 77.0 153.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#5] imaging-gaussian-blur Cycles:10 Average:93.50 Median:86.00 stddev:16.21 (18.8%) stddev-sans-first:14.79 02:23:15 INFO - PROCESS | 1955 | Values: 117.0 130.0 89.0 85.0 87.0 83.0 87.0 85.0 88.0 84.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.95 (1.0%) stddev-sans-first:1.00 02:23:15 INFO - PROCESS | 1955 | Values: 94.0 94.0 93.0 94.0 93.0 93.0 93.0 94.0 96.0 93.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#7] imaging-desaturate Cycles:10 Average:89.20 Median:90.00 stddev:2.35 (2.6%) stddev-sans-first:2.40 02:23:15 INFO - PROCESS | 1955 | Values: 91.0 88.0 92.0 90.0 90.0 87.0 91.0 90.0 89.0 84.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:65.00 stddev:6.13 (9.4%) stddev-sans-first:6.17 02:23:15 INFO - PROCESS | 1955 | Values: 60.0 65.0 64.0 65.0 65.0 64.0 65.0 82.0 65.0 60.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:69.00 stddev:13.60 (19.7%) stddev-sans-first:13.90 02:23:15 INFO - PROCESS | 1955 | Values: 63.0 67.0 68.0 79.0 70.0 70.0 57.0 66.0 92.0 101.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:81.00 stddev:8.37 (10.3%) stddev-sans-first:8.77 02:23:15 INFO - PROCESS | 1955 | Values: 81.0 80.0 81.0 81.0 79.0 94.0 105.0 80.0 81.0 84.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#11] stanford-crypto-ccm Cycles:10 Average:134.00 Median:130.00 stddev:10.91 (8.4%) stddev-sans-first:11.28 02:23:15 INFO - PROCESS | 1955 | Values: 127.0 130.0 130.0 143.0 149.0 126.0 126.0 124.0 130.0 155.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:173.00 stddev:18.97 (11.0%) stddev-sans-first:16.95 02:23:15 INFO - PROCESS | 1955 | Values: 208.0 219.0 180.0 166.0 166.0 170.0 173.0 164.0 173.0 170.0 02:23:15 INFO - PROCESS | 1955 | 02:23:15 INFO - PROCESS | 1955 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 02:23:15 INFO - PROCESS | 1955 | Values: 68.0 67.0 69.0 67.0 68.0 67.0 67.0 70.0 67.0 69.0 02:23:15 INFO - PROCESS | 1955 | -------- Summary: end -------- 02:23:15 INFO - PROCESS | 1955 | 02:23:16 INFO - TEST-INFO | 1955: exit 0 02:23:16 INFO - TEST-OK | kraken | took 58965ms 02:23:16 INFO - SUITE-END | took 1174s 02:23:16 INFO - Completed test suite (00:19:35) 02:23:16 INFO - javascript benchmark 02:23:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3138.6390565085017, 3329.1011426914733, 3359.12662707696, 3385.6275809660947, 3397.473391624299, 5541.085720856054, 5541.390447050826, 5548.583856210786, 5549.056660367747, 5568.916466253005, 5546.556275497961, 5570.470805273492, 5583.199797587927, 5610.438956104387, 5639.0, 5003.624728145385, 5066.594672426216, 5080.390353157626, 5117.437081920985, 5138.768755405997, 4804.942912559231, 5022.17134172861, 5102.770375333119, 5113.565346945496, 5119.078565858144, 4549.658775591827, 5012.8496145115705, 5086.084504789136, 5093.490650934914, 5110.872228194287, 5088.872778180538, 5124.820631277925, 5134.871628209287, 5139.434662187156, 5170.431252562253, 4953.182724850395, 4988.401391833003, 5050.116229659795, 5060.139776238048, 5079.517445842639, 5342.652727572696, 5359.1425371940695, 5378.865528361822, 5395.757190926418, 5410.594205434608, 6051.757929682807, 6053.424924632197, 6067.848303792396, 6087.4825639820665, 6109.847253818646, 5945.910811337833, 6137.693115344215, 6156.230471191101, 6163.291221509539, 6166.8766624667705, 5571.498565129158, 5629.605927585111, 5714.285714285715, 5736.340320863154, 5763.164341170507, 6130.049842274455, 6133.26400831903, 6137.877242455126, 6147.846303842351, 6152.600080994811, 5800.24596802413, 5806.0, 5813.389594092644, 5820.068788993741, 5844.298684157843, 5975.342712301644, 6013.609115407485, 6074.088886666947, 6076.574639775174, 6086.330503644595, 4936.432310284292, 5911.379305172981, 5938.61399009063, 5945.851353716105, 5946.286445626466, 5961.761529538857, 6057.97014507526, 6116.3577824328695, 6138.020573254751, 6141.2098563120835, 4848.903021939542, 4854.611631069506, 4857.101436234238, 4870.853874383774, 4894.853154405374, 4378.671599630041, 4399.032212913154, 4403.933940990888, 4407.228734971432, 4433.0, 5459.454054594588, 5564.554835613141, 5564.582656300713, 5657.60396772222, 5659.971700141473, 6149.538784591174, 6169.321374648785, 6172.660503672341, 6178.980468222783, 6186.19579454668, 5553.72231388437, 5615.9157612635845, 5625.46558076982, 5628.831135065954, 5635.408282130317, 5191.0, 5272.103742363807, 5277.445868183785, 5279.604029697711, 5283.365996080495, 6193.411625895533, 6282.528810339151, 6293.527985401021, 6317.5893566918, 6319.0, 6119.908201376894, 6146.78486252979, 6157.014877619738, 6181.227346581677, 6184.474319682878, 5335.932992459767, 6096.695165241631, 6234.594751341148, 6281.089242059968, 6295.905561416491, 5867.589268751147, 5987.730552125077, 6048.243969503812, 6100.755969761338, 6109.0, 5936.910946335722, 6058.333583305921, 6059.757609695563, 6070.666113363807, 6076.817695469143, 5773.769049237983, 5820.650760954356, 5836.066229403275, 5874.412558744091, 5877.853053673522, 5796.246487956539, 5807.477327040587, 5846.093855452412, 5846.473817356288, 5847.093700476433, 5653.265075540154, 5668.149777533403, 5686.175504551899, 5687.77248910039, 5689.431056894277, 4961.900761984668, 5827.679477628772, 5866.882662346815, 5895.764169433346, 5904.704764761831, 5794.333651630117, 5818.330891947311, 5839.328477225174, 5839.386864379179, 5843.2403787507355], "value": 5539.872443944689, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.7930348557091, 517.2990597740061, 522.7621432248327, 535.296085647374, 547.5072434808674, 30720.846395768018, 37125.25749485012, 37911.43132853006, 38116.42825357622, 38218.04454888629, 41261.793691031504, 41714.0, 41892.37161442581, 41902.790486047605, 41911.79044104783, 8326.6253018614, 9297.116773906502, 9535.0, 9536.998615145414, 9538.856917146248, 8399.706010289641, 11305.547778088867, 11517.596884109058, 11519.65441036766, 11523.48144333507, 11252.831207531894, 11354.261972971772, 11356.829647555292, 11358.886411135907, 11365.829512557319, 8431.241188293021, 8664.046954834963, 11234.887651123507, 11323.547058117665, 11363.090952723804, 8356.874646880302, 8471.788205294855, 11140.108791296758, 11402.543898244061, 11405.828912566318, 38201.0, 38636.227275454614, 38729.22541549181, 38784.80607596972, 38803.02992425184, 3065.390002342672, 3209.293955329824, 3225.3388055448577, 3292.8353582320788, 3323.3187196624876, 26899.596506052425, 27308.726912731017, 27412.0, 27606.447871042466, 27768.861155694296, 2744.972550274492, 2750.5049091163582, 2770.432061427423, 2860.256333353322, 2901.4922388582127, 486032.0, 486969.0, 488623.0, 488810.0, 489001.0, 50898.74550627223, 60961.69519152376, 62105.37894621086, 62157.0, 62257.0, 64608.676956614916, 64783.02825457622, 64795.67602161959, 64880.351196487434, 64927.0, 15577.68844623124, 16880.915595421946, 17540.47378578619, 17545.736813947802, 17556.2099705511, 12349.0, 12652.43064062101, 13401.329933503286, 13523.120997135155, 13611.659708507366, 15811.841881581267, 15891.28489217988, 15893.920530397274, 15912.443064492687, 15934.442294519637, 6035.486983606443, 7071.7878463646175, 7113.89329160063, 7124.893126603105, 7220.350168484756, 831.2477208126655, 942.4486675295029, 997.1823105053786, 999.4253304350028, 1016.8068067067381, 28944.710552894623, 29426.264343391587, 29533.26166845846, 30116.69883301183, 30387.2403189922, 1100.7743412600398, 1128.5090985421368, 1155.5608868629868, 1164.2665120973902, 1246.8342100136401, 14810.259487025822, 15057.698846023019, 15185.392584296504, 15467.5359739208, 15552.844471555365, 617464.0, 617596.0, 624422.0, 625039.0, 625433.0, 60320.79358412807, 60591.39408605857, 60599.69700151559, 60614.696926515964, 60721.78556428847, 1312.9365214176548, 1375.2985977151725, 1420.6590418299327, 1536.7541193409002, 1541.0368519675203, 28127.0, 30703.69296307098, 30770.692293076783, 30825.921092761644, 31463.21341966469, 57559.42440575541, 57977.710111449174, 58393.0, 58421.4157858416, 58519.0, 580700.0, 592371.0, 593364.0, 593639.0, 594490.0, 36099.819500902326, 36648.90053298405, 37726.62273377231, 43729.56270437255, 43809.78095109504, 43316.70049898508, 43625.0, 44924.17661512522, 44935.77532112318, 45699.85750356135, 37974.2405151901, 37990.0, 38093.80953095217, 38280.85157445281, 39231.0, 31250.687493124777, 37302.626973731, 38086.428703570025, 38824.029399265244, 39073.609263907, 31326.373472529966, 36859.52561897601, 38342.0, 38694.0, 38720.80639596784, 32001.519977199896, 32894.34211315808, 33210.0, 33252.836150734605, 33469.33061338808, 24623.38441538976, 24635.507289853744, 25014.37464063413, 25205.49589008173, 25254.494910101326], "value": 20147.16972150465, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.5168539325843, 398.7149188330345, 401.5943896664369, 402.24177425552836, 405.49313358302123, 13392.799108013363, 14889.478868239614, 19653.410397688058, 20253.987300634966, 20291.78249305043, 19700.507487312825, 20285.492862678442, 20320.491987700316, 20477.28329508464, 21030.474238144056, 5755.654660720349, 5899.2036075129845, 6059.303180134297, 6062.363451837559, 6117.113018612298, 4191.392248124021, 5952.077427998667, 5973.193618861442, 6001.369856165105, 6236.438720515152, 6473.28793832678, 6481.416672499474, 6481.902771458432, 6482.351764823527, 6482.416582507573, 5702.800401985911, 6049.304330002062, 6058.242719660043, 6456.515761317896, 6466.0, 5830.912536311958, 5836.474717275466, 5843.9707801461145, 5852.092925596539, 5860.443257890493, 14162.36269367881, 23795.643065354034, 24946.875265623738, 24989.375265618324, 24989.750102498925, 1785.9196336164773, 2079.3242196286114, 2199.0104452996056, 2220.6668999650183, 2230.141395562704, 9736.853947190797, 10156.441395723232, 10197.643082492152, 13307.667308317272, 13775.793363099561, 1438.3239877257777, 1746.3974928649595, 1749.7462867884085, 1749.7550342951997, 1793.2049064630978, 100300.99699002989, 116352.0, 119712.80287197104, 119760.40119799346, 124812.00751969911, 11288.604898828502, 11441.084713222923, 11535.538578456768, 11557.364344960939, 11678.532858685727, 13155.934220329029, 13709.520166794115, 14602.977791554702, 14652.487162949246, 14656.706865862623, 6403.263624683221, 8049.55727434985, 8667.263282621048, 8715.302775777922, 8880.689175878942, 8869.423487473294, 8894.243989260985, 8895.866562001574, 8987.910120898838, 8993.23557497607, 4453.908792345896, 4523.434570678665, 4601.7699115044115, 4603.309503574491, 4641.489436162019, 1917.8807417803405, 1988.2842176816334, 2023.1199428248465, 2024.8738824234495, 2039.643062464063, 895.1943251073956, 954.0650162840456, 992.1963209800085, 1019.3852767650951, 1023.370627064365, 16313.265903034391, 19683.901580492005, 19724.70412943807, 19944.3019494317, 20077.899610501852, 879.9045188740059, 910.5720311453606, 967.2358836519212, 997.7705127820642, 1001.3941565353002, 8032.236937490929, 10433.634822781167, 10550.314229575053, 10555.261131720707, 10613.0, 434936.0, 439349.0, 446022.0, 553277.0, 628336.0, 129072.35463822621, 137272.0, 144521.0, 150472.0, 154979.0, 450.5246964452534, 463.170924045954, 470.4660210660856, 471.5850051954258, 475.265714471136, 10340.948295258777, 10396.168306535308, 10609.416482093558, 11023.888071863104, 11120.830563289424, 52685.0, 53393.733031334596, 53910.65223369447, 54285.35725889023, 56038.71980640071, 63659.681701591195, 76957.0, 78795.81806272797, 81120.18879811362, 81962.0, 8138.633761480866, 8164.673413063411, 8202.58987050657, 8342.541160236246, 8369.079401265977, 8953.373263871466, 11716.882831171579, 12263.754724905273, 12620.684482887635, 12636.368181591068, 10667.946660266649, 10940.288881222696, 10968.780624387628, 11132.332060076422, 11140.610078647209, 10493.527791249564, 10533.789324213627, 10753.784924301313, 10841.0, 11017.614383496539, 10663.89336106629, 10899.400532970763, 10958.56165753361, 11363.943180284046, 11375.886241137483, 7923.088844782924, 8084.434089613906, 8133.186681331819, 8172.83654326922, 8206.671733130846, 8871.0, 9370.437773733598, 10359.792804143724, 10450.634227802295, 10532.157427405637], "value": 9110.490928146719, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.9618497571898, 1557.8364271751466, 1584.690985257875, 1842.7328037434572, 1997.2909617085943, 7376.446766492514, 8031.91968080319, 8473.44922580032, 8656.913430865705, 8686.522241276727, 7413.295736904999, 8517.574121293927, 8578.356623253265, 8683.696070637528, 8707.91292087079, 2418.8306818522756, 2443.8289319747623, 2445.3519817248443, 2448.681671382718, 2449.081594402099, 2073.232903825579, 2405.7594240575977, 2461.520003599299, 2466.432720474283, 2469.0, 2065.452655046414, 2428.987855060731, 2458.6803715516958, 2463.7905778008803, 2468.9753102469017, 2412.2160297903074, 2451.901923923041, 2461.4092617771785, 2462.741412151725, 2464.642626819119, 2029.9289524866558, 2439.316991242455, 2458.606622940321, 2458.7172475165407, 2462.987685061581, 8375.078741338448, 8733.475991440471, 8773.692920747742, 8792.252658523968, 8799.824003519958, 5311.840644780663, 5431.967926094029, 5462.8361149165585, 5466.945330546683, 5474.616776825624, 3314.254292784128, 3388.525606415104, 3402.5236466894653, 3444.8794292199655, 3448.9655103448895, 1044.7283706236433, 1047.4919663962971, 1081.000074930686, 1611.3715650896042, 1657.2542355939875, 11600.651980440516, 11692.590759323468, 11710.882891171064, 11721.355325457096, 11733.237339572901, 4488.528704486047, 4575.908481830411, 4651.534846515321, 4660.627149828006, 4692.671512994126, 9139.131782480654, 9152.496612686366, 9176.44941303524, 9188.95405522968, 9191.080891910755, 5292.894142117136, 5328.600354973392, 5337.839864804062, 5366.651167674088, 5371.543418809367, 4279.5934386233885, 4439.844605438794, 4448.043670610834, 4458.420405347284, 4988.551030407209, 3774.150816066363, 3794.4498047782918, 3887.3974533947285, 3891.1828516011387, 3893.377059670439, 1177.5643012085584, 1188.6137005473256, 1192.9224600400946, 1200.4117982188839, 1206.7646808872187, 853.1724227499316, 853.3727710133047, 872.9388942773945, 913.4747520175792, 954.3605784124653, 5758.251427314506, 5785.247917770663, 5885.264341957255, 5896.64620122794, 6158.846028849225, 916.1251005289932, 970.8640790289365, 977.3402953006693, 991.7272749993809, 1025.333533203409, 3313.3042061167425, 3846.442265871433, 3853.672437842759, 3921.431392448136, 3999.5800440953863, 10251.794964100825, 10557.894421055844, 10760.97770711781, 10847.294925829796, 10867.78264434707, 10646.467676616137, 10718.73203169927, 10724.624638137628, 10847.077998370069, 10932.56269749217, 7973.481723687942, 8200.466969646954, 8255.504669719836, 8266.462679925786, 8454.535000575028, 5732.799352022659, 5761.884762304814, 5831.35855055935, 5892.882142357214, 5975.40245975399, 9052.864207036768, 9360.578773955325, 9378.67174648884, 9379.202767764817, 9390.0, 9427.15155635996, 9448.433094014381, 9455.0, 9479.810403792024, 9506.762330941781, 6635.767748128792, 6837.35045170708, 7162.677679504539, 7512.887306690294, 7528.623568821427, 8075.798105047421, 8155.510669359857, 8253.669853205805, 8279.296259817742, 8288.50268983863, 7200.783976480714, 7301.233370496021, 7304.561726296227, 7409.925900740924, 7440.107187137578, 7148.749793757193, 7162.247963963917, 7256.9274307256255, 7278.871774874903, 7284.125904891447, 5069.822556210515, 6426.325235850355, 6904.0, 6905.0, 6925.68834402443, 4749.311349854321, 4822.132016237111, 4822.565969062801, 4941.925871111865, 4976.576990955809, 4898.387701537308, 4936.679115857458, 4960.925586116139, 5009.0983622948215, 5133.589312854888], "value": 4547.525305359167, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.4341281051754, 328.30235749033307, 330.7205411427345, 335.0167259094556, 339.11152779717116, 6590.934090659092, 6605.0, 6612.23959244687, 6623.635700036502, 6625.933740662592, 6161.630302181872, 6574.934250657492, 6618.139641846566, 6618.801435956917, 6630.701618427171, 4198.6011328923705, 4761.857144285677, 4786.617070634358, 4843.055604157191, 4880.414350277972, 3856.8842934712, 4868.196747536652, 4882.755862206894, 4886.0, 4895.510448955094, 4839.4918533554, 4846.151923413407, 4849.127157111719, 4850.539198776129, 4854.0, 4616.376789133475, 4700.01299727056, 4789.137955168068, 4825.131476334259, 4840.346553215306, 4138.234426631053, 4145.2952997990415, 4150.522689890671, 4151.27352713277, 4154.833806647761, 6156.7537298508005, 6173.0, 6174.166487524217, 6187.84530386744, 6193.907091393633, 5659.8585035374035, 5673.092305231144, 5682.232898558725, 5688.232088668018, 5766.961946849566, 6750.62871542065, 6990.825229369256, 6995.440364770806, 7010.964945175292, 7015.9298407015785, 4743.383360163191, 4760.452547956995, 4762.857114286577, 4763.237881938908, 4763.737994410306, 7341.1557670868, 7409.110906691178, 7417.072865891763, 7430.665620047111, 7432.219616940251, 3998.280309544252, 4822.156122678517, 4823.287732854857, 4845.951540484621, 4851.302044284472, 4899.1426500362295, 4909.459959404463, 4918.852434426973, 4929.0388374267395, 4935.506449355107, 5779.653220806765, 5798.797042103506, 5814.418558144152, 5833.649981001068, 5837.9124313135335, 5721.0, 6786.694598743068, 7067.0, 7079.150501939697, 7107.431405487549, 1602.6634406774474, 1642.031201591043, 1643.3098098798532, 1643.7780899578643, 1644.8519633233066, 960.706984369774, 974.848898420746, 1024.9641262555774, 1029.4955471818905, 1053.2416660004785, 5214.348206474191, 5432.701201433959, 6466.773662921775, 6476.708548115252, 6482.57863238888, 5967.224260846062, 7149.678264478111, 7285.526440781438, 7305.086864141982, 7317.304856038774, 6646.0, 6648.966755166193, 6656.667166641649, 6676.26561078281, 6683.799486015428, 3831.6934645228316, 3872.6320999505, 3874.0121269076767, 3877.1470276539117, 3879.9030024249055, 7360.300771426706, 7630.122535908331, 7640.579768112807, 7649.31156195945, 7666.348360389429, 7531.435142364345, 7555.055618047744, 7558.886616700754, 7562.584057876759, 7567.697292108364, 7630.245043639994, 7664.923350766532, 7667.0416197975255, 7668.156502784577, 7675.385969122457, 6996.510244282851, 7321.853562928818, 7334.3032411920785, 7335.339819416278, 7339.229380914927, 7156.3917067047805, 7191.460640452092, 7195.568265904062, 7212.170600381023, 7220.963895180491, 7590.316871481593, 7595.696172153273, 7599.772006839804, 7603.0876294844975, 7622.16156222804, 3857.305684976619, 4663.883402914954, 4669.276262179367, 4670.159371313196, 4672.976635116803, 7039.0, 7289.307515785992, 7293.161286452126, 7298.343149116605, 7304.342609165201, 7033.683484243324, 7043.436525077879, 7067.858642827012, 7069.893951590833, 7073.610951397723, 6995.440364770704, 7046.753363632249, 7059.435245180476, 7062.929370706227, 7064.258252883374, 5623.959567480029, 6173.81478555644, 6426.0, 6886.965565172142, 6908.412784913338, 6812.693428795816, 7052.224255332012, 7058.576485410892, 7060.399866011447, 7074.257202993817, 5604.0, 6589.80230593083, 6803.557768745016, 6808.217055038734, 6919.896201557081], "value": 5122.6818524812315, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.3722175598449, 736.1975446763028, 742.5025233093827, 749.6326799868062, 793.2979313307721, 12281.0, 14529.92735036326, 14913.403463861447, 15032.624184395396, 15065.6986860263, 11765.352905590189, 13841.0, 13878.79181812271, 13894.166350019006, 13897.444102235899, 738.8780851159552, 748.8165399477108, 917.2295271971543, 929.0894922975488, 948.6347756113877, 908.2869947091534, 934.9439033658002, 936.7564433247337, 936.9718908432724, 937.723371605378, 950.6102497975832, 985.3841349156777, 985.4038306824375, 986.6053578568558, 986.9654562090328, 763.2291385700455, 949.586929685589, 964.6141543382703, 969.9272554558436, 974.546835721393, 716.1334784910213, 719.7768691705588, 721.0338146883201, 724.807925899637, 735.1252010108008, 14744.557663270012, 14839.480618178311, 14846.1834599097, 14861.256937153206, 14862.2568871556, 12337.198082124723, 12361.62915112548, 12563.685907852285, 12721.809172862415, 12721.936390318082, 10160.136388406994, 10195.745106372326, 10199.745006374826, 10421.583136674524, 10429.478526073666, 3493.9126521837156, 3497.440409534462, 3508.08789714676, 3555.982220088909, 3754.455603937414, 70084.6495767523, 70840.29159708388, 80469.59765201136, 80693.0, 80811.19188808095, 9486.0, 9582.377145485521, 9808.852867206997, 9946.403215807075, 10079.445630490394, 20997.845118518628, 21150.0, 21182.682259766116, 21184.0, 21228.0, 7498.234831985896, 7641.732539361095, 7678.731244406419, 7793.0, 7888.053433587891, 4188.643965262987, 4236.216299984513, 4240.5123410808155, 4338.240807858612, 4376.934345984812, 3510.982445087758, 3529.6646818552194, 3564.483149943244, 3569.303985722759, 3569.7679650822615, 579.9739011744482, 655.1254075808754, 656.0127008851648, 658.5883822610868, 668.044696084594, 451.82378872239855, 580.3471095018105, 582.356496071843, 602.7347966894553, 604.0365616841132, 7975.920240797634, 8024.438289319692, 8040.557769322626, 8223.588820559067, 8237.8352432951, 535.8821059366933, 579.5334755521845, 590.8286596886941, 613.2303958532013, 616.4144063139945, 3749.1939233065023, 3766.6871432210514, 3960.7227494075137, 4083.2854250506043, 4146.034661768198, 89490.0, 90969.54515227381, 91092.54453727689, 91966.54016729874, 92332.0, 73982.26017739861, 74564.62717686377, 75160.0, 76188.0, 76742.0, 66708.33291667118, 66944.6652766733, 67709.32290677128, 67940.3205967944, 69515.26211844644, 8233.50598964064, 8304.83390332202, 8331.583420829056, 8402.747917562483, 8417.831643366975, 38200.04499887525, 47381.28928066013, 48126.278105829144, 48489.5151048485, 49086.75456622694, 61291.0, 61841.45396364947, 65067.69864602776, 66367.0, 66708.6664566674, 9444.858327125236, 10862.728431789268, 11082.556697732, 11190.160737944849, 11497.647959741716, 11822.113341499595, 12028.819567706667, 12077.57728479499, 12263.570775023189, 12328.630141095782, 8311.875321870297, 10919.890801091888, 11625.360605166798, 11803.409829508319, 12043.036557075588, 11307.886921130683, 11407.828882566933, 11468.770624587294, 11503.48234329474, 11751.353675547585, 10977.176711746491, 11011.504482298476, 11044.171687123659, 11325.546978120783, 11461.541538338373, 9394.577244024173, 9497.810043799223, 9787.51062446889, 9791.069848364394, 10092.34399764013, 7480.925190748023, 9716.805663886542, 9815.754606134904, 9934.45360505179, 9935.354201976848], "value": 6855.1955177596465, "unit": "score"}], "name": "dromaeo_css", "value": 7386.3701578221835, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 116.0, 119.0, 164.0, 163.0, 115.0, 114.0, 113.0, 133.0], "value": 126.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 122.0, 146.0, 196.0, 152.0, 137.0, 118.0, 149.0, 158.0, 119.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 183.0, 186.0, 182.0, 186.0, 185.0, 184.0, 186.0, 187.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 79.0, 74.0, 154.0, 56.0, 55.0, 59.0, 63.0, 136.0, 57.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 90.0, 140.0, 78.0, 79.0, 85.0, 124.0, 79.0, 77.0, 153.0], "value": 100.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 130.0, 89.0, 85.0, 87.0, 83.0, 87.0, 85.0, 88.0, 84.0], "value": 93.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 93.0, 93.0, 93.0, 94.0, 96.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 92.0, 90.0, 90.0, 87.0, 91.0, 90.0, 89.0, 84.0], "value": 89.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 65.0, 64.0, 65.0, 65.0, 64.0, 65.0, 82.0, 65.0, 60.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 68.0, 79.0, 70.0, 70.0, 57.0, 66.0, 92.0, 101.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 81.0, 81.0, 79.0, 94.0, 105.0, 80.0, 81.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 130.0, 130.0, 143.0, 149.0, 126.0, 126.0, 124.0, 130.0, 155.0], "value": 134.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 219.0, 180.0, 166.0, 166.0, 170.0, 173.0, 164.0, 173.0, 170.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 67.0, 68.0, 67.0, 67.0, 70.0, 67.0, 69.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1517.9, "alertThreshold": 2.0}]} 02:23:16 INFO - Return code: 0 02:23:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:23:16 ERROR - # TBPL SUCCESS # 02:23:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:23:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:23:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:23:16 INFO - 2016-06-05 02:23:16.844 screenresolution[1959:20149] starting screenresolution argv=screenresolution get 02:23:16 INFO - 2016-06-05 02:23:16.847 screenresolution[1959:20149] Display 0: 1600x1200x32@60 02:23:16 INFO - 2016-06-05 02:23:16.853 screenresolution[1960:20152] starting screenresolution argv=screenresolution list 02:23:16 INFO - Available Modes on Display 0 02:23:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:23:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:23:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:23:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:23:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:23:17 INFO - Intel Iris: 02:23:17 INFO - Chipset Model: Intel Iris 02:23:17 INFO - Type: GPU 02:23:17 INFO - Bus: Built-In 02:23:17 INFO - VRAM (Dynamic, Max): 1536 MB 02:23:17 INFO - Vendor: Intel (0x8086) 02:23:17 INFO - Device ID: 0x0a2e 02:23:17 INFO - Revision ID: 0x0009 02:23:17 INFO - Displays: 02:23:17 INFO - Display: 02:23:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:23:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:23:17 INFO - Display Serial Number: 1600x1200 60 02:23:17 INFO - Main Display: Yes 02:23:17 INFO - Mirror: Off 02:23:17 INFO - Online: Yes 02:23:17 INFO - Rotation: Supported 02:23:17 INFO - Return code: 0 02:23:17 INFO - Running post-action listener: _resource_record_post_action 02:23:17 INFO - Running post-run listener: _resource_record_post_run 02:23:18 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 786432; Write bytes: 654761472; Read time: 10; Write time: 1817 02:23:18 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 346814976; Write bytes: 346572288; Read time: 27606; Write time: 698 02:23:18 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 786432; Write bytes: 297392128; Read time: 10; Write time: 1087 02:23:18 INFO - Running post-run listener: _upload_blobber_files 02:23:18 INFO - Blob upload gear active. 02:23:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:23:18 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 02:23:18 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'] 02:23:18 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 02:23:18 INFO - (blobuploader) - INFO - Open directory for files ... 02:23:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:23:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:23:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:23:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:23:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:23:19 INFO - (blobuploader) - INFO - Done attempting. 02:23:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:23:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:23:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:23:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:23:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:23:20 INFO - (blobuploader) - INFO - Done attempting. 02:23:20 INFO - (blobuploader) - INFO - Iteration through files over. 02:23:20 INFO - Return code: 0 02:23:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:23:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:23:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbf028ebd07c3607f8ac71d84cf5ede089033510a953cb73a1ab804a37798610c6b12e4e4b15a40c6ba665dfdb4e506e7d498e9d52492ee61aaa43dd54a1fa01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e28622e11c81e3f6ff4933b65f6de8b0a7c874b493b23d7cc0371bb4f6c005e9aedeb8d65ddb6df05e9fcc88b57fb61130b0fba7cc700218064db66e660f8ed"} 02:23:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:23:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:23:20 INFO - Contents: 02:23:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbf028ebd07c3607f8ac71d84cf5ede089033510a953cb73a1ab804a37798610c6b12e4e4b15a40c6ba665dfdb4e506e7d498e9d52492ee61aaa43dd54a1fa01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e28622e11c81e3f6ff4933b65f6de8b0a7c874b493b23d7cc0371bb4f6c005e9aedeb8d65ddb6df05e9fcc88b57fb61130b0fba7cc700218064db66e660f8ed"} 02:23:20 INFO - Running post-run listener: copy_logs_to_upload_dir 02:23:20 INFO - Copying logs to upload dir... 02:23:20 INFO - mkdir: /builds/slave/test/build/upload/logs 02:23:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.618601 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-06-05 02:23:20.406697) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 02:23:20.410264) ========= 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.Z8vlDCJILh/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.m9QZFxhuua/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbf028ebd07c3607f8ac71d84cf5ede089033510a953cb73a1ab804a37798610c6b12e4e4b15a40c6ba665dfdb4e506e7d498e9d52492ee61aaa43dd54a1fa01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e28622e11c81e3f6ff4933b65f6de8b0a7c874b493b23d7cc0371bb4f6c005e9aedeb8d65ddb6df05e9fcc88b57fb61130b0fba7cc700218064db66e660f8ed"} build_url:https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011057 build_url: 'https://queue.taskcluster.net/v1/task/fn9wqPElSXW6owlPS0CSZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbf028ebd07c3607f8ac71d84cf5ede089033510a953cb73a1ab804a37798610c6b12e4e4b15a40c6ba665dfdb4e506e7d498e9d52492ee61aaa43dd54a1fa01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e28622e11c81e3f6ff4933b65f6de8b0a7c874b493b23d7cc0371bb4f6c005e9aedeb8d65ddb6df05e9fcc88b57fb61130b0fba7cc700218064db66e660f8ed"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 02:23:20.445246) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:23:20.445594) ========= 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.Z8vlDCJILh/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.m9QZFxhuua/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.004948 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 02:23:20.494678) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 02:23:20.495005) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 02:23:20.495324) ========= ========= Total master_lag: 0.17 =========