builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0160 starttime: 1464136329.44 results: success (0) buildid: 20160524153249 builduid: c8ca0dc331264125b1f8d89103e18937 revision: 13da4ea2b371b20490eaf301a9940d1e34005da3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:09.441219) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:09.441635) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:09.441908) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:09.498289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:09.498578) ========= 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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-24 17:32:09-- 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% 15.0M=0.001s 2016-05-24 17:32:09 (15.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132961 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:09.652757) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:09.653071) ========= 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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.027773 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:09.703074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 17:32:09.703461) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 13da4ea2b371b20490eaf301a9940d1e34005da3 --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 13da4ea2b371b20490eaf301a9940d1e34005da3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GW7DshlZ0G/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.yQYDOq7qIC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-24 17:32:09,781 truncating revision to first 12 chars 2016-05-24 17:32:09,781 Setting DEBUG logging. 2016-05-24 17:32:09,781 attempt 1/10 2016-05-24 17:32:09,781 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/13da4ea2b371?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 17:32:10,833 unpacking tar archive at: fx-team-13da4ea2b371/testing/mozharness/ program finished with exit code 0 elapsedTime=1.299890 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 17:32:11.023555) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:11.023877) ========= script_repo_revision: 13da4ea2b371b20490eaf301a9940d1e34005da3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:11.024290) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:11.024561) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 17:32:11.041550) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-24 17:32:11.042122) ========= /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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 17:32:11 INFO - MultiFileLogger online at 20160524 17:32:11 in /builds/slave/test 17:32:11 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 17:32:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:32:11 INFO - {'append_to_log': False, 17:32:11 INFO - 'base_work_dir': '/builds/slave/test', 17:32:11 INFO - 'blob_upload_branch': 'Fx-Team', 17:32:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:32:11 INFO - 'branch': 'Fx-Team', 17:32:11 INFO - 'buildbot_json_path': 'buildprops.json', 17:32:11 INFO - 'config_files': ('talos/mac_config.py',), 17:32:11 INFO - 'default_actions': ('clobber', 17:32:11 INFO - 'read-buildbot-config', 17:32:11 INFO - 'download-and-extract', 17:32:11 INFO - 'populate-webroot', 17:32:11 INFO - 'create-virtualenv', 17:32:11 INFO - 'install', 17:32:11 INFO - 'run-tests'), 17:32:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:32:11 INFO - 'download_minidump_stackwalk': True, 17:32:11 INFO - 'download_symbols': 'ondemand', 17:32:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:32:11 INFO - 'tooltool.py': '/tools/tooltool.py', 17:32:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:32:11 INFO - '/tools/misc-python/virtualenv.py')}, 17:32:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:32:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:32:11 INFO - 'installer_path': 'installer.exe', 17:32:11 INFO - 'log_level': 'info', 17:32:11 INFO - 'log_name': 'talos', 17:32:11 INFO - 'log_to_console': True, 17:32:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:32:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:32:11 INFO - 'opt_config_files': (), 17:32:11 INFO - 'pip_index': False, 17:32:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:32:11 INFO - 'cmd': ('bash', 17:32:11 INFO - '-c', 17:32:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:32:11 INFO - 'enabled': True, 17:32:11 INFO - 'halt_on_failure': False, 17:32:11 INFO - 'name': 'check_screen_resolution'},), 17:32:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:32:11 INFO - 'cmd': ('bash', 17:32:11 INFO - '-c', 17:32:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:32:11 INFO - 'enabled': True, 17:32:11 INFO - 'halt_on_failure': False, 17:32:11 INFO - 'name': 'check_screen_resolution'},), 17:32:11 INFO - 'run_cmd_checks_enabled': True, 17:32:11 INFO - 'sps_profile': False, 17:32:11 INFO - 'sps_profile_interval': 0, 17:32:11 INFO - 'suite': 'dromaeojs', 17:32:11 INFO - 'system_bits': '32', 17:32:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:32:11 INFO - 'title': 't-yosemite-r7-0160', 17:32:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:32:11 INFO - 'use_talos_json': True, 17:32:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:32:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:32:11 INFO - 'work_dir': 'build'} 17:32:11 INFO - ##### 17:32:11 INFO - ##### Running clobber step. 17:32:11 INFO - ##### 17:32:11 INFO - Running pre-action listener: _resource_record_pre_action 17:32:11 INFO - Running main action method: clobber 17:32:11 INFO - rmtree: /builds/slave/test/build 17:32:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:32:13 INFO - Running post-action listener: _resource_record_post_action 17:32:13 INFO - ##### 17:32:13 INFO - ##### Finished clobber step (success) 17:32:13 INFO - ##### 17:32:13 INFO - ##### 17:32:13 INFO - ##### Running read-buildbot-config step. 17:32:13 INFO - ##### 17:32:13 INFO - Running pre-action listener: _resource_record_pre_action 17:32:13 INFO - Running main action method: read_buildbot_config 17:32:13 INFO - Using buildbot properties: 17:32:13 INFO - { 17:32:13 INFO - "project": "", 17:32:13 INFO - "product": "firefox", 17:32:13 INFO - "script_repo_revision": "production", 17:32:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:32:13 INFO - "repository": "", 17:32:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:32:13 INFO - "buildid": "20160524153249", 17:32:13 INFO - "pgo_build": "False", 17:32:13 INFO - "basedir": "/builds/slave/test", 17:32:13 INFO - "buildnumber": 30, 17:32:13 INFO - "slavename": "t-yosemite-r7-0160", 17:32:13 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:32:13 INFO - "platform": "yosemite_r7", 17:32:13 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:32:13 INFO - "branch": "fx-team", 17:32:13 INFO - "repo_path": "integration/fx-team", 17:32:13 INFO - "revision": "13da4ea2b371b20490eaf301a9940d1e34005da3", 17:32:13 INFO - "stage_platform": "macosx64", 17:32:13 INFO - "builduid": "c8ca0dc331264125b1f8d89103e18937", 17:32:13 INFO - "slavebuilddir": "test" 17:32:13 INFO - } 17:32:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 17:32:13 INFO - Running post-action listener: _resource_record_post_action 17:32:13 INFO - ##### 17:32:13 INFO - ##### Finished read-buildbot-config step (success) 17:32:13 INFO - ##### 17:32:13 INFO - ##### 17:32:13 INFO - ##### Running download-and-extract step. 17:32:13 INFO - ##### 17:32:13 INFO - Running pre-action listener: _resource_record_pre_action 17:32:13 INFO - Running main action method: download_and_extract 17:32:13 INFO - mkdir: /builds/slave/test/build/tests 17:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:32:13 INFO - https://queue.taskcluster.net/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:32:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:32:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:32:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/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 17:32:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/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 17:32:17 INFO - Downloaded 1199 bytes. 17:32:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 17:32:17 INFO - Using the following test package requirements: 17:32:17 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:32:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:32:17 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 17:32:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:32:17 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 17:32:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:32:17 INFO - u'jsshell-mac.zip'], 17:32:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:32:17 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 17:32:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:32:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:32:17 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 17:32:17 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:32:17 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 17:32:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:32:17 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 17:32:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:32:17 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 17:32:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:32:17 INFO - https://queue.taskcluster.net/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:32:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:32:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:32:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/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 17:32:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/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 17:32:18 INFO - Downloaded 18633065 bytes. 17:32:18 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'] 17:32:18 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 17:32:21 INFO - Return code: 0 17:32:21 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 17:32:21 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')]} 17:32:21 INFO - https://queue.taskcluster.net/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:32:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:32:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:32:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/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 17:32:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/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 17:32:23 INFO - Downloaded 18633065 bytes. 17:32:23 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'] 17:32:23 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 17:32:27 INFO - Return code: 0 17:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:32:27 INFO - https://queue.taskcluster.net/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:32:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:32:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:32:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/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 17:32:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/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 17:32:28 INFO - Downloaded 10840359 bytes. 17:32:28 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'] 17:32:28 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 17:32:28 INFO - Return code: 0 17:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:32:28 INFO - https://queue.taskcluster.net/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:32:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:32:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:32:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 17:32:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cuIlijCxRwabUM3-wSxccw/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 17:32:32 INFO - Downloaded 110243811 bytes. 17:32:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:32:32 INFO - mkdir: /builds/slave/test/properties 17:32:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:32:32 INFO - Writing to file /builds/slave/test/properties/build_url 17:32:32 INFO - Contents: 17:32:32 INFO - build_url:https://queue.taskcluster.net/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:32:34 INFO - Running post-action listener: _resource_record_post_action 17:32:34 INFO - Running post-action listener: set_extra_try_arguments 17:32:34 INFO - ##### 17:32:34 INFO - ##### Finished download-and-extract step (success) 17:32:34 INFO - ##### 17:32:34 INFO - ##### 17:32:34 INFO - ##### Running populate-webroot step. 17:32:34 INFO - ##### 17:32:34 INFO - Running pre-action listener: _resource_record_pre_action 17:32:34 INFO - Running main action method: populate_webroot 17:32:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:32:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:32:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:32:34 INFO - '--tppagecycles', 17:32:34 INFO - '7'], 17:32:34 INFO - 'tests': ['tsvgm']}}, 17:32:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:32:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:32:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:32:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:32:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:32:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:32:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:34 INFO - 'talos_options': ['--disable-e10s'], 17:32:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:32:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:32:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:34 INFO - 'talos_options': ['--disable-e10s'], 17:32:34 INFO - 'tests': ['damp', 'tps']}, 17:32:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:34 INFO - 'tests': ['damp', 'tps']}, 17:32:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:32:34 INFO - 'tests': ['dromaeo_dom']}, 17:32:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:32:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:32:34 INFO - 'tests': ['a11yr', 17:32:34 INFO - 'ts_paint', 17:32:34 INFO - 'tpaint', 17:32:34 INFO - 'sessionrestore', 17:32:34 INFO - 'sessionrestore_no_auto_restore', 17:32:34 INFO - 'tabpaint']}, 17:32:34 INFO - 'other-e10s': {'tests': ['a11yr', 17:32:34 INFO - 'ts_paint', 17:32:34 INFO - 'tpaint', 17:32:34 INFO - 'sessionrestore', 17:32:34 INFO - 'sessionrestore_no_auto_restore', 17:32:34 INFO - 'tabpaint']}, 17:32:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:32:34 INFO - 'ts_paint', 17:32:34 INFO - 'tpaint', 17:32:34 INFO - 'sessionrestore', 17:32:34 INFO - 'sessionrestore_no_auto_restore', 17:32:34 INFO - 'tabpaint']}, 17:32:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:32:34 INFO - 'ts_paint', 17:32:34 INFO - 'tpaint', 17:32:34 INFO - 'sessionrestore', 17:32:34 INFO - 'sessionrestore_no_auto_restore', 17:32:34 INFO - 'tabpaint']}, 17:32:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:32:34 INFO - 'tests': ['a11yr', 17:32:34 INFO - 'ts_paint', 17:32:34 INFO - 'tpaint', 17:32:34 INFO - 'sessionrestore', 17:32:34 INFO - 'sessionrestore_no_auto_restore', 17:32:34 INFO - 'tabpaint']}, 17:32:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:32:34 INFO - 'tests': ['a11yr', 17:32:34 INFO - 'ts_paint', 17:32:34 INFO - 'tpaint', 17:32:34 INFO - 'sessionrestore', 17:32:34 INFO - 'sessionrestore_no_auto_restore', 17:32:34 INFO - 'tabpaint']}, 17:32:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:32:34 INFO - 'tests': ['tsvgx', 17:32:34 INFO - 'tsvgr_opacity', 17:32:34 INFO - 'tart', 17:32:34 INFO - 'tscrollx', 17:32:34 INFO - 'cart']}, 17:32:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:32:34 INFO - 'tsvgr_opacity', 17:32:34 INFO - 'tart', 17:32:34 INFO - 'tscrollx', 17:32:34 INFO - 'cart']}, 17:32:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:34 INFO - 'talos_options': ['--disable-e10s'], 17:32:34 INFO - 'tests': ['tp5o']}, 17:32:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:34 INFO - 'tests': ['tp5o']}, 17:32:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:34 INFO - 'talos_options': ['--disable-e10s', 17:32:34 INFO - '--xperf_path', 17:32:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:32:34 INFO - 'tests': ['tp5n']}, 17:32:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:34 INFO - 'talos_options': ['--xperf_path', 17:32:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:32:34 INFO - 'tests': ['tp5n']}}, 17:32:34 INFO - 'talos.zip': {'path': '', 17:32:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:32:34 INFO - Running post-action listener: _resource_record_post_action 17:32:34 INFO - ##### 17:32:34 INFO - ##### Finished populate-webroot step (success) 17:32:34 INFO - ##### 17:32:34 INFO - ##### 17:32:34 INFO - ##### Running create-virtualenv step. 17:32:34 INFO - ##### 17:32:34 INFO - Running pre-action listener: _resource_record_pre_action 17:32:34 INFO - Running main action method: create_virtualenv 17:32:34 INFO - Creating virtualenv /builds/slave/test/build/venv 17:32:34 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 17:32:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:32:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:32:34 INFO - Using real prefix '/tools/python27' 17:32:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:32:34 INFO - Installing distribute.............................................................................................................................................................................................done. 17:32:37 INFO - Installing pip.................done. 17:32:37 INFO - Return code: 0 17:32:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:32:37 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')]} 17:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:37 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')]} 17:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:37 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 0x1089ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a05570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9221ca96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a3d30>, '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 0x1086c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889ab20>, '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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 17:32:37 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 17:32:37 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 17:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GW7DshlZ0G/Render', 17:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:37 INFO - 'HOME': '/Users/cltbld', 17:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:37 INFO - 'LOGNAME': 'cltbld', 17:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:37 INFO - 'MOZ_NO_REMOTE': '1', 17:32:37 INFO - 'NO_EM_RESTART': '1', 17:32:37 INFO - 'PAGER': '/bin/cat', 17:32:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:37 INFO - 'PWD': '/builds/slave/test', 17:32:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:37 INFO - 'SHELL': '/bin/bash', 17:32:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yQYDOq7qIC/Listeners', 17:32:37 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:32:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:37 INFO - 'USER': 'cltbld', 17:32:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:37 INFO - 'XPC_FLAGS': '0x0', 17:32:37 INFO - 'XPC_SERVICE_NAME': '0', 17:32:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:38 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 17:32:38 INFO - Cleaning up... 17:32:38 INFO - Return code: 0 17:32:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:32:38 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')]} 17:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:38 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')]} 17:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1089ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a05570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9221ca96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a3d30>, '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 0x1086c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889ab20>, '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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 17:32:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:32:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GW7DshlZ0G/Render', 17:32:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:38 INFO - 'HOME': '/Users/cltbld', 17:32:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:38 INFO - 'LOGNAME': 'cltbld', 17:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:38 INFO - 'MOZ_NO_REMOTE': '1', 17:32:38 INFO - 'NO_EM_RESTART': '1', 17:32:38 INFO - 'PAGER': '/bin/cat', 17:32:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:38 INFO - 'PWD': '/builds/slave/test', 17:32:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:38 INFO - 'SHELL': '/bin/bash', 17:32:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yQYDOq7qIC/Listeners', 17:32:38 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:32:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:38 INFO - 'USER': 'cltbld', 17:32:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:38 INFO - 'XPC_FLAGS': '0x0', 17:32:38 INFO - 'XPC_SERVICE_NAME': '0', 17:32:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:38 INFO - Downloading/unpacking psutil>=3.1.1 17:32:38 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 17:32:38 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 17:32:38 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 17:32:38 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 17:32:38 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 17:32:38 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 17:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:32:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:32:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:32:41 INFO - Installing collected packages: psutil 17:32:41 INFO - Running setup.py install for psutil 17:32:41 INFO - building 'psutil._psutil_osx' extension 17:32:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:32:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:32:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:32:42 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 17:32:42 INFO - building 'psutil._psutil_posix' extension 17:32:42 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 17:32:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:32:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:32:42 INFO - ^ 17:32:42 INFO - 1 warning generated. 17:32:42 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 17:32:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:32:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:32:42 INFO - Successfully installed psutil 17:32:42 INFO - Cleaning up... 17:32:42 INFO - Return code: 0 17:32:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:32: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')]} 17:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32: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')]} 17:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1089ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a05570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9221ca96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a3d30>, '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 0x1086c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889ab20>, '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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 17:32:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:32:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GW7DshlZ0G/Render', 17:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:42 INFO - 'HOME': '/Users/cltbld', 17:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:42 INFO - 'LOGNAME': 'cltbld', 17:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:42 INFO - 'MOZ_NO_REMOTE': '1', 17:32:42 INFO - 'NO_EM_RESTART': '1', 17:32:42 INFO - 'PAGER': '/bin/cat', 17:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:42 INFO - 'PWD': '/builds/slave/test', 17:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:42 INFO - 'SHELL': '/bin/bash', 17:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yQYDOq7qIC/Listeners', 17:32:42 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:42 INFO - 'USER': 'cltbld', 17:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:42 INFO - 'XPC_FLAGS': '0x0', 17:32:42 INFO - 'XPC_SERVICE_NAME': '0', 17:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:32:42 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 17:32:42 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 17:32:42 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 17:32:42 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 17:32:42 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 17:32:42 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 17:32:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:32:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:32:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:32:45 INFO - Installing collected packages: mozsystemmonitor 17:32:45 INFO - Running setup.py install for mozsystemmonitor 17:32:45 INFO - Successfully installed mozsystemmonitor 17:32:45 INFO - Cleaning up... 17:32:45 INFO - Return code: 0 17:32:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:32:45 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')]} 17:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:45 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')]} 17:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:45 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 0x1089ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a05570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9221ca96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a3d30>, '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 0x1086c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889ab20>, '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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 17:32:45 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 17:32:45 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 17:32:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GW7DshlZ0G/Render', 17:32:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:45 INFO - 'HOME': '/Users/cltbld', 17:32:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:45 INFO - 'LOGNAME': 'cltbld', 17:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:45 INFO - 'MOZ_NO_REMOTE': '1', 17:32:45 INFO - 'NO_EM_RESTART': '1', 17:32:45 INFO - 'PAGER': '/bin/cat', 17:32:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:45 INFO - 'PWD': '/builds/slave/test', 17:32:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:45 INFO - 'SHELL': '/bin/bash', 17:32:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yQYDOq7qIC/Listeners', 17:32:45 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:32:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:45 INFO - 'USER': 'cltbld', 17:32:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:45 INFO - 'XPC_FLAGS': '0x0', 17:32:45 INFO - 'XPC_SERVICE_NAME': '0', 17:32:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:45 INFO - Downloading/unpacking blobuploader==1.2.4 17:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:48 INFO - Downloading blobuploader-1.2.4.tar.gz 17:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:32:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:32:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:49 INFO - Downloading docopt-0.6.1.tar.gz 17:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:32:49 INFO - Installing collected packages: blobuploader, requests, docopt 17:32:49 INFO - Running setup.py install for blobuploader 17:32:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:32:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:32:49 INFO - Running setup.py install for requests 17:32:50 INFO - Running setup.py install for docopt 17:32:50 INFO - Successfully installed blobuploader requests docopt 17:32:50 INFO - Cleaning up... 17:32:50 INFO - Return code: 0 17:32:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:32: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')]} 17:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32: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')]} 17:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:50 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 0x1089ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a05570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9221ca96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a3d30>, '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 0x1086c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889ab20>, '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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 17:32:50 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 17:32:50 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 17:32:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GW7DshlZ0G/Render', 17:32:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:50 INFO - 'HOME': '/Users/cltbld', 17:32:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:50 INFO - 'LOGNAME': 'cltbld', 17:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:50 INFO - 'MOZ_NO_REMOTE': '1', 17:32:50 INFO - 'NO_EM_RESTART': '1', 17:32:50 INFO - 'PAGER': '/bin/cat', 17:32:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:50 INFO - 'PWD': '/builds/slave/test', 17:32:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:50 INFO - 'SHELL': '/bin/bash', 17:32:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yQYDOq7qIC/Listeners', 17:32:50 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:32:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:50 INFO - 'USER': 'cltbld', 17:32:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:50 INFO - 'XPC_FLAGS': '0x0', 17:32:50 INFO - 'XPC_SERVICE_NAME': '0', 17:32:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:32:50 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-5f4gQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:32:50 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-HysUVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:32:50 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-V_JGHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:32:50 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-QcASrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:32:50 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-YEparp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:32:51 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-gSKrHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:32:51 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-g663Ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:32:51 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-6qy8tW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:32:51 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-WXPRFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:32:51 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-ss8KHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:32:51 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-l_l66z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:32:51 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-GH0CRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:32:51 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-IdIygV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:32:51 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-PRLWxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:52 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-JenDJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:32:52 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-wMYoRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:32:52 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-6B0F60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:32:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:32:52 INFO - Running setup.py install for manifestparser 17:32:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:32:52 INFO - Running setup.py install for mozcrash 17:32:52 INFO - Running setup.py install for mozdebug 17:32:52 INFO - Running setup.py install for mozdevice 17:32:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:32:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:32:52 INFO - Running setup.py install for mozfile 17:32:53 INFO - Running setup.py install for mozhttpd 17:32:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:32:53 INFO - Running setup.py install for mozinfo 17:32:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:32:53 INFO - Running setup.py install for mozInstall 17:32:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:32:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:32:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:32:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:32:53 INFO - Running setup.py install for mozleak 17:32:53 INFO - Running setup.py install for mozlog 17:32:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:32:53 INFO - Running setup.py install for moznetwork 17:32:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:32:53 INFO - Running setup.py install for mozprocess 17:32:54 INFO - Running setup.py install for mozprofile 17:32:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:32:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:32:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:32:54 INFO - Running setup.py install for mozrunner 17:32:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:32:54 INFO - Running setup.py install for mozscreenshot 17:32:54 INFO - Running setup.py install for moztest 17:32:54 INFO - Running setup.py install for mozversion 17:32:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:32:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:32:54 INFO - Cleaning up... 17:32:54 INFO - Return code: 0 17:32:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:32: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')]} 17:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32: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')]} 17:32:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:54 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 0x1089ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a05570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9221ca96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a3d30>, '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 0x1086c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889ab20>, '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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 17:32:54 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 17:32:54 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 17:32:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GW7DshlZ0G/Render', 17:32:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:54 INFO - 'HOME': '/Users/cltbld', 17:32:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:54 INFO - 'LOGNAME': 'cltbld', 17:32:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:54 INFO - 'MOZ_NO_REMOTE': '1', 17:32:54 INFO - 'NO_EM_RESTART': '1', 17:32:54 INFO - 'PAGER': '/bin/cat', 17:32:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:54 INFO - 'PWD': '/builds/slave/test', 17:32:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:54 INFO - 'SHELL': '/bin/bash', 17:32:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yQYDOq7qIC/Listeners', 17:32:54 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:32:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:54 INFO - 'USER': 'cltbld', 17:32:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:54 INFO - 'XPC_FLAGS': '0x0', 17:32:54 INFO - 'XPC_SERVICE_NAME': '0', 17:32:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:32:55 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-_RRc7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:32:55 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)) 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:32:55 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-E4r6yQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:32:55 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)) 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:32:55 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-WGp0YN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:32:55 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)) 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:32:55 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-wf7uQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:32:55 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)) 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:32:55 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-vvOeQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:32:55 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)) 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:32:55 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-heAnWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:32:55 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)) 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:32:55 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-shX8v9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:32:55 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)) 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:32:55 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-csli4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:32:55 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)) 17:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:32:55 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-pCWuZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:32:56 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)) 17:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:32:56 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-2QIvPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 17:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:32:56 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-BaOyAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:32:56 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)) 17:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:32:56 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-MNl0pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:32:56 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)) 17:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:32:56 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-UIHcIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:32:56 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)) 17:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:32:56 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-YIdsPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:32:56 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)) 17:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:56 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-lqDija-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:56 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)) 17:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:32:56 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-wnQJjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:32:56 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)) 17:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:32:56 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-PQC3RO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:32:56 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)) 17:32:56 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)) 17:32:56 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)) 17:32:56 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)) 17:32:56 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)) 17:32:56 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)) 17:32:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:32:56 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 17:32:56 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 17:32:56 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 17:32:56 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 17:32:56 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 17:32:56 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 17:32:59 INFO - Downloading blessings-1.6.tar.gz 17:32:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:32:59 INFO - Installing collected packages: blessings 17:32:59 INFO - Running setup.py install for blessings 17:32:59 INFO - Successfully installed blessings 17:32:59 INFO - Cleaning up... 17:32:59 INFO - Return code: 0 17:32:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:32:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:33:00 INFO - Reading from file tmpfile_stdout 17:33:00 INFO - Current package versions: 17:33:00 INFO - blessings == 1.6 17:33:00 INFO - blobuploader == 1.2.4 17:33:00 INFO - docopt == 0.6.1 17:33:00 INFO - manifestparser == 1.1 17:33:00 INFO - mozInstall == 1.12 17:33:00 INFO - mozcrash == 0.17 17:33:00 INFO - mozdebug == 0.1 17:33:00 INFO - mozdevice == 0.48 17:33:00 INFO - mozfile == 1.2 17:33:00 INFO - mozhttpd == 0.7 17:33:00 INFO - mozinfo == 0.9 17:33:00 INFO - mozleak == 0.1 17:33:00 INFO - mozlog == 3.2 17:33:00 INFO - moznetwork == 0.27 17:33:00 INFO - mozprocess == 0.22 17:33:00 INFO - mozprofile == 0.28 17:33:00 INFO - mozrunner == 6.11 17:33:00 INFO - mozscreenshot == 0.1 17:33:00 INFO - mozsystemmonitor == 0.3 17:33:00 INFO - moztest == 0.7 17:33:00 INFO - mozversion == 1.4 17:33:00 INFO - psutil == 3.1.1 17:33:00 INFO - requests == 1.2.3 17:33:00 INFO - wsgiref == 0.1.2 17:33:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:33:00 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')]} 17:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:00 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')]} 17:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:00 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 0x1089ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a05570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9221ca96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a3d30>, '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 0x1086c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889ab20>, '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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 17:33:00 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 17:33:00 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 17:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GW7DshlZ0G/Render', 17:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:00 INFO - 'HOME': '/Users/cltbld', 17:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:00 INFO - 'LOGNAME': 'cltbld', 17:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:00 INFO - 'MOZ_NO_REMOTE': '1', 17:33:00 INFO - 'NO_EM_RESTART': '1', 17:33:00 INFO - 'PAGER': '/bin/cat', 17:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:00 INFO - 'PWD': '/builds/slave/test', 17:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:00 INFO - 'SHELL': '/bin/bash', 17:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yQYDOq7qIC/Listeners', 17:33:00 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:00 INFO - 'USER': 'cltbld', 17:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:00 INFO - 'XPC_FLAGS': '0x0', 17:33:00 INFO - 'XPC_SERVICE_NAME': '0', 17:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:00 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)) 17:33:00 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)) 17:33:00 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)) 17:33:00 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)) 17:33:00 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)) 17:33:00 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)) 17:33:00 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)) 17:33:00 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)) 17:33:00 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)) 17:33:00 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)) 17:33:00 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)) 17:33:00 INFO - Cleaning up... 17:33:00 INFO - Return code: 0 17:33:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:33:00 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')]} 17:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:00 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')]} 17:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:00 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 0x1089ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a05570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9221ca96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a3d30>, '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 0x1086c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889ab20>, '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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 17:33:00 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 17:33:00 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 17:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GW7DshlZ0G/Render', 17:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:00 INFO - 'HOME': '/Users/cltbld', 17:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:00 INFO - 'LOGNAME': 'cltbld', 17:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:00 INFO - 'MOZ_NO_REMOTE': '1', 17:33:00 INFO - 'NO_EM_RESTART': '1', 17:33:00 INFO - 'PAGER': '/bin/cat', 17:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:00 INFO - 'PWD': '/builds/slave/test', 17:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:00 INFO - 'SHELL': '/bin/bash', 17:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yQYDOq7qIC/Listeners', 17:33:00 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:00 INFO - 'USER': 'cltbld', 17:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:00 INFO - 'XPC_FLAGS': '0x0', 17:33:00 INFO - 'XPC_SERVICE_NAME': '0', 17:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:00 INFO - Downloading/unpacking jsonschema 17:33: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 17:33: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 17:33: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 17:33: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 17:33: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 17:33: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 17:33:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:33:02 INFO - Downloading/unpacking functools32 (from jsonschema) 17:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:33:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:33:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:33:03 INFO - Downloading functools32-3.2.3-2.tar.gz 17:33:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:33:03 INFO - warning: no files found matching '*.txt' 17:33:03 INFO - no previously-included directories found matching 'build' 17:33:03 INFO - no previously-included directories found matching 'dist' 17:33:03 INFO - no previously-included directories found matching '.git*' 17:33:03 INFO - Installing collected packages: jsonschema, functools32 17:33:03 INFO - Running setup.py install for functools32 17:33:03 INFO - warning: no files found matching '*.txt' 17:33:03 INFO - no previously-included directories found matching 'build' 17:33:03 INFO - no previously-included directories found matching 'dist' 17:33:03 INFO - no previously-included directories found matching '.git*' 17:33:03 INFO - Successfully installed jsonschema functools32 17:33:03 INFO - Cleaning up... 17:33:03 INFO - Return code: 0 17:33:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:33:03 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')]} 17:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:03 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')]} 17:33:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:03 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 0x1089ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a05570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9221ca96f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a3d30>, '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 0x1086c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889ab20>, '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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 17:33:03 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 17:33:03 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 17:33:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GW7DshlZ0G/Render', 17:33:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:03 INFO - 'HOME': '/Users/cltbld', 17:33:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:03 INFO - 'LOGNAME': 'cltbld', 17:33:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:03 INFO - 'MOZ_NO_REMOTE': '1', 17:33:03 INFO - 'NO_EM_RESTART': '1', 17:33:03 INFO - 'PAGER': '/bin/cat', 17:33:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:03 INFO - 'PWD': '/builds/slave/test', 17:33:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:03 INFO - 'SHELL': '/bin/bash', 17:33:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yQYDOq7qIC/Listeners', 17:33:03 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:33:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:03 INFO - 'USER': 'cltbld', 17:33:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:03 INFO - 'XPC_FLAGS': '0x0', 17:33:03 INFO - 'XPC_SERVICE_NAME': '0', 17:33:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:04 INFO - Downloading/unpacking flake8 17:33:04 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 17:33:04 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 17:33:04 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 17:33:04 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 17:33:04 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 17:33:04 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 17:33:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:33:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:33:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:33:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:33:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:33:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:33:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:33:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:33:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:33:07 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 17:33:07 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 17:33:07 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 17:33:07 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 17:33:07 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 17:33:07 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 17:33:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:33:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:33:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:33:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:33:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:33:07 INFO - no previously-included directories found matching 'docs/_build' 17:33:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:33:07 INFO - Running setup.py install for pep8 17:33:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:33:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:33:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:33:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:33:07 INFO - no previously-included directories found matching 'docs/_build' 17:33:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:33:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:33:08 INFO - Cleaning up... 17:33:08 INFO - Return code: 0 17:33:08 INFO - Running post-action listener: _resource_record_post_action 17:33:08 INFO - Running post-action listener: _start_resource_monitoring 17:33:08 INFO - Starting resource monitoring. 17:33:08 INFO - ##### 17:33:08 INFO - ##### Finished create-virtualenv step (success) 17:33:08 INFO - ##### 17:33:08 INFO - ##### 17:33:08 INFO - ##### Running install step. 17:33:08 INFO - ##### 17:33:08 INFO - Running pre-action listener: _resource_record_pre_action 17:33:08 INFO - Running main action method: install 17:33:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:33:08 INFO - Reading from file tmpfile_stdout 17:33:08 INFO - Detecting whether we're running mozinstall >=1.0... 17:33:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:33:08 INFO - Reading from file tmpfile_stdout 17:33:08 INFO - Output received: 17:33:08 INFO - Usage: mozinstall [options] installer 17:33:08 INFO - Options: 17:33:08 INFO - -h, --help show this help message and exit 17:33:08 INFO - -d DEST, --destination=DEST 17:33:08 INFO - Directory to install application into. [default: 17:33:08 INFO - "/builds/slave/test"] 17:33:08 INFO - --app=APP Application being installed. [default: firefox] 17:33:08 INFO - mkdir: /builds/slave/test/build/application 17:33:08 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'] 17:33:08 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 17:33:41 INFO - Reading from file tmpfile_stdout 17:33:41 INFO - Output received: 17:33:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:33:41 INFO - Running post-action listener: _resource_record_post_action 17:33:41 INFO - ##### 17:33:41 INFO - ##### Finished install step (success) 17:33:41 INFO - ##### 17:33:41 INFO - ##### 17:33:41 INFO - ##### Running run-tests step. 17:33:41 INFO - ##### 17:33:41 INFO - Running pre-action listener: _resource_record_pre_action 17:33:41 INFO - Running main action method: run_tests 17:33:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:33:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:33:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:33:41 INFO - 2016-05-24 17:33:41.972 screenresolution[1646:8274] starting screenresolution argv=screenresolution get 17:33:41 INFO - 2016-05-24 17:33:41.975 screenresolution[1646:8274] Display 0: 1600x1200x32@60 17:33:41 INFO - 2016-05-24 17:33:41.981 screenresolution[1647:8276] starting screenresolution argv=screenresolution list 17:33:41 INFO - Available Modes on Display 0 17:33:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:33:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:33:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:33:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:33:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:33:42 INFO - Intel Iris: 17:33:42 INFO - Chipset Model: Intel Iris 17:33:42 INFO - Type: GPU 17:33:42 INFO - Bus: Built-In 17:33:42 INFO - VRAM (Dynamic, Max): 1536 MB 17:33:42 INFO - Vendor: Intel (0x8086) 17:33:42 INFO - Device ID: 0x0a2e 17:33:42 INFO - Revision ID: 0x0009 17:33:42 INFO - Displays: 17:33:42 INFO - Display: 17:33:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:33:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:33:42 INFO - Display Serial Number: 1600x1200 60 17:33:42 INFO - Main Display: Yes 17:33:42 INFO - Mirror: Off 17:33:42 INFO - Online: Yes 17:33:42 INFO - Rotation: Supported 17:33:42 INFO - Return code: 0 17:33:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:33:42 INFO - Python 2.7.3 17:33:42 INFO - Return code: 0 17:33:42 INFO - grabbing minidump binary from tooltool 17:33: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')]} 17:33:42 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088a3d30>, '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 0x1086c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10889ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:33:42 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 17:33:42 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 17:33:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:33:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:33:42 INFO - Return code: 0 17:33:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:33:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:33:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:33:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:33:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:33:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:33:43 INFO - Return code: 0 17:33:43 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/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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 17:33:43 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/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0160 --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 17:33:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GW7DshlZ0G/Render', 17:33:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:43 INFO - 'HOME': '/Users/cltbld', 17:33:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:43 INFO - 'LOGNAME': 'cltbld', 17:33:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:33:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:33:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:43 INFO - 'MOZ_NO_REMOTE': '1', 17:33:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:33:43 INFO - 'NO_EM_RESTART': '1', 17:33:43 INFO - 'PAGER': '/bin/cat', 17:33:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:43 INFO - 'PWD': '/builds/slave/test', 17:33:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:33:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:43 INFO - 'SHELL': '/bin/bash', 17:33:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yQYDOq7qIC/Listeners', 17:33:43 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:33:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:43 INFO - 'USER': 'cltbld', 17:33:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:43 INFO - 'XPC_FLAGS': '0x0', 17:33:43 INFO - 'XPC_SERVICE_NAME': '0', 17:33:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:43 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/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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 17:33:43 INFO - mozversion application_buildid: 20160524153249 17:33:43 INFO - mozversion application_changeset: 13da4ea2b371b20490eaf301a9940d1e34005da3 17:33:43 INFO - mozversion application_display_name: Nightly 17:33:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:33:43 INFO - mozversion application_name: Firefox 17:33:43 INFO - mozversion application_remotingname: firefox 17:33:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:33:43 INFO - mozversion application_vendor: Mozilla 17:33:43 INFO - mozversion application_version: 49.0a1 17:33:43 INFO - mozversion platform_buildid: 20160524153249 17:33:43 INFO - mozversion platform_changeset: 13da4ea2b371b20490eaf301a9940d1e34005da3 17:33:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:33:43 INFO - mozversion platform_version: 49.0a1 17:33:43 INFO - using testdate: 1464136423 17:33:43 INFO - actual date: 1464136423 17:33:43 INFO - starting webserver on 'localhost:49275' 17:33:43 INFO - SUITE-START | Running 2 tests 17:33:43 INFO - TEST-START | dromaeo_css 17:33:43 INFO - operating with platform_type : mac_ 17:33:43 INFO - Initialising browser for dromaeo_css test... 17:33:43 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmphbZIG1/profile http://localhost:49275/getInfo.html) 17:33:46 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 17:33:46 INFO - PROCESS | 1665 | colorDepth:24 17:33:46 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 17:33:46 INFO - PROCESS | 1665 | __metrics 17:33:46 INFO - PROCESS | 1665 | 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" 17:33:46 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:33:47 INFO - TEST-INFO | 1665: exit 0 17:33:47 INFO - Browser initialized. 17:33:47 INFO - Running cycle 1/1 for dromaeo_css test... 17:33:47 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmphbZIG1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:33:59 INFO - PROCESS | 1668 | RSS: Main: 239181824 17:33:59 INFO - PROCESS | 1668 | 17:34:50 INFO - PROCESS | 1668 | 1464136490648 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] 17:37:00 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 17:37:02 INFO - PROCESS | 1668 | RSS: Main: 354717696 17:37:02 INFO - PROCESS | 1668 | 17:40:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 17:40:05 INFO - PROCESS | 1668 | RSS: Main: 369938432 17:40:05 INFO - PROCESS | 1668 | 17:43:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 17:43:08 INFO - PROCESS | 1668 | RSS: Main: 370196480 17:43:08 INFO - PROCESS | 1668 | 17:46:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 17:46:11 INFO - PROCESS | 1668 | RSS: Main: 367616000 17:46:11 INFO - PROCESS | 1668 | 17:49:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 17:49:15 INFO - PROCESS | 1668 | RSS: Main: 334745600 17:49:15 INFO - PROCESS | 1668 | 17:52:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 17:52:18 INFO - PROCESS | 1668 | RSS: Main: 377802752 17:52:18 INFO - PROCESS | 1668 | 17:52:18 INFO - PROCESS | 1668 | __start_tp_report 17:52:18 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 17:52:18 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 17:52:18 INFO - PROCESS | 1668 | |i|pagename|runs| 17:52:18 INFO - PROCESS | 1668 | |0;dojo.html;3414.1976635490664;3452.067941655753;3470.1845066409373;3471.9305613887723;3503.4919936609194;5572.832815015556;5623.212750214973;5647.3505546862125;5698.430156984305;5706.51494622957;5667.0932650775885;5794.333651630064;5809.709514524278;5816.709164541777;5819.476247137757;4494.460664720238;5156.381234251895;5270.262163297122;5281.603879709018;5312.17661262503;5190.532852043315;5266.420693723688;5269.104252277103;5269.420363759983;5279.498447647467;5213.244079608474;5311.389190243133;5361.035013697533;5367.838964831042;5374.355077390699;5113.411957624883;5124.154514505102;5170.457102004273;5216.26972223889;5232.136697444955;5155.536001759822;5164.070467315882;5190.532852043334;5221.373435187764;5248.71132087735;5563.972180139073;5569.832905012816;5665.745041473144;5669.461401166883;5707.800226992035;5910.763569457216;6113.263474096196;6178.69106544671;6243.344448832898;6277.623342599413;6230.750769969196;6293.779717709904;6305.96847015762;6312;6326.145970293997;5792.986227410186;5795.536357091422;5818.0400233961345;5842.211301474289;5845.386234445365;6218.595791273553;6283.151774510474;6334.873302534015;6336.588121772163;6360.268569114519;6032.788852390146;6053.3180948594645;6099.451049405575;6105.694715264219;6109.136713302397;6233.470155036782;6246.406591373812;6248.656323902229;6259;6263.686815659199;5976.402359763989;5995.310539287951;6089.2997305310455;6112.663011815997;6124.014786914908;6182.845428864224;6215.906761398583;6231.408016238541;6232.56372053961;6234.1583886174785;4943.110240156734;4949.925751113737;4957.405111386656;4959.2065269557415;4980.477049909708;4343.5439278875265;4378.715383500126;4392.3191905254735;4417.646588272931;4459.464864216315;5625.578081643893;5648.322201335784;5648.519875810603;5657.4908258256955;5722.77108915647;6094.421030002142;6308.621482711052;6331.778387756406;6333.176687030748;6339.4611458025665;5489.286392768914;5592.356878958891;5620.1288800236025;5645.096784514541;5660.801871934463;5385.5960802939935;5392.676439413569;5394.487523685244;5402.459754024566;5407.378151512627;6249.281332646804;6306.779262725782;6307.842303942438;6330.715117819709;6349.2698339690605;6145.784897528565;6236.594621349598;6246.063090536456;6304.306526282014;6315.5894866833505;6283.96858015707;6299;6304;6304.905426418699;6308.810735677937;6000.939990600038;6075.605085669417;6169.074638804215;6191.628502289877;6203;6155.1075094112;6226.315105338319;6268.561200715906;6276.497880169484;6285.120083188449;5912.172295878667;6012.368701286302;6045.727942242522;6057.091436284592;6103.481204097524;5847.678377689268;5896.115582662635;5897.144913987534;6045.697715114139;6053.182820319201;5367.382750983531;5748.425157484218;5800.506956908541;5825.475707186373;5907.527397808251;5990.371011043778;6058.485028772604;6058.666773327509;6059.545534084873;6072.514198864169;5721.399253078534;5774.249347584787;5856.297244330707;5859.150423188699;5880.294364676266 17:52:18 INFO - PROCESS | 1668 | |1;ext.html;489.8334566247477;496.0871995528227;499.95250451207136;526.4787860018582;532.4675324675325;30631.081067567982;41455;42372.364414533804;42410.30358785645;42429;43011.56988430115;43770.781146094225;44234.894127646825;44282.7785861071;44367.556324436744;8501.702440414587;9546.474943878102;9665.46839923804;9747.268954828382;9769.90230097697;8533.359998000144;9530.952345238298;11578.421078946061;11601.651950441501;11781.469833857496;11572.826407603892;11596.594119205829;11640.243384180043;11641.068714502862;11660.883391166064;8393.916060839374;9725.465099419529;11457.484413201426;11568.24806387582;11716.23844450108;11545.942270288679;11628.709282267926;11636.243644163187;11644.534218631245;11661.242019268806;39502.40746388806;39882.202355952715;39895.20209595821;39962.20075598501;39972.40041399381;3164.019154062225;3187.553742476055;3198.1524895902603;3294.2752594429426;3579.713622910161;28900.27749306284;28994.13017609475;29080.272993175127;29302.41395172106;29550.85224573885;2620.475904819044;2652.2573679369807;2693.4613077384406;2714.172177485866;2763.709810469912;384962;385665;523728;525105;525338.6131485379;33925.151871203416;60085.39914600798;60692;60785.08822367668;60793.08810367848;35336;36219.63780362163;36418.27163456716;36491.08772280661;36521.45217821735;15020.399184032734;15178.848211517965;15196.088234705952;15289.770653440208;15309.6172595686;10734.57061717538;11594.47824847884;11656.592019279284;12666.303353315472;12833.743325133632;6753.425958793459;7864.370850331959;7898.249666281809;7943.205679432011;7944;3235.822029788384;3252.7397808175288;3299.6306532789104;3308.983455082709;3340.281839404491;907.919575704909;926.8841394825647;961.3606951377255;1028.8065843621364;1046.5238316565944;28908.855455722587;28984.710152898624;29313.85343073271;29671.703282967326;29674;1029.5727273181697;1056.413690401819;1075.000499071726;1097.1277834121893;1162.91859569831;14829.40682372693;14895.47865824691;15238.314275857612;15266.847331526764;15363.923180384027;981924;1003194;1004446;1004482;1005090;48234;56965;57262.7136864313;58277.70861145667;58361.124583131284;1274.2991354754847;1317.967050823718;1394.9302534873418;1409.3657853965756;1442.8557144285487;27973.44053118967;29082;29123;29846.850765746032;29885.95399161019;49097.75451122721;49211;49629.25556116733;49688.503114968385;49816;593383;598545;598609;599387;601426;22826;25229;25291.850192733087;26020.089296874517;26051.348716281485;42979.57020429755;43941.560584393745;43989.78005109954;44228.55771442373;45185.548144518136;35165.82417087898;36176.819115904254;36679;37451;37591.6240837599;35447.46828797622;35578.28843423065;35690.10774730652;36181.81909090438;36657;35369;35475;35649.821750892115;35895;36331;30471.542926856557;30489.695103048685;31466;31989.840050799598;32317.192070197492;23221.0711571542;23775.76224237805;23816.285511434686;23998.40003999914;24193 17:52:18 INFO - PROCESS | 1668 | |2;jquery.html;381.6946044527715;384.53663335680505;386.8394616234263;397.93633018716974;404.6519992806186;19078;21754.3473695789;21986.340409787692;22136.446588835268;22159.00284487198;14731.92634036831;20742.89628551859;20746.89626551865;20757.377278681663;21642.89178554105;4834.791302174456;5321.654092483996;6161.537884658646;6161.7535298588;6168.75324987;3817.4655548223272;4074.2055299216663;5736.483716465517;6054.939450605481;6070.908936365958;5922.141289513018;6053.697315134248;6104.877902441948;6106.450419462248;6130;6572.572782769104;6618.966905165444;6643.900341494881;6684.933150668504;6704.094947182117;3630.8366123524506;5472.534834539069;5823.505002074829;5838.766449342022;5873.4126587340925;13220.537281195113;13522.797158042637;13599.456021759117;13631.841293490064;13688.247146406944;1885.3495544037285;1989.0651393844869;2086.384516567616;2692.730726927311;2702.4189799193073;8552.78618034548;9606.519674016272;9792.55933482995;10203.33678310915;10304.94847525765;1682.9915850420668;1723.2331612432472;1724.3447489953737;1753.32496988659;1825.954351141219;117576;117868.41065794617;123914.38042809728;124361.37819310935;126201;9934.701958941243;11301.208915375844;11355.829662555068;11716.305249792595;11907.76184476323;14934.25328733559;14944.40222391114;14982.325795339237;14986.175760333068;15033.624159396102;7095.219525852152;7205.567665940061;8535.615897284637;8608.48349099056;8664.960204775467;6997.895031574531;8533.914660853436;8539.530325832015;8571.742847714453;8670.783230419163;4510.1656193604285;4589.0821835633005;4603.286490593963;4665.743384113906;4684.1334353144775;1260.6533203368963;1774.5016431461909;1804.6751584714614;1887.6319117771902;1971.4972681966008;766.8734658781239;933.4166146158651;962.3504134709043;976.0961140606638;1013.138831992815;19286.035698215033;20232.59534809325;20468.874211918486;21021;21148.15407383718;905.922996545301;928.9270892119613;935.854942483916;956.9712908612753;959.5510778724176;8917.866232006525;11173.10615150786;11186.384748838893;11206.495707693173;11221.607243746594;601262;603126;605582;606605;620025;129196;130644;131813;132422;135093;961.711486554031;965.4593427680521;984.6602921991902;985.0592683986775;985.5220218194313;17990.460286191435;18280.542986424913;18344.541386464913;18439.446816595522;18472.97854068723;31724.682753172172;50861.982760343846;50898.74550627223;52334.21498677599;53189.936201276534;70983;74907.62546187235;76461;76688.61655691685;78506;6977.1627404709725;8142.4300298978505;8200.794980125545;8299.95850020746;8601.483910965362;10863.837042444527;12844.42200100979;13071.803922940973;13119.672008199872;13181.736365272833;10782.892171078189;10816.4050977197;10910.581624388778;11090.500927458444;11304.717382065513;6409.519286053658;7910.169432209766;10879.728006799893;10930.344179349264;11243.325400475998;11099.72250693739;11266.493007814504;11397.08823294118;11485.310881347146;11488.712782180512;7404;7555.471117021756;7572.810679733051;7682.193369696325;7727.652255648397;8714.346424018351;9308.208802251884;11061.612843550438;11211.551537938718;11216.66350009501 17:52:18 INFO - PROCESS | 1668 | |3;mootools.html;1411.8729314361706;1553.2932515705356;1558.3143416896569;1591.952241432757;1979.0896187753633;7815.804604884874;8646.17861303176;8886.46681199127;8901.821963560733;8940.463572185674;8567.614457349418;8896.021437641853;8903.510306933116;8908.599113039912;8917.866232006525;1982.4944639117004;2295.4376177836452;2350.741418443974;2379.7501262367464;2384.630382290739;2386.761323867608;2390.414348484615;2391.964120538193;2422.0553983946274;2422.539717453687;2284.543091381722;2312.629979203328;2312.7571604981486;2329.4176455886027;2355.5995480768224;2075.730155079845;2290.6220473621834;2295.116380193621;2353.9411514712097;2359.929202123939;2371.1345358944036;2391.330427480308;2397.5564520563753;2399.928002159938;2403.2069417092316;8660.956695216484;8724.912750872538;8784.209421152127;8801.163889430494;8806.427582207136;4876.780544875489;4952.579030782388;4982.60139188864;5016.347874776256;5019.14674505335;2883.423315336941;3434.3818112739696;3517.8944631661093;3553.1650062235353;3555.1467647764607;995.6166875752888;1024.4979959819634;1025.2720568396448;1042.49439022075;1045.503385891703;11591.478383472764;11777.352245626487;11811.350375729331;11880.227785193934;11887.989520890735;4581.65637577183;4699.107169637758;4712.88217794551;4715.575598196178;4789.353437285991;9829.754256143511;9947.403155810674;9993.15058220045;10043.648472303434;10045.648402306031;4528.615067719281;5329.573634109262;5383.946160538345;5397.460253974571;5399.919001214985;4381.3208952612395;4555.772211389483;4778.307145463958;4949.505049495094;4949.950500494949;3895.5130608673917;3953;3993.7603743774885;4011.017300761332;4036.8385264589087;1086.2396322574073;1251.3492983648453;1269.6000759760645;1277;1285.4151361130664;815.09524427885;846.7182232797888;888.4003297773977;912.2473958983865;943.613118621362;5650.26546548946;5737.569682273846;5878.265216847894;5878.5297176225795;5931.288245410578;735.6064505489644;887.8091210389797;901.2969883490888;919.2094798473307;961.6634178037631;3160.4311223979903;3337.666233376643;3908.4918960534947;3924.7056470764805;4000.2599519089063;9380.67167649129;10501.21240906935;10810.35137891729;10866.565337386573;10894.782104357868;10497.317674351143;10763.623273185402;10847.67456976292;10861.13110951122;10913.672589822318;7935.2064793520185;8298.21166989135;8301.2943899768;8417.494950303002;8425.536595487185;5720.3707592164;5811.535077193899;5911.822645320647;5919.112133180058;5975.193348897843;10058.597656093967;10227.539760710637;10298.485075746034;10304.536295866854;10313.587456501657;9908.157806586521;10160.831504376796;10167.237457190593;10246.6413675521;10387.480625968823;6938.098047254027;7202.459815513753;7378.852422951618;7554.697812087675;7652.61736913141;8383.496990180609;8405.327573794204;8485.533295668798;8569.014563325049;8574.699885503978;6329.335419780857;7425.14610819741;7550.3582195514;7628.618569071636;7705.422093343133;7251.347378735939;7259.52813067149;7308.30571095745;7379.594122323324;7427.405807535492;6930.306969303029;6985.685644146127;6986.371226589631;6987.091678081817;7012.5792452453015;4756.8097276108565;4767.547083027107;4799.496052914534;4850.587700045536;5010.073136469765;4821.083994041121;4856.757162141949;4876.24418215163;4933.284673722362;5087.3386459759995 17:52:18 INFO - PROCESS | 1668 | |4;prototype.html;306.2985762603638;312.1805261189378;317.2987697189211;318.6510770706076;322.09170140204634;5374.113271310234;5376.301080859493;5390.946090539093;5405;5475.342958844934;5280.541245484204;5354.946450535494;5368;5386.865328366793;5404.4595540445935;3871.560916622531;3911.628395302456;4043.3934909763625;4099.642705444367;4116.876493705194;3371.6122645895666;3640.526731524911;3821.522309711286;3997.6402123808853;4076.2458945095104;3707.2956138333766;4048.311786996203;4061.979690101545;4072.4140170991336;4095.6313931746135;3902.8829135125993;3932.508436445444;4033.6974726895305;4064.1871625674985;4075.6128167824304;3228.3543291341825;3295.7857739246874;3499.632538583463;3504.5268888700207;3527.5414196154593;4397;4492.326151077332;4795.832145874878;5089.262057001714;5095.5923526118195;4202.936955945664;4274.957250427487;4282.843632219284;4592.311153326994;4620.930686039678;5568.443155684439;5573.470520300565;5577.653407955235;5600.803971860965;5629;3799.620037996206;3831.9233615327817;3860.7876566788814;3863.98068009661;3867.8259478323544;4957.7769000395065;5169.534741873287;5843.415658434165;5853.1220316952795;5870.823875283663;3814.9208681268774;3866.4007078902814;3874.767513949172;3878.3600705883778;3884.378499440076;3669.5229620149744;3939.4090886367276;3969.2458432897656;3975.423563583322;3994.500687414073;4172.5410204877435;4580.6945020668945;4621.468531118897;4628.375169352162;4692.788824502905;5067.46791586878;5586;5617.887642247132;5662.37713851476;5664.97167514168;1317.413750880868;1351.6891115043595;1353.9187648741106;1355.7762969110183;1361.9182849028891;675.7938828657209;725.767754318613;744.1888341707564;783.5533745764973;824.7979245084992;4815.7351345676325;5120.667156634807;5235.345581802275;5258.474152584711;5261.552768014685;5788.739506722208;5789.334226563915;5806;5813.593048486566;5836.620619659793;4419.42547468827;4837.927431088537;4870.220764677705;5163.173892177234;5217.58259339252;2906.665733440639;3008.7292143707173;3021.383559795499;3023.5011223148376;3023.667396586374;4706.529347065334;5320.334958130234;6019.879602408015;6038.396160383926;6093.4515893568905;5085.923711144335;5106.4893510648635;5520.641158324776;5858.179854820329;5974.880502389928;5098.413682426569;6068.271807383054;6130.969345153335;6134.202553667995;6137.741762938676;4878.414590249193;5615;5694.800681976111;5798.188253644577;5847.239858818326;5717.942820571741;5747.844558290641;5753.741081651252;5773.451522105393;5778.306603207642;4857.319975203403;5514.476124768141;5933.080372542263;5950.928832810136;6103.633781973096;3051.664316925092;3319.269760652749;3700.4449332600325;3799.8290076947155;3857.9807100964317;4657.161710892072;4931.6301277405055;5735.684537350486;5766.250387449605;5766.740496677743;5598.3282006159525;5628.212050312877;5645.689487078086;5670.489655930986;5694.629849059798;4844.401347555368;5005.199168133081;5355.812546560852;5381.865453363697;5592.720363981866;5012.122878496321;5430.348358197041;5447.564194864322;5509.889802203853;5555.944440555704;4752.667313288037;4938.345654205881;5456.727163641882;5631.971840140773;5652.123920792284;5500.614956953135;5505.614606977473;5506.366767821752;5558.91661625084;5560.36055853582 17:52:18 INFO - PROCESS | 1668 | |5;yui.html;627.573250189871;646.7542333913113;673.4915139069998;731.2723839779419;739.6006156675395;14537.982341236118;14950.401983920658;14958.77561836572;15161;15200.619984500394;13755;14733.189674567895;15377.92311038446;15401.075935443881;15795.052296862197;715.4240836126942;739.548875186136;905.5743800413828;910.4309806371018;918.660095764568;881.1893058386268;916.3768637326615;916.9220616247594;917.124146440148;919.9310051746112;943.2171297822825;950.0879156010204;950.4249928793084;954.885413750351;956.3066776586988;745.4781652843004;907.5915837872983;915.4736026784592;916.0930678628143;943.0804965158915;688.683205725367;698.9336013078749;699.3216579917472;700.1912790726719;701.8175274428685;14692.412303507846;14845.777313340308;14891.255437228096;14926.776098358534;14934.47729329479;11804.763904721858;11917.404129793562;12032.217905836093;12135.211211271328;12184.147109702324;9933.453660048694;10248.743781405525;10639.361638301727;10667.413292268922;11060.668179954615;3977.105151340954;4070.75575465473;4075.3071977763852;4077.5922407759285;4093.038136038029;73290.26709732888;73920.63039684875;75252.6237368815;77260.45479090443;78147;8974.102589740975;9209.677661281823;9304;9458.006909274565;9550.808983820423;20138.798612013987;20162;20201.090950907244;20216.79783202149;20232.494187645425;6984.580925144406;7111.644417779194;7118.216996130422;7156.534825236343;7303.926960730325;3981.5819338969573;4019.537753158366;4032.1330913853662;4032.879013629537;4037.6971727120585;3299.4390953537954;3334.2664613784927;3402.0984439123654;3405.7445691572734;3406.693397594179;525.3013492055557;564.500417130834;637.8724255148989;682.8395327098128;701.9824504387329;452.9909401812011;454.31171774761265;530.4615814947831;577.6014549960514;607.7477846693573;7900.841983160304;8280.130586288473;8388.37087218461;8425.915740842636;8518.957405213057;501.393314089948;576.5010971474218;604.5073265288776;611.5443994224242;642.2966851297822;3645.2527231917393;3719.907002324909;3875.7093218008763;3897.8440862365187;3983.183447393277;82769.58615207006;83371.5831420839;88041.55979220063;88207.55896220607;88890;72576.63711681408;73173.634131829;74084.62957685177;74216;78160.60919695365;64830.67584662046;65310;65336.67331663311;68275.65862170658;68443.27887161118;7617.581033043236;7625.809354766176;7833.1775163607;7842.058952925686;7891;43359.482418115214;43643.78178109089;44382.89042773956;44632.77683611561;44672.659820205445;44594;60882.39117608944;60932.69533652304;61233.69383153056;61405.69297153486;9228.40015398997;9706.369086009387;10280.742981425525;10374.636887708893;10492.055714985383;10714.767801702905;10745.086667633339;10985.61550345734;11059.336439813636;11406.714832129263;8527.53098579584;10327.845082323622;10353.02307080344;10380.32527885685;10631.123804990122;10451;10497.842532362174;10511.894881051398;10744.785104298027;10751.354918704903;10388;10618.469076546296;10626.73433164177;10659.94670026645;10670.35977841332;8392.16078392156;8460.365472589703;8607.913920860712;8666.436681615674;8763.737087887373;8956.193942545202;8964.282857371265;9015.188633022797;9020.097990200928;9135.086491350812 17:52:18 INFO - PROCESS | 1668 | __end_tp_report 17:52:18 INFO - PROCESS | 1668 | __start_cc_report 17:52:18 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1276 17:52:18 INFO - PROCESS | 1668 | __end_cc_report 17:52:18 INFO - PROCESS | 1668 | __startTimestamp1464137538875__endTimestamp 17:52:18 INFO - PROCESS | 1668 | 17:52:18 INFO - PROCESS | 1668 | ------- Summary: start ------- 17:52:18 INFO - PROCESS | 1668 | Number of tests: 6 17:52:18 INFO - PROCESS | 1668 | 17:52:18 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5722.68 Median:5856.30 stddev:622.99 (10.6%) stddev-sans-first:598.00 17:52:18 INFO - PROCESS | 1668 | Values: 3414.2 3452.1 3470.2 3471.9 3503.5 5572.8 5623.2 5647.4 5698.4 5706.5 5667.1 5794.3 5809.7 5816.7 5819.5 4494.5 5156.4 5270.3 5281.6 5312.2 5190.5 5266.4 5269.1 5269.4 5279.5 5213.2 5311.4 5361.0 5367.8 5374.4 5113.4 5124.2 5170.5 5216.3 5232.1 5155.5 5164.1 5190.5 5221.4 5248.7 5564.0 5569.8 5665.7 5669.5 5707.8 5910.8 6113.3 6178.7 6243.3 6277.6 6230.8 6293.8 6306.0 6312.0 6326.1 5793.0 5795.5 5818.0 5842.2 5845.4 6218.6 6283.2 6334.9 6336.6 6360.3 6032.8 6053.3 6099.5 6105.7 6109.1 6233.5 6246.4 6248.7 6259.0 6263.7 5976.4 5995.3 6089.3 6112.7 6124.0 6182.8 6215.9 6231.4 6232.6 6234.2 4943.1 4949.9 4957.4 4959.2 4980.5 4343.5 4378.7 4392.3 4417.6 4459.5 5625.6 5648.3 5648.5 5657.5 5722.8 6094.4 6308.6 6331.8 6333.2 6339.5 5489.3 5592.4 5620.1 5645.1 5660.8 5385.6 5392.7 5394.5 5402.5 5407.4 6249.3 6306.8 6307.8 6330.7 6349.3 6145.8 6236.6 6246.1 6304.3 6315.6 6284.0 6299.0 6304.0 6304.9 6308.8 6000.9 6075.6 6169.1 6191.6 6203.0 6155.1 6226.3 6268.6 6276.5 6285.1 5912.2 6012.4 6045.7 6057.1 6103.5 5847.7 5896.1 5897.1 6045.7 6053.2 5367.4 5748.4 5800.5 5825.5 5907.5 5990.4 6058.5 6058.7 6059.5 6072.5 5721.4 5774.2 5856.3 5859.2 5880.3 17:52:18 INFO - PROCESS | 1668 | 17:52:18 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:78462.19 Median:3324.63 stddev:196881.41 (5921.9%) stddev-sans-first:197346.67 17:52:18 INFO - PROCESS | 1668 | Values: 489.8 496.1 500.0 526.5 532.5 30631.1 41455.0 42372.4 42410.3 42429.0 43011.6 43770.8 44234.9 44282.8 44367.6 8501.7 9546.5 9665.5 9747.3 9769.9 8533.4 9531.0 11578.4 11601.7 11781.5 11572.8 11596.6 11640.2 11641.1 11660.9 8393.9 9725.5 11457.5 11568.2 11716.2 11545.9 11628.7 11636.2 11644.5 11661.2 39502.4 39882.2 39895.2 39962.2 39972.4 3164.0 3187.6 3198.2 3294.3 3579.7 28900.3 28994.1 29080.3 29302.4 29550.9 2620.5 2652.3 2693.5 2714.2 2763.7 384962.0 385665.0 523728.0 525105.0 525338.6 33925.2 60085.4 60692.0 60785.1 60793.1 35336.0 36219.6 36418.3 36491.1 36521.5 15020.4 15178.8 15196.1 15289.8 15309.6 10734.6 11594.5 11656.6 12666.3 12833.7 6753.4 7864.4 7898.2 7943.2 7944.0 3235.8 3252.7 3299.6 3309.0 3340.3 907.9 926.9 961.4 1028.8 1046.5 28908.9 28984.7 29313.9 29671.7 29674.0 1029.6 1056.4 1075.0 1097.1 1162.9 14829.4 14895.5 15238.3 15266.8 15363.9 981924.0 1003194.0 1004446.0 1004482.0 1005090.0 48234.0 56965.0 57262.7 58277.7 58361.1 1274.3 1318.0 1394.9 1409.4 1442.9 27973.4 29082.0 29123.0 29846.9 29886.0 49097.8 49211.0 49629.3 49688.5 49816.0 593383.0 598545.0 598609.0 599387.0 601426.0 22826.0 25229.0 25291.9 26020.1 26051.3 42979.6 43941.6 43989.8 44228.6 45185.5 35165.8 36176.8 36679.0 37451.0 37591.6 35447.5 35578.3 35690.1 36181.8 36657.0 35369.0 35475.0 35649.8 35895.0 36331.0 30471.5 30489.7 31466.0 31989.8 32317.2 23221.1 23775.8 23816.3 23998.4 24193.0 17:52:18 INFO - PROCESS | 1668 | 17:52:18 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34824.25 Median:2101.00 stddev:101522.59 (4832.1%) stddev-sans-first:101774.45 17:52:18 INFO - PROCESS | 1668 | Values: 381.7 384.5 386.8 397.9 404.7 19078.0 21754.3 21986.3 22136.4 22159.0 14731.9 20742.9 20746.9 20757.4 21642.9 4834.8 5321.7 6161.5 6161.8 6168.8 3817.5 4074.2 5736.5 6054.9 6070.9 5922.1 6053.7 6104.9 6106.5 6130.0 6572.6 6619.0 6643.9 6684.9 6704.1 3630.8 5472.5 5823.5 5838.8 5873.4 13220.5 13522.8 13599.5 13631.8 13688.2 1885.3 1989.1 2086.4 2692.7 2702.4 8552.8 9606.5 9792.6 10203.3 10304.9 1683.0 1723.2 1724.3 1753.3 1826.0 117576.0 117868.4 123914.4 124361.4 126201.0 9934.7 11301.2 11355.8 11716.3 11907.8 14934.3 14944.4 14982.3 14986.2 15033.6 7095.2 7205.6 8535.6 8608.5 8665.0 6997.9 8533.9 8539.5 8571.7 8670.8 4510.2 4589.1 4603.3 4665.7 4684.1 1260.7 1774.5 1804.7 1887.6 1971.5 766.9 933.4 962.4 976.1 1013.1 19286.0 20232.6 20468.9 21021.0 21148.2 905.9 928.9 935.9 957.0 959.6 8917.9 11173.1 11186.4 11206.5 11221.6 601262.0 603126.0 605582.0 606605.0 620025.0 129196.0 130644.0 131813.0 132422.0 135093.0 961.7 965.5 984.7 985.1 985.5 17990.5 18280.5 18344.5 18439.4 18473.0 31724.7 50862.0 50898.7 52334.2 53189.9 70983.0 74907.6 76461.0 76688.6 78506.0 6977.2 8142.4 8200.8 8300.0 8601.5 10863.8 12844.4 13071.8 13119.7 13181.7 10782.9 10816.4 10910.6 11090.5 11304.7 6409.5 7910.2 10879.7 10930.3 11243.3 11099.7 11266.5 11397.1 11485.3 11488.7 7404.0 7555.5 7572.8 7682.2 7727.7 8714.3 9308.2 11061.6 11211.6 11216.7 17:52:18 INFO - PROCESS | 1668 | 17:52:18 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5688.66 Median:4810.29 stddev:3241.30 (67.4%) stddev-sans-first:3234.46 17:52:18 INFO - PROCESS | 1668 | Values: 1411.9 1553.3 1558.3 1592.0 1979.1 7815.8 8646.2 8886.5 8901.8 8940.5 8567.6 8896.0 8903.5 8908.6 8917.9 1982.5 2295.4 2350.7 2379.8 2384.6 2386.8 2390.4 2392.0 2422.1 2422.5 2284.5 2312.6 2312.8 2329.4 2355.6 2075.7 2290.6 2295.1 2353.9 2359.9 2371.1 2391.3 2397.6 2399.9 2403.2 8661.0 8724.9 8784.2 8801.2 8806.4 4876.8 4952.6 4982.6 5016.3 5019.1 2883.4 3434.4 3517.9 3553.2 3555.1 995.6 1024.5 1025.3 1042.5 1045.5 11591.5 11777.4 11811.4 11880.2 11888.0 4581.7 4699.1 4712.9 4715.6 4789.4 9829.8 9947.4 9993.2 10043.6 10045.6 4528.6 5329.6 5383.9 5397.5 5399.9 4381.3 4555.8 4778.3 4949.5 4950.0 3895.5 3953.0 3993.8 4011.0 4036.8 1086.2 1251.3 1269.6 1277.0 1285.4 815.1 846.7 888.4 912.2 943.6 5650.3 5737.6 5878.3 5878.5 5931.3 735.6 887.8 901.3 919.2 961.7 3160.4 3337.7 3908.5 3924.7 4000.3 9380.7 10501.2 10810.4 10866.6 10894.8 10497.3 10763.6 10847.7 10861.1 10913.7 7935.2 8298.2 8301.3 8417.5 8425.5 5720.4 5811.5 5911.8 5919.1 5975.2 10058.6 10227.5 10298.5 10304.5 10313.6 9908.2 10160.8 10167.2 10246.6 10387.5 6938.1 7202.5 7378.9 7554.7 7652.6 8383.5 8405.3 8485.5 8569.0 8574.7 6329.3 7425.1 7550.4 7628.6 7705.4 7251.3 7259.5 7308.3 7379.6 7427.4 6930.3 6985.7 6986.4 6987.1 7012.6 4756.8 4767.5 4799.5 4850.6 5010.1 4821.1 4856.8 4876.2 4933.3 5087.3 17:52:18 INFO - PROCESS | 1668 | 17:52:18 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4534.80 Median:5092.43 stddev:1409.00 (27.7%) stddev-sans-first:1376.75 17:52:18 INFO - PROCESS | 1668 | Values: 306.3 312.2 317.3 318.7 322.1 5374.1 5376.3 5390.9 5405.0 5475.3 5280.5 5354.9 5368.0 5386.9 5404.5 3871.6 3911.6 4043.4 4099.6 4116.9 3371.6 3640.5 3821.5 3997.6 4076.2 3707.3 4048.3 4062.0 4072.4 4095.6 3902.9 3932.5 4033.7 4064.2 4075.6 3228.4 3295.8 3499.6 3504.5 3527.5 4397.0 4492.3 4795.8 5089.3 5095.6 4202.9 4275.0 4282.8 4592.3 4620.9 5568.4 5573.5 5577.7 5600.8 5629.0 3799.6 3831.9 3860.8 3864.0 3867.8 4957.8 5169.5 5843.4 5853.1 5870.8 3814.9 3866.4 3874.8 3878.4 3884.4 3669.5 3939.4 3969.2 3975.4 3994.5 4172.5 4580.7 4621.5 4628.4 4692.8 5067.5 5586.0 5617.9 5662.4 5665.0 1317.4 1351.7 1353.9 1355.8 1361.9 675.8 725.8 744.2 783.6 824.8 4815.7 5120.7 5235.3 5258.5 5261.6 5788.7 5789.3 5806.0 5813.6 5836.6 4419.4 4837.9 4870.2 5163.2 5217.6 2906.7 3008.7 3021.4 3023.5 3023.7 4706.5 5320.3 6019.9 6038.4 6093.5 5085.9 5106.5 5520.6 5858.2 5974.9 5098.4 6068.3 6131.0 6134.2 6137.7 4878.4 5615.0 5694.8 5798.2 5847.2 5717.9 5747.8 5753.7 5773.5 5778.3 4857.3 5514.5 5933.1 5950.9 6103.6 3051.7 3319.3 3700.4 3799.8 3858.0 4657.2 4931.6 5735.7 5766.3 5766.7 5598.3 5628.2 5645.7 5670.5 5694.6 4844.4 5005.2 5355.8 5381.9 5592.7 5012.1 5430.3 5447.6 5509.9 5555.9 4752.7 4938.3 5456.7 5632.0 5652.1 5500.6 5505.6 5506.4 5558.9 5560.4 17:52:18 INFO - PROCESS | 1668 | 17:52:18 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:17125.10 Median:61319.69 stddev:23792.82 (38.8%) stddev-sans-first:23827.32 17:52:18 INFO - PROCESS | 1668 | Values: 627.6 646.8 673.5 731.3 739.6 14538.0 14950.4 14958.8 15161.0 15200.6 13755.0 14733.2 15377.9 15401.1 15795.1 715.4 739.5 905.6 910.4 918.7 881.2 916.4 916.9 917.1 919.9 943.2 950.1 950.4 954.9 956.3 745.5 907.6 915.5 916.1 943.1 688.7 698.9 699.3 700.2 701.8 14692.4 14845.8 14891.3 14926.8 14934.5 11804.8 11917.4 12032.2 12135.2 12184.1 9933.5 10248.7 10639.4 10667.4 11060.7 3977.1 4070.8 4075.3 4077.6 4093.0 73290.3 73920.6 75252.6 77260.5 78147.0 8974.1 9209.7 9304.0 9458.0 9550.8 20138.8 20162.0 20201.1 20216.8 20232.5 6984.6 7111.6 7118.2 7156.5 7303.9 3981.6 4019.5 4032.1 4032.9 4037.7 3299.4 3334.3 3402.1 3405.7 3406.7 525.3 564.5 637.9 682.8 702.0 453.0 454.3 530.5 577.6 607.7 7900.8 8280.1 8388.4 8425.9 8519.0 501.4 576.5 604.5 611.5 642.3 3645.3 3719.9 3875.7 3897.8 3983.2 82769.6 83371.6 88041.6 88207.6 88890.0 72576.6 73173.6 74084.6 74216.0 78160.6 64830.7 65310.0 65336.7 68275.7 68443.3 7617.6 7625.8 7833.2 7842.1 7891.0 43359.5 43643.8 44382.9 44632.8 44672.7 44594.0 60882.4 60932.7 61233.7 61405.7 9228.4 9706.4 10280.7 10374.6 10492.1 10714.8 10745.1 10985.6 11059.3 11406.7 8527.5 10327.8 10353.0 10380.3 10631.1 10451.0 10497.8 10511.9 10744.8 10751.4 10388.0 10618.5 10626.7 10659.9 10670.4 8392.2 8460.4 8607.9 8666.4 8763.7 8956.2 8964.3 9015.2 9020.1 9135.1 17:52:18 INFO - PROCESS | 1668 | -------- Summary: end -------- 17:52:18 INFO - PROCESS | 1668 | 17:52:19 INFO - TEST-INFO | 1668: exit 0 17:52:19 INFO - TEST-OK | dromaeo_css | took 1115810ms 17:52:19 INFO - TEST-START | kraken 17:52:19 INFO - operating with platform_type : mac_ 17:52:19 INFO - Initialising browser for kraken test... 17:52:19 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpoz8_3d/profile http://localhost:49275/getInfo.html) 17:52:21 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 17:52:21 INFO - PROCESS | 1674 | colorDepth:24 17:52:21 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 17:52:21 INFO - PROCESS | 1674 | __metrics 17:52:21 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:52:21 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:52:22 INFO - TEST-INFO | 1674: exit 0 17:52:22 INFO - Browser initialized. 17:52:22 INFO - Running cycle 1/1 for kraken test... 17:52:22 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpoz8_3d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:52:34 INFO - PROCESS | 1676 | RSS: Main: 239390720 17:52:34 INFO - PROCESS | 1676 | 17:52:36 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 17:52:37 INFO - PROCESS | 1676 | RSS: Main: 287354880 17:52:37 INFO - PROCESS | 1676 | 17:52:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 17:52:41 INFO - PROCESS | 1676 | RSS: Main: 295477248 17:52:41 INFO - PROCESS | 1676 | 17:52:44 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 17:52:45 INFO - PROCESS | 1676 | RSS: Main: 384528384 17:52:45 INFO - PROCESS | 1676 | 17:52:47 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 17:52:47 INFO - PROCESS | 1676 | RSS: Main: 285102080 17:52:47 INFO - PROCESS | 1676 | 17:52:49 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:52:50 INFO - PROCESS | 1676 | RSS: Main: 289005568 17:52:50 INFO - PROCESS | 1676 | 17:52:53 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 17:52:54 INFO - PROCESS | 1676 | RSS: Main: 327458816 17:52:54 INFO - PROCESS | 1676 | 17:52:56 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 17:52:57 INFO - PROCESS | 1676 | RSS: Main: 326860800 17:52:57 INFO - PROCESS | 1676 | 17:53:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 17:53:00 INFO - PROCESS | 1676 | RSS: Main: 327172096 17:53:00 INFO - PROCESS | 1676 | 17:53:02 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:53:03 INFO - PROCESS | 1676 | RSS: Main: 313585664 17:53:03 INFO - PROCESS | 1676 | 17:53:04 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:53:05 INFO - PROCESS | 1676 | RSS: Main: 320696320 17:53:05 INFO - PROCESS | 1676 | 17:53:07 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:53:08 INFO - PROCESS | 1676 | RSS: Main: 321724416 17:53:08 INFO - PROCESS | 1676 | 17:53:11 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:53:11 INFO - PROCESS | 1676 | RSS: Main: 326447104 17:53:11 INFO - PROCESS | 1676 | 17:53:14 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:53:15 INFO - PROCESS | 1676 | RSS: Main: 324108288 17:53:15 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 17:53:17 INFO - PROCESS | 1676 | RSS: Main: 370417664 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | __start_tp_report 17:53:17 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 17:53:17 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 17:53:17 INFO - PROCESS | 1676 | |i|pagename|runs| 17:53:17 INFO - PROCESS | 1676 | |0;ai-astar;113;108;113;111;119;113;112;112;111;112 17:53:17 INFO - PROCESS | 1676 | |1;audio-beat-detection;156;118;116;168;115;118;139;116;149;122 17:53:17 INFO - PROCESS | 1676 | |2;audio-dft;183;185;187;183;181;181;182;181;181;182 17:53:17 INFO - PROCESS | 1676 | |3;audio-fft;78;58;61;85;60;59;64;81;72;59 17:53:17 INFO - PROCESS | 1676 | |4;audio-oscillator;87;72;129;101;105;68;95;70;68;100 17:53:17 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;89;86;87;86;88;83;90;86;94;132 17:53:17 INFO - PROCESS | 1676 | |6;imaging-darkroom;93;93;93;94;93;97;94;93;133;93 17:53:17 INFO - PROCESS | 1676 | |7;imaging-desaturate;86;85;87;87;84;85;85;86;87;85 17:53:17 INFO - PROCESS | 1676 | |8;json-parse-financial;56;54;56;55;56;56;55;74;58;54 17:53:17 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;62;71;72;74;71;68;69;70;71;72 17:53:17 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;85;85;82;82;85;85;83;87;86;86 17:53:17 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;138;140;154;146;138;137;137;134;186;140 17:53:17 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;189;148;156;170;221;206;165;207;223;163 17:53:17 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;65;70;83;84;84;79;67;65;65;64 17:53:17 INFO - PROCESS | 1676 | __end_tp_report 17:53:17 INFO - PROCESS | 1676 | __start_cc_report 17:53:17 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2003 17:53:17 INFO - PROCESS | 1676 | __end_cc_report 17:53:17 INFO - PROCESS | 1676 | __startTimestamp1464137597672__endTimestamp 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | ------- Summary: start ------- 17:53:17 INFO - PROCESS | 1676 | Number of tests: 14 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:112.40 Median:112.50 stddev:2.76 (2.5%) stddev-sans-first:2.92 17:53:17 INFO - PROCESS | 1676 | Values: 113.0 108.0 113.0 111.0 119.0 113.0 112.0 112.0 111.0 112.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:130.50 stddev:19.73 (15.1%) stddev-sans-first:18.86 17:53:17 INFO - PROCESS | 1676 | Values: 156.0 118.0 116.0 168.0 115.0 118.0 139.0 116.0 149.0 122.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:182.60 Median:182.50 stddev:2.01 (1.1%) stddev-sans-first:2.13 17:53:17 INFO - PROCESS | 1676 | Values: 183.0 185.0 187.0 183.0 181.0 181.0 182.0 181.0 181.0 182.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:67.70 Median:68.00 stddev:10.35 (15.2%) stddev-sans-first:10.28 17:53:17 INFO - PROCESS | 1676 | Values: 78.0 58.0 61.0 85.0 60.0 59.0 64.0 81.0 72.0 59.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:69.00 stddev:20.25 (29.3%) stddev-sans-first:21.46 17:53:17 INFO - PROCESS | 1676 | Values: 87.0 72.0 129.0 101.0 105.0 68.0 95.0 70.0 68.0 100.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:87.50 stddev:14.33 (16.4%) stddev-sans-first:15.15 17:53:17 INFO - PROCESS | 1676 | Values: 89.0 86.0 87.0 86.0 88.0 83.0 90.0 86.0 94.0 132.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:97.60 Median:93.00 stddev:12.50 (13.4%) stddev-sans-first:13.15 17:53:17 INFO - PROCESS | 1676 | Values: 93.0 93.0 93.0 94.0 93.0 97.0 94.0 93.0 133.0 93.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:1.06 (1.2%) stddev-sans-first:1.12 17:53:17 INFO - PROCESS | 1676 | Values: 86.0 85.0 87.0 87.0 84.0 85.0 85.0 86.0 87.0 85.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:56.00 stddev:5.95 (10.6%) stddev-sans-first:6.29 17:53:17 INFO - PROCESS | 1676 | Values: 56.0 54.0 56.0 55.0 56.0 56.0 55.0 74.0 58.0 54.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:71.00 stddev:3.27 (4.6%) stddev-sans-first:1.76 17:53:17 INFO - PROCESS | 1676 | Values: 62.0 71.0 72.0 74.0 71.0 68.0 69.0 70.0 71.0 72.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:85.00 stddev:1.71 (2.0%) stddev-sans-first:1.81 17:53:17 INFO - PROCESS | 1676 | Values: 85.0 85.0 82.0 82.0 85.0 85.0 83.0 87.0 86.0 86.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:140.00 stddev:15.49 (11.1%) stddev-sans-first:16.22 17:53:17 INFO - PROCESS | 1676 | Values: 138.0 140.0 154.0 146.0 138.0 137.0 137.0 134.0 186.0 140.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.80 Median:197.50 stddev:27.89 (14.1%) stddev-sans-first:29.54 17:53:17 INFO - PROCESS | 1676 | Values: 189.0 148.0 156.0 170.0 221.0 206.0 165.0 207.0 223.0 163.0 17:53:17 INFO - PROCESS | 1676 | 17:53:17 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:74.50 stddev:8.78 (11.8%) stddev-sans-first:8.88 17:53:17 INFO - PROCESS | 1676 | Values: 65.0 70.0 83.0 84.0 84.0 79.0 67.0 65.0 65.0 64.0 17:53:17 INFO - PROCESS | 1676 | -------- Summary: end -------- 17:53:17 INFO - PROCESS | 1676 | 17:53:18 INFO - TEST-INFO | 1676: exit 0 17:53:18 INFO - TEST-OK | kraken | took 58999ms 17:53:18 INFO - SUITE-END | took 1174s 17:53:18 INFO - Completed test suite (00:19:35) 17:53:18 INFO - javascript benchmark 17:53:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3414.1976635490664, 3452.067941655753, 3470.1845066409373, 3471.9305613887723, 3503.4919936609194, 5572.832815015556, 5623.212750214973, 5647.3505546862125, 5698.430156984305, 5706.51494622957, 5667.0932650775885, 5794.333651630064, 5809.709514524278, 5816.709164541777, 5819.476247137757, 4494.460664720238, 5156.381234251895, 5270.262163297122, 5281.603879709018, 5312.17661262503, 5190.532852043315, 5266.420693723688, 5269.104252277103, 5269.420363759983, 5279.498447647467, 5213.244079608474, 5311.389190243133, 5361.035013697533, 5367.838964831042, 5374.355077390699, 5113.411957624883, 5124.154514505102, 5170.457102004273, 5216.26972223889, 5232.136697444955, 5155.536001759822, 5164.070467315882, 5190.532852043334, 5221.373435187764, 5248.71132087735, 5563.972180139073, 5569.832905012816, 5665.745041473144, 5669.461401166883, 5707.800226992035, 5910.763569457216, 6113.263474096196, 6178.69106544671, 6243.344448832898, 6277.623342599413, 6230.750769969196, 6293.779717709904, 6305.96847015762, 6312.0, 6326.145970293997, 5792.986227410186, 5795.536357091422, 5818.0400233961345, 5842.211301474289, 5845.386234445365, 6218.595791273553, 6283.151774510474, 6334.873302534015, 6336.588121772163, 6360.268569114519, 6032.788852390146, 6053.3180948594645, 6099.451049405575, 6105.694715264219, 6109.136713302397, 6233.470155036782, 6246.406591373812, 6248.656323902229, 6259.0, 6263.686815659199, 5976.402359763989, 5995.310539287951, 6089.2997305310455, 6112.663011815997, 6124.014786914908, 6182.845428864224, 6215.906761398583, 6231.408016238541, 6232.56372053961, 6234.1583886174785, 4943.110240156734, 4949.925751113737, 4957.405111386656, 4959.2065269557415, 4980.477049909708, 4343.5439278875265, 4378.715383500126, 4392.3191905254735, 4417.646588272931, 4459.464864216315, 5625.578081643893, 5648.322201335784, 5648.519875810603, 5657.4908258256955, 5722.77108915647, 6094.421030002142, 6308.621482711052, 6331.778387756406, 6333.176687030748, 6339.4611458025665, 5489.286392768914, 5592.356878958891, 5620.1288800236025, 5645.096784514541, 5660.801871934463, 5385.5960802939935, 5392.676439413569, 5394.487523685244, 5402.459754024566, 5407.378151512627, 6249.281332646804, 6306.779262725782, 6307.842303942438, 6330.715117819709, 6349.2698339690605, 6145.784897528565, 6236.594621349598, 6246.063090536456, 6304.306526282014, 6315.5894866833505, 6283.96858015707, 6299.0, 6304.0, 6304.905426418699, 6308.810735677937, 6000.939990600038, 6075.605085669417, 6169.074638804215, 6191.628502289877, 6203.0, 6155.1075094112, 6226.315105338319, 6268.561200715906, 6276.497880169484, 6285.120083188449, 5912.172295878667, 6012.368701286302, 6045.727942242522, 6057.091436284592, 6103.481204097524, 5847.678377689268, 5896.115582662635, 5897.144913987534, 6045.697715114139, 6053.182820319201, 5367.382750983531, 5748.425157484218, 5800.506956908541, 5825.475707186373, 5907.527397808251, 5990.371011043778, 6058.485028772604, 6058.666773327509, 6059.545534084873, 6072.514198864169, 5721.399253078534, 5774.249347584787, 5856.297244330707, 5859.150423188699, 5880.294364676266], "value": 5683.8339508212775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.8334566247477, 496.0871995528227, 499.95250451207136, 526.4787860018582, 532.4675324675325, 30631.081067567982, 41455.0, 42372.364414533804, 42410.30358785645, 42429.0, 43011.56988430115, 43770.781146094225, 44234.894127646825, 44282.7785861071, 44367.556324436744, 8501.702440414587, 9546.474943878102, 9665.46839923804, 9747.268954828382, 9769.90230097697, 8533.359998000144, 9530.952345238298, 11578.421078946061, 11601.651950441501, 11781.469833857496, 11572.826407603892, 11596.594119205829, 11640.243384180043, 11641.068714502862, 11660.883391166064, 8393.916060839374, 9725.465099419529, 11457.484413201426, 11568.24806387582, 11716.23844450108, 11545.942270288679, 11628.709282267926, 11636.243644163187, 11644.534218631245, 11661.242019268806, 39502.40746388806, 39882.202355952715, 39895.20209595821, 39962.20075598501, 39972.40041399381, 3164.019154062225, 3187.553742476055, 3198.1524895902603, 3294.2752594429426, 3579.713622910161, 28900.27749306284, 28994.13017609475, 29080.272993175127, 29302.41395172106, 29550.85224573885, 2620.475904819044, 2652.2573679369807, 2693.4613077384406, 2714.172177485866, 2763.709810469912, 384962.0, 385665.0, 523728.0, 525105.0, 525338.6131485379, 33925.151871203416, 60085.39914600798, 60692.0, 60785.08822367668, 60793.08810367848, 35336.0, 36219.63780362163, 36418.27163456716, 36491.08772280661, 36521.45217821735, 15020.399184032734, 15178.848211517965, 15196.088234705952, 15289.770653440208, 15309.6172595686, 10734.57061717538, 11594.47824847884, 11656.592019279284, 12666.303353315472, 12833.743325133632, 6753.425958793459, 7864.370850331959, 7898.249666281809, 7943.205679432011, 7944.0, 3235.822029788384, 3252.7397808175288, 3299.6306532789104, 3308.983455082709, 3340.281839404491, 907.919575704909, 926.8841394825647, 961.3606951377255, 1028.8065843621364, 1046.5238316565944, 28908.855455722587, 28984.710152898624, 29313.85343073271, 29671.703282967326, 29674.0, 1029.5727273181697, 1056.413690401819, 1075.000499071726, 1097.1277834121893, 1162.91859569831, 14829.40682372693, 14895.47865824691, 15238.314275857612, 15266.847331526764, 15363.923180384027, 981924.0, 1003194.0, 1004446.0, 1004482.0, 1005090.0, 48234.0, 56965.0, 57262.7136864313, 58277.70861145667, 58361.124583131284, 1274.2991354754847, 1317.967050823718, 1394.9302534873418, 1409.3657853965756, 1442.8557144285487, 27973.44053118967, 29082.0, 29123.0, 29846.850765746032, 29885.95399161019, 49097.75451122721, 49211.0, 49629.25556116733, 49688.503114968385, 49816.0, 593383.0, 598545.0, 598609.0, 599387.0, 601426.0, 22826.0, 25229.0, 25291.850192733087, 26020.089296874517, 26051.348716281485, 42979.57020429755, 43941.560584393745, 43989.78005109954, 44228.55771442373, 45185.548144518136, 35165.82417087898, 36176.819115904254, 36679.0, 37451.0, 37591.6240837599, 35447.46828797622, 35578.28843423065, 35690.10774730652, 36181.81909090438, 36657.0, 35369.0, 35475.0, 35649.821750892115, 35895.0, 36331.0, 30471.542926856557, 30489.695103048685, 31466.0, 31989.840050799598, 32317.192070197492, 23221.0711571542, 23775.76224237805, 23816.285511434686, 23998.40003999914, 24193.0], "value": 18750.799747576286, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.6946044527715, 384.53663335680505, 386.8394616234263, 397.93633018716974, 404.6519992806186, 19078.0, 21754.3473695789, 21986.340409787692, 22136.446588835268, 22159.00284487198, 14731.92634036831, 20742.89628551859, 20746.89626551865, 20757.377278681663, 21642.89178554105, 4834.791302174456, 5321.654092483996, 6161.537884658646, 6161.7535298588, 6168.75324987, 3817.4655548223272, 4074.2055299216663, 5736.483716465517, 6054.939450605481, 6070.908936365958, 5922.141289513018, 6053.697315134248, 6104.877902441948, 6106.450419462248, 6130.0, 6572.572782769104, 6618.966905165444, 6643.900341494881, 6684.933150668504, 6704.094947182117, 3630.8366123524506, 5472.534834539069, 5823.505002074829, 5838.766449342022, 5873.4126587340925, 13220.537281195113, 13522.797158042637, 13599.456021759117, 13631.841293490064, 13688.247146406944, 1885.3495544037285, 1989.0651393844869, 2086.384516567616, 2692.730726927311, 2702.4189799193073, 8552.78618034548, 9606.519674016272, 9792.55933482995, 10203.33678310915, 10304.94847525765, 1682.9915850420668, 1723.2331612432472, 1724.3447489953737, 1753.32496988659, 1825.954351141219, 117576.0, 117868.41065794617, 123914.38042809728, 124361.37819310935, 126201.0, 9934.701958941243, 11301.208915375844, 11355.829662555068, 11716.305249792595, 11907.76184476323, 14934.25328733559, 14944.40222391114, 14982.325795339237, 14986.175760333068, 15033.624159396102, 7095.219525852152, 7205.567665940061, 8535.615897284637, 8608.48349099056, 8664.960204775467, 6997.895031574531, 8533.914660853436, 8539.530325832015, 8571.742847714453, 8670.783230419163, 4510.1656193604285, 4589.0821835633005, 4603.286490593963, 4665.743384113906, 4684.1334353144775, 1260.6533203368963, 1774.5016431461909, 1804.6751584714614, 1887.6319117771902, 1971.4972681966008, 766.8734658781239, 933.4166146158651, 962.3504134709043, 976.0961140606638, 1013.138831992815, 19286.035698215033, 20232.59534809325, 20468.874211918486, 21021.0, 21148.15407383718, 905.922996545301, 928.9270892119613, 935.854942483916, 956.9712908612753, 959.5510778724176, 8917.866232006525, 11173.10615150786, 11186.384748838893, 11206.495707693173, 11221.607243746594, 601262.0, 603126.0, 605582.0, 606605.0, 620025.0, 129196.0, 130644.0, 131813.0, 132422.0, 135093.0, 961.711486554031, 965.4593427680521, 984.6602921991902, 985.0592683986775, 985.5220218194313, 17990.460286191435, 18280.542986424913, 18344.541386464913, 18439.446816595522, 18472.97854068723, 31724.682753172172, 50861.982760343846, 50898.74550627223, 52334.21498677599, 53189.936201276534, 70983.0, 74907.62546187235, 76461.0, 76688.61655691685, 78506.0, 6977.1627404709725, 8142.4300298978505, 8200.794980125545, 8299.95850020746, 8601.483910965362, 10863.837042444527, 12844.42200100979, 13071.803922940973, 13119.672008199872, 13181.736365272833, 10782.892171078189, 10816.4050977197, 10910.581624388778, 11090.500927458444, 11304.717382065513, 6409.519286053658, 7910.169432209766, 10879.728006799893, 10930.344179349264, 11243.325400475998, 11099.72250693739, 11266.493007814504, 11397.08823294118, 11485.310881347146, 11488.712782180512, 7404.0, 7555.471117021756, 7572.810679733051, 7682.193369696325, 7727.652255648397, 8714.346424018351, 9308.208802251884, 11061.612843550438, 11211.551537938718, 11216.66350009501], "value": 9227.289249452579, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.8729314361706, 1553.2932515705356, 1558.3143416896569, 1591.952241432757, 1979.0896187753633, 7815.804604884874, 8646.17861303176, 8886.46681199127, 8901.821963560733, 8940.463572185674, 8567.614457349418, 8896.021437641853, 8903.510306933116, 8908.599113039912, 8917.866232006525, 1982.4944639117004, 2295.4376177836452, 2350.741418443974, 2379.7501262367464, 2384.630382290739, 2386.761323867608, 2390.414348484615, 2391.964120538193, 2422.0553983946274, 2422.539717453687, 2284.543091381722, 2312.629979203328, 2312.7571604981486, 2329.4176455886027, 2355.5995480768224, 2075.730155079845, 2290.6220473621834, 2295.116380193621, 2353.9411514712097, 2359.929202123939, 2371.1345358944036, 2391.330427480308, 2397.5564520563753, 2399.928002159938, 2403.2069417092316, 8660.956695216484, 8724.912750872538, 8784.209421152127, 8801.163889430494, 8806.427582207136, 4876.780544875489, 4952.579030782388, 4982.60139188864, 5016.347874776256, 5019.14674505335, 2883.423315336941, 3434.3818112739696, 3517.8944631661093, 3553.1650062235353, 3555.1467647764607, 995.6166875752888, 1024.4979959819634, 1025.2720568396448, 1042.49439022075, 1045.503385891703, 11591.478383472764, 11777.352245626487, 11811.350375729331, 11880.227785193934, 11887.989520890735, 4581.65637577183, 4699.107169637758, 4712.88217794551, 4715.575598196178, 4789.353437285991, 9829.754256143511, 9947.403155810674, 9993.15058220045, 10043.648472303434, 10045.648402306031, 4528.615067719281, 5329.573634109262, 5383.946160538345, 5397.460253974571, 5399.919001214985, 4381.3208952612395, 4555.772211389483, 4778.307145463958, 4949.505049495094, 4949.950500494949, 3895.5130608673917, 3953.0, 3993.7603743774885, 4011.017300761332, 4036.8385264589087, 1086.2396322574073, 1251.3492983648453, 1269.6000759760645, 1277.0, 1285.4151361130664, 815.09524427885, 846.7182232797888, 888.4003297773977, 912.2473958983865, 943.613118621362, 5650.26546548946, 5737.569682273846, 5878.265216847894, 5878.5297176225795, 5931.288245410578, 735.6064505489644, 887.8091210389797, 901.2969883490888, 919.2094798473307, 961.6634178037631, 3160.4311223979903, 3337.666233376643, 3908.4918960534947, 3924.7056470764805, 4000.2599519089063, 9380.67167649129, 10501.21240906935, 10810.35137891729, 10866.565337386573, 10894.782104357868, 10497.317674351143, 10763.623273185402, 10847.67456976292, 10861.13110951122, 10913.672589822318, 7935.2064793520185, 8298.21166989135, 8301.2943899768, 8417.494950303002, 8425.536595487185, 5720.3707592164, 5811.535077193899, 5911.822645320647, 5919.112133180058, 5975.193348897843, 10058.597656093967, 10227.539760710637, 10298.485075746034, 10304.536295866854, 10313.587456501657, 9908.157806586521, 10160.831504376796, 10167.237457190593, 10246.6413675521, 10387.480625968823, 6938.098047254027, 7202.459815513753, 7378.852422951618, 7554.697812087675, 7652.61736913141, 8383.496990180609, 8405.327573794204, 8485.533295668798, 8569.014563325049, 8574.699885503978, 6329.335419780857, 7425.14610819741, 7550.3582195514, 7628.618569071636, 7705.422093343133, 7251.347378735939, 7259.52813067149, 7308.30571095745, 7379.594122323324, 7427.405807535492, 6930.306969303029, 6985.685644146127, 6986.371226589631, 6987.091678081817, 7012.5792452453015, 4756.8097276108565, 4767.547083027107, 4799.496052914534, 4850.587700045536, 5010.073136469765, 4821.083994041121, 4856.757162141949, 4876.24418215163, 4933.284673722362, 5087.3386459759995], "value": 4541.088770273156, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.2985762603638, 312.1805261189378, 317.2987697189211, 318.6510770706076, 322.09170140204634, 5374.113271310234, 5376.301080859493, 5390.946090539093, 5405.0, 5475.342958844934, 5280.541245484204, 5354.946450535494, 5368.0, 5386.865328366793, 5404.4595540445935, 3871.560916622531, 3911.628395302456, 4043.3934909763625, 4099.642705444367, 4116.876493705194, 3371.6122645895666, 3640.526731524911, 3821.522309711286, 3997.6402123808853, 4076.2458945095104, 3707.2956138333766, 4048.311786996203, 4061.979690101545, 4072.4140170991336, 4095.6313931746135, 3902.8829135125993, 3932.508436445444, 4033.6974726895305, 4064.1871625674985, 4075.6128167824304, 3228.3543291341825, 3295.7857739246874, 3499.632538583463, 3504.5268888700207, 3527.5414196154593, 4397.0, 4492.326151077332, 4795.832145874878, 5089.262057001714, 5095.5923526118195, 4202.936955945664, 4274.957250427487, 4282.843632219284, 4592.311153326994, 4620.930686039678, 5568.443155684439, 5573.470520300565, 5577.653407955235, 5600.803971860965, 5629.0, 3799.620037996206, 3831.9233615327817, 3860.7876566788814, 3863.98068009661, 3867.8259478323544, 4957.7769000395065, 5169.534741873287, 5843.415658434165, 5853.1220316952795, 5870.823875283663, 3814.9208681268774, 3866.4007078902814, 3874.767513949172, 3878.3600705883778, 3884.378499440076, 3669.5229620149744, 3939.4090886367276, 3969.2458432897656, 3975.423563583322, 3994.500687414073, 4172.5410204877435, 4580.6945020668945, 4621.468531118897, 4628.375169352162, 4692.788824502905, 5067.46791586878, 5586.0, 5617.887642247132, 5662.37713851476, 5664.97167514168, 1317.413750880868, 1351.6891115043595, 1353.9187648741106, 1355.7762969110183, 1361.9182849028891, 675.7938828657209, 725.767754318613, 744.1888341707564, 783.5533745764973, 824.7979245084992, 4815.7351345676325, 5120.667156634807, 5235.345581802275, 5258.474152584711, 5261.552768014685, 5788.739506722208, 5789.334226563915, 5806.0, 5813.593048486566, 5836.620619659793, 4419.42547468827, 4837.927431088537, 4870.220764677705, 5163.173892177234, 5217.58259339252, 2906.665733440639, 3008.7292143707173, 3021.383559795499, 3023.5011223148376, 3023.667396586374, 4706.529347065334, 5320.334958130234, 6019.879602408015, 6038.396160383926, 6093.4515893568905, 5085.923711144335, 5106.4893510648635, 5520.641158324776, 5858.179854820329, 5974.880502389928, 5098.413682426569, 6068.271807383054, 6130.969345153335, 6134.202553667995, 6137.741762938676, 4878.414590249193, 5615.0, 5694.800681976111, 5798.188253644577, 5847.239858818326, 5717.942820571741, 5747.844558290641, 5753.741081651252, 5773.451522105393, 5778.306603207642, 4857.319975203403, 5514.476124768141, 5933.080372542263, 5950.928832810136, 6103.633781973096, 3051.664316925092, 3319.269760652749, 3700.4449332600325, 3799.8290076947155, 3857.9807100964317, 4657.161710892072, 4931.6301277405055, 5735.684537350486, 5766.250387449605, 5766.740496677743, 5598.3282006159525, 5628.212050312877, 5645.689487078086, 5670.489655930986, 5694.629849059798, 4844.401347555368, 5005.199168133081, 5355.812546560852, 5381.865453363697, 5592.720363981866, 5012.122878496321, 5430.348358197041, 5447.564194864322, 5509.889802203853, 5555.944440555704, 4752.667313288037, 4938.345654205881, 5456.727163641882, 5631.971840140773, 5652.123920792284, 5500.614956953135, 5505.614606977473, 5506.366767821752, 5558.91661625084, 5560.36055853582], "value": 4077.2920733590327, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.573250189871, 646.7542333913113, 673.4915139069998, 731.2723839779419, 739.6006156675395, 14537.982341236118, 14950.401983920658, 14958.77561836572, 15161.0, 15200.619984500394, 13755.0, 14733.189674567895, 15377.92311038446, 15401.075935443881, 15795.052296862197, 715.4240836126942, 739.548875186136, 905.5743800413828, 910.4309806371018, 918.660095764568, 881.1893058386268, 916.3768637326615, 916.9220616247594, 917.124146440148, 919.9310051746112, 943.2171297822825, 950.0879156010204, 950.4249928793084, 954.885413750351, 956.3066776586988, 745.4781652843004, 907.5915837872983, 915.4736026784592, 916.0930678628143, 943.0804965158915, 688.683205725367, 698.9336013078749, 699.3216579917472, 700.1912790726719, 701.8175274428685, 14692.412303507846, 14845.777313340308, 14891.255437228096, 14926.776098358534, 14934.47729329479, 11804.763904721858, 11917.404129793562, 12032.217905836093, 12135.211211271328, 12184.147109702324, 9933.453660048694, 10248.743781405525, 10639.361638301727, 10667.413292268922, 11060.668179954615, 3977.105151340954, 4070.75575465473, 4075.3071977763852, 4077.5922407759285, 4093.038136038029, 73290.26709732888, 73920.63039684875, 75252.6237368815, 77260.45479090443, 78147.0, 8974.102589740975, 9209.677661281823, 9304.0, 9458.006909274565, 9550.808983820423, 20138.798612013987, 20162.0, 20201.090950907244, 20216.79783202149, 20232.494187645425, 6984.580925144406, 7111.644417779194, 7118.216996130422, 7156.534825236343, 7303.926960730325, 3981.5819338969573, 4019.537753158366, 4032.1330913853662, 4032.879013629537, 4037.6971727120585, 3299.4390953537954, 3334.2664613784927, 3402.0984439123654, 3405.7445691572734, 3406.693397594179, 525.3013492055557, 564.500417130834, 637.8724255148989, 682.8395327098128, 701.9824504387329, 452.9909401812011, 454.31171774761265, 530.4615814947831, 577.6014549960514, 607.7477846693573, 7900.841983160304, 8280.130586288473, 8388.37087218461, 8425.915740842636, 8518.957405213057, 501.393314089948, 576.5010971474218, 604.5073265288776, 611.5443994224242, 642.2966851297822, 3645.2527231917393, 3719.907002324909, 3875.7093218008763, 3897.8440862365187, 3983.183447393277, 82769.58615207006, 83371.5831420839, 88041.55979220063, 88207.55896220607, 88890.0, 72576.63711681408, 73173.634131829, 74084.62957685177, 74216.0, 78160.60919695365, 64830.67584662046, 65310.0, 65336.67331663311, 68275.65862170658, 68443.27887161118, 7617.581033043236, 7625.809354766176, 7833.1775163607, 7842.058952925686, 7891.0, 43359.482418115214, 43643.78178109089, 44382.89042773956, 44632.77683611561, 44672.659820205445, 44594.0, 60882.39117608944, 60932.69533652304, 61233.69383153056, 61405.69297153486, 9228.40015398997, 9706.369086009387, 10280.742981425525, 10374.636887708893, 10492.055714985383, 10714.767801702905, 10745.086667633339, 10985.61550345734, 11059.336439813636, 11406.714832129263, 8527.53098579584, 10327.845082323622, 10353.02307080344, 10380.32527885685, 10631.123804990122, 10451.0, 10497.842532362174, 10511.894881051398, 10744.785104298027, 10751.354918704903, 10388.0, 10618.469076546296, 10626.73433164177, 10659.94670026645, 10670.35977841332, 8392.16078392156, 8460.365472589703, 8607.913920860712, 8666.436681615674, 8763.737087887373, 8956.193942545202, 8964.282857371265, 9015.188633022797, 9020.097990200928, 9135.086491350812], "value": 6621.426440885901, "unit": "score"}], "name": "dromaeo_css", "value": 7028.73311841376, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 113.0, 111.0, 119.0, 113.0, 112.0, 112.0, 111.0, 112.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 118.0, 116.0, 168.0, 115.0, 118.0, 139.0, 116.0, 149.0, 122.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 187.0, 183.0, 181.0, 181.0, 182.0, 181.0, 181.0, 182.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 61.0, 85.0, 60.0, 59.0, 64.0, 81.0, 72.0, 59.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 129.0, 101.0, 105.0, 68.0, 95.0, 70.0, 68.0, 100.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 87.0, 86.0, 88.0, 83.0, 90.0, 86.0, 94.0, 132.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 97.0, 94.0, 93.0, 133.0, 93.0], "value": 97.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 87.0, 84.0, 85.0, 85.0, 86.0, 87.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 56.0, 55.0, 56.0, 56.0, 55.0, 74.0, 58.0, 54.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 71.0, 72.0, 74.0, 71.0, 68.0, 69.0, 70.0, 71.0, 72.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 82.0, 82.0, 85.0, 85.0, 83.0, 87.0, 86.0, 86.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 154.0, 146.0, 138.0, 137.0, 137.0, 134.0, 186.0, 140.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 148.0, 156.0, 170.0, 221.0, 206.0, 165.0, 207.0, 223.0, 163.0], "value": 184.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 83.0, 84.0, 84.0, 79.0, 67.0, 65.0, 65.0, 64.0], "value": 72.6, "unit": "score"}], "name": "kraken", "value": 1473.6999999999998, "alertThreshold": 2.0}]} 17:53:18 INFO - Return code: 0 17:53:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:53:18 ERROR - # TBPL SUCCESS # 17:53:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:53:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:53:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:53:18 INFO - 2016-05-24 17:53:18.972 screenresolution[1680:10508] starting screenresolution argv=screenresolution get 17:53:18 INFO - 2016-05-24 17:53:18.974 screenresolution[1680:10508] Display 0: 1600x1200x32@60 17:53:18 INFO - 2016-05-24 17:53:18.980 screenresolution[1681:10511] starting screenresolution argv=screenresolution list 17:53:18 INFO - Available Modes on Display 0 17:53:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:53:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:53:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:53:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:53:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:53:19 INFO - Intel Iris: 17:53:19 INFO - Chipset Model: Intel Iris 17:53:19 INFO - Type: GPU 17:53:19 INFO - Bus: Built-In 17:53:19 INFO - VRAM (Dynamic, Max): 1536 MB 17:53:19 INFO - Vendor: Intel (0x8086) 17:53:19 INFO - Device ID: 0x0a2e 17:53:19 INFO - Revision ID: 0x0009 17:53:19 INFO - Displays: 17:53:19 INFO - Display: 17:53:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:53:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:53:19 INFO - Display Serial Number: 1600x1200 60 17:53:19 INFO - Main Display: Yes 17:53:19 INFO - Mirror: Off 17:53:19 INFO - Online: Yes 17:53:19 INFO - Rotation: Supported 17:53:19 INFO - Return code: 0 17:53:19 INFO - Running post-action listener: _resource_record_post_action 17:53:19 INFO - ##### 17:53:19 INFO - ##### Finished run-tests step (success) 17:53:19 INFO - ##### 17:53:19 INFO - Running post-run listener: _resource_record_post_run 17:53:19 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 32284160; Write bytes: 696623104; Read time: 326; Write time: 2418 17:53:19 INFO - TinderboxPrint: CPU usage
53.7% 17:53:19 INFO - TinderboxPrint: I/O read bytes / time
32,284,160 / 326 17:53:19 INFO - TinderboxPrint: I/O write bytes / time
696,623,104 / 2,418 17:53:19 INFO - TinderboxPrint: CPU idle
2,242.2 (46.3%) 17:53:19 INFO - TinderboxPrint: CPU system
968.6 (20.0%) 17:53:19 INFO - TinderboxPrint: CPU user
1,630.8 (33.7%) 17:53:19 INFO - TinderboxPrint: Swap in / out
424,484,864 / 0 17:53:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 379994624; Write bytes: 374421504; Read time: 28209; Write time: 693 17:53:19 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 31362560; Write bytes: 297265152; Read time: 312; Write time: 1671 17:53:19 INFO - Running post-run listener: _upload_blobber_files 17:53:19 INFO - Blob upload gear active. 17:53:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:53:19 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 17:53:19 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'] 17:53:19 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 17:53:20 INFO - (blobuploader) - INFO - Open directory for files ... 17:53:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:53:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:53:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:53:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:53:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:53:20 INFO - (blobuploader) - INFO - Done attempting. 17:53:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:53:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:53:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:53:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:53:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:53:21 INFO - (blobuploader) - INFO - Done attempting. 17:53:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:53:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:53:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:53:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:53:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:53:24 INFO - (blobuploader) - INFO - Done attempting. 17:53:24 INFO - (blobuploader) - INFO - Iteration through files over. 17:53:24 INFO - Return code: 0 17:53:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:53:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:53:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf5294b38e16b639a732f71bbb478aa9c58ed9d97440cfcc0c9f5190d13b9e49ac92f290385df1522923957c761431852d159b766f83024fb6f0e2453fff18be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0cfa8bb42add3513501582ee4c8cc2cf2ea11c87b34e59cad7e24658fb39d3984bcb1c16b88527e147408542cb4ca614347f03068f0691b13cf21734cc009dd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc0d6607f71e7e7df2fed460f22d7d444a8d78e46b673fff1a4b26c9f62c49df47f31200ceb84a7da1880198da8dd292e6c480eb6e6d95d2136d8d0c072d7b1e"} 17:53:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:53:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:53:24 INFO - Contents: 17:53:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bf5294b38e16b639a732f71bbb478aa9c58ed9d97440cfcc0c9f5190d13b9e49ac92f290385df1522923957c761431852d159b766f83024fb6f0e2453fff18be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0cfa8bb42add3513501582ee4c8cc2cf2ea11c87b34e59cad7e24658fb39d3984bcb1c16b88527e147408542cb4ca614347f03068f0691b13cf21734cc009dd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc0d6607f71e7e7df2fed460f22d7d444a8d78e46b673fff1a4b26c9f62c49df47f31200ceb84a7da1880198da8dd292e6c480eb6e6d95d2136d8d0c072d7b1e"} 17:53:24 INFO - Running post-run listener: copy_logs_to_upload_dir 17:53:24 INFO - Copying logs to upload dir... 17:53:24 INFO - mkdir: /builds/slave/test/build/upload/logs 17:53:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.269552 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-24 17:53:24.351875) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 17:53:24.355691) ========= 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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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/bf5294b38e16b639a732f71bbb478aa9c58ed9d97440cfcc0c9f5190d13b9e49ac92f290385df1522923957c761431852d159b766f83024fb6f0e2453fff18be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0cfa8bb42add3513501582ee4c8cc2cf2ea11c87b34e59cad7e24658fb39d3984bcb1c16b88527e147408542cb4ca614347f03068f0691b13cf21734cc009dd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc0d6607f71e7e7df2fed460f22d7d444a8d78e46b673fff1a4b26c9f62c49df47f31200ceb84a7da1880198da8dd292e6c480eb6e6d95d2136d8d0c072d7b1e"} build_url:https://queue.taskcluster.net/v1/task/cuIlijCxRwabUM3-wSxccw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/cuIlijCxRwabUM3-wSxccw/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/bf5294b38e16b639a732f71bbb478aa9c58ed9d97440cfcc0c9f5190d13b9e49ac92f290385df1522923957c761431852d159b766f83024fb6f0e2453fff18be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0cfa8bb42add3513501582ee4c8cc2cf2ea11c87b34e59cad7e24658fb39d3984bcb1c16b88527e147408542cb4ca614347f03068f0691b13cf21734cc009dd8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc0d6607f71e7e7df2fed460f22d7d444a8d78e46b673fff1a4b26c9f62c49df47f31200ceb84a7da1880198da8dd292e6c480eb6e6d95d2136d8d0c072d7b1e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 17:53:24.385836) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 17:53:24.386136) ========= 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.GW7DshlZ0G/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.yQYDOq7qIC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.005201 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 17:53:24.433856) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 17:53:24.434139) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 17:53:24.434466) ========= ========= Total master_lag: 0.17 =========