builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0194 starttime: 1462794027.71 results: success (0) buildid: 20160509022157 builduid: be23fae40e614bac92bb76271339de81 revision: ab8ae2c23df566acee8cbeb64e5164fae8a10b0e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:27.707790) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:27.708239) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:27.708539) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:27.728471) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:27.728890) ========= 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.hcYGatuSGR/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.cfT2dtif3q/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:40:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.13M=0.001s 2016-05-09 04:40:27 (8.13 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108086 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:27.855793) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:27.856399) ========= 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.hcYGatuSGR/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.cfT2dtif3q/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.020512 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:27.906217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 04:40:27.906603) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ab8ae2c23df566acee8cbeb64e5164fae8a10b0e --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 ab8ae2c23df566acee8cbeb64e5164fae8a10b0e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hcYGatuSGR/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.cfT2dtif3q/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:40:27,984 truncating revision to first 12 chars 2016-05-09 04:40:27,985 Setting DEBUG logging. 2016-05-09 04:40:27,985 attempt 1/10 2016-05-09 04:40:27,985 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ab8ae2c23df5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 04:40:29,188 unpacking tar archive at: fx-team-ab8ae2c23df5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.455044 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 04:40:29.380860) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:29.381333) ========= script_repo_revision: ab8ae2c23df566acee8cbeb64e5164fae8a10b0e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:29.381777) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:29.382132) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 04:40:29.398235) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-09 04:40:29.398903) ========= /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.hcYGatuSGR/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.cfT2dtif3q/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:40:29 INFO - MultiFileLogger online at 20160509 04:40:29 in /builds/slave/test 04:40:29 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 04:40:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:40:29 INFO - {'append_to_log': False, 04:40:29 INFO - 'base_work_dir': '/builds/slave/test', 04:40:29 INFO - 'blob_upload_branch': 'Fx-Team', 04:40:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:40:29 INFO - 'branch': 'Fx-Team', 04:40:29 INFO - 'buildbot_json_path': 'buildprops.json', 04:40:29 INFO - 'config_files': ('talos/mac_config.py',), 04:40:29 INFO - 'default_actions': ('clobber', 04:40:29 INFO - 'read-buildbot-config', 04:40:29 INFO - 'download-and-extract', 04:40:29 INFO - 'populate-webroot', 04:40:29 INFO - 'create-virtualenv', 04:40:29 INFO - 'install', 04:40:29 INFO - 'run-tests'), 04:40:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:40:29 INFO - 'download_minidump_stackwalk': True, 04:40:29 INFO - 'download_symbols': 'ondemand', 04:40:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:40:29 INFO - 'tooltool.py': '/tools/tooltool.py', 04:40:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:40:29 INFO - '/tools/misc-python/virtualenv.py')}, 04:40:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:40:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:40:29 INFO - 'installer_path': 'installer.exe', 04:40:29 INFO - 'log_level': 'info', 04:40:29 INFO - 'log_name': 'talos', 04:40:29 INFO - 'log_to_console': True, 04:40:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:40:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:40:29 INFO - 'opt_config_files': (), 04:40:29 INFO - 'pip_index': False, 04:40:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:40:29 INFO - 'cmd': ('bash', 04:40:29 INFO - '-c', 04:40:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:40:29 INFO - 'enabled': True, 04:40:29 INFO - 'halt_on_failure': False, 04:40:29 INFO - 'name': 'check_screen_resolution'},), 04:40:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:40:29 INFO - 'cmd': ('bash', 04:40:29 INFO - '-c', 04:40:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:40:29 INFO - 'enabled': True, 04:40:29 INFO - 'halt_on_failure': False, 04:40:29 INFO - 'name': 'check_screen_resolution'},), 04:40:29 INFO - 'run_cmd_checks_enabled': True, 04:40:29 INFO - 'sps_profile': False, 04:40:29 INFO - 'sps_profile_interval': 0, 04:40:29 INFO - 'suite': 'dromaeojs', 04:40:29 INFO - 'system_bits': '32', 04:40:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:40:29 INFO - 'title': 't-yosemite-r7-0194', 04:40:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:40:29 INFO - 'use_talos_json': True, 04:40:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:40:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:40:29 INFO - 'work_dir': 'build'} 04:40:29 INFO - ##### 04:40:29 INFO - ##### Running clobber step. 04:40:29 INFO - ##### 04:40:29 INFO - Running pre-action listener: _resource_record_pre_action 04:40:29 INFO - Running main action method: clobber 04:40:29 INFO - rmtree: /builds/slave/test/build 04:40:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:40:30 INFO - Running post-action listener: _resource_record_post_action 04:40:30 INFO - ##### 04:40:30 INFO - ##### Finished clobber step (success) 04:40:30 INFO - ##### 04:40:30 INFO - ##### 04:40:30 INFO - ##### Running read-buildbot-config step. 04:40:30 INFO - ##### 04:40:30 INFO - Running pre-action listener: _resource_record_pre_action 04:40:30 INFO - Running main action method: read_buildbot_config 04:40:30 INFO - Using buildbot properties: 04:40:30 INFO - { 04:40:30 INFO - "project": "", 04:40:30 INFO - "product": "firefox", 04:40:30 INFO - "script_repo_revision": "production", 04:40:30 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:40:30 INFO - "repository": "", 04:40:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:40:30 INFO - "buildid": "20160509022157", 04:40:30 INFO - "pgo_build": "False", 04:40:30 INFO - "basedir": "/builds/slave/test", 04:40:30 INFO - "buildnumber": 4, 04:40:30 INFO - "slavename": "t-yosemite-r7-0194", 04:40:30 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:40:30 INFO - "platform": "yosemite_r7", 04:40:30 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:40:30 INFO - "branch": "fx-team", 04:40:30 INFO - "repo_path": "integration/fx-team", 04:40:30 INFO - "revision": "ab8ae2c23df566acee8cbeb64e5164fae8a10b0e", 04:40:30 INFO - "stage_platform": "macosx64", 04:40:30 INFO - "builduid": "be23fae40e614bac92bb76271339de81", 04:40:30 INFO - "slavebuilddir": "test" 04:40:30 INFO - } 04:40:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:40:30 INFO - Running post-action listener: _resource_record_post_action 04:40:30 INFO - ##### 04:40:30 INFO - ##### Finished read-buildbot-config step (success) 04:40:30 INFO - ##### 04:40:30 INFO - ##### 04:40:30 INFO - ##### Running download-and-extract step. 04:40:30 INFO - ##### 04:40:30 INFO - Running pre-action listener: _resource_record_pre_action 04:40:30 INFO - Running main action method: download_and_extract 04:40:30 INFO - mkdir: /builds/slave/test/build/tests 04:40:30 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')]} 04:40:30 INFO - https://queue.taskcluster.net/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:40:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:40:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:40:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/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 04:40:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/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 04:40:31 INFO - Downloaded 1199 bytes. 04:40:31 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:40:31 INFO - Using the following test package requirements: 04:40:31 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:40:31 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:40:31 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:40:31 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:40:31 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:40:31 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:40:31 INFO - u'jsshell-mac.zip'], 04:40:31 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:40:31 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:40:31 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:40:31 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:40:31 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:40:31 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:40:31 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:40:31 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:40:31 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:40:31 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:40:31 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:40:31 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:40:31 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')]} 04:40:31 INFO - https://queue.taskcluster.net/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:40:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:40:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:40:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/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 04:40:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/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 04:40:32 INFO - Downloaded 18146482 bytes. 04:40:32 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'] 04:40:32 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 04:40:35 INFO - Return code: 0 04:40:35 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 04:40:35 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')]} 04:40:35 INFO - https://queue.taskcluster.net/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:40:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:40:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:40:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/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 04:40:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/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 04:40:37 INFO - Downloaded 18146482 bytes. 04:40:37 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'] 04:40:37 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 04:40:40 INFO - Return code: 0 04:40:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:40 INFO - https://queue.taskcluster.net/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:40:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:40:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:40:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/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 04:40:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/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 04:40:41 INFO - Downloaded 10840152 bytes. 04:40:41 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'] 04:40:41 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 04:40:42 INFO - Return code: 0 04:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:40:42 INFO - https://queue.taskcluster.net/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:40:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:40:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:40:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:40:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EWx57r5KQG-L7XD-m1SIMA/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 04:40:45 INFO - Downloaded 110420725 bytes. 04:40:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:40:45 INFO - mkdir: /builds/slave/test/properties 04:40:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:40:45 INFO - Writing to file /builds/slave/test/properties/build_url 04:40:45 INFO - Contents: 04:40:45 INFO - build_url:https://queue.taskcluster.net/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:40:46 INFO - Running post-action listener: _resource_record_post_action 04:40:46 INFO - Running post-action listener: set_extra_try_arguments 04:40:46 INFO - ##### 04:40:46 INFO - ##### Finished download-and-extract step (success) 04:40:46 INFO - ##### 04:40:46 INFO - ##### 04:40:46 INFO - ##### Running populate-webroot step. 04:40:46 INFO - ##### 04:40:46 INFO - Running pre-action listener: _resource_record_pre_action 04:40:46 INFO - Running main action method: populate_webroot 04:40:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:40:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:40:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:40:46 INFO - '--tppagecycles', 04:40:46 INFO - '7'], 04:40:46 INFO - 'tests': ['tsvgm']}}, 04:40:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:40:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:40:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:40:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:40:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:40:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:40:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:46 INFO - 'talos_options': ['--disable-e10s'], 04:40:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:40:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:40:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:46 INFO - 'talos_options': ['--disable-e10s'], 04:40:46 INFO - 'tests': ['damp', 'tps']}, 04:40:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:46 INFO - 'tests': ['damp', 'tps']}, 04:40:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:40:46 INFO - 'tests': ['dromaeo_dom']}, 04:40:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:40:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:40:46 INFO - 'tests': ['a11yr', 04:40:46 INFO - 'ts_paint', 04:40:46 INFO - 'tpaint', 04:40:46 INFO - 'sessionrestore', 04:40:46 INFO - 'sessionrestore_no_auto_restore', 04:40:46 INFO - 'tabpaint']}, 04:40:46 INFO - 'other-e10s': {'tests': ['a11yr', 04:40:46 INFO - 'ts_paint', 04:40:46 INFO - 'tpaint', 04:40:46 INFO - 'sessionrestore', 04:40:46 INFO - 'sessionrestore_no_auto_restore', 04:40:46 INFO - 'tabpaint']}, 04:40:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:40:46 INFO - 'ts_paint', 04:40:46 INFO - 'tpaint', 04:40:46 INFO - 'sessionrestore', 04:40:46 INFO - 'sessionrestore_no_auto_restore', 04:40:46 INFO - 'tabpaint']}, 04:40:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:40:46 INFO - 'ts_paint', 04:40:46 INFO - 'tpaint', 04:40:46 INFO - 'sessionrestore', 04:40:46 INFO - 'sessionrestore_no_auto_restore', 04:40:46 INFO - 'tabpaint']}, 04:40:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:40:46 INFO - 'tests': ['a11yr', 04:40:46 INFO - 'ts_paint', 04:40:46 INFO - 'tpaint', 04:40:46 INFO - 'sessionrestore', 04:40:46 INFO - 'sessionrestore_no_auto_restore', 04:40:46 INFO - 'tabpaint']}, 04:40:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:40:46 INFO - 'tests': ['a11yr', 04:40:46 INFO - 'ts_paint', 04:40:46 INFO - 'tpaint', 04:40:46 INFO - 'sessionrestore', 04:40:46 INFO - 'sessionrestore_no_auto_restore', 04:40:46 INFO - 'tabpaint']}, 04:40:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:40:46 INFO - 'tests': ['tsvgx', 04:40:46 INFO - 'tsvgr_opacity', 04:40:46 INFO - 'tart', 04:40:46 INFO - 'tscrollx', 04:40:46 INFO - 'cart']}, 04:40:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:40:46 INFO - 'tsvgr_opacity', 04:40:46 INFO - 'tart', 04:40:46 INFO - 'tscrollx', 04:40:46 INFO - 'cart']}, 04:40:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:46 INFO - 'talos_options': ['--disable-e10s'], 04:40:46 INFO - 'tests': ['tp5o']}, 04:40:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:46 INFO - 'tests': ['tp5o']}, 04:40:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:46 INFO - 'talos_options': ['--disable-e10s', 04:40:46 INFO - '--xperf_path', 04:40:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:40:46 INFO - 'tests': ['tp5n']}, 04:40:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:46 INFO - 'talos_options': ['--xperf_path', 04:40:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:40:46 INFO - 'tests': ['tp5n']}}, 04:40:46 INFO - 'talos.zip': {'path': '', 04:40:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:40:46 INFO - Running post-action listener: _resource_record_post_action 04:40:46 INFO - ##### 04:40:46 INFO - ##### Finished populate-webroot step (success) 04:40:46 INFO - ##### 04:40:46 INFO - ##### 04:40:46 INFO - ##### Running create-virtualenv step. 04:40:46 INFO - ##### 04:40:46 INFO - Running pre-action listener: _resource_record_pre_action 04:40:46 INFO - Running main action method: create_virtualenv 04:40:46 INFO - Creating virtualenv /builds/slave/test/build/venv 04:40:46 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 04:40:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:40:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:40:46 INFO - Using real prefix '/tools/python27' 04:40:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:40:46 INFO - Installing distribute.............................................................................................................................................................................................done. 04:40:49 INFO - Installing pip.................done. 04:40:49 INFO - Return code: 0 04:40:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:40:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:49 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 0x1099cea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa30d3e6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109872d30>, '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 0x109696db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109869b20>, '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.hcYGatuSGR/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.cfT2dtif3q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:40:49 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 04:40:49 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 04:40:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hcYGatuSGR/Render', 04:40:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:49 INFO - 'HOME': '/Users/cltbld', 04:40:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:49 INFO - 'LOGNAME': 'cltbld', 04:40:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:49 INFO - 'MOZ_NO_REMOTE': '1', 04:40:49 INFO - 'NO_EM_RESTART': '1', 04:40:49 INFO - 'PAGER': '/bin/cat', 04:40:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:49 INFO - 'PWD': '/builds/slave/test', 04:40:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:49 INFO - 'SHELL': '/bin/bash', 04:40:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cfT2dtif3q/Listeners', 04:40:49 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 04:40:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:49 INFO - 'USER': 'cltbld', 04:40:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:49 INFO - 'XPC_FLAGS': '0x0', 04:40:49 INFO - 'XPC_SERVICE_NAME': '0', 04:40:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:40:50 INFO - Cleaning up... 04:40:50 INFO - Return code: 0 04:40:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:40:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:40:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099cea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa30d3e6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109872d30>, '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 0x109696db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109869b20>, '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.hcYGatuSGR/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.cfT2dtif3q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:40:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:40:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:40:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hcYGatuSGR/Render', 04:40:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:50 INFO - 'HOME': '/Users/cltbld', 04:40:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:50 INFO - 'LOGNAME': 'cltbld', 04:40:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:50 INFO - 'MOZ_NO_REMOTE': '1', 04:40:50 INFO - 'NO_EM_RESTART': '1', 04:40:50 INFO - 'PAGER': '/bin/cat', 04:40:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:50 INFO - 'PWD': '/builds/slave/test', 04:40:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:50 INFO - 'SHELL': '/bin/bash', 04:40:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cfT2dtif3q/Listeners', 04:40:50 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 04:40:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:50 INFO - 'USER': 'cltbld', 04:40:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:50 INFO - 'XPC_FLAGS': '0x0', 04:40:50 INFO - 'XPC_SERVICE_NAME': '0', 04:40:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:50 INFO - Downloading/unpacking psutil>=0.7.1 04:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:40:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:40:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:40:53 INFO - Installing collected packages: psutil 04:40:53 INFO - Running setup.py install for psutil 04:40:53 INFO - building 'psutil._psutil_osx' extension 04:40:53 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 04:40:53 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 04:40:53 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 04:40:53 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 04:40:53 INFO - building 'psutil._psutil_posix' extension 04:40:53 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 04:40:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:40:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:40:53 INFO - ^ 04:40:54 INFO - 1 warning generated. 04:40:54 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 04:40:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:40:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:40:54 INFO - Successfully installed psutil 04:40:54 INFO - Cleaning up... 04:40:54 INFO - Return code: 0 04:40:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:40:54 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')]} 04:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:54 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')]} 04:40:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:54 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 0x1099cea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa30d3e6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109872d30>, '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 0x109696db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109869b20>, '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.hcYGatuSGR/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.cfT2dtif3q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:40:54 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 04:40:54 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 04:40:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hcYGatuSGR/Render', 04:40:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:54 INFO - 'HOME': '/Users/cltbld', 04:40:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:54 INFO - 'LOGNAME': 'cltbld', 04:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:54 INFO - 'MOZ_NO_REMOTE': '1', 04:40:54 INFO - 'NO_EM_RESTART': '1', 04:40:54 INFO - 'PAGER': '/bin/cat', 04:40:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:54 INFO - 'PWD': '/builds/slave/test', 04:40:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:54 INFO - 'SHELL': '/bin/bash', 04:40:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cfT2dtif3q/Listeners', 04:40:54 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 04:40:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:54 INFO - 'USER': 'cltbld', 04:40:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:54 INFO - 'XPC_FLAGS': '0x0', 04:40:54 INFO - 'XPC_SERVICE_NAME': '0', 04:40:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:40: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 04:40: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 04:40: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 04:40: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 04:40: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 04:40: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 04:40:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:40:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:40:57 INFO - Installing collected packages: mozsystemmonitor 04:40:57 INFO - Running setup.py install for mozsystemmonitor 04:40:57 INFO - Successfully installed mozsystemmonitor 04:40:57 INFO - Cleaning up... 04:40:57 INFO - Return code: 0 04:40:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:57 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 0x1099cea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa30d3e6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109872d30>, '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 0x109696db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109869b20>, '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.hcYGatuSGR/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.cfT2dtif3q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:40:57 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 04:40:57 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 04:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hcYGatuSGR/Render', 04:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:57 INFO - 'HOME': '/Users/cltbld', 04:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:57 INFO - 'LOGNAME': 'cltbld', 04:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:57 INFO - 'MOZ_NO_REMOTE': '1', 04:40:57 INFO - 'NO_EM_RESTART': '1', 04:40:57 INFO - 'PAGER': '/bin/cat', 04:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:57 INFO - 'PWD': '/builds/slave/test', 04:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:57 INFO - 'SHELL': '/bin/bash', 04:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cfT2dtif3q/Listeners', 04:40:57 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 04:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:57 INFO - 'USER': 'cltbld', 04:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:57 INFO - 'XPC_FLAGS': '0x0', 04:40:57 INFO - 'XPC_SERVICE_NAME': '0', 04:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:57 INFO - Downloading/unpacking blobuploader==1.2.4 04:40: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 04:40: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 04:40: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 04:40: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 04:40: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 04:40: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 04:40:59 INFO - Downloading blobuploader-1.2.4.tar.gz 04:40:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:41:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:41:00 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 04:41:00 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 04:41:00 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 04:41:00 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 04:41:00 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 04:41:00 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 04:41:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:41:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:41:00 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 04:41:00 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 04:41:00 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 04:41:00 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 04:41:00 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 04:41:00 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 04:41:01 INFO - Downloading docopt-0.6.1.tar.gz 04:41:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:41:01 INFO - Installing collected packages: blobuploader, requests, docopt 04:41:01 INFO - Running setup.py install for blobuploader 04:41:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:41:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:41:01 INFO - Running setup.py install for requests 04:41:01 INFO - Running setup.py install for docopt 04:41:02 INFO - Successfully installed blobuploader requests docopt 04:41:02 INFO - Cleaning up... 04:41:02 INFO - Return code: 0 04:41:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:02 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 0x1099cea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa30d3e6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109872d30>, '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 0x109696db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109869b20>, '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.hcYGatuSGR/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.cfT2dtif3q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:41:02 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 04:41:02 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 04:41:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hcYGatuSGR/Render', 04:41:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:02 INFO - 'HOME': '/Users/cltbld', 04:41:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:02 INFO - 'LOGNAME': 'cltbld', 04:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:02 INFO - 'MOZ_NO_REMOTE': '1', 04:41:02 INFO - 'NO_EM_RESTART': '1', 04:41:02 INFO - 'PAGER': '/bin/cat', 04:41:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:02 INFO - 'PWD': '/builds/slave/test', 04:41:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:02 INFO - 'SHELL': '/bin/bash', 04:41:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cfT2dtif3q/Listeners', 04:41:02 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 04:41:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:02 INFO - 'USER': 'cltbld', 04:41:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:02 INFO - 'XPC_FLAGS': '0x0', 04:41:02 INFO - 'XPC_SERVICE_NAME': '0', 04:41:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:41:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-RnEsWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:41:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-b4zryM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:41:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-E3BYVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:41:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-fkvWPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:41:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Hj6LUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:41:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-jJ9NUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:41:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-CPtMhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:41:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-NtgY1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:41:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-zioQ0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:41:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-sWLHH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:41:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-yDUmys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:41:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-pc9Um8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:41:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-hsbZif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:41:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-wqYuSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-O0GjaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:41:04 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-TpkiqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:41:04 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-t3DFeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:41:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:41:04 INFO - Running setup.py install for manifestparser 04:41:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:41:04 INFO - Running setup.py install for mozcrash 04:41:04 INFO - Running setup.py install for mozdebug 04:41:04 INFO - Running setup.py install for mozdevice 04:41:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:41:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:41:04 INFO - Running setup.py install for mozfile 04:41:04 INFO - Running setup.py install for mozhttpd 04:41:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:41:05 INFO - Running setup.py install for mozinfo 04:41:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:41:05 INFO - Running setup.py install for mozInstall 04:41:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:41:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:41:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:41:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:41:05 INFO - Running setup.py install for mozleak 04:41:05 INFO - Running setup.py install for mozlog 04:41:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:41:05 INFO - Running setup.py install for moznetwork 04:41:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:41:05 INFO - Running setup.py install for mozprocess 04:41:06 INFO - Running setup.py install for mozprofile 04:41:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:41:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:41:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:41:06 INFO - Running setup.py install for mozrunner 04:41:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:41:06 INFO - Running setup.py install for mozscreenshot 04:41:06 INFO - Running setup.py install for moztest 04:41:06 INFO - Running setup.py install for mozversion 04:41:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:41:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:41:06 INFO - Cleaning up... 04:41:06 INFO - Return code: 0 04:41:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:41:06 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')]} 04:41:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:06 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')]} 04:41:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:06 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 0x1099cea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa30d3e6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109872d30>, '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 0x109696db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109869b20>, '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.hcYGatuSGR/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.cfT2dtif3q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:41:06 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 04:41:06 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 04:41:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hcYGatuSGR/Render', 04:41:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:06 INFO - 'HOME': '/Users/cltbld', 04:41:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:06 INFO - 'LOGNAME': 'cltbld', 04:41:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:06 INFO - 'MOZ_NO_REMOTE': '1', 04:41:06 INFO - 'NO_EM_RESTART': '1', 04:41:06 INFO - 'PAGER': '/bin/cat', 04:41:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:06 INFO - 'PWD': '/builds/slave/test', 04:41:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:06 INFO - 'SHELL': '/bin/bash', 04:41:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cfT2dtif3q/Listeners', 04:41:06 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 04:41:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:06 INFO - 'USER': 'cltbld', 04:41:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:06 INFO - 'XPC_FLAGS': '0x0', 04:41:06 INFO - 'XPC_SERVICE_NAME': '0', 04:41:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:41:07 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-aZy4EB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:41:07 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)) 04:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:41:07 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-qB_Mry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:41:07 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)) 04:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:41:07 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-FVv9zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:41:07 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)) 04:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:41:07 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-qOmTlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:41:07 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)) 04:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:41:07 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-z5D_Ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:41:07 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)) 04:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:41:07 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-_h7uGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:41:07 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)) 04:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:41:07 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-KLaRmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:41:07 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)) 04:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:41:07 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-14d7z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:41:07 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)) 04:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:41:07 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-j9jH4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:41:08 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)) 04:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:41:08 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-FouawV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:41:08 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)) 04:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:41:08 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-h7qr02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:41:08 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)) 04:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:41:08 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Fh57Aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:41:08 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)) 04:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:41:08 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-mv99jE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:41:08 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)) 04:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:41:08 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-yXlD1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:41:08 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)) 04:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:08 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-3KDqBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:08 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)) 04:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:41:08 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-lXnrNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:41:08 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)) 04:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:41:08 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-jHoVtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:41:08 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)) 04:41:08 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)) 04:41:08 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)) 04:41:08 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)) 04:41:08 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)) 04:41:08 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)) 04:41:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:41:08 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 04:41:08 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 04:41:08 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 04:41:08 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 04:41:08 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 04:41:08 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 04:41:11 INFO - Downloading blessings-1.6.tar.gz 04:41:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:41:11 INFO - Installing collected packages: blessings 04:41:11 INFO - Running setup.py install for blessings 04:41:11 INFO - Successfully installed blessings 04:41:11 INFO - Cleaning up... 04:41:11 INFO - Return code: 0 04:41:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:41:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:41:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:41:11 INFO - Reading from file tmpfile_stdout 04:41:11 INFO - Current package versions: 04:41:11 INFO - blessings == 1.6 04:41:11 INFO - blobuploader == 1.2.4 04:41:11 INFO - docopt == 0.6.1 04:41:11 INFO - manifestparser == 1.1 04:41:11 INFO - mozInstall == 1.12 04:41:11 INFO - mozcrash == 0.17 04:41:11 INFO - mozdebug == 0.1 04:41:11 INFO - mozdevice == 0.48 04:41:11 INFO - mozfile == 1.2 04:41:11 INFO - mozhttpd == 0.7 04:41:11 INFO - mozinfo == 0.9 04:41:11 INFO - mozleak == 0.1 04:41:11 INFO - mozlog == 3.1 04:41:11 INFO - moznetwork == 0.27 04:41:11 INFO - mozprocess == 0.22 04:41:11 INFO - mozprofile == 0.28 04:41:11 INFO - mozrunner == 6.11 04:41:11 INFO - mozscreenshot == 0.1 04:41:11 INFO - mozsystemmonitor == 0.0 04:41:11 INFO - moztest == 0.7 04:41:11 INFO - mozversion == 1.4 04:41:11 INFO - psutil == 3.1.1 04:41:11 INFO - requests == 1.2.3 04:41:11 INFO - wsgiref == 0.1.2 04:41:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099cea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa30d3e6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109872d30>, '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 0x109696db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109869b20>, '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.hcYGatuSGR/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.cfT2dtif3q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:41:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:41:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:41:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hcYGatuSGR/Render', 04:41:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:11 INFO - 'HOME': '/Users/cltbld', 04:41:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:11 INFO - 'LOGNAME': 'cltbld', 04:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:11 INFO - 'MOZ_NO_REMOTE': '1', 04:41:11 INFO - 'NO_EM_RESTART': '1', 04:41:11 INFO - 'PAGER': '/bin/cat', 04:41:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:11 INFO - 'PWD': '/builds/slave/test', 04:41:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:11 INFO - 'SHELL': '/bin/bash', 04:41:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cfT2dtif3q/Listeners', 04:41:11 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 04:41:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:11 INFO - 'USER': 'cltbld', 04:41:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:11 INFO - 'XPC_FLAGS': '0x0', 04:41:11 INFO - 'XPC_SERVICE_NAME': '0', 04:41:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:12 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)) 04:41:12 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)) 04:41:12 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)) 04:41:12 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)) 04:41:12 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)) 04:41:12 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)) 04:41:12 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)) 04:41:12 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)) 04:41:12 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)) 04:41:12 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)) 04:41:12 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)) 04:41:12 INFO - Cleaning up... 04:41:12 INFO - Return code: 0 04:41:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:41:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:41:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:41:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:12 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 0x1099cea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa30d3e6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109872d30>, '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 0x109696db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109869b20>, '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.hcYGatuSGR/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.cfT2dtif3q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:41:12 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 04:41:12 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 04:41:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hcYGatuSGR/Render', 04:41:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:12 INFO - 'HOME': '/Users/cltbld', 04:41:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:12 INFO - 'LOGNAME': 'cltbld', 04:41:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:12 INFO - 'MOZ_NO_REMOTE': '1', 04:41:12 INFO - 'NO_EM_RESTART': '1', 04:41:12 INFO - 'PAGER': '/bin/cat', 04:41:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:12 INFO - 'PWD': '/builds/slave/test', 04:41:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:12 INFO - 'SHELL': '/bin/bash', 04:41:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cfT2dtif3q/Listeners', 04:41:12 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 04:41:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:12 INFO - 'USER': 'cltbld', 04:41:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:12 INFO - 'XPC_FLAGS': '0x0', 04:41:12 INFO - 'XPC_SERVICE_NAME': '0', 04:41:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:12 INFO - Downloading/unpacking jsonschema 04:41:12 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 04:41:12 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 04:41:12 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 04:41:12 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 04:41:12 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 04:41:12 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 04:41:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:41:14 INFO - Downloading/unpacking functools32 (from jsonschema) 04:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:15 INFO - Downloading functools32-3.2.3-2.tar.gz 04:41:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:41:15 INFO - warning: no files found matching '*.txt' 04:41:15 INFO - no previously-included directories found matching 'build' 04:41:15 INFO - no previously-included directories found matching 'dist' 04:41:15 INFO - no previously-included directories found matching '.git*' 04:41:15 INFO - Installing collected packages: jsonschema, functools32 04:41:15 INFO - Running setup.py install for functools32 04:41:15 INFO - warning: no files found matching '*.txt' 04:41:15 INFO - no previously-included directories found matching 'build' 04:41:15 INFO - no previously-included directories found matching 'dist' 04:41:15 INFO - no previously-included directories found matching '.git*' 04:41:15 INFO - Successfully installed jsonschema functools32 04:41:15 INFO - Cleaning up... 04:41:15 INFO - Return code: 0 04:41:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:41:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:15 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 0x1099cea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099d4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa30d3e6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109872d30>, '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 0x109696db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109869b20>, '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.hcYGatuSGR/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.cfT2dtif3q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 04:41:15 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 04:41:15 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 04:41:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hcYGatuSGR/Render', 04:41:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:15 INFO - 'HOME': '/Users/cltbld', 04:41:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:15 INFO - 'LOGNAME': 'cltbld', 04:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:15 INFO - 'MOZ_NO_REMOTE': '1', 04:41:15 INFO - 'NO_EM_RESTART': '1', 04:41:15 INFO - 'PAGER': '/bin/cat', 04:41:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:15 INFO - 'PWD': '/builds/slave/test', 04:41:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:15 INFO - 'SHELL': '/bin/bash', 04:41:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cfT2dtif3q/Listeners', 04:41:15 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 04:41:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:15 INFO - 'USER': 'cltbld', 04:41:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:15 INFO - 'XPC_FLAGS': '0x0', 04:41:15 INFO - 'XPC_SERVICE_NAME': '0', 04:41:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:15 INFO - Downloading/unpacking flake8 04:41:15 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 04:41:15 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 04:41:15 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 04:41:15 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 04:41:15 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 04:41:15 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 04:41:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:41:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:41:18 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 04:41:18 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 04:41:18 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 04:41:18 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 04:41:18 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 04:41:18 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 04:41:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:41:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:41:18 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 04:41:18 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 04:41:18 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 04:41:18 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 04:41:18 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 04:41:18 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 04:41:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:41:19 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 04:41:19 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 04:41:19 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 04:41:19 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 04:41:19 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 04:41:19 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 04:41:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:41:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:41:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:41:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:41:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:41:19 INFO - no previously-included directories found matching 'docs/_build' 04:41:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:41:19 INFO - Running setup.py install for pep8 04:41:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:41:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:41:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:41:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:41:19 INFO - no previously-included directories found matching 'docs/_build' 04:41:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:41:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:41:19 INFO - Cleaning up... 04:41:19 INFO - Return code: 0 04:41:19 INFO - Running post-action listener: _resource_record_post_action 04:41:19 INFO - Running post-action listener: _start_resource_monitoring 04:41:19 INFO - Starting resource monitoring. 04:41:19 INFO - ##### 04:41:19 INFO - ##### Finished create-virtualenv step (success) 04:41:19 INFO - ##### 04:41:19 INFO - ##### 04:41:19 INFO - ##### Running install step. 04:41:19 INFO - ##### 04:41:19 INFO - Running pre-action listener: _resource_record_pre_action 04:41:19 INFO - Running main action method: install 04:41:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:41:20 INFO - Reading from file tmpfile_stdout 04:41:20 INFO - Detecting whether we're running mozinstall >=1.0... 04:41:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:41:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:41:20 INFO - Reading from file tmpfile_stdout 04:41:20 INFO - Output received: 04:41:20 INFO - Usage: mozinstall [options] installer 04:41:20 INFO - Options: 04:41:20 INFO - -h, --help show this help message and exit 04:41:20 INFO - -d DEST, --destination=DEST 04:41:20 INFO - Directory to install application into. [default: 04:41:20 INFO - "/builds/slave/test"] 04:41:20 INFO - --app=APP Application being installed. [default: firefox] 04:41:20 INFO - mkdir: /builds/slave/test/build/application 04:41:20 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'] 04:41:20 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 04:41:53 INFO - Reading from file tmpfile_stdout 04:41:53 INFO - Output received: 04:41:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:41:53 INFO - Running post-action listener: _resource_record_post_action 04:41:53 INFO - ##### 04:41:53 INFO - ##### Finished install step (success) 04:41:53 INFO - ##### 04:41:53 INFO - ##### 04:41:53 INFO - ##### Running run-tests step. 04:41:53 INFO - ##### 04:41:53 INFO - Running pre-action listener: _resource_record_pre_action 04:41:53 INFO - Running main action method: run_tests 04:41:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:41:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:41:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:41:53 INFO - 2016-05-09 04:41:53.940 screenresolution[6790:118541] starting screenresolution argv=screenresolution get 04:41:53 INFO - 2016-05-09 04:41:53.943 screenresolution[6790:118541] Display 0: 1600x1200x32@60 04:41:53 INFO - 2016-05-09 04:41:53.949 screenresolution[6791:118544] starting screenresolution argv=screenresolution list 04:41:53 INFO - Available Modes on Display 0 04:41:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:41:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:41:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:41:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:41:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:41:54 INFO - Intel Iris: 04:41:54 INFO - Chipset Model: Intel Iris 04:41:54 INFO - Type: GPU 04:41:54 INFO - Bus: Built-In 04:41:54 INFO - VRAM (Dynamic, Max): 1536 MB 04:41:54 INFO - Vendor: Intel (0x8086) 04:41:54 INFO - Device ID: 0x0a2e 04:41:54 INFO - Revision ID: 0x0009 04:41:54 INFO - Displays: 04:41:54 INFO - Display: 04:41:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:41:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:41:54 INFO - Display Serial Number: 1600x1200 60 04:41:54 INFO - Main Display: Yes 04:41:54 INFO - Mirror: Off 04:41:54 INFO - Online: Yes 04:41:54 INFO - Rotation: Supported 04:41:54 INFO - Return code: 0 04:41:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:41:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:41:54 INFO - Python 2.7.3 04:41:54 INFO - Return code: 0 04:41:54 INFO - grabbing minidump binary from tooltool 04:41:54 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')]} 04:41:54 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 0x109872d30>, '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 0x109696db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109869b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:41:54 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 04:41:54 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 04:41:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:41:54 INFO - Return code: 0 04:41:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:41:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:41:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:41:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:41:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:41:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:41:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:41:55 INFO - Return code: 0 04:41:55 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/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--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 04:41:55 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/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0194 --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 04:41:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hcYGatuSGR/Render', 04:41:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:55 INFO - 'HOME': '/Users/cltbld', 04:41:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:55 INFO - 'LOGNAME': 'cltbld', 04:41:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:41:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:41:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:55 INFO - 'MOZ_NO_REMOTE': '1', 04:41:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:41:55 INFO - 'NO_EM_RESTART': '1', 04:41:55 INFO - 'PAGER': '/bin/cat', 04:41:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:55 INFO - 'PWD': '/builds/slave/test', 04:41:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:41:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:55 INFO - 'SHELL': '/bin/bash', 04:41:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cfT2dtif3q/Listeners', 04:41:55 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 04:41:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:55 INFO - 'USER': 'cltbld', 04:41:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:55 INFO - 'XPC_FLAGS': '0x0', 04:41:55 INFO - 'XPC_SERVICE_NAME': '0', 04:41:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:55 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/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--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 04:41:55 INFO - mozversion application_buildid: 20160509022157 04:41:55 INFO - mozversion application_changeset: ab8ae2c23df566acee8cbeb64e5164fae8a10b0e 04:41:55 INFO - mozversion application_display_name: Nightly 04:41:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:41:55 INFO - mozversion application_name: Firefox 04:41:55 INFO - mozversion application_remotingname: firefox 04:41:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:41:55 INFO - mozversion application_vendor: Mozilla 04:41:55 INFO - mozversion application_version: 49.0a1 04:41:55 INFO - mozversion platform_buildid: 20160509022157 04:41:55 INFO - mozversion platform_changeset: ab8ae2c23df566acee8cbeb64e5164fae8a10b0e 04:41:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:41:55 INFO - mozversion platform_version: 49.0a1 04:41:55 INFO - using testdate: 1462794115 04:41:55 INFO - actual date: 1462794115 04:41:55 INFO - starting webserver on 'localhost:61796' 04:41:55 INFO - SUITE-START | Running 2 tests 04:41:55 INFO - TEST-START | dromaeo_css 04:41:55 INFO - operating with platform_type : mac_ 04:41:55 INFO - Initialising browser for dromaeo_css test... 04:41:55 INFO - TEST-INFO | started process 6809 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpe79a38/profile http://localhost:61796/getInfo.html) 04:41:57 INFO - PROCESS | 6809 | __metrics Screen width/height:1600/1200 04:41:57 INFO - PROCESS | 6809 | colorDepth:24 04:41:57 INFO - PROCESS | 6809 | Browser inner width/height: 1024/689 04:41:57 INFO - PROCESS | 6809 | __metrics 04:41:58 INFO - PROCESS | 6809 | 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" 04:41:58 ERROR - PROCESS | 6809 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:41:58 INFO - TEST-INFO | 6809: exit 0 04:41:58 INFO - Browser initialized. 04:41:58 INFO - Running cycle 1/1 for dromaeo_css test... 04:41:58 INFO - TEST-INFO | started process 6811 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpe79a38/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:42:10 INFO - PROCESS | 6811 | RSS: Main: 239054848 04:42:10 INFO - PROCESS | 6811 | 04:43:02 INFO - PROCESS | 6811 | 1462794182221 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] 04:45:12 INFO - PROCESS | 6811 | Cycle 1(1): loaded http://localhost:61796/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61796/tests/dromaeo/cssquery-ext.html) 04:45:14 INFO - PROCESS | 6811 | RSS: Main: 391917568 04:45:14 INFO - PROCESS | 6811 | 04:48:17 INFO - PROCESS | 6811 | Cycle 1(1): loaded http://localhost:61796/tests/dromaeo/cssquery-ext.html (next: http://localhost:61796/tests/dromaeo/cssquery-jquery.html) 04:48:17 INFO - PROCESS | 6811 | RSS: Main: 374890496 04:48:17 INFO - PROCESS | 6811 | 04:51:19 INFO - PROCESS | 6811 | Cycle 1(1): loaded http://localhost:61796/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61796/tests/dromaeo/cssquery-mootools.html) 04:51:20 INFO - PROCESS | 6811 | RSS: Main: 370921472 04:51:20 INFO - PROCESS | 6811 | 04:54:22 INFO - PROCESS | 6811 | Cycle 1(1): loaded http://localhost:61796/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61796/tests/dromaeo/cssquery-prototype.html) 04:54:23 INFO - PROCESS | 6811 | RSS: Main: 367788032 04:54:23 INFO - PROCESS | 6811 | 04:57:26 INFO - PROCESS | 6811 | Cycle 1(1): loaded http://localhost:61796/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61796/tests/dromaeo/cssquery-yui.html) 04:57:27 INFO - PROCESS | 6811 | RSS: Main: 336805888 04:57:27 INFO - PROCESS | 6811 | 05:00:30 INFO - PROCESS | 6811 | Cycle 1(1): loaded http://localhost:61796/tests/dromaeo/cssquery-yui.html (next: http://localhost:61796/tests/dromaeo/cssquery-dojo.html) 05:00:30 INFO - PROCESS | 6811 | RSS: Main: 374747136 05:00:30 INFO - PROCESS | 6811 | 05:00:30 INFO - PROCESS | 6811 | __start_tp_report 05:00:30 INFO - PROCESS | 6811 | _x_x_mozilla_page_load 05:00:30 INFO - PROCESS | 6811 | _x_x_mozilla_page_load_details 05:00:30 INFO - PROCESS | 6811 | |i|pagename|runs| 05:00:30 INFO - PROCESS | 6811 | |0;dojo.html;3085.2132706159928;3359.865605375787;3393.9987703627435;3401.812900290483;3442.4492081267003;5545.667259964405;5582;5600.96382169298;5661.348944871341;5677.744501497432;5591.496765291123;5657.547396208293;5658.8019419320335;5710.000749868777;5732.7133643317875;5072.568831649314;5099.184130539114;5126.871828204287;5149.201873709581;5242.370915490146;5235.738213089349;5247.344081989751;5264.026155161288;5289.603279754034;5307.52232299093;5039.748012599373;5101.999999999982;5111.7699703513435;5139.974300128512;5157.355330583677;5154.716490593016;5154.793808247666;5162.948370516285;5166.199239117906;5172.250023746536;5137.409197942247;5171.922421163686;5179.119549676564;5215.791368345262;5226.634135610509;5413.918791218135;5434.755436005389;5489.533389661883;5493.670379777186;5511.366192887829;5151.4333423323405;6112.572119951606;6160.784372546984;6162.7534898604;6199.783007594712;6136.987397079477;6200.81397558074;6203.069539569055;6221.626702397825;6241.750329986795;4944.431390390079;4999.500049995007;5696.629719068289;5781.624194427349;5792.015357389254;6097.878042439126;6203.937960620336;6210.782622608187;6210.937890621036;6229.283632382243;6050.273967123973;6084.241038743713;6124.050772130237;6138.7237574309265;6164.106204600397;6131.816045518642;6185.969070154711;6202.689865506707;6204.751809927552;6215.223097112861;6049.0926361046195;6178.073289006595;6192.969035154795;6195.504359651307;6205.348438413992;6113.567524283629;6122.20411346531;6156.353582873823;6177.444030037318;6220.066989951453;4931.284963680247;4957.082939656103;4957.454679985199;4975.552200301918;4986.675866068694;4381.277089280296;4466.665000125004;4499.842505512357;4503.144402563503;4523.954760452353;5093.923591146136;5545.334559852843;5597.132444471114;5670.262865827416;5732.856678582985;6178.41305076017;6178.814635560941;6185.690715464209;6187.071939209155;6188.319284878659;5424.186372044146;5703.230063941315;5731.254936858182;5731.541476681856;5747.5114615257335;5208.739563021833;5302.840914772563;5364.597655175799;5367.516923476827;5412.972935135299;6166.691665416801;6232.688365581703;6247.0629405589525;6249.96875015622;6269.843253918597;6162.014077747539;6183.690815459119;6205.844853878689;6219.844503887439;6223.315435302204;6142.959235178615;6143.665407145256;6203.286622038343;6252.624842509464;6282.434580887742;6130.0312685453755;6145.66198859067;6152.815415537541;6162.352952939877;6171.6297022178815;6215.875682486235;6248.0940263660605;6249.437550620466;6254.749810007549;6265.843353916189;5814.5348372130975;5924.437178468039;5941;6002.609830361012;6030.095485677183;5922.378150294157;5944.821655350346;5966.19456373384;5989.1914591529585;6016.278046634432;5798.5651076168615;5925.288965324189;5951.375105613848;5985;5993.160957465871;6089.512451073572;6119.510439164945;6124.5406594506485;6133.44798968095;6138.046095287564;5753.510951569164;5809.8547536311935;5956;5957.49361304294;5976.731047102978 05:00:30 INFO - PROCESS | 6811 | |1;ext.html;504.97222652754084;508.8702380892873;510.8824970256843;542.5225801294858;542.8262955854126;24397.63403548946;30643;35175.472367914466;43472.695819125394;43513.7824310878;42412.15175696484;44599.10801783962;44632.330515042304;44721.552784472144;44829.7758511207;9716.271279654018;9905.90094099057;9906.157976571963;9907.801843963116;9972.451515166664;8436.704715334965;8440.577971101451;9710;9840.065193806613;9845.606175752997;8027.438079334449;9890.406575605452;9895;9896.455694936776;9938.15525680318;9784.36401633895;9847.655332063343;9853.261005424622;9856.605735770561;9868.95065524675;9651.855222171673;9714.02859714023;9726;9759;9763.707088787347;41142.177156457;41275;41333;41464.26821586072;41591;2714.362124900647;2832.3485598312295;3424.178197232646;3438.5186073949667;3440.2947395783804;26359.472810543873;29802.956896508735;30166.15004335656;30548.389032219453;30832.845835770902;2629.8165825378464;2668.4796464689393;2724.155511791351;2815.7325054119824;2827.2083816531403;462472.9999999966;465016;473347;475274;476976;35005.64994350075;36182;36313.63686363155;60357;62572;37519.81240093782;37683.62316376856;37686.62313376831;37693.62306376956;37822.05444863845;15549.378024879104;15601;15626.921865390601;15650.530484085495;15665.530034098996;13059.281739504419;13148.934255328853;13149.145305555108;13182.802257965946;13196;7088.645567721696;8318.625661845232;8345.5409952452;8416.579171041423;8424.4945303282;3460.965390346115;3478.3912815257227;3479.7216222702123;3481.8433170506883;3507.1232191952013;988.6292640259902;1011.8178727828914;1013.6654903881849;1037.4916291017348;1105.5798796457295;25946;29850.40299194004;30489.237769055508;30587.847060764554;30830.92091776822;992.6029588164647;1099.8680158380885;1126.808442564766;1131.9943400282946;1234.4876876096318;12943;14910.105393676415;15068;15392.538223853302;15559.611009724622;663279;673061;676621;677431;678328;60348;60913.08630370548;60955.08567371493;60957;61103.69448152731;1191.2614179208947;1459.5402448228774;1494.5068127518111;1513.954581362561;1517.446132161775;30631.54052689256;30726.539101913935;30789.38421231518;31390;31626.841865790524;52373;52896.471035289156;53036.204456932406;53169.936601268535;53195;436784;438006;438725;438899;439017;26599.468010640066;27157.59263611087;27671;27679.861600691867;27728.722712772615;40113.1977360457;45805.08389832251;46742;47709.52290477051;48051.5194848061;37173.814130929175;37912.24175516419;37996.050098746644;38835.805820970716;39021.6097839018;35102.64897351096;37657;37694.62305377021;38406.84779456621;38510.42234366543;37390.06524836901;37669.434958476195;37967;38256.23487530289;38798.41802373023;29909.70090299069;30262.394752105276;30762.84618576893;30818.229544261572;30924.22689432782;24453.144139955017;24767.50464990726;24806.50386992286;25413;25737.069719771465 05:00:30 INFO - PROCESS | 6811 | |2;jquery.html;381.49070990228046;387.77702820877994;390.22958996552507;391.19804400978006;417.36143700138797;18319.084045797706;20488.18047278107;20790.376288711326;21559.56880862382;21628.8918555407;20610.969451527402;20661.380158595268;21435.14259429625;21474.89262553685;21478;5920.851978700534;6208.81373558794;6210.4100110489435;6220.906686399708;6223.2532096148525;6054.273487181545;6102.359252278514;6199.349068347826;6200;6212.534059945499;5962;6149.446549810516;6159.414855588734;6171.629702217859;6184.721687524072;6051.757929682807;6075.574709770318;6133.815985520419;6139.447449729501;6161.7227224775;5256.816011439581;5314.441983591744;5341.679499230058;5346.331708536433;5351.7056561889085;26525;26894.5965810513;27292.727072729416;27466.17601471959;27653.723462765516;2106.178590349765;2215;2227.13141874669;2237.7314722233273;2334.4747431827873;14734.410623575044;15138.318775655121;15182.240887955559;15464.381424742996;15559.844401556065;1487.8363380028188;1714.9742753858702;1793.32750218668;1805.1696219738935;1897.9810201897942;116288.83711162864;118664;118952;119438;119709.40145299218;10964.56141754337;11221;11707.473163707484;11765;12098;14550.781738273934;14612.707745845237;14637.048591841496;14640.04839685417;14653.194074325802;8672.95663521691;8888.555572221492;8901.910980890238;9013.414128081786;9046.638134474548;8834.469931804113;8839.513826739463;8850.601722922354;8955.776105597412;8969.955150224207;6791;7012.193597736224;7023.578585284797;7037.542559733601;7037.6481175941;1593.4741535293326;1949.1618604000132;1949.7855235924037;1970.9014549272479;1976.1897621974922;888.2849306308354;905.3526728389234;1068.5779117248644;1085.529108058585;1089.362722807151;16401.917990409973;19734.90132549328;21723;21898.401335719358;21940.780592194194;1007.4408703169664;1023.2905716776685;1052.3054783842626;1102.7463683352714;1129.7062763681338;9866.742557005202;11506.482208300478;11588.76822463546;11678.299302041905;11838.644840654793;614886;625428;630007;636615;639095;81786;114133;144500.27749861183;151018;154242;1001.9949900250452;1007.5012868630075;1083.766990097133;1085.733995171188;1086.1690806533009;14945;16938.068406237777;17022.659546809242;18772.6245475087;19341.032948352808;50927.74536127296;51848.740756297484;52145.478545214064;53408;54717;63419;79600;81639.59180204061;81843;84218.57890710507;8040.638171282423;8165.061017983008;8254.917450825415;8301.916980830114;8658.48049117055;9542.904570954202;12046.638600841989;12178.512859485521;13135.934320328337;13211.537596184087;11159.051480624237;11455.026322762325;11535.01952334061;11595.536178552764;11632.302061876315;9262.768430789285;10801.243912926335;10981.17641176931;11091;11191.104711623213;9600.039996000343;10940.398278094463;11352.375619340684;11721.648350549498;11751.177417580688;7501.28737769911;8021.2379823916635;8030.799230019063;8249.917500824915;8555.82888342242;8397.985909286623;11103.38931358783;11240.875912408694;11330.660080197607;11404 05:00:30 INFO - PROCESS | 6811 | |3;mootools.html;1354.045397994414;1549.7675348697694;1584.6196912740938;1613.080544089869;1954.1401783215376;7745.3029227369525;8173.877391839127;8358.038825535064;8375.036870759865;8490.235878770909;8089.23152300532;8344.624491897865;8345.290650294732;8364.498130112182;8366.95816520918;2294.609916314222;2326.61610834213;2332.88335583221;2335.2177020698086;2336.9649455258186;2346.765323467648;2370.7866292033714;2389.6893403857475;2394.940126496843;2402.7116745990506;2328.8253380996407;2334.731505876829;2344.2732752846673;2374.6675465434855;2378.2389635316704;2319.5476882008015;2346.296111166643;2356.352003199125;2359.4337359033707;2360.409897525619;2257.2760289358794;2340.648902664597;2340.712608065557;2347.401412639783;2348.154664320843;7754.883676744853;7982.161873003367;8279.793005174859;8343.374246931504;8347;4939.703617782944;5066.265391518246;5075.974620126913;5111.207762796773;5118.923216151761;3434;3502.614712381636;3585.8924232273075;3597.1007248187952;3603.7837729736298;931.1712575807537;952.3999880075509;952.6311354748569;953.6137864165024;972.2319367699508;11513.884861151366;11523.423828808525;11627.94186029073;11633.95294423506;11634.243774154653;4585.862424127282;4813.302071199656;4889.853304400874;4922.33548470959;4937.432195297587;9861.211103111877;10005.699829005142;10007.549660265306;10010.399376037461;10030;5410.91883621746;5450.5367043801725;5451.20110363587;5451.754671039813;5455.318085239345;4814.8796280093275;4886.413630364379;4951.480094590016;4987.750612469362;4995.350604421402;3830.9233815323537;3858.459815625815;3897.922041559153;3921.156951255494;3934.1934903344745;1280.7182419867613;1286.8327117474669;1296.824928634641;1309.9607011789662;1311.7179806341683;906.4923642760075;931.5435436636;933.9486328251876;946.7775072857872;959.0601210813303;5474.534664553478;5881.882362352729;5897.144913987448;6028.608140470856;6125.663088530085;449.55044955044957;450.87600909749426;452.4728691074861;462.576742280816;471.7498628634147;3981.422693709396;3986.5614782373914;4047.3119569672635;4065.8576949806616;4069.674426045909;8880.955595222113;9893.901060989298;10598.947005265078;10599.682009539727;10706.46467676613;10402.167826573856;10493.947530262452;10537.63118290856;10550.208734344955;10677.78644427107;8167.469114507538;8194;8255.711050113217;8281;8299.211574900375;5515.172724091419;5856.882862342644;5896.469317761422;5946.197263369563;5955.285365756137;9791.608335666493;9801.068898454636;10107.848382274125;10117.443540605338;10124.59501619927;9926.205903527845;10001.14990225839;10044.447555384524;10091;10233.079022887976;7249;7282.162551306667;7347.191808901123;7354.6690398931105;7360.1535823381;7361.638096952081;7746.341560967381;7910.5649189295145;7938.206179382016;8204.384671149806;6382.20222472191;7321.524100933422;7420.664280429359;7490.4007679386605;7599.316061554487;7308.976743256055;7327.157376901724;7374.705011799682;7464.552126872405;7480.551166929784;6810.9318906810295;7041.859162816818;7049.718011279491;7053.647317634201;7057.117860267466;3801.657850793442;4466.374707541012;4626.329182268485;4737.668363214542;5113.411957624772;4210.32634778434;4951.084049450863;5029.371328583927;5063.113955057924;5199.532042116228 05:00:30 INFO - PROCESS | 6811 | |4;prototype.html;268.8198906732489;292.3816128887404;301.7194009571099;313.34510872276934;316.60266365711027;5329.866753331169;5334.199870019499;5341.599380046498;5391.137418013119;5402.37872644646;4199.34910088936;4863.27050942358;5149.74251287436;5219.556337711299;5237.188235823453;3999.760014399131;4020.557738648746;4031.3348297530874;4068.9593104068877;4069.9214708102377;4035.47538819953;4038.9394159087637;4046.8785936421955;4076.490438695163;4128.938065929014;3580.427131658935;3783.6594706476412;4031.3952907064026;4032.1330913853517;4053.2298863215897;3303.4969089064416;3620.2578471413563;3881.904590656856;4043.898902527431;4095.8771236862794;3522.718182545416;3543.4664215086336;3545.804980726059;3555.5555555555557;3580.7314451416246;4206.831726730927;4877.170880950247;5026.547610715053;5034.395872495287;5038.395392552917;4108.589141085868;4513.819447222108;4635.281531362644;4676.755157524407;4717.740524271164;5412.2152287918425;5457.808976685837;5482.806877249092;5483.396826349099;5520.365158006841;3793.6016718244737;3885.3977633466857;3893.9502916966603;3922.235164142993;3947.4473573699706;5085.711429856579;5133.666311689765;5307.681539107627;5618.325491253822;5634.2110655225515;3873.9418908716393;3987.760734355948;3989.6608788253325;3990.541087774943;4007.038310805444;3394.6605339465855;3560.857565697343;4039.7576145431367;4050.513938327361;4101.876943691694;4052.2705912935367;4137.317342638491;4494.303382975644;4536.618275405439;4584.862454126382;4567.383403240587;5247.422783493813;5346.4386239445075;5583.664980101207;5597.4327188387315;1105.9004689577816;1198.6224339333082;1311.272243904642;1318.1959005006938;1323.2060763541797;760.3271105072051;810.2221867007621;839.4669384940606;870.5331516394768;926.995365023184;4223.303154979455;4773.76131193439;4828.46886842447;4849.975750121227;4862.027594481118;4639.582437580634;5289.788408463618;5391.78432862689;5416.647917885325;5447.875328621841;4349.717268377545;4432.874823249601;4447.955520444754;4708.670393072521;4764.618830493552;2543.8728063596745;2831.1931099636504;2837.4183292425005;2844.2462747371965;2848.9430211395656;4772.665913386099;5025.321581586512;5449.809256675997;5568.770113334256;5572.3870374258795;4769.591356365014;5039.546440820271;5466.835994920159;5520.1167813149705;5542.3626282977975;5182.792688292426;5428.647137936022;5483.588730845123;5546.889062218814;5547.334319881559;4387.846425375096;4651.883702907455;4850.927236091391;5312.165929004587;5318.920216196838;4301.548337424525;4726.834560790356;4903;5001.974990125026;5139.91833307511;4933.273354325957;5545.528630066489;5780.913286300624;5783.421657834351;6008.279006519246;3727.9068023299633;3865.497485327002;3880.320943834874;3880.902977425587;3882.398228274509;5090.541851233407;5369.174480676589;5703.173039909273;5734.942650573441;5755.798547050833;5234.005538947587;5261.789528418821;5582.748776304995;5609.635373700697;5614.354349249888;4733.290006499053;4763.1426343258545;5179.741012949262;5507.044999025288;5634.408387119293;4296.312589985588;4616.030633566854;5375.462453754593;5430.13117901134;5567.85637529546;5119.641625086358;5327.866803329948;5474.275603184984;5481.574790554405;5502.889942201214;4825.841797968533;5076.923846142237;5390.541803946709;5396.78412863481;5478.2195786370385 05:00:30 INFO - PROCESS | 6811 | |5;yui.html;648.4876947211701;652.5888690125221;653.3988730368061;702.1679310017627;727.7671145233527;14333.784993225097;15051.247437628115;15060.84939150608;15071.773923391143;16586.83413165868;15926.442574509894;16368.099754513498;16379.83620163798;16401.507954761346;16407.17964101793;733.1751779248345;749.9400047996174;928.3501548915752;945.7068308824287;946.0728486083642;898.24996128233;920.2408013388947;923.3536524432924;925.3799954030816;926.4626516620377;955.6750704760381;966.9371490853107;967.7774111954254;968.8982656821038;969.2778879734608;754.272127397059;948.1466679987997;961.7018724195522;961.8845738511388;971.5142428785607;727.8980942668031;729.3363039633961;729.3836707981746;730.6456368661195;731.0970950875665;15261.236938153159;15312.770308445382;15345.539633811004;15348.539543813704;15356.001859879183;11843.467043982955;12081.275123492618;12216.144869859114;12340.876591234062;12598;8184.549849758261;9623.422594644344;9671.90328096717;9883.258755593359;10370.688879333633;3296.0770984124483;3453.8100404477746;3541.8229088545727;3598.2803439312243;3599.4600809878466;81304;82886.9999999994;82997;83220.58389708074;83237;8697.521636309937;8794.340424468062;9419;9472.242220622335;9658.613655453702;20655.8967205166;20917.372478825357;20934.267300644406;20983.580328393346;20987.265445709632;7318.817029574303;7409.555426674417;7522.172561018284;7594.231725509697;7758.107817601048;3948.1116748731306;3955.3869150281753;3965.861194858166;4000.299947509174;4221.641160501392;3554.449060395643;3632.4369722693023;3661.853525858936;3663.285659296465;3666.5416822897137;619.4455961914151;625.4121126141413;633.7433339497511;642.5181114164377;645.7675236914707;558.5782734035859;576.6482445708115;596.8597379615787;614.0696844280918;616.2143267334095;7741.61291935401;8034.47775894565;8179.754607361789;8229.711960081368;8238.052623948202;561.1946856261278;577.4398833131855;579.8115612425978;586.2408181405091;587.9827897736939;3519.1202199450136;3657.5976642569294;3726.16161363691;3936.4292177634084;3942.1327307992196;85679.57160214284;87313;88333.11666883249;91147.63278550826;91940.54029729808;51052.23421648678;63547;70733.64633176904;78255;81433.18566814256;54868;70331.29668703247;70692;70970;71742.84771456865;7120.216776154515;7157.856842863218;7348.154962179204;7364.5581265124265;7372.705091796268;45322.54677453183;45946.77026614846;46511.83720407017;47628;47910;41032.38451423291;61234.38765612287;64517;65051;65485;9809.755029750753;10049.196064314983;10248.282620216512;10302.124319432933;10313.381197128196;10901.182411319342;11467.483963221843;11567.305961642329;11646.068314534987;12125.575604853788;11050.944745276543;11543.88456115428;11827.645170644895;11877.346745929057;11921.165518413627;11430.828537571777;11523.769524609294;11551.249168804;11691.883081169079;11779.646610601696;11391.829122563335;11420.828687569527;11438.256513326607;11488.138389620644;11593.768124637292;9422.246220302222;9436.764080898032;9474.062322267866;9573.04269573065;9604.9039509604;8966.372353935161;9164.762757027716;9426.104520070583;9435.811283774423;9915.55380007887 05:00:30 INFO - PROCESS | 6811 | __end_tp_report 05:00:30 INFO - PROCESS | 6811 | __start_cc_report 05:00:30 INFO - PROCESS | 6811 | _x_x_mozilla_cycle_collect,1607 05:00:30 INFO - PROCESS | 6811 | __end_cc_report 05:00:30 INFO - PROCESS | 6811 | __startTimestamp1462795230616__endTimestamp 05:00:30 INFO - PROCESS | 6811 | 05:00:30 INFO - PROCESS | 6811 | ------- Summary: start ------- 05:00:30 INFO - PROCESS | 6811 | Number of tests: 6 05:00:30 INFO - PROCESS | 6811 | 05:00:30 INFO - PROCESS | 6811 | [#0] dojo.html Cycles:165 Average:5678.93 Median:5925.29 stddev:631.52 (10.7%) stddev-sans-first:599.78 05:00:30 INFO - PROCESS | 6811 | Values: 3085.2 3359.9 3394.0 3401.8 3442.4 5545.7 5582.0 5601.0 5661.3 5677.7 5591.5 5657.5 5658.8 5710.0 5732.7 5072.6 5099.2 5126.9 5149.2 5242.4 5235.7 5247.3 5264.0 5289.6 5307.5 5039.7 5102.0 5111.8 5140.0 5157.4 5154.7 5154.8 5162.9 5166.2 5172.3 5137.4 5171.9 5179.1 5215.8 5226.6 5413.9 5434.8 5489.5 5493.7 5511.4 5151.4 6112.6 6160.8 6162.8 6199.8 6137.0 6200.8 6203.1 6221.6 6241.8 4944.4 4999.5 5696.6 5781.6 5792.0 6097.9 6203.9 6210.8 6210.9 6229.3 6050.3 6084.2 6124.1 6138.7 6164.1 6131.8 6186.0 6202.7 6204.8 6215.2 6049.1 6178.1 6193.0 6195.5 6205.3 6113.6 6122.2 6156.4 6177.4 6220.1 4931.3 4957.1 4957.5 4975.6 4986.7 4381.3 4466.7 4499.8 4503.1 4524.0 5093.9 5545.3 5597.1 5670.3 5732.9 6178.4 6178.8 6185.7 6187.1 6188.3 5424.2 5703.2 5731.3 5731.5 5747.5 5208.7 5302.8 5364.6 5367.5 5413.0 6166.7 6232.7 6247.1 6250.0 6269.8 6162.0 6183.7 6205.8 6219.8 6223.3 6143.0 6143.7 6203.3 6252.6 6282.4 6130.0 6145.7 6152.8 6162.4 6171.6 6215.9 6248.1 6249.4 6254.7 6265.8 5814.5 5924.4 5941.0 6002.6 6030.1 5922.4 5944.8 5966.2 5989.2 6016.3 5798.6 5925.3 5951.4 5985.0 5993.2 6089.5 6119.5 6124.5 6133.4 6138.0 5753.5 5809.9 5956.0 5957.5 5976.7 05:00:30 INFO - PROCESS | 6811 | 05:00:30 INFO - PROCESS | 6811 | [#1] ext.html Cycles:180 Average:65195.62 Median:37939.62 stddev:144002.40 (379.6%) stddev-sans-first:144324.45 05:00:30 INFO - PROCESS | 6811 | Values: 505.0 508.9 510.9 542.5 542.8 24397.6 30643.0 35175.5 43472.7 43513.8 42412.2 44599.1 44632.3 44721.6 44829.8 9716.3 9905.9 9906.2 9907.8 9972.5 8436.7 8440.6 9710.0 9840.1 9845.6 8027.4 9890.4 9895.0 9896.5 9938.2 9784.4 9847.7 9853.3 9856.6 9869.0 9651.9 9714.0 9726.0 9759.0 9763.7 41142.2 41275.0 41333.0 41464.3 41591.0 2714.4 2832.3 3424.2 3438.5 3440.3 26359.5 29803.0 30166.2 30548.4 30832.8 2629.8 2668.5 2724.2 2815.7 2827.2 462473.0 465016.0 473347.0 475274.0 476976.0 35005.6 36182.0 36313.6 60357.0 62572.0 37519.8 37683.6 37686.6 37693.6 37822.1 15549.4 15601.0 15626.9 15650.5 15665.5 13059.3 13148.9 13149.1 13182.8 13196.0 7088.6 8318.6 8345.5 8416.6 8424.5 3461.0 3478.4 3479.7 3481.8 3507.1 988.6 1011.8 1013.7 1037.5 1105.6 25946.0 29850.4 30489.2 30587.8 30830.9 992.6 1099.9 1126.8 1132.0 1234.5 12943.0 14910.1 15068.0 15392.5 15559.6 663279.0 673061.0 676621.0 677431.0 678328.0 60348.0 60913.1 60955.1 60957.0 61103.7 1191.3 1459.5 1494.5 1514.0 1517.4 30631.5 30726.5 30789.4 31390.0 31626.8 52373.0 52896.5 53036.2 53169.9 53195.0 436784.0 438006.0 438725.0 438899.0 439017.0 26599.5 27157.6 27671.0 27679.9 27728.7 40113.2 45805.1 46742.0 47709.5 48051.5 37173.8 37912.2 37996.1 38835.8 39021.6 35102.6 37657.0 37694.6 38406.8 38510.4 37390.1 37669.4 37967.0 38256.2 38798.4 29909.7 30262.4 30762.8 30818.2 30924.2 24453.1 24767.5 24806.5 25413.0 25737.1 05:00:30 INFO - PROCESS | 6811 | 05:00:30 INFO - PROCESS | 6811 | [#2] jquery.html Cycles:180 Average:36202.51 Median:21810.70 stddev:104832.83 (480.6%) stddev-sans-first:105092.41 05:00:30 INFO - PROCESS | 6811 | Values: 381.5 387.8 390.2 391.2 417.4 18319.1 20488.2 20790.4 21559.6 21628.9 20611.0 20661.4 21435.1 21474.9 21478.0 5920.9 6208.8 6210.4 6220.9 6223.3 6054.3 6102.4 6199.3 6200.0 6212.5 5962.0 6149.4 6159.4 6171.6 6184.7 6051.8 6075.6 6133.8 6139.4 6161.7 5256.8 5314.4 5341.7 5346.3 5351.7 26525.0 26894.6 27292.7 27466.2 27653.7 2106.2 2215.0 2227.1 2237.7 2334.5 14734.4 15138.3 15182.2 15464.4 15559.8 1487.8 1715.0 1793.3 1805.2 1898.0 116288.8 118664.0 118952.0 119438.0 119709.4 10964.6 11221.0 11707.5 11765.0 12098.0 14550.8 14612.7 14637.0 14640.0 14653.2 8673.0 8888.6 8901.9 9013.4 9046.6 8834.5 8839.5 8850.6 8955.8 8970.0 6791.0 7012.2 7023.6 7037.5 7037.6 1593.5 1949.2 1949.8 1970.9 1976.2 888.3 905.4 1068.6 1085.5 1089.4 16401.9 19734.9 21723.0 21898.4 21940.8 1007.4 1023.3 1052.3 1102.7 1129.7 9866.7 11506.5 11588.8 11678.3 11838.6 614886.0 625428.0 630007.0 636615.0 639095.0 81786.0 114133.0 144500.3 151018.0 154242.0 1002.0 1007.5 1083.8 1085.7 1086.2 14945.0 16938.1 17022.7 18772.6 19341.0 50927.7 51848.7 52145.5 53408.0 54717.0 63419.0 79600.0 81639.6 81843.0 84218.6 8040.6 8165.1 8254.9 8301.9 8658.5 9542.9 12046.6 12178.5 13135.9 13211.5 11159.1 11455.0 11535.0 11595.5 11632.3 9262.8 10801.2 10981.2 11091.0 11191.1 9600.0 10940.4 11352.4 11721.6 11751.2 7501.3 8021.2 8030.8 8249.9 8555.8 8398.0 11103.4 11240.9 11330.7 11404.0 05:00:30 INFO - PROCESS | 6811 | 05:00:30 INFO - PROCESS | 6811 | [#3] mootools.html Cycles:180 Average:5607.06 Median:4938.57 stddev:3143.76 (63.7%) stddev-sans-first:3136.33 05:00:30 INFO - PROCESS | 6811 | Values: 1354.0 1549.8 1584.6 1613.1 1954.1 7745.3 8173.9 8358.0 8375.0 8490.2 8089.2 8344.6 8345.3 8364.5 8367.0 2294.6 2326.6 2332.9 2335.2 2337.0 2346.8 2370.8 2389.7 2394.9 2402.7 2328.8 2334.7 2344.3 2374.7 2378.2 2319.5 2346.3 2356.4 2359.4 2360.4 2257.3 2340.6 2340.7 2347.4 2348.2 7754.9 7982.2 8279.8 8343.4 8347.0 4939.7 5066.3 5076.0 5111.2 5118.9 3434.0 3502.6 3585.9 3597.1 3603.8 931.2 952.4 952.6 953.6 972.2 11513.9 11523.4 11627.9 11634.0 11634.2 4585.9 4813.3 4889.9 4922.3 4937.4 9861.2 10005.7 10007.5 10010.4 10030.0 5410.9 5450.5 5451.2 5451.8 5455.3 4814.9 4886.4 4951.5 4987.8 4995.4 3830.9 3858.5 3897.9 3921.2 3934.2 1280.7 1286.8 1296.8 1310.0 1311.7 906.5 931.5 933.9 946.8 959.1 5474.5 5881.9 5897.1 6028.6 6125.7 449.6 450.9 452.5 462.6 471.7 3981.4 3986.6 4047.3 4065.9 4069.7 8881.0 9893.9 10598.9 10599.7 10706.5 10402.2 10493.9 10537.6 10550.2 10677.8 8167.5 8194.0 8255.7 8281.0 8299.2 5515.2 5856.9 5896.5 5946.2 5955.3 9791.6 9801.1 10107.8 10117.4 10124.6 9926.2 10001.1 10044.4 10091.0 10233.1 7249.0 7282.2 7347.2 7354.7 7360.2 7361.6 7746.3 7910.6 7938.2 8204.4 6382.2 7321.5 7420.7 7490.4 7599.3 7309.0 7327.2 7374.7 7464.6 7480.6 6810.9 7041.9 7049.7 7053.6 7057.1 3801.7 4466.4 4626.3 4737.7 5113.4 4210.3 4951.1 5029.4 5063.1 5199.5 05:00:30 INFO - PROCESS | 6811 | 05:00:30 INFO - PROCESS | 6811 | [#4] prototype.html Cycles:180 Average:4383.02 Median:4799.80 stddev:1309.72 (27.3%) stddev-sans-first:1276.47 05:00:30 INFO - PROCESS | 6811 | Values: 268.8 292.4 301.7 313.3 316.6 5329.9 5334.2 5341.6 5391.1 5402.4 4199.3 4863.3 5149.7 5219.6 5237.2 3999.8 4020.6 4031.3 4069.0 4069.9 4035.5 4038.9 4046.9 4076.5 4128.9 3580.4 3783.7 4031.4 4032.1 4053.2 3303.5 3620.3 3881.9 4043.9 4095.9 3522.7 3543.5 3545.8 3555.6 3580.7 4206.8 4877.2 5026.5 5034.4 5038.4 4108.6 4513.8 4635.3 4676.8 4717.7 5412.2 5457.8 5482.8 5483.4 5520.4 3793.6 3885.4 3894.0 3922.2 3947.4 5085.7 5133.7 5307.7 5618.3 5634.2 3873.9 3987.8 3989.7 3990.5 4007.0 3394.7 3560.9 4039.8 4050.5 4101.9 4052.3 4137.3 4494.3 4536.6 4584.9 4567.4 5247.4 5346.4 5583.7 5597.4 1105.9 1198.6 1311.3 1318.2 1323.2 760.3 810.2 839.5 870.5 927.0 4223.3 4773.8 4828.5 4850.0 4862.0 4639.6 5289.8 5391.8 5416.6 5447.9 4349.7 4432.9 4448.0 4708.7 4764.6 2543.9 2831.2 2837.4 2844.2 2848.9 4772.7 5025.3 5449.8 5568.8 5572.4 4769.6 5039.5 5466.8 5520.1 5542.4 5182.8 5428.6 5483.6 5546.9 5547.3 4387.8 4651.9 4850.9 5312.2 5318.9 4301.5 4726.8 4903.0 5002.0 5139.9 4933.3 5545.5 5780.9 5783.4 6008.3 3727.9 3865.5 3880.3 3880.9 3882.4 5090.5 5369.2 5703.2 5734.9 5755.8 5234.0 5261.8 5582.7 5609.6 5614.4 4733.3 4763.1 5179.7 5507.0 5634.4 4296.3 4616.0 5375.5 5430.1 5567.9 5119.6 5327.9 5474.3 5481.6 5502.9 4825.8 5076.9 5390.5 5396.8 5478.2 05:00:30 INFO - PROCESS | 6811 | 05:00:30 INFO - PROCESS | 6811 | [#5] yui.html Cycles:180 Average:17586.44 Median:629.58 stddev:24491.28 (3890.1%) stddev-sans-first:24526.96 05:00:30 INFO - PROCESS | 6811 | Values: 648.5 652.6 653.4 702.2 727.8 14333.8 15051.2 15060.8 15071.8 16586.8 15926.4 16368.1 16379.8 16401.5 16407.2 733.2 749.9 928.4 945.7 946.1 898.2 920.2 923.4 925.4 926.5 955.7 966.9 967.8 968.9 969.3 754.3 948.1 961.7 961.9 971.5 727.9 729.3 729.4 730.6 731.1 15261.2 15312.8 15345.5 15348.5 15356.0 11843.5 12081.3 12216.1 12340.9 12598.0 8184.5 9623.4 9671.9 9883.3 10370.7 3296.1 3453.8 3541.8 3598.3 3599.5 81304.0 82887.0 82997.0 83220.6 83237.0 8697.5 8794.3 9419.0 9472.2 9658.6 20655.9 20917.4 20934.3 20983.6 20987.3 7318.8 7409.6 7522.2 7594.2 7758.1 3948.1 3955.4 3965.9 4000.3 4221.6 3554.4 3632.4 3661.9 3663.3 3666.5 619.4 625.4 633.7 642.5 645.8 558.6 576.6 596.9 614.1 616.2 7741.6 8034.5 8179.8 8229.7 8238.1 561.2 577.4 579.8 586.2 588.0 3519.1 3657.6 3726.2 3936.4 3942.1 85679.6 87313.0 88333.1 91147.6 91940.5 51052.2 63547.0 70733.6 78255.0 81433.2 54868.0 70331.3 70692.0 70970.0 71742.8 7120.2 7157.9 7348.2 7364.6 7372.7 45322.5 45946.8 46511.8 47628.0 47910.0 41032.4 61234.4 64517.0 65051.0 65485.0 9809.8 10049.2 10248.3 10302.1 10313.4 10901.2 11467.5 11567.3 11646.1 12125.6 11050.9 11543.9 11827.6 11877.3 11921.2 11430.8 11523.8 11551.2 11691.9 11779.6 11391.8 11420.8 11438.3 11488.1 11593.8 9422.2 9436.8 9474.1 9573.0 9604.9 8966.4 9164.8 9426.1 9435.8 9915.6 05:00:30 INFO - PROCESS | 6811 | -------- Summary: end -------- 05:00:30 INFO - PROCESS | 6811 | 05:00:31 INFO - TEST-INFO | 6811: exit 0 05:00:31 INFO - TEST-OK | dromaeo_css | took 1115724ms 05:00:31 INFO - TEST-START | kraken 05:00:31 INFO - operating with platform_type : mac_ 05:00:31 INFO - Initialising browser for kraken test... 05:00:31 INFO - TEST-INFO | started process 6813 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpGTzFrN/profile http://localhost:61796/getInfo.html) 05:00:33 INFO - PROCESS | 6813 | __metrics Screen width/height:1600/1200 05:00:33 INFO - PROCESS | 6813 | colorDepth:24 05:00:33 INFO - PROCESS | 6813 | Browser inner width/height: 1024/689 05:00:33 INFO - PROCESS | 6813 | __metrics 05:00:33 INFO - PROCESS | 6813 | 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" 05:00:33 ERROR - PROCESS | 6813 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:00:34 INFO - TEST-INFO | 6813: exit 0 05:00:34 INFO - Browser initialized. 05:00:34 INFO - Running cycle 1/1 for kraken test... 05:00:34 INFO - TEST-INFO | started process 6815 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpGTzFrN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:00:46 INFO - PROCESS | 6815 | RSS: Main: 238952448 05:00:46 INFO - PROCESS | 6815 | 05:00:49 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61796/tests/kraken/driver.html?testName=audio-beat-detection) 05:00:50 INFO - PROCESS | 6815 | RSS: Main: 288055296 05:00:50 INFO - PROCESS | 6815 | 05:00:52 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61796/tests/kraken/driver.html?testName=audio-dft) 05:00:53 INFO - PROCESS | 6815 | RSS: Main: 296136704 05:00:53 INFO - PROCESS | 6815 | 05:00:57 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61796/tests/kraken/driver.html?testName=audio-fft) 05:00:57 INFO - PROCESS | 6815 | RSS: Main: 384835584 05:00:57 INFO - PROCESS | 6815 | 05:01:00 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61796/tests/kraken/driver.html?testName=audio-oscillator) 05:01:00 INFO - PROCESS | 6815 | RSS: Main: 283688960 05:01:00 INFO - PROCESS | 6815 | 05:01:03 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61796/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:01:03 INFO - PROCESS | 6815 | RSS: Main: 287715328 05:01:03 INFO - PROCESS | 6815 | 05:01:06 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61796/tests/kraken/driver.html?testName=imaging-darkroom) 05:01:07 INFO - PROCESS | 6815 | RSS: Main: 326246400 05:01:07 INFO - PROCESS | 6815 | 05:01:10 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61796/tests/kraken/driver.html?testName=imaging-desaturate) 05:01:11 INFO - PROCESS | 6815 | RSS: Main: 331059200 05:01:11 INFO - PROCESS | 6815 | 05:01:14 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61796/tests/kraken/driver.html?testName=json-parse-financial) 05:01:14 INFO - PROCESS | 6815 | RSS: Main: 327135232 05:01:14 INFO - PROCESS | 6815 | 05:01:16 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61796/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:01:17 INFO - PROCESS | 6815 | RSS: Main: 316575744 05:01:17 INFO - PROCESS | 6815 | 05:01:19 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61796/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:01:19 INFO - PROCESS | 6815 | RSS: Main: 322818048 05:01:19 INFO - PROCESS | 6815 | 05:01:22 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61796/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:01:22 INFO - PROCESS | 6815 | RSS: Main: 326385664 05:01:22 INFO - PROCESS | 6815 | 05:01:25 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61796/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:01:26 INFO - PROCESS | 6815 | RSS: Main: 329977856 05:01:26 INFO - PROCESS | 6815 | 05:01:29 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61796/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:01:30 INFO - PROCESS | 6815 | RSS: Main: 327532544 05:01:30 INFO - PROCESS | 6815 | 05:01:31 INFO - PROCESS | 6815 | Cycle 1(1): loaded http://localhost:61796/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61796/tests/kraken/driver.html?testName=ai-astar) 05:01:32 INFO - PROCESS | 6815 | RSS: Main: 374640640 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | __start_tp_report 05:01:32 INFO - PROCESS | 6815 | _x_x_mozilla_page_load 05:01:32 INFO - PROCESS | 6815 | _x_x_mozilla_page_load_details 05:01:32 INFO - PROCESS | 6815 | |i|pagename|runs| 05:01:32 INFO - PROCESS | 6815 | |0;ai-astar;111;107;111;110;111;113;110;111;112;112 05:01:32 INFO - PROCESS | 6815 | |1;audio-beat-detection;162;117;143;203;134;170;169;119;168;118 05:01:32 INFO - PROCESS | 6815 | |2;audio-dft;199;261;192;187;186;188;230;185;185;182 05:01:32 INFO - PROCESS | 6815 | |3;audio-fft;79;74;59;165;80;84;58;59;121;73 05:01:32 INFO - PROCESS | 6815 | |4;audio-oscillator;94;101;134;79;78;77;102;79;80;130 05:01:32 INFO - PROCESS | 6815 | |5;imaging-gaussian-blur;89;85;89;87;133;107;89;85;90;129 05:01:32 INFO - PROCESS | 6815 | |6;imaging-darkroom;121;93;94;94;94;91;93;94;94;93 05:01:32 INFO - PROCESS | 6815 | |7;imaging-desaturate;84;84;83;85;85;83;84;84;87;83 05:01:32 INFO - PROCESS | 6815 | |8;json-parse-financial;60;59;60;58;58;57;58;122;58;58 05:01:32 INFO - PROCESS | 6815 | |9;json-stringify-tinderbox;63;69;81;75;72;81;101;98;81;68 05:01:32 INFO - PROCESS | 6815 | |10;stanford-crypto-aes;81;85;85;84;85;86;86;84;85;87 05:01:32 INFO - PROCESS | 6815 | |11;stanford-crypto-ccm;145;137;162;138;138;137;139;149;234;156 05:01:32 INFO - PROCESS | 6815 | |12;stanford-crypto-pbkdf2;172;169;167;169;168;168;170;169;168;166 05:01:32 INFO - PROCESS | 6815 | |13;stanford-crypto-sha256-iterative;61;66;90;67;67;65;66;65;66;66 05:01:32 INFO - PROCESS | 6815 | __end_tp_report 05:01:32 INFO - PROCESS | 6815 | __start_cc_report 05:01:32 INFO - PROCESS | 6815 | _x_x_mozilla_cycle_collect,2250 05:01:32 INFO - PROCESS | 6815 | __end_cc_report 05:01:32 INFO - PROCESS | 6815 | __startTimestamp1462795292073__endTimestamp 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | ------- Summary: start ------- 05:01:32 INFO - PROCESS | 6815 | Number of tests: 14 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:1.62 (1.5%) stddev-sans-first:1.72 05:01:32 INFO - PROCESS | 6815 | Values: 111.0 107.0 111.0 110.0 111.0 113.0 110.0 111.0 112.0 112.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#1] audio-beat-detection Cycles:10 Average:150.30 Median:165.00 stddev:28.71 (17.4%) stddev-sans-first:30.13 05:01:32 INFO - PROCESS | 6815 | Values: 162.0 117.0 143.0 203.0 134.0 170.0 169.0 119.0 168.0 118.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#2] audio-dft Cycles:10 Average:199.50 Median:190.00 stddev:25.75 (13.6%) stddev-sans-first:27.31 05:01:32 INFO - PROCESS | 6815 | Values: 199.0 261.0 192.0 187.0 186.0 188.0 230.0 185.0 185.0 182.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#3] audio-fft Cycles:10 Average:85.20 Median:73.50 stddev:33.51 (45.6%) stddev-sans-first:35.46 05:01:32 INFO - PROCESS | 6815 | Values: 79.0 74.0 59.0 165.0 80.0 84.0 58.0 59.0 121.0 73.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:78.50 stddev:21.55 (27.5%) stddev-sans-first:22.85 05:01:32 INFO - PROCESS | 6815 | Values: 94.0 101.0 134.0 79.0 78.0 77.0 102.0 79.0 80.0 130.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#5] imaging-gaussian-blur Cycles:10 Average:98.30 Median:88.00 stddev:18.35 (20.9%) stddev-sans-first:19.16 05:01:32 INFO - PROCESS | 6815 | Values: 89.0 85.0 89.0 87.0 133.0 107.0 89.0 85.0 90.0 129.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:94.00 stddev:8.80 (9.4%) stddev-sans-first:1.00 05:01:32 INFO - PROCESS | 6815 | Values: 121.0 93.0 94.0 94.0 94.0 91.0 93.0 94.0 94.0 93.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:1.23 (1.5%) stddev-sans-first:1.30 05:01:32 INFO - PROCESS | 6815 | Values: 84.0 84.0 83.0 85.0 85.0 83.0 84.0 84.0 87.0 83.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:58.00 stddev:20.12 (34.7%) stddev-sans-first:21.27 05:01:32 INFO - PROCESS | 6815 | Values: 60.0 59.0 60.0 58.0 58.0 57.0 58.0 122.0 58.0 58.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#9] json-stringify-tinderbox Cycles:10 Average:78.90 Median:78.00 stddev:12.47 (16.0%) stddev-sans-first:11.82 05:01:32 INFO - PROCESS | 6815 | Values: 63.0 69.0 81.0 75.0 72.0 81.0 101.0 98.0 81.0 68.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:85.00 stddev:1.62 (1.9%) stddev-sans-first:0.97 05:01:32 INFO - PROCESS | 6815 | Values: 81.0 85.0 85.0 84.0 85.0 86.0 86.0 84.0 85.0 87.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:147.00 stddev:29.60 (20.1%) stddev-sans-first:31.24 05:01:32 INFO - PROCESS | 6815 | Values: 145.0 137.0 162.0 138.0 138.0 137.0 139.0 149.0 234.0 156.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:169.00 stddev:1.65 (1.0%) stddev-sans-first:1.20 05:01:32 INFO - PROCESS | 6815 | Values: 172.0 169.0 167.0 169.0 168.0 168.0 170.0 169.0 168.0 166.0 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - PROCESS | 6815 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:66.00 stddev:7.95 (12.0%) stddev-sans-first:8.03 05:01:32 INFO - PROCESS | 6815 | Values: 61.0 66.0 90.0 67.0 67.0 65.0 66.0 65.0 66.0 66.0 05:01:32 INFO - PROCESS | 6815 | -------- Summary: end -------- 05:01:32 INFO - PROCESS | 6815 | 05:01:32 INFO - TEST-INFO | 6815: exit 0 05:01:32 INFO - TEST-OK | kraken | took 61602ms 05:01:32 INFO - SUITE-END | took 1177s 05:01:33 INFO - Completed test suite (00:19:37) 05:01:33 INFO - javascript benchmark 05:01:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3085.2132706159928, 3359.865605375787, 3393.9987703627435, 3401.812900290483, 3442.4492081267003, 5545.667259964405, 5582.0, 5600.96382169298, 5661.348944871341, 5677.744501497432, 5591.496765291123, 5657.547396208293, 5658.8019419320335, 5710.000749868777, 5732.7133643317875, 5072.568831649314, 5099.184130539114, 5126.871828204287, 5149.201873709581, 5242.370915490146, 5235.738213089349, 5247.344081989751, 5264.026155161288, 5289.603279754034, 5307.52232299093, 5039.748012599373, 5101.999999999982, 5111.7699703513435, 5139.974300128512, 5157.355330583677, 5154.716490593016, 5154.793808247666, 5162.948370516285, 5166.199239117906, 5172.250023746536, 5137.409197942247, 5171.922421163686, 5179.119549676564, 5215.791368345262, 5226.634135610509, 5413.918791218135, 5434.755436005389, 5489.533389661883, 5493.670379777186, 5511.366192887829, 5151.4333423323405, 6112.572119951606, 6160.784372546984, 6162.7534898604, 6199.783007594712, 6136.987397079477, 6200.81397558074, 6203.069539569055, 6221.626702397825, 6241.750329986795, 4944.431390390079, 4999.500049995007, 5696.629719068289, 5781.624194427349, 5792.015357389254, 6097.878042439126, 6203.937960620336, 6210.782622608187, 6210.937890621036, 6229.283632382243, 6050.273967123973, 6084.241038743713, 6124.050772130237, 6138.7237574309265, 6164.106204600397, 6131.816045518642, 6185.969070154711, 6202.689865506707, 6204.751809927552, 6215.223097112861, 6049.0926361046195, 6178.073289006595, 6192.969035154795, 6195.504359651307, 6205.348438413992, 6113.567524283629, 6122.20411346531, 6156.353582873823, 6177.444030037318, 6220.066989951453, 4931.284963680247, 4957.082939656103, 4957.454679985199, 4975.552200301918, 4986.675866068694, 4381.277089280296, 4466.665000125004, 4499.842505512357, 4503.144402563503, 4523.954760452353, 5093.923591146136, 5545.334559852843, 5597.132444471114, 5670.262865827416, 5732.856678582985, 6178.41305076017, 6178.814635560941, 6185.690715464209, 6187.071939209155, 6188.319284878659, 5424.186372044146, 5703.230063941315, 5731.254936858182, 5731.541476681856, 5747.5114615257335, 5208.739563021833, 5302.840914772563, 5364.597655175799, 5367.516923476827, 5412.972935135299, 6166.691665416801, 6232.688365581703, 6247.0629405589525, 6249.96875015622, 6269.843253918597, 6162.014077747539, 6183.690815459119, 6205.844853878689, 6219.844503887439, 6223.315435302204, 6142.959235178615, 6143.665407145256, 6203.286622038343, 6252.624842509464, 6282.434580887742, 6130.0312685453755, 6145.66198859067, 6152.815415537541, 6162.352952939877, 6171.6297022178815, 6215.875682486235, 6248.0940263660605, 6249.437550620466, 6254.749810007549, 6265.843353916189, 5814.5348372130975, 5924.437178468039, 5941.0, 6002.609830361012, 6030.095485677183, 5922.378150294157, 5944.821655350346, 5966.19456373384, 5989.1914591529585, 6016.278046634432, 5798.5651076168615, 5925.288965324189, 5951.375105613848, 5985.0, 5993.160957465871, 6089.512451073572, 6119.510439164945, 6124.5406594506485, 6133.44798968095, 6138.046095287564, 5753.510951569164, 5809.8547536311935, 5956.0, 5957.49361304294, 5976.731047102978], "value": 5638.982584242606, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.97222652754084, 508.8702380892873, 510.8824970256843, 542.5225801294858, 542.8262955854126, 24397.63403548946, 30643.0, 35175.472367914466, 43472.695819125394, 43513.7824310878, 42412.15175696484, 44599.10801783962, 44632.330515042304, 44721.552784472144, 44829.7758511207, 9716.271279654018, 9905.90094099057, 9906.157976571963, 9907.801843963116, 9972.451515166664, 8436.704715334965, 8440.577971101451, 9710.0, 9840.065193806613, 9845.606175752997, 8027.438079334449, 9890.406575605452, 9895.0, 9896.455694936776, 9938.15525680318, 9784.36401633895, 9847.655332063343, 9853.261005424622, 9856.605735770561, 9868.95065524675, 9651.855222171673, 9714.02859714023, 9726.0, 9759.0, 9763.707088787347, 41142.177156457, 41275.0, 41333.0, 41464.26821586072, 41591.0, 2714.362124900647, 2832.3485598312295, 3424.178197232646, 3438.5186073949667, 3440.2947395783804, 26359.472810543873, 29802.956896508735, 30166.15004335656, 30548.389032219453, 30832.845835770902, 2629.8165825378464, 2668.4796464689393, 2724.155511791351, 2815.7325054119824, 2827.2083816531403, 462472.9999999966, 465016.0, 473347.0, 475274.0, 476976.0, 35005.64994350075, 36182.0, 36313.63686363155, 60357.0, 62572.0, 37519.81240093782, 37683.62316376856, 37686.62313376831, 37693.62306376956, 37822.05444863845, 15549.378024879104, 15601.0, 15626.921865390601, 15650.530484085495, 15665.530034098996, 13059.281739504419, 13148.934255328853, 13149.145305555108, 13182.802257965946, 13196.0, 7088.645567721696, 8318.625661845232, 8345.5409952452, 8416.579171041423, 8424.4945303282, 3460.965390346115, 3478.3912815257227, 3479.7216222702123, 3481.8433170506883, 3507.1232191952013, 988.6292640259902, 1011.8178727828914, 1013.6654903881849, 1037.4916291017348, 1105.5798796457295, 25946.0, 29850.40299194004, 30489.237769055508, 30587.847060764554, 30830.92091776822, 992.6029588164647, 1099.8680158380885, 1126.808442564766, 1131.9943400282946, 1234.4876876096318, 12943.0, 14910.105393676415, 15068.0, 15392.538223853302, 15559.611009724622, 663279.0, 673061.0, 676621.0, 677431.0, 678328.0, 60348.0, 60913.08630370548, 60955.08567371493, 60957.0, 61103.69448152731, 1191.2614179208947, 1459.5402448228774, 1494.5068127518111, 1513.954581362561, 1517.446132161775, 30631.54052689256, 30726.539101913935, 30789.38421231518, 31390.0, 31626.841865790524, 52373.0, 52896.471035289156, 53036.204456932406, 53169.936601268535, 53195.0, 436784.0, 438006.0, 438725.0, 438899.0, 439017.0, 26599.468010640066, 27157.59263611087, 27671.0, 27679.861600691867, 27728.722712772615, 40113.1977360457, 45805.08389832251, 46742.0, 47709.52290477051, 48051.5194848061, 37173.814130929175, 37912.24175516419, 37996.050098746644, 38835.805820970716, 39021.6097839018, 35102.64897351096, 37657.0, 37694.62305377021, 38406.84779456621, 38510.42234366543, 37390.06524836901, 37669.434958476195, 37967.0, 38256.23487530289, 38798.41802373023, 29909.70090299069, 30262.394752105276, 30762.84618576893, 30818.229544261572, 30924.22689432782, 24453.144139955017, 24767.50464990726, 24806.50386992286, 25413.0, 25737.069719771465], "value": 18456.786568879685, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.49070990228046, 387.77702820877994, 390.22958996552507, 391.19804400978006, 417.36143700138797, 18319.084045797706, 20488.18047278107, 20790.376288711326, 21559.56880862382, 21628.8918555407, 20610.969451527402, 20661.380158595268, 21435.14259429625, 21474.89262553685, 21478.0, 5920.851978700534, 6208.81373558794, 6210.4100110489435, 6220.906686399708, 6223.2532096148525, 6054.273487181545, 6102.359252278514, 6199.349068347826, 6200.0, 6212.534059945499, 5962.0, 6149.446549810516, 6159.414855588734, 6171.629702217859, 6184.721687524072, 6051.757929682807, 6075.574709770318, 6133.815985520419, 6139.447449729501, 6161.7227224775, 5256.816011439581, 5314.441983591744, 5341.679499230058, 5346.331708536433, 5351.7056561889085, 26525.0, 26894.5965810513, 27292.727072729416, 27466.17601471959, 27653.723462765516, 2106.178590349765, 2215.0, 2227.13141874669, 2237.7314722233273, 2334.4747431827873, 14734.410623575044, 15138.318775655121, 15182.240887955559, 15464.381424742996, 15559.844401556065, 1487.8363380028188, 1714.9742753858702, 1793.32750218668, 1805.1696219738935, 1897.9810201897942, 116288.83711162864, 118664.0, 118952.0, 119438.0, 119709.40145299218, 10964.56141754337, 11221.0, 11707.473163707484, 11765.0, 12098.0, 14550.781738273934, 14612.707745845237, 14637.048591841496, 14640.04839685417, 14653.194074325802, 8672.95663521691, 8888.555572221492, 8901.910980890238, 9013.414128081786, 9046.638134474548, 8834.469931804113, 8839.513826739463, 8850.601722922354, 8955.776105597412, 8969.955150224207, 6791.0, 7012.193597736224, 7023.578585284797, 7037.542559733601, 7037.6481175941, 1593.4741535293326, 1949.1618604000132, 1949.7855235924037, 1970.9014549272479, 1976.1897621974922, 888.2849306308354, 905.3526728389234, 1068.5779117248644, 1085.529108058585, 1089.362722807151, 16401.917990409973, 19734.90132549328, 21723.0, 21898.401335719358, 21940.780592194194, 1007.4408703169664, 1023.2905716776685, 1052.3054783842626, 1102.7463683352714, 1129.7062763681338, 9866.742557005202, 11506.482208300478, 11588.76822463546, 11678.299302041905, 11838.644840654793, 614886.0, 625428.0, 630007.0, 636615.0, 639095.0, 81786.0, 114133.0, 144500.27749861183, 151018.0, 154242.0, 1001.9949900250452, 1007.5012868630075, 1083.766990097133, 1085.733995171188, 1086.1690806533009, 14945.0, 16938.068406237777, 17022.659546809242, 18772.6245475087, 19341.032948352808, 50927.74536127296, 51848.740756297484, 52145.478545214064, 53408.0, 54717.0, 63419.0, 79600.0, 81639.59180204061, 81843.0, 84218.57890710507, 8040.638171282423, 8165.061017983008, 8254.917450825415, 8301.916980830114, 8658.48049117055, 9542.904570954202, 12046.638600841989, 12178.512859485521, 13135.934320328337, 13211.537596184087, 11159.051480624237, 11455.026322762325, 11535.01952334061, 11595.536178552764, 11632.302061876315, 9262.768430789285, 10801.243912926335, 10981.17641176931, 11091.0, 11191.104711623213, 9600.039996000343, 10940.398278094463, 11352.375619340684, 11721.648350549498, 11751.177417580688, 7501.28737769911, 8021.2379823916635, 8030.799230019063, 8249.917500824915, 8555.82888342242, 8397.985909286623, 11103.38931358783, 11240.875912408694, 11330.660080197607, 11404.0], "value": 9924.734153196965, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.045397994414, 1549.7675348697694, 1584.6196912740938, 1613.080544089869, 1954.1401783215376, 7745.3029227369525, 8173.877391839127, 8358.038825535064, 8375.036870759865, 8490.235878770909, 8089.23152300532, 8344.624491897865, 8345.290650294732, 8364.498130112182, 8366.95816520918, 2294.609916314222, 2326.61610834213, 2332.88335583221, 2335.2177020698086, 2336.9649455258186, 2346.765323467648, 2370.7866292033714, 2389.6893403857475, 2394.940126496843, 2402.7116745990506, 2328.8253380996407, 2334.731505876829, 2344.2732752846673, 2374.6675465434855, 2378.2389635316704, 2319.5476882008015, 2346.296111166643, 2356.352003199125, 2359.4337359033707, 2360.409897525619, 2257.2760289358794, 2340.648902664597, 2340.712608065557, 2347.401412639783, 2348.154664320843, 7754.883676744853, 7982.161873003367, 8279.793005174859, 8343.374246931504, 8347.0, 4939.703617782944, 5066.265391518246, 5075.974620126913, 5111.207762796773, 5118.923216151761, 3434.0, 3502.614712381636, 3585.8924232273075, 3597.1007248187952, 3603.7837729736298, 931.1712575807537, 952.3999880075509, 952.6311354748569, 953.6137864165024, 972.2319367699508, 11513.884861151366, 11523.423828808525, 11627.94186029073, 11633.95294423506, 11634.243774154653, 4585.862424127282, 4813.302071199656, 4889.853304400874, 4922.33548470959, 4937.432195297587, 9861.211103111877, 10005.699829005142, 10007.549660265306, 10010.399376037461, 10030.0, 5410.91883621746, 5450.5367043801725, 5451.20110363587, 5451.754671039813, 5455.318085239345, 4814.8796280093275, 4886.413630364379, 4951.480094590016, 4987.750612469362, 4995.350604421402, 3830.9233815323537, 3858.459815625815, 3897.922041559153, 3921.156951255494, 3934.1934903344745, 1280.7182419867613, 1286.8327117474669, 1296.824928634641, 1309.9607011789662, 1311.7179806341683, 906.4923642760075, 931.5435436636, 933.9486328251876, 946.7775072857872, 959.0601210813303, 5474.534664553478, 5881.882362352729, 5897.144913987448, 6028.608140470856, 6125.663088530085, 449.55044955044957, 450.87600909749426, 452.4728691074861, 462.576742280816, 471.7498628634147, 3981.422693709396, 3986.5614782373914, 4047.3119569672635, 4065.8576949806616, 4069.674426045909, 8880.955595222113, 9893.901060989298, 10598.947005265078, 10599.682009539727, 10706.46467676613, 10402.167826573856, 10493.947530262452, 10537.63118290856, 10550.208734344955, 10677.78644427107, 8167.469114507538, 8194.0, 8255.711050113217, 8281.0, 8299.211574900375, 5515.172724091419, 5856.882862342644, 5896.469317761422, 5946.197263369563, 5955.285365756137, 9791.608335666493, 9801.068898454636, 10107.848382274125, 10117.443540605338, 10124.59501619927, 9926.205903527845, 10001.14990225839, 10044.447555384524, 10091.0, 10233.079022887976, 7249.0, 7282.162551306667, 7347.191808901123, 7354.6690398931105, 7360.1535823381, 7361.638096952081, 7746.341560967381, 7910.5649189295145, 7938.206179382016, 8204.384671149806, 6382.20222472191, 7321.524100933422, 7420.664280429359, 7490.4007679386605, 7599.316061554487, 7308.976743256055, 7327.157376901724, 7374.705011799682, 7464.552126872405, 7480.551166929784, 6810.9318906810295, 7041.859162816818, 7049.718011279491, 7053.647317634201, 7057.117860267466, 3801.657850793442, 4466.374707541012, 4626.329182268485, 4737.668363214542, 5113.411957624772, 4210.32634778434, 4951.084049450863, 5029.371328583927, 5063.113955057924, 5199.532042116228], "value": 4442.754265030047, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.8198906732489, 292.3816128887404, 301.7194009571099, 313.34510872276934, 316.60266365711027, 5329.866753331169, 5334.199870019499, 5341.599380046498, 5391.137418013119, 5402.37872644646, 4199.34910088936, 4863.27050942358, 5149.74251287436, 5219.556337711299, 5237.188235823453, 3999.760014399131, 4020.557738648746, 4031.3348297530874, 4068.9593104068877, 4069.9214708102377, 4035.47538819953, 4038.9394159087637, 4046.8785936421955, 4076.490438695163, 4128.938065929014, 3580.427131658935, 3783.6594706476412, 4031.3952907064026, 4032.1330913853517, 4053.2298863215897, 3303.4969089064416, 3620.2578471413563, 3881.904590656856, 4043.898902527431, 4095.8771236862794, 3522.718182545416, 3543.4664215086336, 3545.804980726059, 3555.5555555555557, 3580.7314451416246, 4206.831726730927, 4877.170880950247, 5026.547610715053, 5034.395872495287, 5038.395392552917, 4108.589141085868, 4513.819447222108, 4635.281531362644, 4676.755157524407, 4717.740524271164, 5412.2152287918425, 5457.808976685837, 5482.806877249092, 5483.396826349099, 5520.365158006841, 3793.6016718244737, 3885.3977633466857, 3893.9502916966603, 3922.235164142993, 3947.4473573699706, 5085.711429856579, 5133.666311689765, 5307.681539107627, 5618.325491253822, 5634.2110655225515, 3873.9418908716393, 3987.760734355948, 3989.6608788253325, 3990.541087774943, 4007.038310805444, 3394.6605339465855, 3560.857565697343, 4039.7576145431367, 4050.513938327361, 4101.876943691694, 4052.2705912935367, 4137.317342638491, 4494.303382975644, 4536.618275405439, 4584.862454126382, 4567.383403240587, 5247.422783493813, 5346.4386239445075, 5583.664980101207, 5597.4327188387315, 1105.9004689577816, 1198.6224339333082, 1311.272243904642, 1318.1959005006938, 1323.2060763541797, 760.3271105072051, 810.2221867007621, 839.4669384940606, 870.5331516394768, 926.995365023184, 4223.303154979455, 4773.76131193439, 4828.46886842447, 4849.975750121227, 4862.027594481118, 4639.582437580634, 5289.788408463618, 5391.78432862689, 5416.647917885325, 5447.875328621841, 4349.717268377545, 4432.874823249601, 4447.955520444754, 4708.670393072521, 4764.618830493552, 2543.8728063596745, 2831.1931099636504, 2837.4183292425005, 2844.2462747371965, 2848.9430211395656, 4772.665913386099, 5025.321581586512, 5449.809256675997, 5568.770113334256, 5572.3870374258795, 4769.591356365014, 5039.546440820271, 5466.835994920159, 5520.1167813149705, 5542.3626282977975, 5182.792688292426, 5428.647137936022, 5483.588730845123, 5546.889062218814, 5547.334319881559, 4387.846425375096, 4651.883702907455, 4850.927236091391, 5312.165929004587, 5318.920216196838, 4301.548337424525, 4726.834560790356, 4903.0, 5001.974990125026, 5139.91833307511, 4933.273354325957, 5545.528630066489, 5780.913286300624, 5783.421657834351, 6008.279006519246, 3727.9068023299633, 3865.497485327002, 3880.320943834874, 3880.902977425587, 3882.398228274509, 5090.541851233407, 5369.174480676589, 5703.173039909273, 5734.942650573441, 5755.798547050833, 5234.005538947587, 5261.789528418821, 5582.748776304995, 5609.635373700697, 5614.354349249888, 4733.290006499053, 4763.1426343258545, 5179.741012949262, 5507.044999025288, 5634.408387119293, 4296.312589985588, 4616.030633566854, 5375.462453754593, 5430.13117901134, 5567.85637529546, 5119.641625086358, 5327.866803329948, 5474.275603184984, 5481.574790554405, 5502.889942201214, 4825.841797968533, 5076.923846142237, 5390.541803946709, 5396.78412863481, 5478.2195786370385], "value": 3961.5549220077164, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.4876947211701, 652.5888690125221, 653.3988730368061, 702.1679310017627, 727.7671145233527, 14333.784993225097, 15051.247437628115, 15060.84939150608, 15071.773923391143, 16586.83413165868, 15926.442574509894, 16368.099754513498, 16379.83620163798, 16401.507954761346, 16407.17964101793, 733.1751779248345, 749.9400047996174, 928.3501548915752, 945.7068308824287, 946.0728486083642, 898.24996128233, 920.2408013388947, 923.3536524432924, 925.3799954030816, 926.4626516620377, 955.6750704760381, 966.9371490853107, 967.7774111954254, 968.8982656821038, 969.2778879734608, 754.272127397059, 948.1466679987997, 961.7018724195522, 961.8845738511388, 971.5142428785607, 727.8980942668031, 729.3363039633961, 729.3836707981746, 730.6456368661195, 731.0970950875665, 15261.236938153159, 15312.770308445382, 15345.539633811004, 15348.539543813704, 15356.001859879183, 11843.467043982955, 12081.275123492618, 12216.144869859114, 12340.876591234062, 12598.0, 8184.549849758261, 9623.422594644344, 9671.90328096717, 9883.258755593359, 10370.688879333633, 3296.0770984124483, 3453.8100404477746, 3541.8229088545727, 3598.2803439312243, 3599.4600809878466, 81304.0, 82886.9999999994, 82997.0, 83220.58389708074, 83237.0, 8697.521636309937, 8794.340424468062, 9419.0, 9472.242220622335, 9658.613655453702, 20655.8967205166, 20917.372478825357, 20934.267300644406, 20983.580328393346, 20987.265445709632, 7318.817029574303, 7409.555426674417, 7522.172561018284, 7594.231725509697, 7758.107817601048, 3948.1116748731306, 3955.3869150281753, 3965.861194858166, 4000.299947509174, 4221.641160501392, 3554.449060395643, 3632.4369722693023, 3661.853525858936, 3663.285659296465, 3666.5416822897137, 619.4455961914151, 625.4121126141413, 633.7433339497511, 642.5181114164377, 645.7675236914707, 558.5782734035859, 576.6482445708115, 596.8597379615787, 614.0696844280918, 616.2143267334095, 7741.61291935401, 8034.47775894565, 8179.754607361789, 8229.711960081368, 8238.052623948202, 561.1946856261278, 577.4398833131855, 579.8115612425978, 586.2408181405091, 587.9827897736939, 3519.1202199450136, 3657.5976642569294, 3726.16161363691, 3936.4292177634084, 3942.1327307992196, 85679.57160214284, 87313.0, 88333.11666883249, 91147.63278550826, 91940.54029729808, 51052.23421648678, 63547.0, 70733.64633176904, 78255.0, 81433.18566814256, 54868.0, 70331.29668703247, 70692.0, 70970.0, 71742.84771456865, 7120.216776154515, 7157.856842863218, 7348.154962179204, 7364.5581265124265, 7372.705091796268, 45322.54677453183, 45946.77026614846, 46511.83720407017, 47628.0, 47910.0, 41032.38451423291, 61234.38765612287, 64517.0, 65051.0, 65485.0, 9809.755029750753, 10049.196064314983, 10248.282620216512, 10302.124319432933, 10313.381197128196, 10901.182411319342, 11467.483963221843, 11567.305961642329, 11646.068314534987, 12125.575604853788, 11050.944745276543, 11543.88456115428, 11827.645170644895, 11877.346745929057, 11921.165518413627, 11430.828537571777, 11523.769524609294, 11551.249168804, 11691.883081169079, 11779.646610601696, 11391.829122563335, 11420.828687569527, 11438.256513326607, 11488.138389620644, 11593.768124637292, 9422.246220302222, 9436.764080898032, 9474.062322267866, 9573.04269573065, 9604.9039509604, 8966.372353935161, 9164.762757027716, 9426.104520070583, 9435.811283774423, 9915.55380007887], "value": 6781.797695972773, "unit": "score"}], "name": "dromaeo_css", "value": 7055.007534970711, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 107.0, 111.0, 110.0, 111.0, 113.0, 110.0, 111.0, 112.0, 112.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 117.0, 143.0, 203.0, 134.0, 170.0, 169.0, 119.0, 168.0, 118.0], "value": 150.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 261.0, 192.0, 187.0, 186.0, 188.0, 230.0, 185.0, 185.0, 182.0], "value": 199.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 59.0, 165.0, 80.0, 84.0, 58.0, 59.0, 121.0, 73.0], "value": 85.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 101.0, 134.0, 79.0, 78.0, 77.0, 102.0, 79.0, 80.0, 130.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 87.0, 133.0, 107.0, 89.0, 85.0, 90.0, 129.0], "value": 98.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 93.0, 94.0, 94.0, 94.0, 91.0, 93.0, 94.0, 94.0, 93.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 85.0, 85.0, 83.0, 84.0, 84.0, 87.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 60.0, 58.0, 58.0, 57.0, 58.0, 122.0, 58.0, 58.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 69.0, 81.0, 75.0, 72.0, 81.0, 101.0, 98.0, 81.0, 68.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 85.0, 84.0, 85.0, 86.0, 86.0, 84.0, 85.0, 87.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 137.0, 162.0, 138.0, 138.0, 137.0, 139.0, 149.0, 234.0, 156.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 167.0, 169.0, 168.0, 168.0, 170.0, 169.0, 168.0, 166.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 90.0, 67.0, 67.0, 65.0, 66.0, 65.0, 66.0, 66.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1538.3, "alertThreshold": 2.0}]} 05:01:33 INFO - Return code: 0 05:01:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:01:33 ERROR - # TBPL SUCCESS # 05:01:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:01:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:01:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:01:33 INFO - 2016-05-09 05:01:33.371 screenresolution[6819:120305] starting screenresolution argv=screenresolution get 05:01:33 INFO - 2016-05-09 05:01:33.374 screenresolution[6819:120305] Display 0: 1600x1200x32@60 05:01:33 INFO - 2016-05-09 05:01:33.380 screenresolution[6820:120308] starting screenresolution argv=screenresolution list 05:01:33 INFO - Available Modes on Display 0 05:01:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:01:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:01:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:01:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:01:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:01:33 INFO - Intel Iris: 05:01:33 INFO - Chipset Model: Intel Iris 05:01:33 INFO - Type: GPU 05:01:33 INFO - Bus: Built-In 05:01:33 INFO - VRAM (Dynamic, Max): 1536 MB 05:01:33 INFO - Vendor: Intel (0x8086) 05:01:33 INFO - Device ID: 0x0a2e 05:01:33 INFO - Revision ID: 0x0009 05:01:33 INFO - Displays: 05:01:33 INFO - Display: 05:01:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:01:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:01:33 INFO - Display Serial Number: 1600x1200 60 05:01:33 INFO - Main Display: Yes 05:01:33 INFO - Mirror: Off 05:01:33 INFO - Online: Yes 05:01:33 INFO - Rotation: Supported 05:01:33 INFO - Return code: 0 05:01:33 INFO - Running post-action listener: _resource_record_post_action 05:01:33 INFO - ##### 05:01:33 INFO - ##### Finished run-tests step (success) 05:01:33 INFO - ##### 05:01:33 INFO - Running post-run listener: _resource_record_post_run 05:01:34 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 4132864; Write bytes: 694491136; Read time: 98; Write time: 1989 05:01:34 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 376603136; Write bytes: 369823232; Read time: 28091; Write time: 694 05:01:34 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 2908160; Write bytes: 294429696; Read time: 66; Write time: 1158 05:01:34 INFO - Running post-run listener: _upload_blobber_files 05:01:34 INFO - Blob upload gear active. 05:01:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:01:34 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 05:01:34 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'] 05:01:34 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 05:01:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:01:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:01:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:01:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:01:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:01:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:01:35 INFO - (blobuploader) - INFO - Done attempting. 05:01:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:01:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:01:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:01:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:01:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:01:36 INFO - (blobuploader) - INFO - Done attempting. 05:01:36 INFO - (blobuploader) - INFO - Iteration through files over. 05:01:36 INFO - Return code: 0 05:01:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:01:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:01:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/98f27451a7eddeba8fcdb66f2fe350c5bb1205cb2fbb96ac7bc4953e8755ff4a0291c0379706b4cf842a9e3144137d399e70aa2b7f1ada57828add85fa59c45a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57b44b7211b6d5ccb1e5f8063c830b167abe6db3943c310bb2a4792ada95bd30119de945a6e6773d603e84389d8dfc7373c1fde8d0ec21b583ebe74737ea1bb4"} 05:01:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:01:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:01:36 INFO - Contents: 05:01:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/98f27451a7eddeba8fcdb66f2fe350c5bb1205cb2fbb96ac7bc4953e8755ff4a0291c0379706b4cf842a9e3144137d399e70aa2b7f1ada57828add85fa59c45a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57b44b7211b6d5ccb1e5f8063c830b167abe6db3943c310bb2a4792ada95bd30119de945a6e6773d603e84389d8dfc7373c1fde8d0ec21b583ebe74737ea1bb4"} 05:01:36 INFO - Running post-run listener: copy_logs_to_upload_dir 05:01:36 INFO - Copying logs to upload dir... 05:01:36 INFO - mkdir: /builds/slave/test/build/upload/logs 05:01:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.372842 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-09 05:01:36.804253) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 05:01:36.808270) ========= 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.hcYGatuSGR/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.cfT2dtif3q/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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/98f27451a7eddeba8fcdb66f2fe350c5bb1205cb2fbb96ac7bc4953e8755ff4a0291c0379706b4cf842a9e3144137d399e70aa2b7f1ada57828add85fa59c45a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57b44b7211b6d5ccb1e5f8063c830b167abe6db3943c310bb2a4792ada95bd30119de945a6e6773d603e84389d8dfc7373c1fde8d0ec21b583ebe74737ea1bb4"} build_url:https://queue.taskcluster.net/v1/task/EWx57r5KQG-L7XD-m1SIMA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011831 build_url: 'https://queue.taskcluster.net/v1/task/EWx57r5KQG-L7XD-m1SIMA/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/98f27451a7eddeba8fcdb66f2fe350c5bb1205cb2fbb96ac7bc4953e8755ff4a0291c0379706b4cf842a9e3144137d399e70aa2b7f1ada57828add85fa59c45a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57b44b7211b6d5ccb1e5f8063c830b167abe6db3943c310bb2a4792ada95bd30119de945a6e6773d603e84389d8dfc7373c1fde8d0ec21b583ebe74737ea1bb4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 05:01:36.839008) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:01:36.839584) ========= 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.hcYGatuSGR/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.cfT2dtif3q/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.005573 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:01:36.889687) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 05:01:36.890071) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 05:01:36.890383) ========= ========= Total master_lag: 0.16 =========