builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0084 starttime: 1462803362.93 results: success (0) buildid: 20160509045548 builduid: 039db17d53974ffb91704586ba114135 revision: da2b27e1aabd9e23870c09072753faba6ad0a42d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:02.933388) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:02.933809) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:02.934088) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:02.953331) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:02.953692) ========= 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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-05-09 07:16:02-- 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% 75.7M=0s 2016-05-09 07:16:03 (75.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107966 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:03.083458) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:03.083760) ========= 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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.029976 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:03.131782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 07:16:03.132130) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev da2b27e1aabd9e23870c09072753faba6ad0a42d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev da2b27e1aabd9e23870c09072753faba6ad0a42d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SnRLP8tbUr/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.mjdgcAuY4G/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-05-09 07:16:03,207 truncating revision to first 12 chars 2016-05-09 07:16:03,207 Setting DEBUG logging. 2016-05-09 07:16:03,208 attempt 1/10 2016-05-09 07:16:03,208 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/da2b27e1aabd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 07:16:04,724 unpacking tar archive at: fx-team-da2b27e1aabd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.763699 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 07:16:04.912887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:04.913243) ========= script_repo_revision: da2b27e1aabd9e23870c09072753faba6ad0a42d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:04.913620) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:04.913884) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 07:16:04.927079) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-09 07:16:04.927362) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SnRLP8tbUr/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.mjdgcAuY4G/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 07:16:05 INFO - MultiFileLogger online at 20160509 07:16:05 in /builds/slave/test 07:16:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 07:16:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:16:05 INFO - {'append_to_log': False, 07:16:05 INFO - 'base_work_dir': '/builds/slave/test', 07:16:05 INFO - 'blob_upload_branch': 'Fx-Team', 07:16:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:16:05 INFO - 'branch': 'Fx-Team', 07:16:05 INFO - 'buildbot_json_path': 'buildprops.json', 07:16:05 INFO - 'config_files': ('talos/mac_config.py',), 07:16:05 INFO - 'default_actions': ('clobber', 07:16:05 INFO - 'read-buildbot-config', 07:16:05 INFO - 'download-and-extract', 07:16:05 INFO - 'populate-webroot', 07:16:05 INFO - 'create-virtualenv', 07:16:05 INFO - 'install', 07:16:05 INFO - 'run-tests'), 07:16:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:16:05 INFO - 'download_minidump_stackwalk': True, 07:16:05 INFO - 'download_symbols': 'ondemand', 07:16:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:16:05 INFO - 'tooltool.py': '/tools/tooltool.py', 07:16:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:16:05 INFO - '/tools/misc-python/virtualenv.py')}, 07:16:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:16:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:16:05 INFO - 'installer_path': 'installer.exe', 07:16:05 INFO - 'log_level': 'info', 07:16:05 INFO - 'log_name': 'talos', 07:16:05 INFO - 'log_to_console': True, 07:16:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:16:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:16:05 INFO - 'opt_config_files': (), 07:16:05 INFO - 'pip_index': False, 07:16:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:16:05 INFO - 'cmd': ('bash', 07:16:05 INFO - '-c', 07:16:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:16:05 INFO - 'enabled': True, 07:16:05 INFO - 'halt_on_failure': False, 07:16:05 INFO - 'name': 'check_screen_resolution'},), 07:16:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:16:05 INFO - 'cmd': ('bash', 07:16:05 INFO - '-c', 07:16:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:16:05 INFO - 'enabled': True, 07:16:05 INFO - 'halt_on_failure': False, 07:16:05 INFO - 'name': 'check_screen_resolution'},), 07:16:05 INFO - 'run_cmd_checks_enabled': True, 07:16:05 INFO - 'sps_profile': False, 07:16:05 INFO - 'sps_profile_interval': 0, 07:16:05 INFO - 'suite': 'dromaeojs', 07:16:05 INFO - 'system_bits': '32', 07:16:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:16:05 INFO - 'title': 't-yosemite-r7-0084', 07:16:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:16:05 INFO - 'use_talos_json': True, 07:16:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:16:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:16:05 INFO - 'work_dir': 'build'} 07:16:05 INFO - ##### 07:16:05 INFO - ##### Running clobber step. 07:16:05 INFO - ##### 07:16:05 INFO - Running pre-action listener: _resource_record_pre_action 07:16:05 INFO - Running main action method: clobber 07:16:05 INFO - rmtree: /builds/slave/test/build 07:16:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:16:07 INFO - Running post-action listener: _resource_record_post_action 07:16:07 INFO - ##### 07:16:07 INFO - ##### Finished clobber step (success) 07:16:07 INFO - ##### 07:16:07 INFO - ##### 07:16:07 INFO - ##### Running read-buildbot-config step. 07:16:07 INFO - ##### 07:16:07 INFO - Running pre-action listener: _resource_record_pre_action 07:16:07 INFO - Running main action method: read_buildbot_config 07:16:07 INFO - Using buildbot properties: 07:16:07 INFO - { 07:16:07 INFO - "project": "", 07:16:07 INFO - "product": "firefox", 07:16:07 INFO - "script_repo_revision": "production", 07:16:07 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:16:07 INFO - "repository": "", 07:16:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:16:07 INFO - "buildid": "20160509045548", 07:16:07 INFO - "pgo_build": "False", 07:16:07 INFO - "basedir": "/builds/slave/test", 07:16:07 INFO - "buildnumber": 1, 07:16:07 INFO - "slavename": "t-yosemite-r7-0084", 07:16:07 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:16:07 INFO - "platform": "yosemite_r7", 07:16:07 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:16:07 INFO - "branch": "fx-team", 07:16:07 INFO - "repo_path": "integration/fx-team", 07:16:07 INFO - "revision": "da2b27e1aabd9e23870c09072753faba6ad0a42d", 07:16:07 INFO - "stage_platform": "macosx64", 07:16:07 INFO - "builduid": "039db17d53974ffb91704586ba114135", 07:16:07 INFO - "slavebuilddir": "test" 07:16:07 INFO - } 07:16:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:16:07 INFO - Running post-action listener: _resource_record_post_action 07:16:07 INFO - ##### 07:16:07 INFO - ##### Finished read-buildbot-config step (success) 07:16:07 INFO - ##### 07:16:07 INFO - ##### 07:16:07 INFO - ##### Running download-and-extract step. 07:16:07 INFO - ##### 07:16:07 INFO - Running pre-action listener: _resource_record_pre_action 07:16:07 INFO - Running main action method: download_and_extract 07:16:07 INFO - mkdir: /builds/slave/test/build/tests 07:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:07 INFO - https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:16:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:16:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:16:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:16:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 07:16:09 INFO - Downloaded 1199 bytes. 07:16:09 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:16:09 INFO - Using the following test package requirements: 07:16:09 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:16:09 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:16:09 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:16:09 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:16:09 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:16:09 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:16:09 INFO - u'jsshell-mac.zip'], 07:16:09 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:16:09 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:16:09 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:16:09 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:16:09 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:16:09 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:16:09 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:16:09 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:16:09 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:16:09 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:16:09 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:16:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:09 INFO - https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:16:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:16:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:16:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:16:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:16:10 INFO - Downloaded 18146583 bytes. 07:16:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:16:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:16:13 INFO - Return code: 0 07:16:13 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:16:13 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')]} 07:16:13 INFO - https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:16:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:16:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:16:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:16:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:16:14 INFO - Downloaded 18146583 bytes. 07:16:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:16:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:16:17 INFO - Return code: 0 07:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:17 INFO - https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:16:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:16:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:16:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:16:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:16:18 INFO - Downloaded 10840152 bytes. 07:16:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:16:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:16:19 INFO - Return code: 0 07:16: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')]} 07:16:19 INFO - https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:16:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:16:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:16:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:16:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 07:16:24 INFO - Downloaded 110425905 bytes. 07:16:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:16:24 INFO - mkdir: /builds/slave/test/properties 07:16:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:16:24 INFO - Writing to file /builds/slave/test/properties/build_url 07:16:24 INFO - Contents: 07:16:24 INFO - build_url:https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:16:25 INFO - Running post-action listener: _resource_record_post_action 07:16:25 INFO - Running post-action listener: set_extra_try_arguments 07:16:25 INFO - ##### 07:16:25 INFO - ##### Finished download-and-extract step (success) 07:16:25 INFO - ##### 07:16:25 INFO - ##### 07:16:25 INFO - ##### Running populate-webroot step. 07:16:25 INFO - ##### 07:16:25 INFO - Running pre-action listener: _resource_record_pre_action 07:16:25 INFO - Running main action method: populate_webroot 07:16:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:16:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:16:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:16:25 INFO - '--tppagecycles', 07:16:25 INFO - '7'], 07:16:25 INFO - 'tests': ['tsvgm']}}, 07:16:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:16:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:16:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:16:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:16:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:16:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:16:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:25 INFO - 'talos_options': ['--disable-e10s'], 07:16:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:16:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:16:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:25 INFO - 'talos_options': ['--disable-e10s'], 07:16:25 INFO - 'tests': ['damp', 'tps']}, 07:16:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:25 INFO - 'tests': ['damp', 'tps']}, 07:16:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:16:25 INFO - 'tests': ['dromaeo_dom']}, 07:16:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:16:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:16:25 INFO - 'tests': ['a11yr', 07:16:25 INFO - 'ts_paint', 07:16:25 INFO - 'tpaint', 07:16:25 INFO - 'sessionrestore', 07:16:25 INFO - 'sessionrestore_no_auto_restore', 07:16:25 INFO - 'tabpaint']}, 07:16:25 INFO - 'other-e10s': {'tests': ['a11yr', 07:16:25 INFO - 'ts_paint', 07:16:25 INFO - 'tpaint', 07:16:25 INFO - 'sessionrestore', 07:16:25 INFO - 'sessionrestore_no_auto_restore', 07:16:25 INFO - 'tabpaint']}, 07:16:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:16:25 INFO - 'ts_paint', 07:16:25 INFO - 'tpaint', 07:16:25 INFO - 'sessionrestore', 07:16:25 INFO - 'sessionrestore_no_auto_restore', 07:16:25 INFO - 'tabpaint']}, 07:16:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:16:25 INFO - 'ts_paint', 07:16:25 INFO - 'tpaint', 07:16:25 INFO - 'sessionrestore', 07:16:25 INFO - 'sessionrestore_no_auto_restore', 07:16:25 INFO - 'tabpaint']}, 07:16:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:16:25 INFO - 'tests': ['a11yr', 07:16:25 INFO - 'ts_paint', 07:16:25 INFO - 'tpaint', 07:16:25 INFO - 'sessionrestore', 07:16:25 INFO - 'sessionrestore_no_auto_restore', 07:16:25 INFO - 'tabpaint']}, 07:16:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:16:25 INFO - 'tests': ['a11yr', 07:16:25 INFO - 'ts_paint', 07:16:25 INFO - 'tpaint', 07:16:25 INFO - 'sessionrestore', 07:16:25 INFO - 'sessionrestore_no_auto_restore', 07:16:25 INFO - 'tabpaint']}, 07:16:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:16:25 INFO - 'tests': ['tsvgx', 07:16:25 INFO - 'tsvgr_opacity', 07:16:25 INFO - 'tart', 07:16:25 INFO - 'tscrollx', 07:16:25 INFO - 'cart']}, 07:16:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:16:25 INFO - 'tsvgr_opacity', 07:16:25 INFO - 'tart', 07:16:25 INFO - 'tscrollx', 07:16:25 INFO - 'cart']}, 07:16:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:25 INFO - 'talos_options': ['--disable-e10s'], 07:16:25 INFO - 'tests': ['tp5o']}, 07:16:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:25 INFO - 'tests': ['tp5o']}, 07:16:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:25 INFO - 'talos_options': ['--disable-e10s', 07:16:25 INFO - '--xperf_path', 07:16:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:16:25 INFO - 'tests': ['tp5n']}, 07:16:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:16:25 INFO - 'talos_options': ['--xperf_path', 07:16:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:16:25 INFO - 'tests': ['tp5n']}}, 07:16:25 INFO - 'talos.zip': {'path': '', 07:16:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:16:25 INFO - Running post-action listener: _resource_record_post_action 07:16:25 INFO - ##### 07:16:25 INFO - ##### Finished populate-webroot step (success) 07:16:25 INFO - ##### 07:16:25 INFO - ##### 07:16:25 INFO - ##### Running create-virtualenv step. 07:16:25 INFO - ##### 07:16:25 INFO - Running pre-action listener: _resource_record_pre_action 07:16:25 INFO - Running main action method: create_virtualenv 07:16:25 INFO - Creating virtualenv /builds/slave/test/build/venv 07:16:25 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 07:16:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:16:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:16:25 INFO - Using real prefix '/tools/python27' 07:16:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:16:26 INFO - Installing distribute.............................................................................................................................................................................................done. 07:16:28 INFO - Installing pip.................done. 07:16:28 INFO - Return code: 0 07:16:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:28 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 0x102559a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10255f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd689c6cc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023fdd30>, '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 0x102221db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023f4b20>, '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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 07:16:28 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 07:16:28 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 07:16:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SnRLP8tbUr/Render', 07:16:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:28 INFO - 'HOME': '/Users/cltbld', 07:16:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:28 INFO - 'LOGNAME': 'cltbld', 07:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:28 INFO - 'MOZ_NO_REMOTE': '1', 07:16:28 INFO - 'NO_EM_RESTART': '1', 07:16:28 INFO - 'PAGER': '/bin/cat', 07:16:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:28 INFO - 'PWD': '/builds/slave/test', 07:16:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:28 INFO - 'SHELL': '/bin/bash', 07:16:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mjdgcAuY4G/Listeners', 07:16:28 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:16:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:28 INFO - 'USER': 'cltbld', 07:16:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:28 INFO - 'XPC_FLAGS': '0x0', 07:16:28 INFO - 'XPC_SERVICE_NAME': '0', 07:16:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:29 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 07:16:29 INFO - Cleaning up... 07:16:29 INFO - Return code: 0 07:16:29 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:16:29 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')]} 07:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:29 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')]} 07:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:29 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 0x102559a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10255f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd689c6cc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023fdd30>, '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 0x102221db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023f4b20>, '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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 07:16:29 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 07:16:29 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 07:16:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SnRLP8tbUr/Render', 07:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:29 INFO - 'HOME': '/Users/cltbld', 07:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:29 INFO - 'LOGNAME': 'cltbld', 07:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:29 INFO - 'MOZ_NO_REMOTE': '1', 07:16:29 INFO - 'NO_EM_RESTART': '1', 07:16:29 INFO - 'PAGER': '/bin/cat', 07:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:29 INFO - 'PWD': '/builds/slave/test', 07:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:29 INFO - 'SHELL': '/bin/bash', 07:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mjdgcAuY4G/Listeners', 07:16:29 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:29 INFO - 'USER': 'cltbld', 07:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:29 INFO - 'XPC_FLAGS': '0x0', 07:16:29 INFO - 'XPC_SERVICE_NAME': '0', 07:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:29 INFO - Downloading/unpacking psutil>=0.7.1 07:16:29 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 07:16:29 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 07:16:29 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 07:16:29 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 07:16:29 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 07:16:29 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 07:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:16:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:16:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:16:32 INFO - Installing collected packages: psutil 07:16:32 INFO - Running setup.py install for psutil 07:16:32 INFO - building 'psutil._psutil_osx' extension 07:16:32 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 07:16:33 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 07:16:33 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 07:16:33 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 07:16:33 INFO - building 'psutil._psutil_posix' extension 07:16:33 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 07:16:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:16:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:16:33 INFO - ^ 07:16:33 INFO - 1 warning generated. 07:16:33 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 07:16:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:16:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:16:33 INFO - Successfully installed psutil 07:16:33 INFO - Cleaning up... 07:16:33 INFO - Return code: 0 07:16:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:33 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 0x102559a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10255f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd689c6cc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023fdd30>, '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 0x102221db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023f4b20>, '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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 07:16:33 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 07:16:33 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 07:16:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SnRLP8tbUr/Render', 07:16:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:33 INFO - 'HOME': '/Users/cltbld', 07:16:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:33 INFO - 'LOGNAME': 'cltbld', 07:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:33 INFO - 'MOZ_NO_REMOTE': '1', 07:16:33 INFO - 'NO_EM_RESTART': '1', 07:16:33 INFO - 'PAGER': '/bin/cat', 07:16:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:33 INFO - 'PWD': '/builds/slave/test', 07:16:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:33 INFO - 'SHELL': '/bin/bash', 07:16:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mjdgcAuY4G/Listeners', 07:16:33 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:16:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:33 INFO - 'USER': 'cltbld', 07:16:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:33 INFO - 'XPC_FLAGS': '0x0', 07:16:33 INFO - 'XPC_SERVICE_NAME': '0', 07:16:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:16:33 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 07:16:33 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 07:16:33 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 07:16:33 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 07:16:33 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 07:16:33 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 07:16:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:16:36 INFO - Installing collected packages: mozsystemmonitor 07:16:36 INFO - Running setup.py install for mozsystemmonitor 07:16:36 INFO - Successfully installed mozsystemmonitor 07:16:36 INFO - Cleaning up... 07:16:36 INFO - Return code: 0 07:16:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:16: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')]} 07:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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')]} 07:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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', '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 0x102559a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10255f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd689c6cc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023fdd30>, '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 0x102221db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023f4b20>, '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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 07:16: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:16: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 blobuploader==1.2.4 07:16:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SnRLP8tbUr/Render', 07:16:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:36 INFO - 'HOME': '/Users/cltbld', 07:16:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:36 INFO - 'LOGNAME': 'cltbld', 07:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:36 INFO - 'MOZ_NO_REMOTE': '1', 07:16:36 INFO - 'NO_EM_RESTART': '1', 07:16:36 INFO - 'PAGER': '/bin/cat', 07:16:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:36 INFO - 'PWD': '/builds/slave/test', 07:16:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:36 INFO - 'SHELL': '/bin/bash', 07:16:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mjdgcAuY4G/Listeners', 07:16:36 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:16:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:36 INFO - 'USER': 'cltbld', 07:16:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:36 INFO - 'XPC_FLAGS': '0x0', 07:16:36 INFO - 'XPC_SERVICE_NAME': '0', 07:16:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:36 INFO - Downloading/unpacking blobuploader==1.2.4 07:16:36 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 07:16:36 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 07:16:36 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 07:16:36 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 07:16:36 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 07:16:36 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 07:16:39 INFO - Downloading blobuploader-1.2.4.tar.gz 07:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:16:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:16:39 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 07:16:39 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 07:16:39 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 07:16:39 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 07:16:39 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 07:16:39 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 07:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:16:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:40 INFO - Downloading docopt-0.6.1.tar.gz 07:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:16:40 INFO - Installing collected packages: blobuploader, requests, docopt 07:16:40 INFO - Running setup.py install for blobuploader 07:16:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:16:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:16:40 INFO - Running setup.py install for requests 07:16:41 INFO - Running setup.py install for docopt 07:16:41 INFO - Successfully installed blobuploader requests docopt 07:16:41 INFO - Cleaning up... 07:16:41 INFO - Return code: 0 07:16:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16: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')]} 07:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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')]} 07:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:41 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 0x102559a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10255f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd689c6cc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023fdd30>, '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 0x102221db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023f4b20>, '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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 07:16:41 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 07:16:41 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 07:16:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SnRLP8tbUr/Render', 07:16:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:41 INFO - 'HOME': '/Users/cltbld', 07:16:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:41 INFO - 'LOGNAME': 'cltbld', 07:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:41 INFO - 'MOZ_NO_REMOTE': '1', 07:16:41 INFO - 'NO_EM_RESTART': '1', 07:16:41 INFO - 'PAGER': '/bin/cat', 07:16:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:41 INFO - 'PWD': '/builds/slave/test', 07:16:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:41 INFO - 'SHELL': '/bin/bash', 07:16:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mjdgcAuY4G/Listeners', 07:16:41 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:16:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:41 INFO - 'USER': 'cltbld', 07:16:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:41 INFO - 'XPC_FLAGS': '0x0', 07:16:41 INFO - 'XPC_SERVICE_NAME': '0', 07:16:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:16:41 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-R63J2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:16:42 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-uG2k7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:16:42 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ytYKjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:16:42 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-9P6Qxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:16:42 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-e0Pe5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:16:42 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-NFuwzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:16:42 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Rf3ca2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:16:42 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-d6y3MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:16:42 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-pXCofH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:16:42 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-67IajX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:16:42 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-fIvksi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:16:43 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-WArxSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:16:43 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-26eAwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:16:43 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-9BwV9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:43 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-kdY25n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:16:43 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-8PeQsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:16:43 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-R7GU2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:16:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:16:43 INFO - Running setup.py install for manifestparser 07:16:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:16:43 INFO - Running setup.py install for mozcrash 07:16:43 INFO - Running setup.py install for mozdebug 07:16:44 INFO - Running setup.py install for mozdevice 07:16:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:16:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:16:44 INFO - Running setup.py install for mozfile 07:16:44 INFO - Running setup.py install for mozhttpd 07:16:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:16:44 INFO - Running setup.py install for mozinfo 07:16:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:16:44 INFO - Running setup.py install for mozInstall 07:16:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:16:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:16:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:16:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:16:44 INFO - Running setup.py install for mozleak 07:16:44 INFO - Running setup.py install for mozlog 07:16:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:16:45 INFO - Running setup.py install for moznetwork 07:16:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:16:45 INFO - Running setup.py install for mozprocess 07:16:45 INFO - Running setup.py install for mozprofile 07:16:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:16:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:16:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:16:45 INFO - Running setup.py install for mozrunner 07:16:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:16:45 INFO - Running setup.py install for mozscreenshot 07:16:45 INFO - Running setup.py install for moztest 07:16:46 INFO - Running setup.py install for mozversion 07:16:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:16:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:16:46 INFO - Cleaning up... 07:16:46 INFO - Return code: 0 07:16:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16:46 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')]} 07:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:46 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')]} 07:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:46 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 0x102559a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10255f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd689c6cc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023fdd30>, '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 0x102221db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023f4b20>, '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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 07:16:46 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 07:16:46 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 07:16:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SnRLP8tbUr/Render', 07:16:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:46 INFO - 'HOME': '/Users/cltbld', 07:16:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:46 INFO - 'LOGNAME': 'cltbld', 07:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:46 INFO - 'MOZ_NO_REMOTE': '1', 07:16:46 INFO - 'NO_EM_RESTART': '1', 07:16:46 INFO - 'PAGER': '/bin/cat', 07:16:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:46 INFO - 'PWD': '/builds/slave/test', 07:16:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:46 INFO - 'SHELL': '/bin/bash', 07:16:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mjdgcAuY4G/Listeners', 07:16:46 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:16:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:46 INFO - 'USER': 'cltbld', 07:16:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:46 INFO - 'XPC_FLAGS': '0x0', 07:16:46 INFO - 'XPC_SERVICE_NAME': '0', 07:16:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:16:46 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-uOhgFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:16:46 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)) 07:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:16:46 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-cKVlKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:16:46 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)) 07:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:16:46 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-4CCVo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:16:46 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)) 07:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:16:46 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-BR0FcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:16:46 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)) 07:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:16:46 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-vdijMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:16:47 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)) 07:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:16:47 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-vDReJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:16:47 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)) 07:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:16:47 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-THyA9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:16:47 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)) 07:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:16:47 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-7fgKRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:16:47 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)) 07:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:16:47 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-3yigEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:16:47 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)) 07:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:16:47 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-3fWW0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:16:47 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)) 07:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:16:47 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-c68B5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:16:47 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)) 07:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:16:47 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-tUFTbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:16:47 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)) 07:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:16:47 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-u92b3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:16:47 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)) 07:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:16:47 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-8Uyual-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:16:47 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)) 07:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:47 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Xtrs1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:16:48 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-DyLiIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:16:48 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)) 07:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:16:48 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-RcjAgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:16:48 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)) 07:16:48 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)) 07:16:48 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)) 07:16:48 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)) 07:16:48 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)) 07:16:48 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)) 07:16:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:50 INFO - Downloading blessings-1.6.tar.gz 07:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:16:50 INFO - Installing collected packages: blessings 07:16:50 INFO - Running setup.py install for blessings 07:16:51 INFO - Successfully installed blessings 07:16:51 INFO - Cleaning up... 07:16:51 INFO - Return code: 0 07:16:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:16:51 INFO - Reading from file tmpfile_stdout 07:16:51 INFO - Current package versions: 07:16:51 INFO - blessings == 1.6 07:16:51 INFO - blobuploader == 1.2.4 07:16:51 INFO - docopt == 0.6.1 07:16:51 INFO - manifestparser == 1.1 07:16:51 INFO - mozInstall == 1.12 07:16:51 INFO - mozcrash == 0.17 07:16:51 INFO - mozdebug == 0.1 07:16:51 INFO - mozdevice == 0.48 07:16:51 INFO - mozfile == 1.2 07:16:51 INFO - mozhttpd == 0.7 07:16:51 INFO - mozinfo == 0.9 07:16:51 INFO - mozleak == 0.1 07:16:51 INFO - mozlog == 3.1 07:16:51 INFO - moznetwork == 0.27 07:16:51 INFO - mozprocess == 0.22 07:16:51 INFO - mozprofile == 0.28 07:16:51 INFO - mozrunner == 6.11 07:16:51 INFO - mozscreenshot == 0.1 07:16:51 INFO - mozsystemmonitor == 0.0 07:16:51 INFO - moztest == 0.7 07:16:51 INFO - mozversion == 1.4 07:16:51 INFO - psutil == 3.1.1 07:16:51 INFO - requests == 1.2.3 07:16:51 INFO - wsgiref == 0.1.2 07:16:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:51 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 0x102559a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10255f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd689c6cc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023fdd30>, '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 0x102221db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023f4b20>, '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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 07:16:51 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 07:16:51 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 07:16:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SnRLP8tbUr/Render', 07:16:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:51 INFO - 'HOME': '/Users/cltbld', 07:16:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:51 INFO - 'LOGNAME': 'cltbld', 07:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:51 INFO - 'MOZ_NO_REMOTE': '1', 07:16:51 INFO - 'NO_EM_RESTART': '1', 07:16:51 INFO - 'PAGER': '/bin/cat', 07:16:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:51 INFO - 'PWD': '/builds/slave/test', 07:16:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:51 INFO - 'SHELL': '/bin/bash', 07:16:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mjdgcAuY4G/Listeners', 07:16:51 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:16:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:51 INFO - 'USER': 'cltbld', 07:16:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:51 INFO - 'XPC_FLAGS': '0x0', 07:16:51 INFO - 'XPC_SERVICE_NAME': '0', 07:16:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:51 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)) 07:16:51 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)) 07:16:51 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)) 07:16:51 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)) 07:16:51 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)) 07:16:51 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)) 07:16:51 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)) 07:16:51 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)) 07:16:51 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)) 07:16:51 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)) 07:16:51 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)) 07:16:51 INFO - Cleaning up... 07:16:51 INFO - Return code: 0 07:16:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102559a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10255f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd689c6cc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023fdd30>, '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 0x102221db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023f4b20>, '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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 07:16:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:16:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SnRLP8tbUr/Render', 07:16:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:51 INFO - 'HOME': '/Users/cltbld', 07:16:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:51 INFO - 'LOGNAME': 'cltbld', 07:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:51 INFO - 'MOZ_NO_REMOTE': '1', 07:16:51 INFO - 'NO_EM_RESTART': '1', 07:16:51 INFO - 'PAGER': '/bin/cat', 07:16:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:51 INFO - 'PWD': '/builds/slave/test', 07:16:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:51 INFO - 'SHELL': '/bin/bash', 07:16:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mjdgcAuY4G/Listeners', 07:16:51 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:16:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:51 INFO - 'USER': 'cltbld', 07:16:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:51 INFO - 'XPC_FLAGS': '0x0', 07:16:51 INFO - 'XPC_SERVICE_NAME': '0', 07:16:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:51 INFO - Downloading/unpacking jsonschema 07:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:16:54 INFO - Downloading/unpacking functools32 (from jsonschema) 07:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:54 INFO - Downloading functools32-3.2.3-2.tar.gz 07:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:16:54 INFO - warning: no files found matching '*.txt' 07:16:54 INFO - no previously-included directories found matching 'build' 07:16:54 INFO - no previously-included directories found matching 'dist' 07:16:54 INFO - no previously-included directories found matching '.git*' 07:16:54 INFO - Installing collected packages: jsonschema, functools32 07:16:54 INFO - Running setup.py install for functools32 07:16:55 INFO - warning: no files found matching '*.txt' 07:16:55 INFO - no previously-included directories found matching 'build' 07:16:55 INFO - no previously-included directories found matching 'dist' 07:16:55 INFO - no previously-included directories found matching '.git*' 07:16:55 INFO - Successfully installed jsonschema functools32 07:16:55 INFO - Cleaning up... 07:16:55 INFO - Return code: 0 07:16:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102559a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10255f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd689c6cc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023fdd30>, '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 0x102221db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023f4b20>, '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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 07:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 07:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 07:16:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SnRLP8tbUr/Render', 07:16:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:55 INFO - 'HOME': '/Users/cltbld', 07:16:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:55 INFO - 'LOGNAME': 'cltbld', 07:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:55 INFO - 'MOZ_NO_REMOTE': '1', 07:16:55 INFO - 'NO_EM_RESTART': '1', 07:16:55 INFO - 'PAGER': '/bin/cat', 07:16:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:55 INFO - 'PWD': '/builds/slave/test', 07:16:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:55 INFO - 'SHELL': '/bin/bash', 07:16:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mjdgcAuY4G/Listeners', 07:16:55 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:16:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:55 INFO - 'USER': 'cltbld', 07:16:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:55 INFO - 'XPC_FLAGS': '0x0', 07:16:55 INFO - 'XPC_SERVICE_NAME': '0', 07:16:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:55 INFO - Downloading/unpacking flake8 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:16:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:16:57 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 07:16:57 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 07:16:57 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 07:16:57 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 07:16:57 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 07:16:57 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 07:16:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:16:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:16:59 INFO - no previously-included directories found matching 'docs/_build' 07:16:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:16:59 INFO - Running setup.py install for pep8 07:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:16:59 INFO - no previously-included directories found matching 'docs/_build' 07:16:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:16:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:16:59 INFO - Cleaning up... 07:16:59 INFO - Return code: 0 07:16:59 INFO - Running post-action listener: _resource_record_post_action 07:16:59 INFO - Running post-action listener: _start_resource_monitoring 07:16:59 INFO - Starting resource monitoring. 07:16:59 INFO - ##### 07:16:59 INFO - ##### Finished create-virtualenv step (success) 07:16:59 INFO - ##### 07:16:59 INFO - ##### 07:16:59 INFO - ##### Running install step. 07:16:59 INFO - ##### 07:16:59 INFO - Running pre-action listener: _resource_record_pre_action 07:16:59 INFO - Running main action method: install 07:16:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:16:59 INFO - Reading from file tmpfile_stdout 07:16:59 INFO - Detecting whether we're running mozinstall >=1.0... 07:16:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:16:59 INFO - Reading from file tmpfile_stdout 07:16:59 INFO - Output received: 07:16:59 INFO - Usage: mozinstall [options] installer 07:16:59 INFO - Options: 07:16:59 INFO - -h, --help show this help message and exit 07:16:59 INFO - -d DEST, --destination=DEST 07:16:59 INFO - Directory to install application into. [default: 07:16:59 INFO - "/builds/slave/test"] 07:16:59 INFO - --app=APP Application being installed. [default: firefox] 07:16:59 INFO - mkdir: /builds/slave/test/build/application 07:16:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:17:33 INFO - Reading from file tmpfile_stdout 07:17:33 INFO - Output received: 07:17:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:17:33 INFO - Running post-action listener: _resource_record_post_action 07:17:33 INFO - ##### 07:17:33 INFO - ##### Finished install step (success) 07:17:33 INFO - ##### 07:17:33 INFO - ##### 07:17:33 INFO - ##### Running run-tests step. 07:17:33 INFO - ##### 07:17:33 INFO - Running pre-action listener: _resource_record_pre_action 07:17:33 INFO - Running main action method: run_tests 07:17:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:17:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:17:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:17:33 INFO - 2016-05-09 07:17:33.951 screenresolution[1642:7571] starting screenresolution argv=screenresolution get 07:17:33 INFO - 2016-05-09 07:17:33.954 screenresolution[1642:7571] Display 0: 1600x1200x32@60 07:17:33 INFO - 2016-05-09 07:17:33.960 screenresolution[1643:7574] starting screenresolution argv=screenresolution list 07:17:33 INFO - Available Modes on Display 0 07:17:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:17:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:17:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:17:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:17:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:17:34 INFO - Intel Iris: 07:17:34 INFO - Chipset Model: Intel Iris 07:17:34 INFO - Type: GPU 07:17:34 INFO - Bus: Built-In 07:17:34 INFO - VRAM (Dynamic, Max): 1536 MB 07:17:34 INFO - Vendor: Intel (0x8086) 07:17:34 INFO - Device ID: 0x0a2e 07:17:34 INFO - Revision ID: 0x0009 07:17:34 INFO - Displays: 07:17:34 INFO - Display: 07:17:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:17:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:17:34 INFO - Display Serial Number: 1600x1200 60 07:17:34 INFO - Main Display: Yes 07:17:34 INFO - Mirror: Off 07:17:34 INFO - Online: Yes 07:17:34 INFO - Rotation: Supported 07:17:34 INFO - Return code: 0 07:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:17:34 INFO - Python 2.7.3 07:17:34 INFO - Return code: 0 07:17:34 INFO - grabbing minidump binary from tooltool 07:17:34 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')]} 07:17:34 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 0x1023fdd30>, '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 0x102221db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023f4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:17:34 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 07:17:34 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 07:17:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:17:34 INFO - Return code: 0 07:17:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:17:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:17:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:17:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:17:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:17:35 INFO - Return code: 0 07:17:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 07:17:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0084 --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 07:17:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SnRLP8tbUr/Render', 07:17:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:17:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:17:35 INFO - 'HOME': '/Users/cltbld', 07:17:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:17:35 INFO - 'LOGNAME': 'cltbld', 07:17:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:17:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:17:35 INFO - 'MOZ_NO_REMOTE': '1', 07:17:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:17:35 INFO - 'NO_EM_RESTART': '1', 07:17:35 INFO - 'PAGER': '/bin/cat', 07:17:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:17:35 INFO - 'PWD': '/builds/slave/test', 07:17:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:17:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:17:35 INFO - 'SHELL': '/bin/bash', 07:17:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mjdgcAuY4G/Listeners', 07:17:35 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:17:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:17:35 INFO - 'USER': 'cltbld', 07:17:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:17:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:17:35 INFO - 'XPC_FLAGS': '0x0', 07:17:35 INFO - 'XPC_SERVICE_NAME': '0', 07:17:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:17:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 07:17:35 INFO - mozversion application_buildid: 20160509045548 07:17:35 INFO - mozversion application_changeset: da2b27e1aabd9e23870c09072753faba6ad0a42d 07:17:35 INFO - mozversion application_display_name: Nightly 07:17:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:17:35 INFO - mozversion application_name: Firefox 07:17:35 INFO - mozversion application_remotingname: firefox 07:17:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:17:35 INFO - mozversion application_vendor: Mozilla 07:17:35 INFO - mozversion application_version: 49.0a1 07:17:35 INFO - mozversion platform_buildid: 20160509045548 07:17:35 INFO - mozversion platform_changeset: da2b27e1aabd9e23870c09072753faba6ad0a42d 07:17:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:17:35 INFO - mozversion platform_version: 49.0a1 07:17:35 INFO - using testdate: 1462803455 07:17:35 INFO - actual date: 1462803455 07:17:35 INFO - starting webserver on 'localhost:49273' 07:17:35 INFO - SUITE-START | Running 2 tests 07:17:35 INFO - TEST-START | dromaeo_css 07:17:35 INFO - operating with platform_type : mac_ 07:17:35 INFO - Initialising browser for dromaeo_css test... 07:17:35 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpKh2NZ4/profile http://localhost:49273/getInfo.html) 07:17:37 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 07:17:37 INFO - PROCESS | 1661 | colorDepth:24 07:17:37 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 07:17:37 INFO - PROCESS | 1661 | __metrics 07:17:38 INFO - PROCESS | 1661 | 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" 07:17:38 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:17:38 INFO - TEST-INFO | 1661: exit 0 07:17:38 INFO - Browser initialized. 07:17:38 INFO - Running cycle 1/1 for dromaeo_css test... 07:17:38 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpKh2NZ4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:17:50 INFO - PROCESS | 1663 | RSS: Main: 239075328 07:17:50 INFO - PROCESS | 1663 | 07:18:42 INFO - PROCESS | 1663 | 1462803522415 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] 07:20:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 07:20:54 INFO - PROCESS | 1663 | RSS: Main: 321753088 07:20:54 INFO - PROCESS | 1663 | 07:23:57 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 07:23:57 INFO - PROCESS | 1663 | RSS: Main: 371953664 07:23:57 INFO - PROCESS | 1663 | 07:26:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 07:27:00 INFO - PROCESS | 1663 | RSS: Main: 368779264 07:27:00 INFO - PROCESS | 1663 | 07:30:02 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 07:30:03 INFO - PROCESS | 1663 | RSS: Main: 363835392 07:30:03 INFO - PROCESS | 1663 | 07:33:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 07:33:07 INFO - PROCESS | 1663 | RSS: Main: 332509184 07:33:07 INFO - PROCESS | 1663 | 07:36:10 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 07:36:10 INFO - PROCESS | 1663 | RSS: Main: 372658176 07:36:10 INFO - PROCESS | 1663 | 07:36:10 INFO - PROCESS | 1663 | __start_tp_report 07:36:10 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 07:36:10 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 07:36:10 INFO - PROCESS | 1663 | |i|pagename|runs| 07:36:10 INFO - PROCESS | 1663 | |0;dojo.html;3413.8122403267826;3421.6065152507463;3426.725861931046;3445.0870519312402;3493.947590786139;5584.21820945068;5588.888222235553;5632.07070833312;5685.971570142148;5732.627379220347;4921.532454416824;5502.2296878436955;5658.547316214693;5749.0514065179295;5775.797847075342;5095.286659867622;5116.590672746172;5168.302279192299;5183.611229157809;5217.86955326118;4357.869263922072;5274.762635681384;5307.655002424849;5329.333833270841;5362.195670649414;5128.230765385185;5184.040952423814;5196.714180720059;5230;5248.921266181011;5134.255532947739;5163.070647283487;5174.818881339173;5192.766325515361;5225.817096401645;5131.615128865313;5137.101007323703;5156.071907056728;5184.559312458446;5243.161094224905;5654.575906807006;5678.176664383683;5690.06113991191;5691.117876729114;5701.4013528579735;6051.001584738557;6067.811449151365;6116.883633902611;6163.260408750979;6216.222972128484;5219.321488206547;6290.119383286344;6291.937080629181;6302.338254483259;6308.842278943017;5819.88360232793;5831.854203644901;5867.325257595388;5894.086416605433;5896.439838215406;6194.132821405007;6233.252009758858;6262.1546091278005;6295.213098362705;6297.590656607306;6151.815445536641;6155.199824022849;6155.891939450852;6158.907616385758;6164.907526387108;6204.472619827366;6249.281332646713;6265.216847894013;6276.592021518587;6285.528585356116;6076.210092687922;6102.549845102896;6103.822585931194;6162.168107305546;6176;6189.35011823761;6236.812895613139;6244.1570387997945;6253.499720022387;6255.968720156461;4945.208766597327;4951.653384263067;4952.182889823139;4955.98402327522;4990.251462280615;4357.997660538094;4419.983403817076;4427.512973572904;4446.777661116931;4480.39514665516;4289.485261768607;5582.190582365615;5621.887562248732;5659.377468478464;5841.474267315877;6193.1329613854095;6249;6271.153394291712;6272.498200143978;6279.497640188774;5652.689102099424;5660.066089095253;5667.716614169275;5682.715864206773;5687.829365119053;5268.446813084571;5322.494363035506;5334;5349.384820745586;5380.058489764276;6216.533759968021;6308.810735677937;6311.179546658905;6312.7790527332245;6323.9683801580695;6154.230721159855;6256.405641464054;6257.530685198537;6270;6278.121063051085;6211.3167551570195;6243.750249989949;6261.530385221217;6293.716782744879;6294.590851594632;6061.515078793598;6080.3919608040615;6089.117078023751;6115.327313995546;6137.1101190328045;6132.693365331626;6165.969170154121;6172.629642221481;6179.6910154493;6214.937850621617;5893.351731309467;5934.881302374015;5962.761489540543;5963.970180149245;5987.910181347196;6032.245969253843;6068.21113255274;6074.939250607437;6087.05650624154;6118.418750218722;5783.045797443543;5871.442212989759;5901.350851406428;5904.498117660047;5924.318703348998;5823.417658234142;5980.491658208927;6018.36807135262;6046.2139921809885;6050.062240352752;5883.61756485827;5897.292324921037;5909.2908850938165;5930.703464826655;5942.376050514807 07:36:10 INFO - PROCESS | 1663 | |1;ext.html;515.9448926944399;530.14646419265;539.9919001214982;553.8866877576073;587.7913340764027;28500.28749281269;32250.838745806268;32295.35409291813;32372;43739.78130109353;34606;45239.54760452395;45341.546584534146;45489.09021819562;45660.315095273516;9599.232061435068;9815.50922453878;9823.459709715962;9826.263030272723;9832.164266037395;8636.611352489153;8791.076936921627;9618.711438656852;9728.416295022285;11517.36654484003;11417.771644567103;11695.7076073098;11697.766044679101;11699.06407487399;11701.882981170165;11598.072154227684;11655.475503602358;11659.183857130005;11668;11674;8641.870371944426;11373.431328433628;11614.825777613343;11622.767544649145;11781.764364712744;41163.58836411627;41452.79273603613;41587.79206103981;41646;41759.58240417617;3314.867405303785;3360.9831950840335;3438.2435864109857;3593.1735700788704;3720.795356255405;26694.73305266942;27833.16500504968;29008.564871526945;29374.55938160929;29523.70476295231;2680.986595067032;2691.232998595391;2698.7571118599226;2718.768904643108;2812.268810109366;482715;489367;494596;495201;495322;65731;66050.66974665193;66062.33937660563;66099.66950165218;66312.66843665847;37531.81234093867;38459.23081538409;38485.230295393936;39528.80235598804;39566.80216598956;17046.23291951865;17064.061476618655;17102.657946841242;17109.657806844043;17124.48626541206;11809.822852657217;12962.935185324202;13060.673483162807;13074.215547067206;13091.476340946447;17896.731549026776;20365.981700914894;20849.79150208509;20862;21411.571768564543;8044.115147333789;8067.314278286294;8067.959660201779;8072.515649061075;8127.1466496017065;860.8966923969164;867.2628265973998;916.7020718266457;1030.8762948446235;1100.3178029621686;29320.853395733313;29567;29771.85114074416;30522;30557.69442305593;1116.0457808573553;1142.8057230270874;1166.2943918929095;1208.1301462946665;1227.0061250387214;12778.61664150077;13278.668033299246;15193.088414695154;15281.235938203044;15557.29992150356;661437;666359;667961;672864;676264;50685.23972140421;61668.69165654143;61887;61891;61950;1247.5134697467995;1248.4881198708485;1365.4401695304678;1365.9686936362982;1464.2459133546445;29421.97023104181;30437.543436848024;30494.237644059074;30577.775119736598;30855.382892342477;50857.49142508676;51781;52144.21783673172;52295.47704523058;52398.21402678887;506636;506792.4660376675;508825;509018;509479;24096;26892.462150756484;27554.724452755217;27948.720512795426;28809.42381152407;46242.30636540522;46546;46940.06119877515;47058.76470617625;48185.75907120442;31599.526007110373;37697;37856;37867.242655147296;38656;33777.32445351128;34255.65744342525;37177.814110930354;38058.32594378582;38551.61448385593;36772.03008115554;36960.89117326485;37666;37811;37981.240375192894;32205.033848984567;32801.83599081989;32901;32986.83506582452;33171.50242746312;24121;24444;24460.388490287885;25080.37299067538;25358.112466064336 07:36:10 INFO - PROCESS | 1663 | |2;jquery.html;387.18883938149577;396.1561873210062;396.6271704597679;397.2908358579934;419.2725621047482;13735.58793236202;19569.608607827835;20572.691409628867;20766.27318043869;20773.584528309424;16012.759808602852;17500.82499175011;19927.900360498214;20716.689249661267;20807.27174548891;5696.943030569693;5882.735276912528;5891.469767720904;5930.258717660293;5950.970245148768;5712.028955077626;5798.507126894219;5802.912956305659;5846.327672317674;5872.17789509469;5634.3520495143175;5762.7406766695385;5764.365919748824;5799.043157878945;5816.854578635525;5632.295963004604;5753.856153596173;5787.971060144714;5991.4907232884825;5993.820185394402;5786.913196302059;5786.942130578683;5799.768009279624;5807.680577568232;5816.389279125716;18306.718529702925;18852.905735471373;21351.67972480414;22263.44341391462;25270.49459010828;2039.9082041308177;2155.471909382196;2182.9563408731897;2248.190651365507;2268.383963052912;8192.426530142893;9835.360701554448;9867.60529578816;10123.645672401504;13483.865161348458;1640.1307307127242;1643.539808853523;1648.7691723158766;1682.856957158643;1708.1373906177425;99125.76327207188;102275;103475.44786828203;110905.44547277293;111276.443617783;11074.723131921766;11182.944085279521;11370.31778093317;11481.598144065085;11629.59296424621;14730.410783568752;14797.704045919021;14805.333759980827;14805.407783688746;14815.777633355083;6165.691715414211;7392.593407362539;7952.960235198902;8538.146185381413;8686.91313086861;8275.08974012858;8322.70870519529;8343.541105239148;8463.619137138843;8467.703630372907;4498.482674492475;4548.090381923563;4572.88567785808;4604.779733370659;4607.539246075433;1658.0051968818852;1911.1686416408907;1957.3932081054918;1964.7838737738837;1994.1425187169657;915.6474757218518;930.7068273493828;954.4225743425267;968.7626531499686;1067.5516283160935;18692.532686682942;19554.413367598994;19904.004799759954;20143.899280503505;20441.48896277605;881.850085485469;954.9400165815939;970.9805803883883;981.2582665768318;1010.242318261304;7904.920950790533;10544.57821687124;10559.260851740459;10591.364518128938;10599.417032063157;549612;555781.2210939;575200;577754;579477;118375.40812296055;120310;123154.38422807828;126505;130974.34512827566;997.3218211616025;998.3859926145368;1019.0624625344701;1024.3137098144261;1036.7356324137297;17360.131993400028;17755.023473708552;17768.733468997718;17780.110994449966;17800.3769868054;46216.768916155204;47663;48351;48886.5111348882;49253;68999;69616.6519167401;69934.65032674804;70269.64865175642;70545.29454705592;7330.6701198445135;7740.7677769667;8079.87880181786;8293.33653307722;8324.583770811314;8929.73210803677;11491.195616307112;12029.518819247132;12508.312042837368;12660.493580257054;10607.469626518488;10654.254202205771;10716.51775670081;10911.072558832588;10955.178361622751;7630.694772209049;10641.787164256517;10698.62554810578;10873.782524349626;11090.001899829054;10656.946715266375;10973.615923442641;10984.725381865197;11014.284071535325;11150.219484636322;7993.240642138988;8066.435349525477;8100.108988011432;8423.452475589067;8425;10310.32982856112;10749.677509674722;11014.944925275642;11106.970920960252;11108.944650258212 07:36:10 INFO - PROCESS | 1663 | |3;mootools.html;1427.1009264163577;1601.1994002998504;1628.0801347238812;1874.1940965384879;1987.1852540458415;7183.102112235971;8393.286570641496;8598.269147122486;8603.65585376585;8788.121187881208;7319.938608901706;8388.874166887486;8624.698135565257;8638.265747411462;8642.913570864304;1884.4346695991217;2093.3615247349553;2165.1880544795704;2165.263810304496;2420.2860156253946;1976.5849171673894;2101.8003289687454;2280.4982903761147;2362.0551779288335;2378.63131214662;1999.4301624037153;2374.2877136858874;2400.3039118655656;2400.627902675088;2401.435662619283;2082.1046949811575;2386.5226954609147;2396.5566370221477;2396.6524853896262;2396.916107936231;2342.10999820069;2447.902083916641;2449.963250551243;2454.4722884579724;2463.5811911975006;8281.627326770309;8365.456245344034;8466.449680770791;8477.10990346011;8487.66049358025;5129;5293.179557168645;5303.814366497193;5329.173978033411;5338.733063346856;2993.685663005375;3316.817575033372;3445.414279572454;3516.6988214360595;3529.2059286660296;1010.3887148275335;1028.203142564511;1030.1192480429274;1032.3238278927315;1035.8705161854768;11036.889631103666;11232.932871377288;11232.943835280854;11252.718682032933;11261.774764504746;4662.440507139165;4706.623470122382;4791.832285870048;4819.614430845594;4823.758812059383;9828.21374290055;9832.360896541702;9834.114929656222;9839.360441571274;9855.45794981283;5214.296070030573;5332.5467335276935;5338.012467693489;5342.866428339323;5343.572514198855;3835.1822503961644;4636.79134438951;4693.95306046942;4708.411448568929;4738.147133515931;3933.9606603934167;3967.285888540033;4006.1386801837743;4049.0484736086996;4055.8986025349604;1304.243538747524;1328.8538260791306;1333.6599167212298;1342.7046049869014;1357;817.8814071959532;829.5478963964654;916.2395211974178;953.6471505542855;955.1737746848926;5644.068728659725;5812.88374232513;5910.143029260733;5935.6735379553675;5967.522598192134;463.31892118585625;467.28272102322774;468.0265048698698;477.07209477566494;477.8112645505006;3820.5033345665443;3877.1664092219744;3952.940705889414;3965.2862484752445;4037.051292946155;9891.703248902544;10329.276950613536;10341.638042668621;10437.582496700199;10457.163426925828;10235.130013948898;10274.589016439408;10366.274360794672;10400.167986561057;10403.895961040444;6690.397864192246;7949.324307433817;8036.4776289541;8040.2361775631225;8050;5942.940570594239;5955;6095.542834287535;6111.633302001894;6166.198394208904;9219.308551858503;9405.106514881076;9688.806223875343;9743.269254805777;9863.556139973574;9717.80564388694;9861.556229969525;9947.104760571583;9991.350562213433;10114.342567733074;7198.820029499305;7711.0746710395115;8070.636821342936;8081.87877181831;8084.797880053045;8056.033276006917;8074.434789564674;8146.714864979697;8228.819362868964;8232.547209903512;7137.321954414322;7252.528585641917;7330.340269375569;7393.149787774475;7427.22014188524;7008.369246767816;7175.390091842043;7187.712491500281;7261.636918154177;7315.926840731525;6885.586864788129;6920.273371295942;6983.790486285419;6984.965075174795;7006.544574602634;4696.361910158673;4888.755562221803;4901.264810278487;4990.026944745885;5069.797208111634;4917.311576379238;4925.605951523941;4999.77501012448;5109.8722531937;5240.502152295526 07:36:10 INFO - PROCESS | 1663 | |4;prototype.html;305.9189314831569;309.93336432666973;321.84390570573277;322.86601060559866;325.4613614468055;4843.515648435155;4855.514448555147;5288.668139072383;5318.680879147254;5336.973315133424;3880.8059597020106;4358.6513078953685;4548.817307500049;5141.254518094884;5221.530062294392;3982.1438390745984;4012.739171953828;4025.939610905839;4030.3148464760916;4032.8021228430575;3166.7941583797096;3973.5827738087373;4044.5146582410152;4049.6152865477734;4061;3847.769133851964;3979.701522385818;3986;4003.1993601279714;4008.879733607982;3518.768431049112;3918.490596222487;3964.2864284428792;3972.6226008529143;3973.980130099331;3395.6434574369828;3467.9653203467897;3495.405781017233;3504.667056629616;3505.7554568128458;4386.868393948187;4816.927746083811;5021.5229553192385;5044.4451110378195;5050.924236136461;4075.6142911409966;4103.9179216415805;4473.552644735533;4518.706284091556;4544.9319409938635;5428.375736790241;5433.381625618233;5456.172182318928;5462.781488740446;5503.6147469677135;3709.8516059357594;3848.614498780437;3857.633524815138;3867.916983244696;3884.941725874114;4361.629261512743;5220.4779522047875;5527.778888844482;5550.392245040715;5683.2895888014;3697.297513472485;3748.438982966193;3749.3251214781603;3767.9246415072093;3780.3384407729086;3061.755059595227;3502.964970350315;3929.685625149981;3940.7241493095207;3969.2458432897656;3745.5318085239346;4067.2678917794487;4121.464209652726;4157.854475093357;4484.273554681471;4850.442199147073;5134.948650513447;5458.945410545924;5462.481064298885;5505.697186654773;1242.2608547913976;1258.534342293339;1276.074845736852;1285.2481298440516;1285.7557064157968;816.5386556595505;839.4459656626597;856.674463703801;894.0255121917141;900.8738776571286;4178.310578754472;4615.353850460938;4796.880077998008;4831.95168048315;4869.829555965524;5346.861255497832;5363.812266570651;5368.651037682616;5373.259637584782;5391.514763671288;3916.980415097906;4279.379489973936;4340.848070317524;4754;4864.294677271776;2463.322586288785;2653.5223659741046;2697.5683890577807;2815.3254408312187;2824.209221418006;5066.569341606005;5332;5612.971935140298;5631.915521267184;5638.774449022075;4684.664870511956;4815.831445899376;5087.778933056114;5385.542228910508;5514.972425137928;5196.818111366084;5424.620276580601;5848.298204215522;5867.501262392574;5889.882202355929;5645.322561292671;5650.971745141248;5674.801381951612;5677.801276955287;5691.666416769105;4811.735354555533;5191.558717509054;5543.307086614173;5589.692566908697;5628.943710562842;4588.77056147198;4767.070421267958;5529.695866727368;5703.515201207943;5869.94130058711;3278.5246139310143;3344.5317655528734;3828.9042773930873;3830.1765120499226;3870.1872606752895;4350.673699472489;4744.217204161413;5669.971650141723;5674.467394290698;5679.233303504139;4625.444946606429;5072.365954255718;5199.66202196856;5488.286522752017;5498;4861.465238823797;5058.443571207238;5192.844214673566;5449.64577302474;5458.317710286215;4619.745913974763;4913.297686272607;5087.389513258285;5385.165299378603;5419.810306639249;4759.7858096386435;5022.488755622189;5270.78916843322;5342.332208473941;5477.75350109238;5335.253064570886;5373.731313434391;5381.569474441957;5406.432324605859;5410.513053825174 07:36:10 INFO - PROCESS | 1663 | |5;yui.html;650.3886346833975;744.7802898145046;753.111328632214;755.3579457461159;758.7534051433286;13625.433075196343;14455.349509272082;16860.325586976538;17643.55891102723;17644.206010729515;16435.58911027225;16484.83515164848;16544;16555.66888662226;16563.503094907173;732.3957734868728;935.2611436964806;948.9893263674165;949.8052899155656;954.6372378496196;750.5796753817853;908.3323757038571;939.567798812547;941.1670671455777;943.075785729985;982.3663736889702;988.6737376665683;989.0356902020544;989.1740396768706;990.0792263195226;762.3900879296596;952.476138124029;982.4400091947592;983.7983213441228;984.9803003939917;735.0848193998506;735.7535225699397;736.7458226911707;736.9410447164214;738.8817789153763;15506.224688765516;15609;15610.843891561053;15633.765493517607;15644.296006679613;10582.9470852646;12054.517819287217;12078.335691536962;12154.939225303906;12413.896376160563;10927.945360273148;11004.229703920728;11538.884611153864;11588.420578971101;11803.940980295129;4002.2595819773437;4140.482439695038;4142.482189726285;4155.065110350147;4268.850590229327;83893;86886.13113868843;87553;87657;88187;7751.302382785577;7789.1821358756515;7792.454528182972;7796.532208067534;7893.250141236574;19343.516412089528;19379.51551211231;19388.12753426099;19417;19430.319938802073;7224.349808517259;7266.927330726625;7282.198958114604;7288.198298187195;7318.3047610476915;4016.859409920639;4051.3517837146505;4064;4087.3868919662286;4264.211120942574;3477.443609022544;3567.6253993330847;3575.1777091269155;3591.3715099857423;3591.3894637911094;541.4481004609772;612.2133059019228;615.3077787489075;634.0869148426251;658.6212927566669;477.5081665884145;528.7118520406387;546.9972650136724;584.7544031506692;589.9675517846474;7285.927140728525;7551.395888328921;7775.989121414293;8285.42002059862;8336.291415229773;476.1762151477893;504.5282660712246;542.1596525385637;594.6759016336107;622.0793226025507;3817.7518461300483;3847.8076096195077;3921.6078392160557;3972.56301806801;4004.439378487014;74113.62943185249;76352;80904;82857.58571207106;83368;71745.20636984013;73322.25067768924;75103.62448187724;76464.61767691126;76498.61750691211;67450.66274668595;68434.65782671055;68613.65693171503;69467.95798063034;69914;7899.973003509507;7932.167122452291;8005.759827205193;8080.676772929017;8271.669133234844;44039.339409908236;44224.658050378035;45011.32483012692;45199.87000324886;46004.07991840078;57419.425805741404;58003.41996580149;60078.69960650169;62198.378016219256;62536.37463625488;9592.23262139041;10570.630027949292;10811.296995862616;11088.223824332543;11099.112071034458;9356.95321523388;11614.419279036183;11857.822132667845;12079.39603019863;12621.053420993645;9328.860067098864;10783.191260655325;11165.106791456501;11266.098712103176;11714;11589.536418543501;11787.705307367041;11804.527818887149;11914.106442017057;11977.64067077989;11087.778244434905;11123.054540364159;11240.88759112431;11451.648868957724;11622.186446948632;8966.820663586823;9209.493477858781;9247.953760231156;9439;9930.205583553154;7831.216878312123;8843.380963332505;9101.453912765255;9142.725718228463;9703.805923881624 07:36:10 INFO - PROCESS | 1663 | __end_tp_report 07:36:10 INFO - PROCESS | 1663 | __start_cc_report 07:36:10 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1290 07:36:10 INFO - PROCESS | 1663 | __end_cc_report 07:36:10 INFO - PROCESS | 1663 | __startTimestamp1462804570646__endTimestamp 07:36:10 INFO - PROCESS | 1663 | 07:36:10 INFO - PROCESS | 1663 | ------- Summary: start ------- 07:36:10 INFO - PROCESS | 1663 | Number of tests: 6 07:36:10 INFO - PROCESS | 1663 | 07:36:10 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5700.65 Median:5901.35 stddev:639.63 (10.8%) stddev-sans-first:615.92 07:36:10 INFO - PROCESS | 1663 | Values: 3413.8 3421.6 3426.7 3445.1 3493.9 5584.2 5588.9 5632.1 5686.0 5732.6 4921.5 5502.2 5658.5 5749.1 5775.8 5095.3 5116.6 5168.3 5183.6 5217.9 4357.9 5274.8 5307.7 5329.3 5362.2 5128.2 5184.0 5196.7 5230.0 5248.9 5134.3 5163.1 5174.8 5192.8 5225.8 5131.6 5137.1 5156.1 5184.6 5243.2 5654.6 5678.2 5690.1 5691.1 5701.4 6051.0 6067.8 6116.9 6163.3 6216.2 5219.3 6290.1 6291.9 6302.3 6308.8 5819.9 5831.9 5867.3 5894.1 5896.4 6194.1 6233.3 6262.2 6295.2 6297.6 6151.8 6155.2 6155.9 6158.9 6164.9 6204.5 6249.3 6265.2 6276.6 6285.5 6076.2 6102.5 6103.8 6162.2 6176.0 6189.4 6236.8 6244.2 6253.5 6256.0 4945.2 4951.7 4952.2 4956.0 4990.3 4358.0 4420.0 4427.5 4446.8 4480.4 4289.5 5582.2 5621.9 5659.4 5841.5 6193.1 6249.0 6271.2 6272.5 6279.5 5652.7 5660.1 5667.7 5682.7 5687.8 5268.4 5322.5 5334.0 5349.4 5380.1 6216.5 6308.8 6311.2 6312.8 6324.0 6154.2 6256.4 6257.5 6270.0 6278.1 6211.3 6243.8 6261.5 6293.7 6294.6 6061.5 6080.4 6089.1 6115.3 6137.1 6132.7 6166.0 6172.6 6179.7 6214.9 5893.4 5934.9 5962.8 5964.0 5987.9 6032.2 6068.2 6074.9 6087.1 6118.4 5783.0 5871.4 5901.4 5904.5 5924.3 5823.4 5980.5 6018.4 6046.2 6050.1 5883.6 5897.3 5909.3 5930.7 5942.4 07:36:10 INFO - PROCESS | 1663 | 07:36:10 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:68638.09 Median:18846.95 stddev:150206.82 (797.0%) stddev-sans-first:150541.11 07:36:10 INFO - PROCESS | 1663 | Values: 515.9 530.1 540.0 553.9 587.8 28500.3 32250.8 32295.4 32372.0 43739.8 34606.0 45239.5 45341.5 45489.1 45660.3 9599.2 9815.5 9823.5 9826.3 9832.2 8636.6 8791.1 9618.7 9728.4 11517.4 11417.8 11695.7 11697.8 11699.1 11701.9 11598.1 11655.5 11659.2 11668.0 11674.0 8641.9 11373.4 11614.8 11622.8 11781.8 41163.6 41452.8 41587.8 41646.0 41759.6 3314.9 3361.0 3438.2 3593.2 3720.8 26694.7 27833.2 29008.6 29374.6 29523.7 2681.0 2691.2 2698.8 2718.8 2812.3 482715.0 489367.0 494596.0 495201.0 495322.0 65731.0 66050.7 66062.3 66099.7 66312.7 37531.8 38459.2 38485.2 39528.8 39566.8 17046.2 17064.1 17102.7 17109.7 17124.5 11809.8 12962.9 13060.7 13074.2 13091.5 17896.7 20366.0 20849.8 20862.0 21411.6 8044.1 8067.3 8068.0 8072.5 8127.1 860.9 867.3 916.7 1030.9 1100.3 29320.9 29567.0 29771.9 30522.0 30557.7 1116.0 1142.8 1166.3 1208.1 1227.0 12778.6 13278.7 15193.1 15281.2 15557.3 661437.0 666359.0 667961.0 672864.0 676264.0 50685.2 61668.7 61887.0 61891.0 61950.0 1247.5 1248.5 1365.4 1366.0 1464.2 29422.0 30437.5 30494.2 30577.8 30855.4 50857.5 51781.0 52144.2 52295.5 52398.2 506636.0 506792.5 508825.0 509018.0 509479.0 24096.0 26892.5 27554.7 27948.7 28809.4 46242.3 46546.0 46940.1 47058.8 48185.8 31599.5 37697.0 37856.0 37867.2 38656.0 33777.3 34255.7 37177.8 38058.3 38551.6 36772.0 36960.9 37666.0 37811.0 37981.2 32205.0 32801.8 32901.0 32986.8 33171.5 24121.0 24444.0 24460.4 25080.4 25358.1 07:36:10 INFO - PROCESS | 1663 | 07:36:10 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:32979.90 Median:2258.29 stddev:94593.06 (4188.7%) stddev-sans-first:94826.76 07:36:10 INFO - PROCESS | 1663 | Values: 387.2 396.2 396.6 397.3 419.3 13735.6 19569.6 20572.7 20766.3 20773.6 16012.8 17500.8 19927.9 20716.7 20807.3 5696.9 5882.7 5891.5 5930.3 5951.0 5712.0 5798.5 5802.9 5846.3 5872.2 5634.4 5762.7 5764.4 5799.0 5816.9 5632.3 5753.9 5788.0 5991.5 5993.8 5786.9 5786.9 5799.8 5807.7 5816.4 18306.7 18852.9 21351.7 22263.4 25270.5 2039.9 2155.5 2183.0 2248.2 2268.4 8192.4 9835.4 9867.6 10123.6 13483.9 1640.1 1643.5 1648.8 1682.9 1708.1 99125.8 102275.0 103475.4 110905.4 111276.4 11074.7 11182.9 11370.3 11481.6 11629.6 14730.4 14797.7 14805.3 14805.4 14815.8 6165.7 7392.6 7953.0 8538.1 8686.9 8275.1 8322.7 8343.5 8463.6 8467.7 4498.5 4548.1 4572.9 4604.8 4607.5 1658.0 1911.2 1957.4 1964.8 1994.1 915.6 930.7 954.4 968.8 1067.6 18692.5 19554.4 19904.0 20143.9 20441.5 881.9 954.9 971.0 981.3 1010.2 7904.9 10544.6 10559.3 10591.4 10599.4 549612.0 555781.2 575200.0 577754.0 579477.0 118375.4 120310.0 123154.4 126505.0 130974.3 997.3 998.4 1019.1 1024.3 1036.7 17360.1 17755.0 17768.7 17780.1 17800.4 46216.8 47663.0 48351.0 48886.5 49253.0 68999.0 69616.7 69934.7 70269.6 70545.3 7330.7 7740.8 8079.9 8293.3 8324.6 8929.7 11491.2 12029.5 12508.3 12660.5 10607.5 10654.3 10716.5 10911.1 10955.2 7630.7 10641.8 10698.6 10873.8 11090.0 10656.9 10973.6 10984.7 11014.3 11150.2 7993.2 8066.4 8100.1 8423.5 8425.0 10310.3 10749.7 11014.9 11107.0 11108.9 07:36:10 INFO - PROCESS | 1663 | 07:36:10 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5592.09 Median:4994.90 stddev:3113.72 (62.3%) stddev-sans-first:3106.72 07:36:10 INFO - PROCESS | 1663 | Values: 1427.1 1601.2 1628.1 1874.2 1987.2 7183.1 8393.3 8598.3 8603.7 8788.1 7319.9 8388.9 8624.7 8638.3 8642.9 1884.4 2093.4 2165.2 2165.3 2420.3 1976.6 2101.8 2280.5 2362.1 2378.6 1999.4 2374.3 2400.3 2400.6 2401.4 2082.1 2386.5 2396.6 2396.7 2396.9 2342.1 2447.9 2450.0 2454.5 2463.6 8281.6 8365.5 8466.4 8477.1 8487.7 5129.0 5293.2 5303.8 5329.2 5338.7 2993.7 3316.8 3445.4 3516.7 3529.2 1010.4 1028.2 1030.1 1032.3 1035.9 11036.9 11232.9 11232.9 11252.7 11261.8 4662.4 4706.6 4791.8 4819.6 4823.8 9828.2 9832.4 9834.1 9839.4 9855.5 5214.3 5332.5 5338.0 5342.9 5343.6 3835.2 4636.8 4694.0 4708.4 4738.1 3934.0 3967.3 4006.1 4049.0 4055.9 1304.2 1328.9 1333.7 1342.7 1357.0 817.9 829.5 916.2 953.6 955.2 5644.1 5812.9 5910.1 5935.7 5967.5 463.3 467.3 468.0 477.1 477.8 3820.5 3877.2 3952.9 3965.3 4037.1 9891.7 10329.3 10341.6 10437.6 10457.2 10235.1 10274.6 10366.3 10400.2 10403.9 6690.4 7949.3 8036.5 8040.2 8050.0 5942.9 5955.0 6095.5 6111.6 6166.2 9219.3 9405.1 9688.8 9743.3 9863.6 9717.8 9861.6 9947.1 9991.4 10114.3 7198.8 7711.1 8070.6 8081.9 8084.8 8056.0 8074.4 8146.7 8228.8 8232.5 7137.3 7252.5 7330.3 7393.1 7427.2 7008.4 7175.4 7187.7 7261.6 7315.9 6885.6 6920.3 6983.8 6985.0 7006.5 4696.4 4888.8 4901.3 4990.0 5069.8 4917.3 4925.6 4999.8 5109.9 5240.5 07:36:10 INFO - PROCESS | 1663 | 07:36:10 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4347.89 Median:4813.78 stddev:1326.02 (27.5%) stddev-sans-first:1294.57 07:36:10 INFO - PROCESS | 1663 | Values: 305.9 309.9 321.8 322.9 325.5 4843.5 4855.5 5288.7 5318.7 5337.0 3880.8 4358.7 4548.8 5141.3 5221.5 3982.1 4012.7 4025.9 4030.3 4032.8 3166.8 3973.6 4044.5 4049.6 4061.0 3847.8 3979.7 3986.0 4003.2 4008.9 3518.8 3918.5 3964.3 3972.6 3974.0 3395.6 3468.0 3495.4 3504.7 3505.8 4386.9 4816.9 5021.5 5044.4 5050.9 4075.6 4103.9 4473.6 4518.7 4544.9 5428.4 5433.4 5456.2 5462.8 5503.6 3709.9 3848.6 3857.6 3867.9 3884.9 4361.6 5220.5 5527.8 5550.4 5683.3 3697.3 3748.4 3749.3 3767.9 3780.3 3061.8 3503.0 3929.7 3940.7 3969.2 3745.5 4067.3 4121.5 4157.9 4484.3 4850.4 5134.9 5458.9 5462.5 5505.7 1242.3 1258.5 1276.1 1285.2 1285.8 816.5 839.4 856.7 894.0 900.9 4178.3 4615.4 4796.9 4832.0 4869.8 5346.9 5363.8 5368.7 5373.3 5391.5 3917.0 4279.4 4340.8 4754.0 4864.3 2463.3 2653.5 2697.6 2815.3 2824.2 5066.6 5332.0 5613.0 5631.9 5638.8 4684.7 4815.8 5087.8 5385.5 5515.0 5196.8 5424.6 5848.3 5867.5 5889.9 5645.3 5651.0 5674.8 5677.8 5691.7 4811.7 5191.6 5543.3 5589.7 5628.9 4588.8 4767.1 5529.7 5703.5 5869.9 3278.5 3344.5 3828.9 3830.2 3870.2 4350.7 4744.2 5670.0 5674.5 5679.2 4625.4 5072.4 5199.7 5488.3 5498.0 4861.5 5058.4 5192.8 5449.6 5458.3 4619.7 4913.3 5087.4 5385.2 5419.8 4759.8 5022.5 5270.8 5342.3 5477.8 5335.3 5373.7 5381.6 5406.4 5410.5 07:36:10 INFO - PROCESS | 1663 | 07:36:10 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:17595.90 Median:613.76 stddev:24331.41 (3964.3%) stddev-sans-first:24366.40 07:36:10 INFO - PROCESS | 1663 | Values: 650.4 744.8 753.1 755.4 758.8 13625.4 14455.3 16860.3 17643.6 17644.2 16435.6 16484.8 16544.0 16555.7 16563.5 732.4 935.3 949.0 949.8 954.6 750.6 908.3 939.6 941.2 943.1 982.4 988.7 989.0 989.2 990.1 762.4 952.5 982.4 983.8 985.0 735.1 735.8 736.7 736.9 738.9 15506.2 15609.0 15610.8 15633.8 15644.3 10582.9 12054.5 12078.3 12154.9 12413.9 10927.9 11004.2 11538.9 11588.4 11803.9 4002.3 4140.5 4142.5 4155.1 4268.9 83893.0 86886.1 87553.0 87657.0 88187.0 7751.3 7789.2 7792.5 7796.5 7893.3 19343.5 19379.5 19388.1 19417.0 19430.3 7224.3 7266.9 7282.2 7288.2 7318.3 4016.9 4051.4 4064.0 4087.4 4264.2 3477.4 3567.6 3575.2 3591.4 3591.4 541.4 612.2 615.3 634.1 658.6 477.5 528.7 547.0 584.8 590.0 7285.9 7551.4 7776.0 8285.4 8336.3 476.2 504.5 542.2 594.7 622.1 3817.8 3847.8 3921.6 3972.6 4004.4 74113.6 76352.0 80904.0 82857.6 83368.0 71745.2 73322.3 75103.6 76464.6 76498.6 67450.7 68434.7 68613.7 69468.0 69914.0 7900.0 7932.2 8005.8 8080.7 8271.7 44039.3 44224.7 45011.3 45199.9 46004.1 57419.4 58003.4 60078.7 62198.4 62536.4 9592.2 10570.6 10811.3 11088.2 11099.1 9357.0 11614.4 11857.8 12079.4 12621.1 9328.9 10783.2 11165.1 11266.1 11714.0 11589.5 11787.7 11804.5 11914.1 11977.6 11087.8 11123.1 11240.9 11451.6 11622.2 8966.8 9209.5 9248.0 9439.0 9930.2 7831.2 8843.4 9101.5 9142.7 9703.8 07:36:10 INFO - PROCESS | 1663 | -------- Summary: end -------- 07:36:10 INFO - PROCESS | 1663 | 07:36:11 INFO - TEST-INFO | 1663: exit 0 07:36:11 INFO - TEST-OK | dromaeo_css | took 1115600ms 07:36:11 INFO - TEST-START | kraken 07:36:11 INFO - operating with platform_type : mac_ 07:36:11 INFO - Initialising browser for kraken test... 07:36:11 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpZuibwj/profile http://localhost:49273/getInfo.html) 07:36:13 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 07:36:13 INFO - PROCESS | 1674 | colorDepth:24 07:36:13 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 07:36:13 INFO - PROCESS | 1674 | __metrics 07:36:13 INFO - PROCESS | 1674 | 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" 07:36:13 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:36:14 INFO - TEST-INFO | 1674: exit 0 07:36:14 INFO - Browser initialized. 07:36:14 INFO - Running cycle 1/1 for kraken test... 07:36:14 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpZuibwj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:36:26 INFO - PROCESS | 1676 | RSS: Main: 239005696 07:36:26 INFO - PROCESS | 1676 | 07:36:29 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 07:36:30 INFO - PROCESS | 1676 | RSS: Main: 287010816 07:36:30 INFO - PROCESS | 1676 | 07:36:32 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 07:36:33 INFO - PROCESS | 1676 | RSS: Main: 296075264 07:36:33 INFO - PROCESS | 1676 | 07:36:36 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 07:36:37 INFO - PROCESS | 1676 | RSS: Main: 385253376 07:36:37 INFO - PROCESS | 1676 | 07:36:39 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 07:36:40 INFO - PROCESS | 1676 | RSS: Main: 285237248 07:36:40 INFO - PROCESS | 1676 | 07:36:42 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:36:43 INFO - PROCESS | 1676 | RSS: Main: 288862208 07:36:43 INFO - PROCESS | 1676 | 07:36:45 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 07:36:46 INFO - PROCESS | 1676 | RSS: Main: 327495680 07:36:46 INFO - PROCESS | 1676 | 07:36:49 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 07:36:50 INFO - PROCESS | 1676 | RSS: Main: 328343552 07:36:50 INFO - PROCESS | 1676 | 07:36:53 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 07:36:53 INFO - PROCESS | 1676 | RSS: Main: 327573504 07:36:53 INFO - PROCESS | 1676 | 07:36:55 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:36:56 INFO - PROCESS | 1676 | RSS: Main: 316624896 07:36:56 INFO - PROCESS | 1676 | 07:36:58 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:36:58 INFO - PROCESS | 1676 | RSS: Main: 323465216 07:36:58 INFO - PROCESS | 1676 | 07:37:01 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:37:01 INFO - PROCESS | 1676 | RSS: Main: 325242880 07:37:01 INFO - PROCESS | 1676 | 07:37:04 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:37:05 INFO - PROCESS | 1676 | RSS: Main: 329981952 07:37:05 INFO - PROCESS | 1676 | 07:37:08 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:37:09 INFO - PROCESS | 1676 | RSS: Main: 327618560 07:37:09 INFO - PROCESS | 1676 | 07:37:10 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 07:37:11 INFO - PROCESS | 1676 | RSS: Main: 374296576 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | __start_tp_report 07:37:11 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 07:37:11 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 07:37:11 INFO - PROCESS | 1676 | |i|pagename|runs| 07:37:11 INFO - PROCESS | 1676 | |0;ai-astar;113;112;163;163;109;116;157;165;108;111 07:37:11 INFO - PROCESS | 1676 | |1;audio-beat-detection;155;121;144;199;123;143;137;120;171;120 07:37:11 INFO - PROCESS | 1676 | |2;audio-dft;186;186;183;184;183;181;187;186;192;179 07:37:11 INFO - PROCESS | 1676 | |3;audio-fft;81;73;57;125;61;65;57;62;108;56 07:37:11 INFO - PROCESS | 1676 | |4;audio-oscillator;93;92;134;80;79;77;102;80;80;101 07:37:11 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;86;84;85;84;86;84;87;84;86;83 07:37:11 INFO - PROCESS | 1676 | |6;imaging-darkroom;92;93;92;93;93;93;94;93;93;94 07:37:11 INFO - PROCESS | 1676 | |7;imaging-desaturate;85;85;87;87;86;85;86;83;87;86 07:37:11 INFO - PROCESS | 1676 | |8;json-parse-financial;61;62;60;60;60;60;58;119;57;57 07:37:11 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;65;71;71;76;71;73;73;69;69;70 07:37:11 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;84;88;74;99;97;105;105;106;85;86 07:37:11 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;141;140;146;149;143;138;142;155;210;140 07:37:11 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;172;169;174;166;169;167;168;169;167;178 07:37:11 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;67;68;67;69;81;86;87;85;73;66 07:37:11 INFO - PROCESS | 1676 | __end_tp_report 07:37:11 INFO - PROCESS | 1676 | __start_cc_report 07:37:11 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2216 07:37:11 INFO - PROCESS | 1676 | __end_cc_report 07:37:11 INFO - PROCESS | 1676 | __startTimestamp1462804631144__endTimestamp 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | ------- Summary: start ------- 07:37:11 INFO - PROCESS | 1676 | Number of tests: 14 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:131.70 Median:136.50 stddev:26.24 (19.2%) stddev-sans-first:26.95 07:37:11 INFO - PROCESS | 1676 | Values: 113.0 112.0 163.0 163.0 109.0 116.0 157.0 165.0 108.0 111.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:143.50 stddev:25.91 (18.1%) stddev-sans-first:27.13 07:37:11 INFO - PROCESS | 1676 | Values: 155.0 121.0 144.0 199.0 123.0 143.0 137.0 120.0 171.0 120.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:184.70 Median:186.00 stddev:3.59 (1.9%) stddev-sans-first:3.78 07:37:11 INFO - PROCESS | 1676 | Values: 186.0 186.0 183.0 184.0 183.0 181.0 187.0 186.0 192.0 179.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:74.50 Median:61.50 stddev:23.81 (38.7%) stddev-sans-first:25.13 07:37:11 INFO - PROCESS | 1676 | Values: 81.0 73.0 57.0 125.0 61.0 65.0 57.0 62.0 108.0 56.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:80.00 stddev:17.55 (21.9%) stddev-sans-first:18.61 07:37:11 INFO - PROCESS | 1676 | Values: 93.0 92.0 134.0 80.0 79.0 77.0 102.0 80.0 80.0 101.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:85.50 stddev:1.29 (1.5%) stddev-sans-first:1.30 07:37:11 INFO - PROCESS | 1676 | Values: 86.0 84.0 85.0 84.0 86.0 84.0 87.0 84.0 86.0 83.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 07:37:11 INFO - PROCESS | 1676 | Values: 92.0 93.0 92.0 93.0 93.0 93.0 94.0 93.0 93.0 94.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:1.25 (1.5%) stddev-sans-first:1.30 07:37:11 INFO - PROCESS | 1676 | Values: 85.0 85.0 87.0 87.0 86.0 85.0 86.0 83.0 87.0 86.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:65.40 Median:60.00 stddev:18.90 (31.5%) stddev-sans-first:19.98 07:37:11 INFO - PROCESS | 1676 | Values: 61.0 62.0 60.0 60.0 60.0 60.0 58.0 119.0 57.0 57.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:2.94 (4.1%) stddev-sans-first:2.24 07:37:11 INFO - PROCESS | 1676 | Values: 65.0 71.0 71.0 76.0 71.0 73.0 73.0 69.0 69.0 70.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:85.50 stddev:11.00 (12.9%) stddev-sans-first:11.19 07:37:11 INFO - PROCESS | 1676 | Values: 84.0 88.0 74.0 99.0 97.0 105.0 105.0 106.0 85.0 86.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:144.50 stddev:21.55 (14.9%) stddev-sans-first:22.58 07:37:11 INFO - PROCESS | 1676 | Values: 141.0 140.0 146.0 149.0 143.0 138.0 142.0 155.0 210.0 140.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:169.00 stddev:3.73 (2.2%) stddev-sans-first:3.87 07:37:11 INFO - PROCESS | 1676 | Values: 172.0 169.0 174.0 166.0 169.0 167.0 168.0 169.0 167.0 178.0 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:77.00 stddev:8.81 (11.4%) stddev-sans-first:8.87 07:37:11 INFO - PROCESS | 1676 | Values: 67.0 68.0 67.0 69.0 81.0 86.0 87.0 85.0 73.0 66.0 07:37:11 INFO - PROCESS | 1676 | -------- Summary: end -------- 07:37:11 INFO - PROCESS | 1676 | 07:37:11 INFO - TEST-INFO | 1676: exit 0 07:37:12 INFO - TEST-OK | kraken | took 60814ms 07:37:12 INFO - SUITE-END | took 1176s 07:37:12 INFO - Completed test suite (00:19:36) 07:37:12 INFO - javascript benchmark 07:37:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3413.8122403267826, 3421.6065152507463, 3426.725861931046, 3445.0870519312402, 3493.947590786139, 5584.21820945068, 5588.888222235553, 5632.07070833312, 5685.971570142148, 5732.627379220347, 4921.532454416824, 5502.2296878436955, 5658.547316214693, 5749.0514065179295, 5775.797847075342, 5095.286659867622, 5116.590672746172, 5168.302279192299, 5183.611229157809, 5217.86955326118, 4357.869263922072, 5274.762635681384, 5307.655002424849, 5329.333833270841, 5362.195670649414, 5128.230765385185, 5184.040952423814, 5196.714180720059, 5230.0, 5248.921266181011, 5134.255532947739, 5163.070647283487, 5174.818881339173, 5192.766325515361, 5225.817096401645, 5131.615128865313, 5137.101007323703, 5156.071907056728, 5184.559312458446, 5243.161094224905, 5654.575906807006, 5678.176664383683, 5690.06113991191, 5691.117876729114, 5701.4013528579735, 6051.001584738557, 6067.811449151365, 6116.883633902611, 6163.260408750979, 6216.222972128484, 5219.321488206547, 6290.119383286344, 6291.937080629181, 6302.338254483259, 6308.842278943017, 5819.88360232793, 5831.854203644901, 5867.325257595388, 5894.086416605433, 5896.439838215406, 6194.132821405007, 6233.252009758858, 6262.1546091278005, 6295.213098362705, 6297.590656607306, 6151.815445536641, 6155.199824022849, 6155.891939450852, 6158.907616385758, 6164.907526387108, 6204.472619827366, 6249.281332646713, 6265.216847894013, 6276.592021518587, 6285.528585356116, 6076.210092687922, 6102.549845102896, 6103.822585931194, 6162.168107305546, 6176.0, 6189.35011823761, 6236.812895613139, 6244.1570387997945, 6253.499720022387, 6255.968720156461, 4945.208766597327, 4951.653384263067, 4952.182889823139, 4955.98402327522, 4990.251462280615, 4357.997660538094, 4419.983403817076, 4427.512973572904, 4446.777661116931, 4480.39514665516, 4289.485261768607, 5582.190582365615, 5621.887562248732, 5659.377468478464, 5841.474267315877, 6193.1329613854095, 6249.0, 6271.153394291712, 6272.498200143978, 6279.497640188774, 5652.689102099424, 5660.066089095253, 5667.716614169275, 5682.715864206773, 5687.829365119053, 5268.446813084571, 5322.494363035506, 5334.0, 5349.384820745586, 5380.058489764276, 6216.533759968021, 6308.810735677937, 6311.179546658905, 6312.7790527332245, 6323.9683801580695, 6154.230721159855, 6256.405641464054, 6257.530685198537, 6270.0, 6278.121063051085, 6211.3167551570195, 6243.750249989949, 6261.530385221217, 6293.716782744879, 6294.590851594632, 6061.515078793598, 6080.3919608040615, 6089.117078023751, 6115.327313995546, 6137.1101190328045, 6132.693365331626, 6165.969170154121, 6172.629642221481, 6179.6910154493, 6214.937850621617, 5893.351731309467, 5934.881302374015, 5962.761489540543, 5963.970180149245, 5987.910181347196, 6032.245969253843, 6068.21113255274, 6074.939250607437, 6087.05650624154, 6118.418750218722, 5783.045797443543, 5871.442212989759, 5901.350851406428, 5904.498117660047, 5924.318703348998, 5823.417658234142, 5980.491658208927, 6018.36807135262, 6046.2139921809885, 6050.062240352752, 5883.61756485827, 5897.292324921037, 5909.2908850938165, 5930.703464826655, 5942.376050514807], "value": 5661.3865770095435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.9448926944399, 530.14646419265, 539.9919001214982, 553.8866877576073, 587.7913340764027, 28500.28749281269, 32250.838745806268, 32295.35409291813, 32372.0, 43739.78130109353, 34606.0, 45239.54760452395, 45341.546584534146, 45489.09021819562, 45660.315095273516, 9599.232061435068, 9815.50922453878, 9823.459709715962, 9826.263030272723, 9832.164266037395, 8636.611352489153, 8791.076936921627, 9618.711438656852, 9728.416295022285, 11517.36654484003, 11417.771644567103, 11695.7076073098, 11697.766044679101, 11699.06407487399, 11701.882981170165, 11598.072154227684, 11655.475503602358, 11659.183857130005, 11668.0, 11674.0, 8641.870371944426, 11373.431328433628, 11614.825777613343, 11622.767544649145, 11781.764364712744, 41163.58836411627, 41452.79273603613, 41587.79206103981, 41646.0, 41759.58240417617, 3314.867405303785, 3360.9831950840335, 3438.2435864109857, 3593.1735700788704, 3720.795356255405, 26694.73305266942, 27833.16500504968, 29008.564871526945, 29374.55938160929, 29523.70476295231, 2680.986595067032, 2691.232998595391, 2698.7571118599226, 2718.768904643108, 2812.268810109366, 482715.0, 489367.0, 494596.0, 495201.0, 495322.0, 65731.0, 66050.66974665193, 66062.33937660563, 66099.66950165218, 66312.66843665847, 37531.81234093867, 38459.23081538409, 38485.230295393936, 39528.80235598804, 39566.80216598956, 17046.23291951865, 17064.061476618655, 17102.657946841242, 17109.657806844043, 17124.48626541206, 11809.822852657217, 12962.935185324202, 13060.673483162807, 13074.215547067206, 13091.476340946447, 17896.731549026776, 20365.981700914894, 20849.79150208509, 20862.0, 21411.571768564543, 8044.115147333789, 8067.314278286294, 8067.959660201779, 8072.515649061075, 8127.1466496017065, 860.8966923969164, 867.2628265973998, 916.7020718266457, 1030.8762948446235, 1100.3178029621686, 29320.853395733313, 29567.0, 29771.85114074416, 30522.0, 30557.69442305593, 1116.0457808573553, 1142.8057230270874, 1166.2943918929095, 1208.1301462946665, 1227.0061250387214, 12778.61664150077, 13278.668033299246, 15193.088414695154, 15281.235938203044, 15557.29992150356, 661437.0, 666359.0, 667961.0, 672864.0, 676264.0, 50685.23972140421, 61668.69165654143, 61887.0, 61891.0, 61950.0, 1247.5134697467995, 1248.4881198708485, 1365.4401695304678, 1365.9686936362982, 1464.2459133546445, 29421.97023104181, 30437.543436848024, 30494.237644059074, 30577.775119736598, 30855.382892342477, 50857.49142508676, 51781.0, 52144.21783673172, 52295.47704523058, 52398.21402678887, 506636.0, 506792.4660376675, 508825.0, 509018.0, 509479.0, 24096.0, 26892.462150756484, 27554.724452755217, 27948.720512795426, 28809.42381152407, 46242.30636540522, 46546.0, 46940.06119877515, 47058.76470617625, 48185.75907120442, 31599.526007110373, 37697.0, 37856.0, 37867.242655147296, 38656.0, 33777.32445351128, 34255.65744342525, 37177.814110930354, 38058.32594378582, 38551.61448385593, 36772.03008115554, 36960.89117326485, 37666.0, 37811.0, 37981.240375192894, 32205.033848984567, 32801.83599081989, 32901.0, 32986.83506582452, 33171.50242746312, 24121.0, 24444.0, 24460.388490287885, 25080.37299067538, 25358.112466064336], "value": 19932.107544992952, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.18883938149577, 396.1561873210062, 396.6271704597679, 397.2908358579934, 419.2725621047482, 13735.58793236202, 19569.608607827835, 20572.691409628867, 20766.27318043869, 20773.584528309424, 16012.759808602852, 17500.82499175011, 19927.900360498214, 20716.689249661267, 20807.27174548891, 5696.943030569693, 5882.735276912528, 5891.469767720904, 5930.258717660293, 5950.970245148768, 5712.028955077626, 5798.507126894219, 5802.912956305659, 5846.327672317674, 5872.17789509469, 5634.3520495143175, 5762.7406766695385, 5764.365919748824, 5799.043157878945, 5816.854578635525, 5632.295963004604, 5753.856153596173, 5787.971060144714, 5991.4907232884825, 5993.820185394402, 5786.913196302059, 5786.942130578683, 5799.768009279624, 5807.680577568232, 5816.389279125716, 18306.718529702925, 18852.905735471373, 21351.67972480414, 22263.44341391462, 25270.49459010828, 2039.9082041308177, 2155.471909382196, 2182.9563408731897, 2248.190651365507, 2268.383963052912, 8192.426530142893, 9835.360701554448, 9867.60529578816, 10123.645672401504, 13483.865161348458, 1640.1307307127242, 1643.539808853523, 1648.7691723158766, 1682.856957158643, 1708.1373906177425, 99125.76327207188, 102275.0, 103475.44786828203, 110905.44547277293, 111276.443617783, 11074.723131921766, 11182.944085279521, 11370.31778093317, 11481.598144065085, 11629.59296424621, 14730.410783568752, 14797.704045919021, 14805.333759980827, 14805.407783688746, 14815.777633355083, 6165.691715414211, 7392.593407362539, 7952.960235198902, 8538.146185381413, 8686.91313086861, 8275.08974012858, 8322.70870519529, 8343.541105239148, 8463.619137138843, 8467.703630372907, 4498.482674492475, 4548.090381923563, 4572.88567785808, 4604.779733370659, 4607.539246075433, 1658.0051968818852, 1911.1686416408907, 1957.3932081054918, 1964.7838737738837, 1994.1425187169657, 915.6474757218518, 930.7068273493828, 954.4225743425267, 968.7626531499686, 1067.5516283160935, 18692.532686682942, 19554.413367598994, 19904.004799759954, 20143.899280503505, 20441.48896277605, 881.850085485469, 954.9400165815939, 970.9805803883883, 981.2582665768318, 1010.242318261304, 7904.920950790533, 10544.57821687124, 10559.260851740459, 10591.364518128938, 10599.417032063157, 549612.0, 555781.2210939, 575200.0, 577754.0, 579477.0, 118375.40812296055, 120310.0, 123154.38422807828, 126505.0, 130974.34512827566, 997.3218211616025, 998.3859926145368, 1019.0624625344701, 1024.3137098144261, 1036.7356324137297, 17360.131993400028, 17755.023473708552, 17768.733468997718, 17780.110994449966, 17800.3769868054, 46216.768916155204, 47663.0, 48351.0, 48886.5111348882, 49253.0, 68999.0, 69616.6519167401, 69934.65032674804, 70269.64865175642, 70545.29454705592, 7330.6701198445135, 7740.7677769667, 8079.87880181786, 8293.33653307722, 8324.583770811314, 8929.73210803677, 11491.195616307112, 12029.518819247132, 12508.312042837368, 12660.493580257054, 10607.469626518488, 10654.254202205771, 10716.51775670081, 10911.072558832588, 10955.178361622751, 7630.694772209049, 10641.787164256517, 10698.62554810578, 10873.782524349626, 11090.001899829054, 10656.946715266375, 10973.615923442641, 10984.725381865197, 11014.284071535325, 11150.219484636322, 7993.240642138988, 8066.435349525477, 8100.108988011432, 8423.452475589067, 8425.0, 10310.32982856112, 10749.677509674722, 11014.944925275642, 11106.970920960252, 11108.944650258212], "value": 9240.960419539095, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.1009264163577, 1601.1994002998504, 1628.0801347238812, 1874.1940965384879, 1987.1852540458415, 7183.102112235971, 8393.286570641496, 8598.269147122486, 8603.65585376585, 8788.121187881208, 7319.938608901706, 8388.874166887486, 8624.698135565257, 8638.265747411462, 8642.913570864304, 1884.4346695991217, 2093.3615247349553, 2165.1880544795704, 2165.263810304496, 2420.2860156253946, 1976.5849171673894, 2101.8003289687454, 2280.4982903761147, 2362.0551779288335, 2378.63131214662, 1999.4301624037153, 2374.2877136858874, 2400.3039118655656, 2400.627902675088, 2401.435662619283, 2082.1046949811575, 2386.5226954609147, 2396.5566370221477, 2396.6524853896262, 2396.916107936231, 2342.10999820069, 2447.902083916641, 2449.963250551243, 2454.4722884579724, 2463.5811911975006, 8281.627326770309, 8365.456245344034, 8466.449680770791, 8477.10990346011, 8487.66049358025, 5129.0, 5293.179557168645, 5303.814366497193, 5329.173978033411, 5338.733063346856, 2993.685663005375, 3316.817575033372, 3445.414279572454, 3516.6988214360595, 3529.2059286660296, 1010.3887148275335, 1028.203142564511, 1030.1192480429274, 1032.3238278927315, 1035.8705161854768, 11036.889631103666, 11232.932871377288, 11232.943835280854, 11252.718682032933, 11261.774764504746, 4662.440507139165, 4706.623470122382, 4791.832285870048, 4819.614430845594, 4823.758812059383, 9828.21374290055, 9832.360896541702, 9834.114929656222, 9839.360441571274, 9855.45794981283, 5214.296070030573, 5332.5467335276935, 5338.012467693489, 5342.866428339323, 5343.572514198855, 3835.1822503961644, 4636.79134438951, 4693.95306046942, 4708.411448568929, 4738.147133515931, 3933.9606603934167, 3967.285888540033, 4006.1386801837743, 4049.0484736086996, 4055.8986025349604, 1304.243538747524, 1328.8538260791306, 1333.6599167212298, 1342.7046049869014, 1357.0, 817.8814071959532, 829.5478963964654, 916.2395211974178, 953.6471505542855, 955.1737746848926, 5644.068728659725, 5812.88374232513, 5910.143029260733, 5935.6735379553675, 5967.522598192134, 463.31892118585625, 467.28272102322774, 468.0265048698698, 477.07209477566494, 477.8112645505006, 3820.5033345665443, 3877.1664092219744, 3952.940705889414, 3965.2862484752445, 4037.051292946155, 9891.703248902544, 10329.276950613536, 10341.638042668621, 10437.582496700199, 10457.163426925828, 10235.130013948898, 10274.589016439408, 10366.274360794672, 10400.167986561057, 10403.895961040444, 6690.397864192246, 7949.324307433817, 8036.4776289541, 8040.2361775631225, 8050.0, 5942.940570594239, 5955.0, 6095.542834287535, 6111.633302001894, 6166.198394208904, 9219.308551858503, 9405.106514881076, 9688.806223875343, 9743.269254805777, 9863.556139973574, 9717.80564388694, 9861.556229969525, 9947.104760571583, 9991.350562213433, 10114.342567733074, 7198.820029499305, 7711.0746710395115, 8070.636821342936, 8081.87877181831, 8084.797880053045, 8056.033276006917, 8074.434789564674, 8146.714864979697, 8228.819362868964, 8232.547209903512, 7137.321954414322, 7252.528585641917, 7330.340269375569, 7393.149787774475, 7427.22014188524, 7008.369246767816, 7175.390091842043, 7187.712491500281, 7261.636918154177, 7315.926840731525, 6885.586864788129, 6920.273371295942, 6983.790486285419, 6984.965075174795, 7006.544574602634, 4696.361910158673, 4888.755562221803, 4901.264810278487, 4990.026944745885, 5069.797208111634, 4917.311576379238, 4925.605951523941, 4999.77501012448, 5109.8722531937, 5240.502152295526], "value": 4440.286786410278, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.9189314831569, 309.93336432666973, 321.84390570573277, 322.86601060559866, 325.4613614468055, 4843.515648435155, 4855.514448555147, 5288.668139072383, 5318.680879147254, 5336.973315133424, 3880.8059597020106, 4358.6513078953685, 4548.817307500049, 5141.254518094884, 5221.530062294392, 3982.1438390745984, 4012.739171953828, 4025.939610905839, 4030.3148464760916, 4032.8021228430575, 3166.7941583797096, 3973.5827738087373, 4044.5146582410152, 4049.6152865477734, 4061.0, 3847.769133851964, 3979.701522385818, 3986.0, 4003.1993601279714, 4008.879733607982, 3518.768431049112, 3918.490596222487, 3964.2864284428792, 3972.6226008529143, 3973.980130099331, 3395.6434574369828, 3467.9653203467897, 3495.405781017233, 3504.667056629616, 3505.7554568128458, 4386.868393948187, 4816.927746083811, 5021.5229553192385, 5044.4451110378195, 5050.924236136461, 4075.6142911409966, 4103.9179216415805, 4473.552644735533, 4518.706284091556, 4544.9319409938635, 5428.375736790241, 5433.381625618233, 5456.172182318928, 5462.781488740446, 5503.6147469677135, 3709.8516059357594, 3848.614498780437, 3857.633524815138, 3867.916983244696, 3884.941725874114, 4361.629261512743, 5220.4779522047875, 5527.778888844482, 5550.392245040715, 5683.2895888014, 3697.297513472485, 3748.438982966193, 3749.3251214781603, 3767.9246415072093, 3780.3384407729086, 3061.755059595227, 3502.964970350315, 3929.685625149981, 3940.7241493095207, 3969.2458432897656, 3745.5318085239346, 4067.2678917794487, 4121.464209652726, 4157.854475093357, 4484.273554681471, 4850.442199147073, 5134.948650513447, 5458.945410545924, 5462.481064298885, 5505.697186654773, 1242.2608547913976, 1258.534342293339, 1276.074845736852, 1285.2481298440516, 1285.7557064157968, 816.5386556595505, 839.4459656626597, 856.674463703801, 894.0255121917141, 900.8738776571286, 4178.310578754472, 4615.353850460938, 4796.880077998008, 4831.95168048315, 4869.829555965524, 5346.861255497832, 5363.812266570651, 5368.651037682616, 5373.259637584782, 5391.514763671288, 3916.980415097906, 4279.379489973936, 4340.848070317524, 4754.0, 4864.294677271776, 2463.322586288785, 2653.5223659741046, 2697.5683890577807, 2815.3254408312187, 2824.209221418006, 5066.569341606005, 5332.0, 5612.971935140298, 5631.915521267184, 5638.774449022075, 4684.664870511956, 4815.831445899376, 5087.778933056114, 5385.542228910508, 5514.972425137928, 5196.818111366084, 5424.620276580601, 5848.298204215522, 5867.501262392574, 5889.882202355929, 5645.322561292671, 5650.971745141248, 5674.801381951612, 5677.801276955287, 5691.666416769105, 4811.735354555533, 5191.558717509054, 5543.307086614173, 5589.692566908697, 5628.943710562842, 4588.77056147198, 4767.070421267958, 5529.695866727368, 5703.515201207943, 5869.94130058711, 3278.5246139310143, 3344.5317655528734, 3828.9042773930873, 3830.1765120499226, 3870.1872606752895, 4350.673699472489, 4744.217204161413, 5669.971650141723, 5674.467394290698, 5679.233303504139, 4625.444946606429, 5072.365954255718, 5199.66202196856, 5488.286522752017, 5498.0, 4861.465238823797, 5058.443571207238, 5192.844214673566, 5449.64577302474, 5458.317710286215, 4619.745913974763, 4913.297686272607, 5087.389513258285, 5385.165299378603, 5419.810306639249, 4759.7858096386435, 5022.488755622189, 5270.78916843322, 5342.332208473941, 5477.75350109238, 5335.253064570886, 5373.731313434391, 5381.569474441957, 5406.432324605859, 5410.513053825174], "value": 3934.0208235125874, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.3886346833975, 744.7802898145046, 753.111328632214, 755.3579457461159, 758.7534051433286, 13625.433075196343, 14455.349509272082, 16860.325586976538, 17643.55891102723, 17644.206010729515, 16435.58911027225, 16484.83515164848, 16544.0, 16555.66888662226, 16563.503094907173, 732.3957734868728, 935.2611436964806, 948.9893263674165, 949.8052899155656, 954.6372378496196, 750.5796753817853, 908.3323757038571, 939.567798812547, 941.1670671455777, 943.075785729985, 982.3663736889702, 988.6737376665683, 989.0356902020544, 989.1740396768706, 990.0792263195226, 762.3900879296596, 952.476138124029, 982.4400091947592, 983.7983213441228, 984.9803003939917, 735.0848193998506, 735.7535225699397, 736.7458226911707, 736.9410447164214, 738.8817789153763, 15506.224688765516, 15609.0, 15610.843891561053, 15633.765493517607, 15644.296006679613, 10582.9470852646, 12054.517819287217, 12078.335691536962, 12154.939225303906, 12413.896376160563, 10927.945360273148, 11004.229703920728, 11538.884611153864, 11588.420578971101, 11803.940980295129, 4002.2595819773437, 4140.482439695038, 4142.482189726285, 4155.065110350147, 4268.850590229327, 83893.0, 86886.13113868843, 87553.0, 87657.0, 88187.0, 7751.302382785577, 7789.1821358756515, 7792.454528182972, 7796.532208067534, 7893.250141236574, 19343.516412089528, 19379.51551211231, 19388.12753426099, 19417.0, 19430.319938802073, 7224.349808517259, 7266.927330726625, 7282.198958114604, 7288.198298187195, 7318.3047610476915, 4016.859409920639, 4051.3517837146505, 4064.0, 4087.3868919662286, 4264.211120942574, 3477.443609022544, 3567.6253993330847, 3575.1777091269155, 3591.3715099857423, 3591.3894637911094, 541.4481004609772, 612.2133059019228, 615.3077787489075, 634.0869148426251, 658.6212927566669, 477.5081665884145, 528.7118520406387, 546.9972650136724, 584.7544031506692, 589.9675517846474, 7285.927140728525, 7551.395888328921, 7775.989121414293, 8285.42002059862, 8336.291415229773, 476.1762151477893, 504.5282660712246, 542.1596525385637, 594.6759016336107, 622.0793226025507, 3817.7518461300483, 3847.8076096195077, 3921.6078392160557, 3972.56301806801, 4004.439378487014, 74113.62943185249, 76352.0, 80904.0, 82857.58571207106, 83368.0, 71745.20636984013, 73322.25067768924, 75103.62448187724, 76464.61767691126, 76498.61750691211, 67450.66274668595, 68434.65782671055, 68613.65693171503, 69467.95798063034, 69914.0, 7899.973003509507, 7932.167122452291, 8005.759827205193, 8080.676772929017, 8271.669133234844, 44039.339409908236, 44224.658050378035, 45011.32483012692, 45199.87000324886, 46004.07991840078, 57419.425805741404, 58003.41996580149, 60078.69960650169, 62198.378016219256, 62536.37463625488, 9592.23262139041, 10570.630027949292, 10811.296995862616, 11088.223824332543, 11099.112071034458, 9356.95321523388, 11614.419279036183, 11857.822132667845, 12079.39603019863, 12621.053420993645, 9328.860067098864, 10783.191260655325, 11165.106791456501, 11266.098712103176, 11714.0, 11589.536418543501, 11787.705307367041, 11804.527818887149, 11914.106442017057, 11977.64067077989, 11087.778244434905, 11123.054540364159, 11240.88759112431, 11451.648868957724, 11622.186446948632, 8966.820663586823, 9209.493477858781, 9247.953760231156, 9439.0, 9930.205583553154, 7831.216878312123, 8843.380963332505, 9101.453912765255, 9142.725718228463, 9703.805923881624], "value": 6806.282106887159, "unit": "score"}], "name": "dromaeo_css", "value": 7061.547340368714, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 163.0, 163.0, 109.0, 116.0, 157.0, 165.0, 108.0, 111.0], "value": 131.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 121.0, 144.0, 199.0, 123.0, 143.0, 137.0, 120.0, 171.0, 120.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 183.0, 184.0, 183.0, 181.0, 187.0, 186.0, 192.0, 179.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 57.0, 125.0, 61.0, 65.0, 57.0, 62.0, 108.0, 56.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 134.0, 80.0, 79.0, 77.0, 102.0, 80.0, 80.0, 101.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 84.0, 86.0, 84.0, 87.0, 84.0, 86.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 94.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 87.0, 86.0, 85.0, 86.0, 83.0, 87.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 60.0, 60.0, 60.0, 60.0, 58.0, 119.0, 57.0, 57.0], "value": 65.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 71.0, 76.0, 71.0, 73.0, 73.0, 69.0, 69.0, 70.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 74.0, 99.0, 97.0, 105.0, 105.0, 106.0, 85.0, 86.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 146.0, 149.0, 143.0, 138.0, 142.0, 155.0, 210.0, 140.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 174.0, 166.0, 169.0, 167.0, 168.0, 169.0, 167.0, 178.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 69.0, 81.0, 86.0, 87.0, 85.0, 73.0, 66.0], "value": 74.9, "unit": "score"}], "name": "kraken", "value": 1513.9000000000003, "alertThreshold": 2.0}]} 07:37:12 INFO - Return code: 0 07:37:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:37:12 ERROR - # TBPL SUCCESS # 07:37:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:37:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:37:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:37:12 INFO - 2016-05-09 07:37:12.449 screenresolution[1680:9982] starting screenresolution argv=screenresolution get 07:37:12 INFO - 2016-05-09 07:37:12.452 screenresolution[1680:9982] Display 0: 1600x1200x32@60 07:37:12 INFO - 2016-05-09 07:37:12.458 screenresolution[1681:9986] starting screenresolution argv=screenresolution list 07:37:12 INFO - Available Modes on Display 0 07:37:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:37:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:37:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:37:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:37:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:37:12 INFO - Intel Iris: 07:37:12 INFO - Chipset Model: Intel Iris 07:37:12 INFO - Type: GPU 07:37:12 INFO - Bus: Built-In 07:37:12 INFO - VRAM (Dynamic, Max): 1536 MB 07:37:12 INFO - Vendor: Intel (0x8086) 07:37:12 INFO - Device ID: 0x0a2e 07:37:12 INFO - Revision ID: 0x0009 07:37:12 INFO - Displays: 07:37:12 INFO - Display: 07:37:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:37:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:37:12 INFO - Display Serial Number: 1600x1200 60 07:37:12 INFO - Main Display: Yes 07:37:12 INFO - Mirror: Off 07:37:12 INFO - Online: Yes 07:37:12 INFO - Rotation: Supported 07:37:12 INFO - Return code: 0 07:37:12 INFO - Running post-action listener: _resource_record_post_action 07:37:12 INFO - ##### 07:37:12 INFO - ##### Finished run-tests step (success) 07:37:12 INFO - ##### 07:37:12 INFO - Running post-run listener: _resource_record_post_run 07:37:13 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38114816; Write bytes: 699490816; Read time: 415; Write time: 1930 07:37:13 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 390924800; Write bytes: 385930752; Read time: 29927; Write time: 764 07:37:13 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36132352; Write bytes: 298339328; Read time: 382; Write time: 1125 07:37:13 INFO - Running post-run listener: _upload_blobber_files 07:37:13 INFO - Blob upload gear active. 07:37:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:37:13 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 07:37:13 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:37:13 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:37:14 INFO - (blobuploader) - INFO - Open directory for files ... 07:37:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:37:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:37:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:37:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:37:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:37:15 INFO - (blobuploader) - INFO - Done attempting. 07:37:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:37:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:37:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:37:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:37:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:37:16 INFO - (blobuploader) - INFO - Done attempting. 07:37:16 INFO - (blobuploader) - INFO - Iteration through files over. 07:37:16 INFO - Return code: 0 07:37:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:37:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:37:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c46c358f5afd8371c446ef0e2b14b87131437f09466ed23de8ba042f7a29f27682e7da60a9305c1bbda95d378d472df12d90dafc28e19e5e740e5e077e4b4a2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b0cdca3120a717097b2cebfeaf3cebf155bfb698420388fcbf4fd45fb56d63a79c62808c554eeb4608c8862e54165985ff8111631d2e9c6d609718fbc1cfcf5"} 07:37:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:37:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:37:16 INFO - Contents: 07:37:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c46c358f5afd8371c446ef0e2b14b87131437f09466ed23de8ba042f7a29f27682e7da60a9305c1bbda95d378d472df12d90dafc28e19e5e740e5e077e4b4a2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b0cdca3120a717097b2cebfeaf3cebf155bfb698420388fcbf4fd45fb56d63a79c62808c554eeb4608c8862e54165985ff8111631d2e9c6d609718fbc1cfcf5"} 07:37:16 INFO - Running post-run listener: copy_logs_to_upload_dir 07:37:16 INFO - Copying logs to upload dir... 07:37:16 INFO - mkdir: /builds/slave/test/build/upload/logs 07:37:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.359937 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-09 07:37:16.321804) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 07:37:16.325471) ========= 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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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/Fx-Team/sha512/c46c358f5afd8371c446ef0e2b14b87131437f09466ed23de8ba042f7a29f27682e7da60a9305c1bbda95d378d472df12d90dafc28e19e5e740e5e077e4b4a2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b0cdca3120a717097b2cebfeaf3cebf155bfb698420388fcbf4fd45fb56d63a79c62808c554eeb4608c8862e54165985ff8111631d2e9c6d609718fbc1cfcf5"} build_url:https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011292 build_url: 'https://queue.taskcluster.net/v1/task/eVJiS6bhQnmn9goouF2hKA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c46c358f5afd8371c446ef0e2b14b87131437f09466ed23de8ba042f7a29f27682e7da60a9305c1bbda95d378d472df12d90dafc28e19e5e740e5e077e4b4a2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b0cdca3120a717097b2cebfeaf3cebf155bfb698420388fcbf4fd45fb56d63a79c62808c554eeb4608c8862e54165985ff8111631d2e9c6d609718fbc1cfcf5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 07:37:16.353497) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:37:16.353833) ========= 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.SnRLP8tbUr/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.mjdgcAuY4G/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.005166 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 07:37:16.401788) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 07:37:16.402101) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 07:37:16.402452) ========= ========= Total master_lag: 0.15 =========