builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0262 starttime: 1464041468.85 results: success (0) buildid: 20160523140246 builduid: 96841e9debb84a929b67c1d9857f5291 revision: ca0d04ce03040d726cd96d112761e79317587c18 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:08.850341) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:08.850700) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:08.850982) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:08.870454) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:08.870710) ========= 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.faWIfklJcx/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.8f3uXUui34/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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-23 15:11:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.5M=0.001s 2016-05-23 15:11:09 (10.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.525915 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:09.414458) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:09.414746) ========= 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.faWIfklJcx/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.8f3uXUui34/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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.020306 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:09.463654) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 15:11:09.464008) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ca0d04ce03040d726cd96d112761e79317587c18 --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 ca0d04ce03040d726cd96d112761e79317587c18 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.faWIfklJcx/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.8f3uXUui34/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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-23 15:11:09,543 truncating revision to first 12 chars 2016-05-23 15:11:09,544 Setting DEBUG logging. 2016-05-23 15:11:09,544 attempt 1/10 2016-05-23 15:11:09,544 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ca0d04ce0304?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 15:11:10,362 unpacking tar archive at: fx-team-ca0d04ce0304/testing/mozharness/ program finished with exit code 0 elapsedTime=1.072867 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 15:11:10.552702) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:10.553038) ========= script_repo_revision: ca0d04ce03040d726cd96d112761e79317587c18 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:10.553416) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:10.553677) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 15:11:10.569422) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-23 15:11:10.569722) ========= /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.faWIfklJcx/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.8f3uXUui34/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 15:11:10 INFO - MultiFileLogger online at 20160523 15:11:10 in /builds/slave/test 15:11:10 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 15:11:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:11:10 INFO - {'append_to_log': False, 15:11:10 INFO - 'base_work_dir': '/builds/slave/test', 15:11:10 INFO - 'blob_upload_branch': 'Fx-Team', 15:11:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:11:10 INFO - 'branch': 'Fx-Team', 15:11:10 INFO - 'buildbot_json_path': 'buildprops.json', 15:11:10 INFO - 'config_files': ('talos/mac_config.py',), 15:11:10 INFO - 'default_actions': ('clobber', 15:11:10 INFO - 'read-buildbot-config', 15:11:10 INFO - 'download-and-extract', 15:11:10 INFO - 'populate-webroot', 15:11:10 INFO - 'create-virtualenv', 15:11:10 INFO - 'install', 15:11:10 INFO - 'run-tests'), 15:11:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:11:10 INFO - 'download_minidump_stackwalk': True, 15:11:10 INFO - 'download_symbols': 'ondemand', 15:11:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:11:10 INFO - 'tooltool.py': '/tools/tooltool.py', 15:11:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:11:10 INFO - '/tools/misc-python/virtualenv.py')}, 15:11:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:11:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:11:10 INFO - 'installer_path': 'installer.exe', 15:11:10 INFO - 'log_level': 'info', 15:11:10 INFO - 'log_name': 'talos', 15:11:10 INFO - 'log_to_console': True, 15:11:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:11:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:11:10 INFO - 'opt_config_files': (), 15:11:10 INFO - 'pip_index': False, 15:11:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:11:10 INFO - 'cmd': ('bash', 15:11:10 INFO - '-c', 15:11:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:11:10 INFO - 'enabled': True, 15:11:10 INFO - 'halt_on_failure': False, 15:11:10 INFO - 'name': 'check_screen_resolution'},), 15:11:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:11:10 INFO - 'cmd': ('bash', 15:11:10 INFO - '-c', 15:11:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:11:10 INFO - 'enabled': True, 15:11:10 INFO - 'halt_on_failure': False, 15:11:10 INFO - 'name': 'check_screen_resolution'},), 15:11:10 INFO - 'run_cmd_checks_enabled': True, 15:11:10 INFO - 'sps_profile': False, 15:11:10 INFO - 'sps_profile_interval': 0, 15:11:10 INFO - 'suite': 'dromaeojs', 15:11:10 INFO - 'system_bits': '32', 15:11:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:11:10 INFO - 'title': 't-yosemite-r7-0262', 15:11:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:11:10 INFO - 'use_talos_json': True, 15:11:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:11:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:11:10 INFO - 'work_dir': 'build'} 15:11:10 INFO - ##### 15:11:10 INFO - ##### Running clobber step. 15:11:10 INFO - ##### 15:11:10 INFO - Running pre-action listener: _resource_record_pre_action 15:11:10 INFO - Running main action method: clobber 15:11:10 INFO - rmtree: /builds/slave/test/build 15:11:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:11:11 INFO - Running post-action listener: _resource_record_post_action 15:11:11 INFO - ##### 15:11:11 INFO - ##### Finished clobber step (success) 15:11:11 INFO - ##### 15:11:11 INFO - ##### 15:11:11 INFO - ##### Running read-buildbot-config step. 15:11:11 INFO - ##### 15:11:11 INFO - Running pre-action listener: _resource_record_pre_action 15:11:11 INFO - Running main action method: read_buildbot_config 15:11:11 INFO - Using buildbot properties: 15:11:11 INFO - { 15:11:11 INFO - "project": "", 15:11:11 INFO - "product": "firefox", 15:11:11 INFO - "script_repo_revision": "production", 15:11:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:11:11 INFO - "repository": "", 15:11:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:11:11 INFO - "buildid": "20160523140246", 15:11:11 INFO - "pgo_build": "False", 15:11:11 INFO - "basedir": "/builds/slave/test", 15:11:11 INFO - "buildnumber": 16, 15:11:11 INFO - "slavename": "t-yosemite-r7-0262", 15:11:11 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:11:11 INFO - "platform": "yosemite_r7", 15:11:11 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:11:11 INFO - "branch": "fx-team", 15:11:11 INFO - "repo_path": "integration/fx-team", 15:11:11 INFO - "revision": "ca0d04ce03040d726cd96d112761e79317587c18", 15:11:11 INFO - "stage_platform": "macosx64", 15:11:11 INFO - "builduid": "96841e9debb84a929b67c1d9857f5291", 15:11:11 INFO - "slavebuilddir": "test" 15:11:11 INFO - } 15:11:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:11:11 INFO - Running post-action listener: _resource_record_post_action 15:11:11 INFO - ##### 15:11:11 INFO - ##### Finished read-buildbot-config step (success) 15:11:11 INFO - ##### 15:11:11 INFO - ##### 15:11:11 INFO - ##### Running download-and-extract step. 15:11:11 INFO - ##### 15:11:11 INFO - Running pre-action listener: _resource_record_pre_action 15:11:11 INFO - Running main action method: download_and_extract 15:11:11 INFO - mkdir: /builds/slave/test/build/tests 15:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:11 INFO - https://queue.taskcluster.net/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:11:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:11:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:11:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/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 15:11:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/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 15:11:12 INFO - Downloaded 1199 bytes. 15:11:12 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:11:12 INFO - Using the following test package requirements: 15:11:12 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:11:12 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:11:12 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:11:12 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:11:12 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:11:12 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:11:12 INFO - u'jsshell-mac.zip'], 15:11:12 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:11:12 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:11:12 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:11:12 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:11:12 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:11:12 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:11:12 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:11:12 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:11:12 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:11:12 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:11:12 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:11:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:12 INFO - https://queue.taskcluster.net/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:11:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:11:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:11:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/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 15:11:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/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 15:11:14 INFO - Downloaded 18630483 bytes. 15:11:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:11:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:11:17 INFO - Return code: 0 15:11:17 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 15:11: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')]} 15:11:17 INFO - https://queue.taskcluster.net/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:11:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:11:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:11:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/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 15:11:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/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 15:11:21 INFO - Downloaded 18630483 bytes. 15:11:21 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'] 15:11:21 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 15:11:24 INFO - Return code: 0 15:11:24 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')]} 15:11:24 INFO - https://queue.taskcluster.net/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:11:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:11:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:11:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/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 15:11:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/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 15:11:26 INFO - Downloaded 10840359 bytes. 15:11:26 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'] 15:11:26 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 15:11:26 INFO - Return code: 0 15:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:26 INFO - https://queue.taskcluster.net/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:11:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:11:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:11:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:11:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V2-j6-XhQ5-s7FlM14-3OA/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 15:11:31 INFO - Downloaded 110170609 bytes. 15:11:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:11:31 INFO - mkdir: /builds/slave/test/properties 15:11:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:11:31 INFO - Writing to file /builds/slave/test/properties/build_url 15:11:31 INFO - Contents: 15:11:31 INFO - build_url:https://queue.taskcluster.net/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:11:32 INFO - Running post-action listener: _resource_record_post_action 15:11:32 INFO - Running post-action listener: set_extra_try_arguments 15:11:32 INFO - ##### 15:11:32 INFO - ##### Finished download-and-extract step (success) 15:11:32 INFO - ##### 15:11:32 INFO - ##### 15:11:32 INFO - ##### Running populate-webroot step. 15:11:32 INFO - ##### 15:11:32 INFO - Running pre-action listener: _resource_record_pre_action 15:11:32 INFO - Running main action method: populate_webroot 15:11:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:11:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:11:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:11:32 INFO - '--tppagecycles', 15:11:32 INFO - '7'], 15:11:32 INFO - 'tests': ['tsvgm']}}, 15:11:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:11:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:11:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:11:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:11:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:11:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:11:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:32 INFO - 'talos_options': ['--disable-e10s'], 15:11:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:11:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:11:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:32 INFO - 'talos_options': ['--disable-e10s'], 15:11:32 INFO - 'tests': ['damp', 'tps']}, 15:11:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:32 INFO - 'tests': ['damp', 'tps']}, 15:11:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:11:32 INFO - 'tests': ['dromaeo_dom']}, 15:11:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:11:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:11:32 INFO - 'tests': ['a11yr', 15:11:32 INFO - 'ts_paint', 15:11:32 INFO - 'tpaint', 15:11:32 INFO - 'sessionrestore', 15:11:32 INFO - 'sessionrestore_no_auto_restore', 15:11:32 INFO - 'tabpaint']}, 15:11:32 INFO - 'other-e10s': {'tests': ['a11yr', 15:11:32 INFO - 'ts_paint', 15:11:32 INFO - 'tpaint', 15:11:32 INFO - 'sessionrestore', 15:11:32 INFO - 'sessionrestore_no_auto_restore', 15:11:32 INFO - 'tabpaint']}, 15:11:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:11:32 INFO - 'ts_paint', 15:11:32 INFO - 'tpaint', 15:11:32 INFO - 'sessionrestore', 15:11:32 INFO - 'sessionrestore_no_auto_restore', 15:11:32 INFO - 'tabpaint']}, 15:11:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:11:32 INFO - 'ts_paint', 15:11:32 INFO - 'tpaint', 15:11:32 INFO - 'sessionrestore', 15:11:32 INFO - 'sessionrestore_no_auto_restore', 15:11:32 INFO - 'tabpaint']}, 15:11:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:11:32 INFO - 'tests': ['a11yr', 15:11:32 INFO - 'ts_paint', 15:11:32 INFO - 'tpaint', 15:11:32 INFO - 'sessionrestore', 15:11:32 INFO - 'sessionrestore_no_auto_restore', 15:11:32 INFO - 'tabpaint']}, 15:11:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:11:32 INFO - 'tests': ['a11yr', 15:11:32 INFO - 'ts_paint', 15:11:32 INFO - 'tpaint', 15:11:32 INFO - 'sessionrestore', 15:11:32 INFO - 'sessionrestore_no_auto_restore', 15:11:32 INFO - 'tabpaint']}, 15:11:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:11:32 INFO - 'tests': ['tsvgx', 15:11:32 INFO - 'tsvgr_opacity', 15:11:32 INFO - 'tart', 15:11:32 INFO - 'tscrollx', 15:11:32 INFO - 'cart']}, 15:11:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:11:32 INFO - 'tsvgr_opacity', 15:11:32 INFO - 'tart', 15:11:32 INFO - 'tscrollx', 15:11:32 INFO - 'cart']}, 15:11:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:32 INFO - 'talos_options': ['--disable-e10s'], 15:11:32 INFO - 'tests': ['tp5o']}, 15:11:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:32 INFO - 'tests': ['tp5o']}, 15:11:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:32 INFO - 'talos_options': ['--disable-e10s', 15:11:32 INFO - '--xperf_path', 15:11:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:11:32 INFO - 'tests': ['tp5n']}, 15:11:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:11:32 INFO - 'talos_options': ['--xperf_path', 15:11:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:11:32 INFO - 'tests': ['tp5n']}}, 15:11:32 INFO - 'talos.zip': {'path': '', 15:11:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:11:32 INFO - Running post-action listener: _resource_record_post_action 15:11:32 INFO - ##### 15:11:32 INFO - ##### Finished populate-webroot step (success) 15:11:32 INFO - ##### 15:11:32 INFO - ##### 15:11:32 INFO - ##### Running create-virtualenv step. 15:11:32 INFO - ##### 15:11:32 INFO - Running pre-action listener: _resource_record_pre_action 15:11:32 INFO - Running main action method: create_virtualenv 15:11:32 INFO - Creating virtualenv /builds/slave/test/build/venv 15:11:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:11:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:11:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:11:32 INFO - Using real prefix '/tools/python27' 15:11:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:11:33 INFO - Installing distribute.............................................................................................................................................................................................done. 15:11:36 INFO - Installing pip.................done. 15:11:36 INFO - Return code: 0 15:11:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104fe2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9428479ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e86d30>, '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 0x104caadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7db20>, '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.faWIfklJcx/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.8f3uXUui34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 15:11:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:11:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.faWIfklJcx/Render', 15:11:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:36 INFO - 'HOME': '/Users/cltbld', 15:11:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:36 INFO - 'LOGNAME': 'cltbld', 15:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:36 INFO - 'MOZ_NO_REMOTE': '1', 15:11:36 INFO - 'NO_EM_RESTART': '1', 15:11:36 INFO - 'PAGER': '/bin/cat', 15:11:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:36 INFO - 'PWD': '/builds/slave/test', 15:11:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:36 INFO - 'SHELL': '/bin/bash', 15:11:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8f3uXUui34/Listeners', 15:11:36 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 15:11:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:36 INFO - 'USER': 'cltbld', 15:11:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:36 INFO - 'XPC_FLAGS': '0x0', 15:11:36 INFO - 'XPC_SERVICE_NAME': '0', 15:11:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:11:36 INFO - Cleaning up... 15:11:36 INFO - Return code: 0 15:11:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104fe2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9428479ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e86d30>, '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 0x104caadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7db20>, '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.faWIfklJcx/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.8f3uXUui34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 15:11:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:11:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.faWIfklJcx/Render', 15:11:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:36 INFO - 'HOME': '/Users/cltbld', 15:11:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:36 INFO - 'LOGNAME': 'cltbld', 15:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:36 INFO - 'MOZ_NO_REMOTE': '1', 15:11:36 INFO - 'NO_EM_RESTART': '1', 15:11:36 INFO - 'PAGER': '/bin/cat', 15:11:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:36 INFO - 'PWD': '/builds/slave/test', 15:11:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:36 INFO - 'SHELL': '/bin/bash', 15:11:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8f3uXUui34/Listeners', 15:11:36 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 15:11:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:36 INFO - 'USER': 'cltbld', 15:11:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:36 INFO - 'XPC_FLAGS': '0x0', 15:11:36 INFO - 'XPC_SERVICE_NAME': '0', 15:11:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:37 INFO - Downloading/unpacking psutil>=3.1.1 15:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:11:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:11:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:11:40 INFO - Installing collected packages: psutil 15:11:40 INFO - Running setup.py install for psutil 15:11:40 INFO - building 'psutil._psutil_osx' extension 15:11:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:11:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:11:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:11:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:11:41 INFO - building 'psutil._psutil_posix' extension 15:11:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:11:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:11:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:11:41 INFO - ^ 15:11:41 INFO - 1 warning generated. 15:11:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:11:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:11:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:11:41 INFO - Successfully installed psutil 15:11:41 INFO - Cleaning up... 15:11:41 INFO - Return code: 0 15:11:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:11:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104fe2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9428479ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e86d30>, '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 0x104caadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7db20>, '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.faWIfklJcx/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.8f3uXUui34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 15:11:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:11:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:11:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.faWIfklJcx/Render', 15:11:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:41 INFO - 'HOME': '/Users/cltbld', 15:11:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:41 INFO - 'LOGNAME': 'cltbld', 15:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:41 INFO - 'MOZ_NO_REMOTE': '1', 15:11:41 INFO - 'NO_EM_RESTART': '1', 15:11:41 INFO - 'PAGER': '/bin/cat', 15:11:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:41 INFO - 'PWD': '/builds/slave/test', 15:11:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:41 INFO - 'SHELL': '/bin/bash', 15:11:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8f3uXUui34/Listeners', 15:11:41 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 15:11:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:41 INFO - 'USER': 'cltbld', 15:11:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:41 INFO - 'XPC_FLAGS': '0x0', 15:11:41 INFO - 'XPC_SERVICE_NAME': '0', 15:11:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:11:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:11:44 INFO - Installing collected packages: mozsystemmonitor 15:11:44 INFO - Running setup.py install for mozsystemmonitor 15:11:44 INFO - Successfully installed mozsystemmonitor 15:11:44 INFO - Cleaning up... 15:11:44 INFO - Return code: 0 15:11:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104fe2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9428479ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e86d30>, '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 0x104caadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7db20>, '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.faWIfklJcx/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.8f3uXUui34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 15:11:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:11:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.faWIfklJcx/Render', 15:11:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:44 INFO - 'HOME': '/Users/cltbld', 15:11:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:44 INFO - 'LOGNAME': 'cltbld', 15:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:44 INFO - 'MOZ_NO_REMOTE': '1', 15:11:44 INFO - 'NO_EM_RESTART': '1', 15:11:44 INFO - 'PAGER': '/bin/cat', 15:11:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:44 INFO - 'PWD': '/builds/slave/test', 15:11:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:44 INFO - 'SHELL': '/bin/bash', 15:11:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8f3uXUui34/Listeners', 15:11:44 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 15:11:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:44 INFO - 'USER': 'cltbld', 15:11:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:44 INFO - 'XPC_FLAGS': '0x0', 15:11:44 INFO - 'XPC_SERVICE_NAME': '0', 15:11:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:44 INFO - Downloading/unpacking blobuploader==1.2.4 15:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:47 INFO - Downloading blobuploader-1.2.4.tar.gz 15:11:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:11:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:11:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:48 INFO - Downloading docopt-0.6.1.tar.gz 15:11:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:11:48 INFO - Installing collected packages: blobuploader, requests, docopt 15:11:48 INFO - Running setup.py install for blobuploader 15:11:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:11:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:11:48 INFO - Running setup.py install for requests 15:11:49 INFO - Running setup.py install for docopt 15:11:49 INFO - Successfully installed blobuploader requests docopt 15:11:49 INFO - Cleaning up... 15:11:49 INFO - Return code: 0 15:11:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104fe2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9428479ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e86d30>, '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 0x104caadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7db20>, '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.faWIfklJcx/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.8f3uXUui34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 15:11:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:11:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.faWIfklJcx/Render', 15:11:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:49 INFO - 'HOME': '/Users/cltbld', 15:11:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:49 INFO - 'LOGNAME': 'cltbld', 15:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:49 INFO - 'MOZ_NO_REMOTE': '1', 15:11:49 INFO - 'NO_EM_RESTART': '1', 15:11:49 INFO - 'PAGER': '/bin/cat', 15:11:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:49 INFO - 'PWD': '/builds/slave/test', 15:11:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:49 INFO - 'SHELL': '/bin/bash', 15:11:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8f3uXUui34/Listeners', 15:11:49 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 15:11:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:49 INFO - 'USER': 'cltbld', 15:11:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:49 INFO - 'XPC_FLAGS': '0x0', 15:11:49 INFO - 'XPC_SERVICE_NAME': '0', 15:11:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:11:49 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-6bQDDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:11:49 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-TnMLzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:11:49 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-CTj5g9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:11:49 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-eDebcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:11:50 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-dhoB59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:11:50 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-rTqNwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:11:50 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-0V4kVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:11:50 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-4CrIxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:11:50 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-biooV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:11:50 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-xOZ788-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:11:50 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-0tVZCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:11:50 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-2JZWNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:11:50 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-3hx4uD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:11:50 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-mYNtYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:51 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-wjT54j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:11:51 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-WxsSAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:11:51 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-717Plt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:11:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:11:51 INFO - Running setup.py install for manifestparser 15:11:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:11:51 INFO - Running setup.py install for mozcrash 15:11:51 INFO - Running setup.py install for mozdebug 15:11:51 INFO - Running setup.py install for mozdevice 15:11:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:11:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:11:52 INFO - Running setup.py install for mozfile 15:11:52 INFO - Running setup.py install for mozhttpd 15:11:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:11:52 INFO - Running setup.py install for mozinfo 15:11:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:11:52 INFO - Running setup.py install for mozInstall 15:11:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:11:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:11:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:11:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:11:52 INFO - Running setup.py install for mozleak 15:11:52 INFO - Running setup.py install for mozlog 15:11:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:11:52 INFO - Running setup.py install for moznetwork 15:11:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:11:53 INFO - Running setup.py install for mozprocess 15:11:53 INFO - Running setup.py install for mozprofile 15:11:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:11:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:11:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:11:53 INFO - Running setup.py install for mozrunner 15:11:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:11:53 INFO - Running setup.py install for mozscreenshot 15:11:53 INFO - Running setup.py install for moztest 15:11:53 INFO - Running setup.py install for mozversion 15:11:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:11:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:11:53 INFO - Cleaning up... 15:11:53 INFO - Return code: 0 15:11:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104fe2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9428479ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e86d30>, '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 0x104caadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7db20>, '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.faWIfklJcx/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.8f3uXUui34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 15:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:11:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.faWIfklJcx/Render', 15:11:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:53 INFO - 'HOME': '/Users/cltbld', 15:11:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:53 INFO - 'LOGNAME': 'cltbld', 15:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:53 INFO - 'MOZ_NO_REMOTE': '1', 15:11:53 INFO - 'NO_EM_RESTART': '1', 15:11:53 INFO - 'PAGER': '/bin/cat', 15:11:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:53 INFO - 'PWD': '/builds/slave/test', 15:11:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:53 INFO - 'SHELL': '/bin/bash', 15:11:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8f3uXUui34/Listeners', 15:11:53 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 15:11:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:53 INFO - 'USER': 'cltbld', 15:11:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:53 INFO - 'XPC_FLAGS': '0x0', 15:11:53 INFO - 'XPC_SERVICE_NAME': '0', 15:11:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:11:54 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-Yz_GKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:11:54 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-wTQfRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:11:54 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-VILY9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:11:54 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-L3Zsx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:11:54 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-SvHIFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:11:54 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-RJOCXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:11:54 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-h7HsgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:11:54 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-F3Im2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:11: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)) 15:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:11:55 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-RbvEKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:11:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:11:55 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-FXvlby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:11:55 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)) 15:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:11:55 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-XnXTbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:11:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:11:55 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-Au6SuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:11:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:11:55 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-fnl4X3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:11:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:11:55 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-AN3R1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:11:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:55 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-Nl9Mhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:11:55 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-pdQ6dZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:11:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:11:55 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-CAI7wM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:11: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)) 15:11: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)) 15:11: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)) 15:11: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)) 15:11: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)) 15:11: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)) 15:11:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:11: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 15:11: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 15:11: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 15:11: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 15:11: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 15:11: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 15:11:58 INFO - Downloading blessings-1.6.tar.gz 15:11:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:11:58 INFO - Installing collected packages: blessings 15:11:58 INFO - Running setup.py install for blessings 15:11:58 INFO - Successfully installed blessings 15:11:58 INFO - Cleaning up... 15:11:58 INFO - Return code: 0 15:11:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:11:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:11:59 INFO - Reading from file tmpfile_stdout 15:11:59 INFO - Current package versions: 15:11:59 INFO - blessings == 1.6 15:11:59 INFO - blobuploader == 1.2.4 15:11:59 INFO - docopt == 0.6.1 15:11:59 INFO - manifestparser == 1.1 15:11:59 INFO - mozInstall == 1.12 15:11:59 INFO - mozcrash == 0.17 15:11:59 INFO - mozdebug == 0.1 15:11:59 INFO - mozdevice == 0.48 15:11:59 INFO - mozfile == 1.2 15:11:59 INFO - mozhttpd == 0.7 15:11:59 INFO - mozinfo == 0.9 15:11:59 INFO - mozleak == 0.1 15:11:59 INFO - mozlog == 3.2 15:11:59 INFO - moznetwork == 0.27 15:11:59 INFO - mozprocess == 0.22 15:11:59 INFO - mozprofile == 0.28 15:11:59 INFO - mozrunner == 6.11 15:11:59 INFO - mozscreenshot == 0.1 15:11:59 INFO - mozsystemmonitor == 0.3 15:11:59 INFO - moztest == 0.7 15:11:59 INFO - mozversion == 1.4 15:11:59 INFO - psutil == 3.1.1 15:11:59 INFO - requests == 1.2.3 15:11:59 INFO - wsgiref == 0.1.2 15:11:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:59 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 0x104fe2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9428479ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e86d30>, '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 0x104caadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7db20>, '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.faWIfklJcx/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.8f3uXUui34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 15:11:59 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 15:11:59 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 15:11:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.faWIfklJcx/Render', 15:11:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:59 INFO - 'HOME': '/Users/cltbld', 15:11:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:59 INFO - 'LOGNAME': 'cltbld', 15:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:59 INFO - 'MOZ_NO_REMOTE': '1', 15:11:59 INFO - 'NO_EM_RESTART': '1', 15:11:59 INFO - 'PAGER': '/bin/cat', 15:11:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:59 INFO - 'PWD': '/builds/slave/test', 15:11:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:59 INFO - 'SHELL': '/bin/bash', 15:11:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8f3uXUui34/Listeners', 15:11:59 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 15:11:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:59 INFO - 'USER': 'cltbld', 15:11:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:59 INFO - 'XPC_FLAGS': '0x0', 15:11:59 INFO - 'XPC_SERVICE_NAME': '0', 15:11:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:11:59 INFO - Cleaning up... 15:11:59 INFO - Return code: 0 15:11:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104fe2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9428479ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e86d30>, '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 0x104caadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7db20>, '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.faWIfklJcx/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.8f3uXUui34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 15:11:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:11:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.faWIfklJcx/Render', 15:11:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:59 INFO - 'HOME': '/Users/cltbld', 15:11:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:59 INFO - 'LOGNAME': 'cltbld', 15:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:59 INFO - 'MOZ_NO_REMOTE': '1', 15:11:59 INFO - 'NO_EM_RESTART': '1', 15:11:59 INFO - 'PAGER': '/bin/cat', 15:11:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:59 INFO - 'PWD': '/builds/slave/test', 15:11:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:59 INFO - 'SHELL': '/bin/bash', 15:11:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8f3uXUui34/Listeners', 15:11:59 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 15:11:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:59 INFO - 'USER': 'cltbld', 15:11:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:59 INFO - 'XPC_FLAGS': '0x0', 15:11:59 INFO - 'XPC_SERVICE_NAME': '0', 15:11:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:59 INFO - Downloading/unpacking jsonschema 15:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:12:02 INFO - Downloading/unpacking functools32 (from jsonschema) 15:12: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 15:12: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 15:12: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 15:12: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 15:12: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 15:12: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 15:12:02 INFO - Downloading functools32-3.2.3-2.tar.gz 15:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:12:02 INFO - warning: no files found matching '*.txt' 15:12:02 INFO - no previously-included directories found matching 'build' 15:12:02 INFO - no previously-included directories found matching 'dist' 15:12:02 INFO - no previously-included directories found matching '.git*' 15:12:02 INFO - Installing collected packages: jsonschema, functools32 15:12:02 INFO - Running setup.py install for functools32 15:12:02 INFO - warning: no files found matching '*.txt' 15:12:02 INFO - no previously-included directories found matching 'build' 15:12:02 INFO - no previously-included directories found matching 'dist' 15:12:02 INFO - no previously-included directories found matching '.git*' 15:12:02 INFO - Successfully installed jsonschema functools32 15:12:02 INFO - Cleaning up... 15:12:02 INFO - Return code: 0 15:12:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:12:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104fe2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fe8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9428479ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e86d30>, '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 0x104caadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7db20>, '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.faWIfklJcx/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.8f3uXUui34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 15:12:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:12:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.faWIfklJcx/Render', 15:12:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:02 INFO - 'HOME': '/Users/cltbld', 15:12:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:02 INFO - 'LOGNAME': 'cltbld', 15:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:02 INFO - 'MOZ_NO_REMOTE': '1', 15:12:02 INFO - 'NO_EM_RESTART': '1', 15:12:02 INFO - 'PAGER': '/bin/cat', 15:12:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:02 INFO - 'PWD': '/builds/slave/test', 15:12:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:02 INFO - 'SHELL': '/bin/bash', 15:12:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8f3uXUui34/Listeners', 15:12:02 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 15:12:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:02 INFO - 'USER': 'cltbld', 15:12:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:02 INFO - 'XPC_FLAGS': '0x0', 15:12:02 INFO - 'XPC_SERVICE_NAME': '0', 15:12:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:03 INFO - Downloading/unpacking flake8 15:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:12:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:12:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:12: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 15:12: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 15:12: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 15:12: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 15:12: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 15:12: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 15:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:12:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:12:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:12:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:12:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:12:06 INFO - no previously-included directories found matching 'docs/_build' 15:12:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:12:06 INFO - Running setup.py install for pep8 15:12:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:12:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:12:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:12:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:12:07 INFO - no previously-included directories found matching 'docs/_build' 15:12:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:12:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:12:07 INFO - Cleaning up... 15:12:07 INFO - Return code: 0 15:12:07 INFO - Running post-action listener: _resource_record_post_action 15:12:07 INFO - Running post-action listener: _start_resource_monitoring 15:12:07 INFO - Starting resource monitoring. 15:12:07 INFO - ##### 15:12:07 INFO - ##### Finished create-virtualenv step (success) 15:12:07 INFO - ##### 15:12:07 INFO - ##### 15:12:07 INFO - ##### Running install step. 15:12:07 INFO - ##### 15:12:07 INFO - Running pre-action listener: _resource_record_pre_action 15:12:07 INFO - Running main action method: install 15:12:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:12:07 INFO - Reading from file tmpfile_stdout 15:12:07 INFO - Detecting whether we're running mozinstall >=1.0... 15:12:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:12:07 INFO - Reading from file tmpfile_stdout 15:12:07 INFO - Output received: 15:12:07 INFO - Usage: mozinstall [options] installer 15:12:07 INFO - Options: 15:12:07 INFO - -h, --help show this help message and exit 15:12:07 INFO - -d DEST, --destination=DEST 15:12:07 INFO - Directory to install application into. [default: 15:12:07 INFO - "/builds/slave/test"] 15:12:07 INFO - --app=APP Application being installed. [default: firefox] 15:12:07 INFO - mkdir: /builds/slave/test/build/application 15:12:07 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'] 15:12:07 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 15:12:41 INFO - Reading from file tmpfile_stdout 15:12:41 INFO - Output received: 15:12:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:12:41 INFO - Running post-action listener: _resource_record_post_action 15:12:41 INFO - ##### 15:12:41 INFO - ##### Finished install step (success) 15:12:41 INFO - ##### 15:12:41 INFO - ##### 15:12:41 INFO - ##### Running run-tests step. 15:12:41 INFO - ##### 15:12:41 INFO - Running pre-action listener: _resource_record_pre_action 15:12:41 INFO - Running main action method: run_tests 15:12:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:12:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:12:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:12:41 INFO - 2016-05-23 15:12:41.171 screenresolution[6463:158228] starting screenresolution argv=screenresolution get 15:12:41 INFO - 2016-05-23 15:12:41.174 screenresolution[6463:158228] Display 0: 1600x1200x32@60 15:12:41 INFO - 2016-05-23 15:12:41.180 screenresolution[6464:158230] starting screenresolution argv=screenresolution list 15:12:41 INFO - Available Modes on Display 0 15:12:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:12:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:12:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:12:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:12:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:12:41 INFO - Intel Iris: 15:12:41 INFO - Chipset Model: Intel Iris 15:12:41 INFO - Type: GPU 15:12:41 INFO - Bus: Built-In 15:12:41 INFO - VRAM (Dynamic, Max): 1536 MB 15:12:41 INFO - Vendor: Intel (0x8086) 15:12:41 INFO - Device ID: 0x0a2e 15:12:41 INFO - Revision ID: 0x0009 15:12:41 INFO - Displays: 15:12:41 INFO - Display: 15:12:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:12:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:12:41 INFO - Display Serial Number: 1600x1200 60 15:12:41 INFO - Main Display: Yes 15:12:41 INFO - Mirror: Off 15:12:41 INFO - Online: Yes 15:12:41 INFO - Rotation: Supported 15:12:41 INFO - Return code: 0 15:12:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:12:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:12:41 INFO - Python 2.7.3 15:12:41 INFO - Return code: 0 15:12:41 INFO - grabbing minidump binary from tooltool 15:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:12:41 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 0x104e86d30>, '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 0x104caadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e7db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:12:41 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 15:12:41 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 15:12:41 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 15:12:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:12:41 INFO - Return code: 0 15:12:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:12:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:12:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:12:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:12:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:12:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:12:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:12:42 INFO - Return code: 0 15:12:42 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/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0262', '--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 15:12:42 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/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0262 --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 15:12:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.faWIfklJcx/Render', 15:12:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:12:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:12:42 INFO - 'HOME': '/Users/cltbld', 15:12:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:12:42 INFO - 'LOGNAME': 'cltbld', 15:12:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:12:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:42 INFO - 'MOZ_NO_REMOTE': '1', 15:12:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:12:42 INFO - 'NO_EM_RESTART': '1', 15:12:42 INFO - 'PAGER': '/bin/cat', 15:12:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:42 INFO - 'PWD': '/builds/slave/test', 15:12:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:12:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:12:42 INFO - 'SHELL': '/bin/bash', 15:12:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8f3uXUui34/Listeners', 15:12:42 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 15:12:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:12:42 INFO - 'USER': 'cltbld', 15:12:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:12:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:42 INFO - 'XPC_FLAGS': '0x0', 15:12:42 INFO - 'XPC_SERVICE_NAME': '0', 15:12:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:12:42 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/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0262', '--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 15:12:42 INFO - mozversion application_buildid: 20160523140246 15:12:42 INFO - mozversion application_changeset: ca0d04ce03040d726cd96d112761e79317587c18 15:12:42 INFO - mozversion application_display_name: Nightly 15:12:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:12:42 INFO - mozversion application_name: Firefox 15:12:42 INFO - mozversion application_remotingname: firefox 15:12:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:12:42 INFO - mozversion application_vendor: Mozilla 15:12:42 INFO - mozversion application_version: 49.0a1 15:12:42 INFO - mozversion platform_buildid: 20160523140246 15:12:42 INFO - mozversion platform_changeset: ca0d04ce03040d726cd96d112761e79317587c18 15:12:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:12:42 INFO - mozversion platform_version: 49.0a1 15:12:42 INFO - using testdate: 1464041562 15:12:42 INFO - actual date: 1464041562 15:12:42 INFO - starting webserver on 'localhost:57924' 15:12:42 INFO - SUITE-START | Running 2 tests 15:12:42 INFO - TEST-START | dromaeo_css 15:12:42 INFO - operating with platform_type : mac_ 15:12:42 INFO - Initialising browser for dromaeo_css test... 15:12:42 INFO - TEST-INFO | started process 6482 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpb5knQV/profile http://localhost:57924/getInfo.html) 15:12:45 INFO - PROCESS | 6482 | __metrics Screen width/height:1600/1200 15:12:45 INFO - PROCESS | 6482 | colorDepth:24 15:12:45 INFO - PROCESS | 6482 | Browser inner width/height: 1024/689 15:12:45 INFO - PROCESS | 6482 | __metrics 15:12:45 INFO - PROCESS | 6482 | 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" 15:12:45 ERROR - PROCESS | 6482 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:12:46 INFO - TEST-INFO | 6482: exit 0 15:12:46 INFO - Browser initialized. 15:12:46 INFO - Running cycle 1/1 for dromaeo_css test... 15:12:46 INFO - TEST-INFO | started process 6484 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpb5knQV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:12:58 INFO - PROCESS | 6484 | RSS: Main: 238850048 15:12:58 INFO - PROCESS | 6484 | 15:13:49 INFO - PROCESS | 6484 | 1464041629476 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] 15:15:59 INFO - PROCESS | 6484 | Cycle 1(1): loaded http://localhost:57924/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57924/tests/dromaeo/cssquery-ext.html) 15:16:01 INFO - PROCESS | 6484 | RSS: Main: 344371200 15:16:01 INFO - PROCESS | 6484 | 15:19:04 INFO - PROCESS | 6484 | Cycle 1(1): loaded http://localhost:57924/tests/dromaeo/cssquery-ext.html (next: http://localhost:57924/tests/dromaeo/cssquery-jquery.html) 15:19:04 INFO - PROCESS | 6484 | RSS: Main: 374194176 15:19:04 INFO - PROCESS | 6484 | 15:22:07 INFO - PROCESS | 6484 | Cycle 1(1): loaded http://localhost:57924/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57924/tests/dromaeo/cssquery-mootools.html) 15:22:07 INFO - PROCESS | 6484 | RSS: Main: 367362048 15:22:07 INFO - PROCESS | 6484 | 15:25:10 INFO - PROCESS | 6484 | Cycle 1(1): loaded http://localhost:57924/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57924/tests/dromaeo/cssquery-prototype.html) 15:25:10 INFO - PROCESS | 6484 | RSS: Main: 367464448 15:25:10 INFO - PROCESS | 6484 | 15:28:13 INFO - PROCESS | 6484 | Cycle 1(1): loaded http://localhost:57924/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57924/tests/dromaeo/cssquery-yui.html) 15:28:14 INFO - PROCESS | 6484 | RSS: Main: 335364096 15:28:14 INFO - PROCESS | 6484 | 15:31:17 INFO - PROCESS | 6484 | Cycle 1(1): loaded http://localhost:57924/tests/dromaeo/cssquery-yui.html (next: http://localhost:57924/tests/dromaeo/cssquery-dojo.html) 15:31:17 INFO - PROCESS | 6484 | RSS: Main: 386650112 15:31:17 INFO - PROCESS | 6484 | 15:31:17 INFO - PROCESS | 6484 | __start_tp_report 15:31:17 INFO - PROCESS | 6484 | _x_x_mozilla_page_load 15:31:17 INFO - PROCESS | 6484 | _x_x_mozilla_page_load_details 15:31:17 INFO - PROCESS | 6484 | |i|pagename|runs| 15:31:17 INFO - PROCESS | 6484 | |0;dojo.html;3457.4640930655755;3461.2558299965503;3464.099334173115;3473.992542162772;3543.255916257586;5696.914546281799;5748.9651862664705;5749.626274292168;5853.385394533571;5899.970500147499;5730.169125476803;5765.45228203321;5843.912341314884;5871.178035075082;5888.793892213774;5301.549368303689;5369.382521010075;5374.408815030343;5408.215808707735;5409.729513524328;5286.814961476349;5321.494458026481;5328.440513746059;5382.703951282678;5394.298741163663;5237.79048838046;5407.432219616942;5456;5469.042917489443;5477.726113694319;5214.32213812202;5255.842324730264;5330.573554115662;5354.651947623412;5369.731513424353;5149;5190.584753219771;5242.55438287746;5257.658252213594;5300.708461034642;5630.15547667849;5740.82777516671;5776.36459989405;5792.131180322943;5836.007878660638;6105.095857753072;6259.530535209878;6313.684315784239;6314.494840412756;6337.87324253517;6234.251889773211;6245.000799872044;6280.120783090325;6292.339304373067;6307.9684601577155;5915.497182739516;5965.403459654043;5979.372165922602;5980.372060933626;5992.850178745479;6186.226721659793;6213.782517611952;6263.686815659199;6329.240491141092;6334.524910631629;6217.751289948352;6221.253449586078;6227.937720622826;6229.657368844666;6248.250209974832;5317.9202311965355;6265.373462653698;6275.466585340298;6278.6860656966965;6294.653794041372;6028.8794224116145;6137.568252096927;6180.196574445294;6239.388373132141;6262.2172228471445;6288.748450061947;6340.239171299474;6342.8414289642205;6345.143405640272;6347.5239357047485;5021.648484606042;5025.221090730943;5028.421731500851;5036.093503169392;5051.949480505148;4386.385905973167;4396.076823867023;4520.2315606347;4535.569120933506;4542.704724192917;5717.42825717425;5762.769489220468;5771.9711401443565;5819.418058194231;5823.708814559255;6265.843353916189;6289.779857704958;6310.52671049664;6323.399277068671;6325.7785977491485;5663.377028526858;5723.055695810144;5725.484706376363;5755.539556835527;5762.4525670061275;5459.508644222039;5459.590530710213;5478.972605136949;5489.58828087887;5525.336959564878;6320.494360451153;6332.651704156224;6375.6812159392775;6387.201599800025;6390.297067322498;6177.2587289525545;6224.1597384354345;6274.215723034621;6311.463525600376;6334.588251763621;6332.778352757631;6336.112944187909;6337.334579869232;6349;6358.650274234961;6047.697615119138;6211.81364559064;6221;6224.035274532454;6245.906311405423;5494.230807687007;6200.596961197508;6209.59637623553;6265;6265.49876009909;6131.816045518642;6169.290531588746;6186.81439556814;6187;6200.596961197508;6010.879782404415;6195.915714749991;6196.287426945959;6198.411150940653;6216.689165541795;5864.179014937827;5966.850828729316;5970.283565972111;6019.277686677627;6062.080953563447;6155.538334625011;6161.045038019114;6166.969165154145;6169.413905678954;6173.81478555644;5851.1515830205235;6035.668038257762;6039.914398048932;6043.214382130295;6085.66528840918 15:31:17 INFO - PROCESS | 6484 | |1;ext.html;474.4970331448664;480.0926249388654;486.81014404382273;491.3416022529809;523.9808572326823;29779.851100744494;33661.83169084154;43242.9189270268;44069;44338.556614433925;37973.81013094937;45517.31724024142;46026.539734602644;46138;46164.76917615407;8231.876521852148;9499.952500237507;9745.561449734743;9749.805003899917;9769.413835169913;8557.786055348604;11511.884881151207;11775.057995360394;11776.352300623463;11889.286642801417;10455.95440455956;11451.427428628534;11698.590549330775;11823.586174483895;11834.93485586297;8556.18716221958;10435.37387756737;11220.765715771258;11413.086953043736;11743.119266054995;11453.541858325658;11685.006774424184;11729.648110556696;11739.47172377245;11741.647750567497;41359.793201033805;41476.792616037026;41597.376039359435;42134.15731685349;42179.57820421818;2668.2262143978223;3088.366884788613;3136.796108252979;3217.710406063442;3390.5083762854497;29694.109176724734;29829.552556711667;29915.165798684855;30399.848000760077;30457.847710761525;2718.836869787799;2731.8634068296706;2778.0693467688147;2794.273488892882;2835.6880743118445;430042;509855.90144098457;509931.90068099217;511365.9999999963;511497;64645.03032454517;65425;66422;66496.66751666211;67022;35425.822870886;35956.460653090224;36452;37801.43297850534;37833;16657.50027499177;16939.830601694073;17152.88506247115;17187.91406042962;17334.393296234823;11600.6519804406;11849.881501185051;12741.49034038628;12883.033772467103;13052.869471305165;19557.546009816728;19731.704024439645;19879.00604969746;20101.899490502747;20207;7861.331786798072;7950.681972721142;7961.960190199012;8011.719589814328;8029.076656184614;909.2998391238709;967.9177269931994;997.670768646345;1058.9523471443804;1060.125396547851;24396.51206975886;30010.549841752392;30084.796608135428;30635.2341191472;31286.68713312883;979.9167070799062;1053.6417618009914;1069.2996087562658;1080.9027187553158;1093.3822390349428;15266.931314808078;15343.846561534465;15592.298346574431;15664.060156390653;15726.764098538531;684810;689838;689867;691201;691552;61395.69302153461;61504;62556;63204.683976580745;63298.73402531924;1276.5914907229596;1421.1260075053767;1439.9136051836924;1472.1829384691384;1475.1591592792006;30522.84738576293;30583;30792.692073078983;31234.843825780725;31425.74297028093;51981.7400912993;52268.21597676114;52792.73603631957;52950.470495294554;53053.46946530485;468978;548912.2554387202;552658;553372;554172;23284.185053523044;25733.84197711145;26571.7342826577;26652.60021099646;26885.327866802705;47091.76454117707;47491.762541187076;47796;48265.75867120642;48770.26844597405;30952.535711963887;35625.643743562236;38571.614283857925;38629.22741545209;39556.60443395645;37133.07167320839;37729.81135094307;37955.24089518249;38157.85526434211;38817;36099.63900360963;38096.80951595224;38811.417828732025;39028.6097139025;39268.01829954274;31793.682063179072;33397.332053359285;34149.31701366008;34285.82857085699;34485.482717759754;24719.134830280855;24982.62526062147;24991.500169996863;25344.239672809847;25522 15:31:17 INFO - PROCESS | 6484 | |2;jquery.html;332.93341331733654;388.1150975775233;391.1629113696688;401.8372559113556;404.58125839755843;19906.601867962632;22066.007029683664;22208.222712205075;22340.329790106283;22404;20379;21405.250816221436;21497.92510374479;21500.462488437795;22397.888010559924;5222.9477705223035;5413.729313534327;5579.218909352694;5584.329880414356;5593.216949627055;3908.237893610733;4111.629953304202;5484.204790305404;5834.854128646796;6158.56890017699;6183.845403864894;6191.969040154815;6194.070889366586;6196.070589411579;6202.19371481709;6012.609180403259;6046.848828779272;6058.878822423571;6075.939240607604;6190.442860142563;3067.9079627610986;5614.775408983635;5896.646201227897;5976.910346344765;5986.551008674323;13477.460901563925;13578.321083945862;13764.793528097087;21188.470288242763;22885.885570572205;2050.456628993318;2071.533904871407;2071.554615757604;2237.6084185267678;2445.29086564896;8599.398042137052;8707.25988290996;9853.753656158582;9891.258155638285;10108.039736225046;1749.7550342951997;1772.1316554888012;1812.3747307179003;1825.1421831739062;1825.3063835742466;125102.37448812788;128806.71193288134;129371.70628293786;130291.34854325763;131109.34445327808;9806.607735690492;10954.39750813697;11573.854191403307;11630.592929247605;11811.645650630495;14822.629434264014;14895.553133406016;14897.031692939925;14915.925420372829;14990.100593964396;7277.59973201479;7692.192319806451;8249.381296402793;8694.217520423066;8700.695475658322;8550.273226775793;8587.355948303903;8689.131086891386;8696.04343522212;8807.735767926972;4510.571495707903;4568.977155114203;4609.985803123308;4635.351050852883;4690.53094690535;1741.2512619573704;1797.6038447665787;1814.1745505794831;1862.1899473728972;1894.5547796267865;844.8225872566707;914.3919293669635;943.9244860411151;1002.9218590015762;1009.3540134314045;20127.39617811468;21020;21242.78757212439;21265.68101478479;21272.468188295108;903.9186473217442;913.3697748553477;978.3054031637618;981.2444417798398;1025.4308858583559;10735.409552474533;10991;11141.777164456666;11170.553177872795;11194.832077518844;617356;621719;626376;626793;637062;126400;132899.3355033238;135077.32461337827;135389;135787;979.3487330924997;980.2648013989508;994.2940512236248;994.393420013791;996.3274789517047;18336.81663183351;18349.54126146857;18385.632287354445;18468.44594662162;18494.815051849848;51525.7423712879;51843.481565183865;52260.21609675934;53128.468715313895;53194.468055318954;77178;78127;78433.60783196047;79606.81572737257;81389;6443.548951573345;6966.79099627012;8087.757367278991;8310.667573297242;8534.658613655385;10681.635284885548;12698.873011269769;12965.546205882749;13050.15174013686;13229.536966206137;10309.639162629272;10823.458827058457;10829;11081.27971681838;11169;8679.739607811776;10735.248532602644;10911;11138.164637652371;11309.547618095185;5923.111533270044;9121.680741174026;10123.79752404933;10238.744031398977;11447.942760286145;8106.837863242584;8152.347812174893;8158.673653053811;8209.466384684976;8446.07093219733;8046.798830029296;9933.552990115317;10409.11522520564;10946.288491248044;11428.714282143013 15:31:17 INFO - PROCESS | 6484 | |3;mootools.html;1392.8746412822843;1510.2222355486924;1545.8531439513247;1787.54417623506;1931.4398824340951;7709.614519274042;8457.323414126871;8695.91304086959;8705.65177392905;8782.560871956393;8498.107698691641;8614.396992210532;8703.43427677202;8735.868961965576;8747.693830715927;2102.8317734581274;2227.2984010036866;2301.4131396493954;2346.8826558672085;2379.048380647746;2387.797037251827;2419.620816134804;2423.2487928742057;2427.647991041298;2428.18655750323;2333.5099629077913;2382.17814853876;2383.5947888858936;2397.940051498718;2402.8558286502866;2075.813176814094;2252.335561009506;2339.2982105368405;2368.46709490365;2370.952580948371;2360.7167139943144;2387.140629373424;2389.8446600970883;2389.892454839536;2390.748971358;8440.28257598105;8495.108013658539;8496.70261540843;8546.273566746833;8546.700865469678;5032.2703208035;5041.798328066873;5196.246544251062;5203.947960520384;5210.6613070150315;3421.794692318469;3487.424574945131;3546.042568506489;3552.520409744677;3555.3422616815974;998.9001099889953;1025.4052649463292;1026.6098882424706;1040.287403128859;1044.1333693034762;11148.665540033811;11342.432878356132;11348.262362946465;11375.203735738502;11433.314001159873;4592.265237561974;4699.835505757282;4776.331313616097;4778.737169455713;4781.139394908881;9925.15636170919;10009.749756256006;10014.54934527948;10028.598856045823;10037.69886903394;4530.6602004849765;4679.3916790816975;5350.759215835296;5356.892862142735;5370.785168593291;4293.549177336397;4440.289553671461;4481.2381895077915;4829.758512074382;4833.951660483421;3867.187890542961;3923.6272554107886;3931.6461518463475;3994.6005399460405;4050.817713202854;1037.3309215555923;1114.7993361194901;1184.7452797648375;1188.2395267029108;1204.5964601858475;818.7800177735251;833.6998680475024;891.7235656946367;940.3323640215386;951.9666811661558;5520.365158006881;5544.91682624761;5604.495595396434;5615.578831587647;5839.9124013139835;435.2187822857978;437.8051766963671;443.79288335727415;446.63376031653735;449.97300161990387;3750.774953502799;3819.866304679323;3823.5220597425323;3851.9614803852164;3880.41793730937;10145.08694217524;10529.684109476728;10639.574417023387;10651.520681569193;10651.73370665724;9835.409875407498;10388.948055259676;10569.365838049742;10580.629677961235;10598.25812193139;6242.344553821874;7992.960035199787;8060.435769496196;8150.551719655476;8152.551609661526;5751.654900705971;5775.884482310246;5942.970285148546;5981.1028345746745;6024.337322894566;9385.624575016924;9456.432614043179;9971.850422243528;9984.900150998397;9988.850167247643;9485.383450075724;9957.004299569984;9970.40177589347;10147.594096236067;10173.84739228927;6616.735330586723;7218.422526197996;7376.852462950818;7492.737754178577;7556.508826926232;8257.05043919957;8279.172082791672;8315.916840831514;8330.125336839787;8366;7204.71181152748;7247.456440767069;7278.199398066315;7408;7451.962740186264;6910.792676219721;7131;7131;7201.567905925661;7224.096987876515;6765.154355705537;6880.105586273952;6880.518363714492;6884.51808373409;6899.103116594811;4381.53993830656;4688.413948256468;4761.381020467317;4889.9022019560125;4921.63087768426;4824.927626085681;4841.636877234292;4889.217725163957;4894.412670479565;4946.530079642428 15:31:17 INFO - PROCESS | 6484 | |4;prototype.html;303.8070825026108;303.9073082709774;317.61251273446396;321.8760777100816;321.90825614699816;4300.8709738707885;4680.859574212779;5485.670859748418;5510.641808282464;5531.419200983894;4621.722696638196;4672.649551283659;5233.345831771028;5369.067135831097;5384.596155288359;3712.350338690718;4156.896077598054;4159.604837540444;4160.292750232467;4180.3520454329555;3701.666849983501;3725.627437256266;3800.8099595020276;3987.541432735229;4095.0858969237033;3401.727861771052;3606.9639303607023;3930.3121953658137;4043.6360727534516;4099.770068979295;3614.331348700499;4071.857484988026;4111.383292506118;4140.730852494578;4182.247195504808;3600.72994525409;3643.4716966039778;3643.635636436362;3649.178934739689;3671.880076576643;4011.097503061817;4862.984949331437;5067.746612669352;5114.565261952738;5165.199394093921;4669.6731228814;4689.9765501172615;4719.9292010619865;4732.692374995614;4742.786574604151;5626.859328516779;5679.829605111853;5680.943190568083;5709.543236541108;5734.770609175628;3594.023287192034;3730.776153430803;3936.4095385692376;3950.980245098785;3956.3867600521676;4792.952070479286;5108.4125325587665;5858.179854820329;5948.40515948406;5981.431763982415;3444.345574340867;3902.297586434412;4047.919041619151;4051.493563304587;4064.3700226464944;3398.6431424700545;3698.7965661888315;4023.4970628671417;4103.425520427142;4118.4671427072135;3950.064468410458;4097.1191193893455;4375.47494300686;4648.349231107648;4671.04243630055;4626.537346265414;5789.681567513827;5813.82558523245;5823.359430462646;5880.8235752927485;1238.6717519857245;1270.8157317188957;1292.7414517096618;1329.1891945912985;1358.395513996282;831.1771346367053;876.6324533727405;910.3354551177678;919.7792529792802;921.4885738415244;5292.353823088456;5293.444188360244;5299.258103865462;5303.15149576066;5306.389765176977;5826.504747096459;5826.708664566755;5839;5844.298684157929;5873.647581145145;4690.742009189527;4958.206686930074;5265.3944796348915;5281.603879709037;5287.814926477555;2416.66166736657;2458.139651122093;2620.6724159480063;2629.3303752117163;2633.0126202673996;4259.063006138645;4389.385486031958;4698.613908896884;5101.744912754348;5178.56607394524;4589.862304130881;4678.929816052762;4720.929186062212;5330.893382132335;5997.4119798354295;6147.170132032208;6203.2245969253845;6208.627482351073;6215.720292586844;6229.657368844757;5657.575681823962;5948;5981.7308221131025;5982.7307771149535;6027.909581356371;5083.644144909968;5194.7922083116255;5863.501602363676;5867.5305975521005;5928.318243401892;5740.68426236561;6188.628682279078;6204.937950620617;6206.727620837808;6242.659002873922;3277.3281477297573;3897.8111675939113;3943.82252798619;3951.3085210087543;3965.464662270672;4771.284307353924;5009.824656137018;5117.2068329409;5778.942210578009;5934.584579079596;4547.795349209359;5211.374635043819;5632.746526406403;5729.252577963761;5774.711264436845;5290.550303224269;5724.883647688661;5732.484076433141;5762.481376676119;5777.566682498746;5132.5124113209185;5376.301080859464;5663.603547751618;5667.574931880208;5667.659940403589;4869.222733702244;5479.369872464717;5604.271444712162;5711.772408760669;5758.942410575841;5197.8440646780655;5618.769207705667;5655.830325090254;5671.347795003628;5673.489385955284 15:31:17 INFO - PROCESS | 6484 | |5;yui.html;657.934206579342;680.1905732178707;693.4798900824381;721.9964249692928;736.929991650793;15029.473968411108;15372.769408458867;15609.609759756011;15631.609209769733;15656.452024179142;13996.300184990761;14290.999630025903;14330;14345.497907573237;14435.989480736353;698.9720411183572;730.4740586777511;866.5840396609631;883.9071897450772;901.197933838884;901.715959472764;910.713125365511;911.4804561400005;912.1608120529096;912.4160537256162;928.6842473558988;947.1239103828965;960.1694534227878;963.1764841060897;965.8213230552335;741.0847603210032;907.2333877873186;922.8523436250168;949.3354651743807;953.2374100719431;705.8552996635677;706.4030893894702;707.2785758526275;708.9967695710563;709.6664567653195;14620.561383158521;14696.26518674062;14719.779203311959;14767.335469903881;14770.778438323434;11706;12185.878141218564;12451.066170037284;12590.496380144783;12625;10716.464176791207;11015;11069.225154239206;11177.385243811586;11552.249640763568;4007.158496715693;4034.132661477767;4067.715259931806;4175.498940127174;4216.7680777557225;82699.3460130794;83867.5806620963;83952.58023709903;84366.57816710939;84656.73014904781;9173.816523669622;9354.064593540728;9365;9390.62437502506;9529.714108576754;19502.219911203676;20228.393148205578;20252.493687657927;20253.189872405233;20262.98685065741;6781.6609169541325;6827.726890924308;6983.196932352743;7002.894956575656;7036.894446583306;3494.807785571767;3987.1626958338493;4030.7984600769846;4067.5525692173837;4136.8138433770555;3316.701496865294;3376.6623337666038;3439.8796042138906;3444.5866496020135;3446.6553344665836;583.3553922915203;591.5563327504372;599.430540986065;610.1579819848669;631.823089534931;496.89813588214327;509.6789022915539;559.2673597587093;567.9062954612442;572.7222297185862;7978.401619878532;8055.919440805634;8114.229148230908;8242.917570824215;8392.16078392156;517.7928828468642;562.9155626656035;599.1252770954368;603.9033754599243;604.1391017799618;3631.9818400908357;3887.676066868035;3944.3886197639413;3970.2456533258587;3977.284088864032;82686.17313826905;96714.51642741742;97560.0243997551;97780.02219977851;98213;77319;80075.59962200269;83448;83965;84932.30135397258;75637.62181189058;75702.62148689221;76564.61717691488;76696.61651691818;77472.61263693645;7097.787066388017;7563.205863384485;7818.765437036898;7841.372690184658;8049.597520124087;37586.24827503489;38730.61269387383;39022.4146637795;39166.41250381304;39382.81851544458;50448.73878153075;56952;58371.4162858383;62302.37697622966;63567.91456683362;10708.785824283315;11136.777264454828;11296.717582060513;11449;11456.541738330374;12090.637280881587;12196.085293603193;12385.133040687426;12421;13038.739225215631;11376.919192676682;11502.769944601228;11736.647900562997;11898.464569094238;11941.104417168573;11553.590940697195;11712.70718232017;11715.648530544097;11795.705107372385;11873.228240164362;11013.724656883642;11092.33445993243;11150.665480035612;11165.665030049111;11339.546418143182;9052.456852588866;9379.484128373006;9546.570404331684;9610.471424071744;9630.2777291702;9568.76078098053;9596.424214547149;9617.51912404363;9684.225261979165;9833.901660983585 15:31:17 INFO - PROCESS | 6484 | __end_tp_report 15:31:17 INFO - PROCESS | 6484 | __start_cc_report 15:31:17 INFO - PROCESS | 6484 | _x_x_mozilla_cycle_collect,1232 15:31:17 INFO - PROCESS | 6484 | __end_cc_report 15:31:17 INFO - PROCESS | 6484 | __startTimestamp1464042677763__endTimestamp 15:31:17 INFO - PROCESS | 6484 | 15:31:17 INFO - PROCESS | 6484 | ------- Summary: start ------- 15:31:17 INFO - PROCESS | 6484 | Number of tests: 6 15:31:17 INFO - PROCESS | 6484 | 15:31:17 INFO - PROCESS | 6484 | [#0] dojo.html Cycles:165 Average:5806.91 Median:6019.28 stddev:615.80 (10.2%) stddev-sans-first:589.47 15:31:17 INFO - PROCESS | 6484 | Values: 3457.5 3461.3 3464.1 3474.0 3543.3 5696.9 5749.0 5749.6 5853.4 5900.0 5730.2 5765.5 5843.9 5871.2 5888.8 5301.5 5369.4 5374.4 5408.2 5409.7 5286.8 5321.5 5328.4 5382.7 5394.3 5237.8 5407.4 5456.0 5469.0 5477.7 5214.3 5255.8 5330.6 5354.7 5369.7 5149.0 5190.6 5242.6 5257.7 5300.7 5630.2 5740.8 5776.4 5792.1 5836.0 6105.1 6259.5 6313.7 6314.5 6337.9 6234.3 6245.0 6280.1 6292.3 6308.0 5915.5 5965.4 5979.4 5980.4 5992.9 6186.2 6213.8 6263.7 6329.2 6334.5 6217.8 6221.3 6227.9 6229.7 6248.3 5317.9 6265.4 6275.5 6278.7 6294.7 6028.9 6137.6 6180.2 6239.4 6262.2 6288.7 6340.2 6342.8 6345.1 6347.5 5021.6 5025.2 5028.4 5036.1 5051.9 4386.4 4396.1 4520.2 4535.6 4542.7 5717.4 5762.8 5772.0 5819.4 5823.7 6265.8 6289.8 6310.5 6323.4 6325.8 5663.4 5723.1 5725.5 5755.5 5762.5 5459.5 5459.6 5479.0 5489.6 5525.3 6320.5 6332.7 6375.7 6387.2 6390.3 6177.3 6224.2 6274.2 6311.5 6334.6 6332.8 6336.1 6337.3 6349.0 6358.7 6047.7 6211.8 6221.0 6224.0 6245.9 5494.2 6200.6 6209.6 6265.0 6265.5 6131.8 6169.3 6186.8 6187.0 6200.6 6010.9 6195.9 6196.3 6198.4 6216.7 5864.2 5966.9 5970.3 6019.3 6062.1 6155.5 6161.0 6167.0 6169.4 6173.8 5851.2 6035.7 6039.9 6043.2 6085.7 15:31:17 INFO - PROCESS | 6484 | 15:31:17 INFO - PROCESS | 6484 | [#1] ext.html Cycles:180 Average:70417.02 Median:34385.66 stddev:155133.04 (451.2%) stddev-sans-first:155479.34 15:31:17 INFO - PROCESS | 6484 | Values: 474.5 480.1 486.8 491.3 524.0 29779.9 33661.8 43242.9 44069.0 44338.6 37973.8 45517.3 46026.5 46138.0 46164.8 8231.9 9500.0 9745.6 9749.8 9769.4 8557.8 11511.9 11775.1 11776.4 11889.3 10456.0 11451.4 11698.6 11823.6 11834.9 8556.2 10435.4 11220.8 11413.1 11743.1 11453.5 11685.0 11729.6 11739.5 11741.6 41359.8 41476.8 41597.4 42134.2 42179.6 2668.2 3088.4 3136.8 3217.7 3390.5 29694.1 29829.6 29915.2 30399.8 30457.8 2718.8 2731.9 2778.1 2794.3 2835.7 430042.0 509855.9 509931.9 511366.0 511497.0 64645.0 65425.0 66422.0 66496.7 67022.0 35425.8 35956.5 36452.0 37801.4 37833.0 16657.5 16939.8 17152.9 17187.9 17334.4 11600.7 11849.9 12741.5 12883.0 13052.9 19557.5 19731.7 19879.0 20101.9 20207.0 7861.3 7950.7 7962.0 8011.7 8029.1 909.3 967.9 997.7 1059.0 1060.1 24396.5 30010.5 30084.8 30635.2 31286.7 979.9 1053.6 1069.3 1080.9 1093.4 15266.9 15343.8 15592.3 15664.1 15726.8 684810.0 689838.0 689867.0 691201.0 691552.0 61395.7 61504.0 62556.0 63204.7 63298.7 1276.6 1421.1 1439.9 1472.2 1475.2 30522.8 30583.0 30792.7 31234.8 31425.7 51981.7 52268.2 52792.7 52950.5 53053.5 468978.0 548912.3 552658.0 553372.0 554172.0 23284.2 25733.8 26571.7 26652.6 26885.3 47091.8 47491.8 47796.0 48265.8 48770.3 30952.5 35625.6 38571.6 38629.2 39556.6 37133.1 37729.8 37955.2 38157.9 38817.0 36099.6 38096.8 38811.4 39028.6 39268.0 31793.7 33397.3 34149.3 34285.8 34485.5 24719.1 24982.6 24991.5 25344.2 25522.0 15:31:17 INFO - PROCESS | 6484 | 15:31:17 INFO - PROCESS | 6484 | [#2] jquery.html Cycles:180 Average:35925.72 Median:2148.18 stddev:104674.17 (4872.7%) stddev-sans-first:104933.69 15:31:17 INFO - PROCESS | 6484 | Values: 332.9 388.1 391.2 401.8 404.6 19906.6 22066.0 22208.2 22340.3 22404.0 20379.0 21405.3 21497.9 21500.5 22397.9 5222.9 5413.7 5579.2 5584.3 5593.2 3908.2 4111.6 5484.2 5834.9 6158.6 6183.8 6192.0 6194.1 6196.1 6202.2 6012.6 6046.8 6058.9 6075.9 6190.4 3067.9 5614.8 5896.6 5976.9 5986.6 13477.5 13578.3 13764.8 21188.5 22885.9 2050.5 2071.5 2071.6 2237.6 2445.3 8599.4 8707.3 9853.8 9891.3 10108.0 1749.8 1772.1 1812.4 1825.1 1825.3 125102.4 128806.7 129371.7 130291.3 131109.3 9806.6 10954.4 11573.9 11630.6 11811.6 14822.6 14895.6 14897.0 14915.9 14990.1 7277.6 7692.2 8249.4 8694.2 8700.7 8550.3 8587.4 8689.1 8696.0 8807.7 4510.6 4569.0 4610.0 4635.4 4690.5 1741.3 1797.6 1814.2 1862.2 1894.6 844.8 914.4 943.9 1002.9 1009.4 20127.4 21020.0 21242.8 21265.7 21272.5 903.9 913.4 978.3 981.2 1025.4 10735.4 10991.0 11141.8 11170.6 11194.8 617356.0 621719.0 626376.0 626793.0 637062.0 126400.0 132899.3 135077.3 135389.0 135787.0 979.3 980.3 994.3 994.4 996.3 18336.8 18349.5 18385.6 18468.4 18494.8 51525.7 51843.5 52260.2 53128.5 53194.5 77178.0 78127.0 78433.6 79606.8 81389.0 6443.5 6966.8 8087.8 8310.7 8534.7 10681.6 12698.9 12965.5 13050.2 13229.5 10309.6 10823.5 10829.0 11081.3 11169.0 8679.7 10735.2 10911.0 11138.2 11309.5 5923.1 9121.7 10123.8 10238.7 11447.9 8106.8 8152.3 8158.7 8209.5 8446.1 8046.8 9933.6 10409.1 10946.3 11428.7 15:31:17 INFO - PROCESS | 6484 | 15:31:17 INFO - PROCESS | 6484 | [#3] mootools.html Cycles:180 Average:5582.16 Median:4779.94 stddev:3156.39 (66.0%) stddev-sans-first:3149.55 15:31:17 INFO - PROCESS | 6484 | Values: 1392.9 1510.2 1545.9 1787.5 1931.4 7709.6 8457.3 8695.9 8705.7 8782.6 8498.1 8614.4 8703.4 8735.9 8747.7 2102.8 2227.3 2301.4 2346.9 2379.0 2387.8 2419.6 2423.2 2427.6 2428.2 2333.5 2382.2 2383.6 2397.9 2402.9 2075.8 2252.3 2339.3 2368.5 2371.0 2360.7 2387.1 2389.8 2389.9 2390.7 8440.3 8495.1 8496.7 8546.3 8546.7 5032.3 5041.8 5196.2 5203.9 5210.7 3421.8 3487.4 3546.0 3552.5 3555.3 998.9 1025.4 1026.6 1040.3 1044.1 11148.7 11342.4 11348.3 11375.2 11433.3 4592.3 4699.8 4776.3 4778.7 4781.1 9925.2 10009.7 10014.5 10028.6 10037.7 4530.7 4679.4 5350.8 5356.9 5370.8 4293.5 4440.3 4481.2 4829.8 4834.0 3867.2 3923.6 3931.6 3994.6 4050.8 1037.3 1114.8 1184.7 1188.2 1204.6 818.8 833.7 891.7 940.3 952.0 5520.4 5544.9 5604.5 5615.6 5839.9 435.2 437.8 443.8 446.6 450.0 3750.8 3819.9 3823.5 3852.0 3880.4 10145.1 10529.7 10639.6 10651.5 10651.7 9835.4 10388.9 10569.4 10580.6 10598.3 6242.3 7993.0 8060.4 8150.6 8152.6 5751.7 5775.9 5943.0 5981.1 6024.3 9385.6 9456.4 9971.9 9984.9 9988.9 9485.4 9957.0 9970.4 10147.6 10173.8 6616.7 7218.4 7376.9 7492.7 7556.5 8257.1 8279.2 8315.9 8330.1 8366.0 7204.7 7247.5 7278.2 7408.0 7452.0 6910.8 7131.0 7131.0 7201.6 7224.1 6765.2 6880.1 6880.5 6884.5 6899.1 4381.5 4688.4 4761.4 4889.9 4921.6 4824.9 4841.6 4889.2 4894.4 4946.5 15:31:17 INFO - PROCESS | 6484 | 15:31:17 INFO - PROCESS | 6484 | [#4] prototype.html Cycles:180 Average:4542.18 Median:5075.70 stddev:1431.38 (28.2%) stddev-sans-first:1399.60 15:31:17 INFO - PROCESS | 6484 | Values: 303.8 303.9 317.6 321.9 321.9 4300.9 4680.9 5485.7 5510.6 5531.4 4621.7 4672.6 5233.3 5369.1 5384.6 3712.4 4156.9 4159.6 4160.3 4180.4 3701.7 3725.6 3800.8 3987.5 4095.1 3401.7 3607.0 3930.3 4043.6 4099.8 3614.3 4071.9 4111.4 4140.7 4182.2 3600.7 3643.5 3643.6 3649.2 3671.9 4011.1 4863.0 5067.7 5114.6 5165.2 4669.7 4690.0 4719.9 4732.7 4742.8 5626.9 5679.8 5680.9 5709.5 5734.8 3594.0 3730.8 3936.4 3951.0 3956.4 4793.0 5108.4 5858.2 5948.4 5981.4 3444.3 3902.3 4047.9 4051.5 4064.4 3398.6 3698.8 4023.5 4103.4 4118.5 3950.1 4097.1 4375.5 4648.3 4671.0 4626.5 5789.7 5813.8 5823.4 5880.8 1238.7 1270.8 1292.7 1329.2 1358.4 831.2 876.6 910.3 919.8 921.5 5292.4 5293.4 5299.3 5303.2 5306.4 5826.5 5826.7 5839.0 5844.3 5873.6 4690.7 4958.2 5265.4 5281.6 5287.8 2416.7 2458.1 2620.7 2629.3 2633.0 4259.1 4389.4 4698.6 5101.7 5178.6 4589.9 4678.9 4720.9 5330.9 5997.4 6147.2 6203.2 6208.6 6215.7 6229.7 5657.6 5948.0 5981.7 5982.7 6027.9 5083.6 5194.8 5863.5 5867.5 5928.3 5740.7 6188.6 6204.9 6206.7 6242.7 3277.3 3897.8 3943.8 3951.3 3965.5 4771.3 5009.8 5117.2 5778.9 5934.6 4547.8 5211.4 5632.7 5729.3 5774.7 5290.6 5724.9 5732.5 5762.5 5777.6 5132.5 5376.3 5663.6 5667.6 5667.7 4869.2 5479.4 5604.3 5711.8 5758.9 5197.8 5618.8 5655.8 5671.3 5673.5 15:31:17 INFO - PROCESS | 6484 | 15:31:17 INFO - PROCESS | 6484 | [#5] yui.html Cycles:180 Average:18150.19 Median:29481.49 stddev:25989.62 (88.2%) stddev-sans-first:26029.34 15:31:17 INFO - PROCESS | 6484 | Values: 657.9 680.2 693.5 722.0 736.9 15029.5 15372.8 15609.6 15631.6 15656.5 13996.3 14291.0 14330.0 14345.5 14436.0 699.0 730.5 866.6 883.9 901.2 901.7 910.7 911.5 912.2 912.4 928.7 947.1 960.2 963.2 965.8 741.1 907.2 922.9 949.3 953.2 705.9 706.4 707.3 709.0 709.7 14620.6 14696.3 14719.8 14767.3 14770.8 11706.0 12185.9 12451.1 12590.5 12625.0 10716.5 11015.0 11069.2 11177.4 11552.2 4007.2 4034.1 4067.7 4175.5 4216.8 82699.3 83867.6 83952.6 84366.6 84656.7 9173.8 9354.1 9365.0 9390.6 9529.7 19502.2 20228.4 20252.5 20253.2 20263.0 6781.7 6827.7 6983.2 7002.9 7036.9 3494.8 3987.2 4030.8 4067.6 4136.8 3316.7 3376.7 3439.9 3444.6 3446.7 583.4 591.6 599.4 610.2 631.8 496.9 509.7 559.3 567.9 572.7 7978.4 8055.9 8114.2 8242.9 8392.2 517.8 562.9 599.1 603.9 604.1 3632.0 3887.7 3944.4 3970.2 3977.3 82686.2 96714.5 97560.0 97780.0 98213.0 77319.0 80075.6 83448.0 83965.0 84932.3 75637.6 75702.6 76564.6 76696.6 77472.6 7097.8 7563.2 7818.8 7841.4 8049.6 37586.2 38730.6 39022.4 39166.4 39382.8 50448.7 56952.0 58371.4 62302.4 63567.9 10708.8 11136.8 11296.7 11449.0 11456.5 12090.6 12196.1 12385.1 12421.0 13038.7 11376.9 11502.8 11736.6 11898.5 11941.1 11553.6 11712.7 11715.6 11795.7 11873.2 11013.7 11092.3 11150.7 11165.7 11339.5 9052.5 9379.5 9546.6 9610.5 9630.3 9568.8 9596.4 9617.5 9684.2 9833.9 15:31:17 INFO - PROCESS | 6484 | -------- Summary: end -------- 15:31:17 INFO - PROCESS | 6484 | 15:31:18 INFO - TEST-INFO | 6484: exit 0 15:31:18 INFO - TEST-OK | dromaeo_css | took 1115506ms 15:31:18 INFO - TEST-START | kraken 15:31:18 INFO - operating with platform_type : mac_ 15:31:18 INFO - Initialising browser for kraken test... 15:31:18 INFO - TEST-INFO | started process 6488 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpIqZ1W_/profile http://localhost:57924/getInfo.html) 15:31:20 INFO - PROCESS | 6488 | __metrics Screen width/height:1600/1200 15:31:20 INFO - PROCESS | 6488 | colorDepth:24 15:31:20 INFO - PROCESS | 6488 | Browser inner width/height: 1024/689 15:31:20 INFO - PROCESS | 6488 | __metrics 15:31:20 INFO - PROCESS | 6488 | 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" 15:31:20 ERROR - PROCESS | 6488 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:31:21 INFO - TEST-INFO | 6488: exit 0 15:31:21 INFO - Browser initialized. 15:31:21 INFO - Running cycle 1/1 for kraken test... 15:31:21 INFO - TEST-INFO | started process 6490 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpIqZ1W_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:31:33 INFO - PROCESS | 6490 | RSS: Main: 239063040 15:31:33 INFO - PROCESS | 6490 | 15:31:36 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57924/tests/kraken/driver.html?testName=audio-beat-detection) 15:31:36 INFO - PROCESS | 6490 | RSS: Main: 287944704 15:31:36 INFO - PROCESS | 6490 | 15:31:39 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57924/tests/kraken/driver.html?testName=audio-dft) 15:31:40 INFO - PROCESS | 6490 | RSS: Main: 295809024 15:31:40 INFO - PROCESS | 6490 | 15:31:43 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57924/tests/kraken/driver.html?testName=audio-fft) 15:31:44 INFO - PROCESS | 6490 | RSS: Main: 386150400 15:31:44 INFO - PROCESS | 6490 | 15:31:46 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57924/tests/kraken/driver.html?testName=audio-oscillator) 15:31:47 INFO - PROCESS | 6490 | RSS: Main: 284254208 15:31:47 INFO - PROCESS | 6490 | 15:31:49 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57924/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:31:49 INFO - PROCESS | 6490 | RSS: Main: 288665600 15:31:49 INFO - PROCESS | 6490 | 15:31:53 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57924/tests/kraken/driver.html?testName=imaging-darkroom) 15:31:53 INFO - PROCESS | 6490 | RSS: Main: 328282112 15:31:53 INFO - PROCESS | 6490 | 15:31:56 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57924/tests/kraken/driver.html?testName=imaging-desaturate) 15:31:57 INFO - PROCESS | 6490 | RSS: Main: 329867264 15:31:57 INFO - PROCESS | 6490 | 15:32:00 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57924/tests/kraken/driver.html?testName=json-parse-financial) 15:32:01 INFO - PROCESS | 6490 | RSS: Main: 328531968 15:32:01 INFO - PROCESS | 6490 | 15:32:02 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57924/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:32:03 INFO - PROCESS | 6490 | RSS: Main: 315838464 15:32:03 INFO - PROCESS | 6490 | 15:32:05 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57924/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:32:05 INFO - PROCESS | 6490 | RSS: Main: 322707456 15:32:05 INFO - PROCESS | 6490 | 15:32:08 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57924/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:32:08 INFO - PROCESS | 6490 | RSS: Main: 325992448 15:32:08 INFO - PROCESS | 6490 | 15:32:11 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57924/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:32:12 INFO - PROCESS | 6490 | RSS: Main: 330977280 15:32:12 INFO - PROCESS | 6490 | 15:32:15 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57924/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:32:16 INFO - PROCESS | 6490 | RSS: Main: 328732672 15:32:16 INFO - PROCESS | 6490 | 15:32:17 INFO - PROCESS | 6490 | Cycle 1(1): loaded http://localhost:57924/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57924/tests/kraken/driver.html?testName=ai-astar) 15:32:18 INFO - PROCESS | 6490 | RSS: Main: 375054336 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | __start_tp_report 15:32:18 INFO - PROCESS | 6490 | _x_x_mozilla_page_load 15:32:18 INFO - PROCESS | 6490 | _x_x_mozilla_page_load_details 15:32:18 INFO - PROCESS | 6490 | |i|pagename|runs| 15:32:18 INFO - PROCESS | 6490 | |0;ai-astar;112;105;111;112;111;112;111;111;112;111 15:32:18 INFO - PROCESS | 6490 | |1;audio-beat-detection;158;117;119;200;117;118;141;116;152;120 15:32:18 INFO - PROCESS | 6490 | |2;audio-dft;185;185;189;183;184;186;184;183;182;186 15:32:18 INFO - PROCESS | 6490 | |3;audio-fft;78;57;61;120;57;56;64;56;98;56 15:32:18 INFO - PROCESS | 6490 | |4;audio-oscillator;103;76;142;78;83;76;103;79;76;109 15:32:18 INFO - PROCESS | 6490 | |5;imaging-gaussian-blur;89;86;89;86;89;86;134;87;89;86 15:32:18 INFO - PROCESS | 6490 | |6;imaging-darkroom;94;98;94;95;94;94;94;94;93;94 15:32:18 INFO - PROCESS | 6490 | |7;imaging-desaturate;84;85;81;87;86;85;84;84;88;87 15:32:18 INFO - PROCESS | 6490 | |8;json-parse-financial;57;58;59;57;58;56;56;117;55;55 15:32:18 INFO - PROCESS | 6490 | |9;json-stringify-tinderbox;65;67;74;72;77;72;71;73;71;73 15:32:18 INFO - PROCESS | 6490 | |10;stanford-crypto-aes;86;82;85;99;98;84;84;83;85;84 15:32:18 INFO - PROCESS | 6490 | |11;stanford-crypto-ccm;140;141;137;138;143;142;135;135;214;139 15:32:18 INFO - PROCESS | 6490 | |12;stanford-crypto-pbkdf2;165;165;163;167;166;164;168;166;161;167 15:32:18 INFO - PROCESS | 6490 | |13;stanford-crypto-sha256-iterative;66;68;77;67;65;65;67;84;83;82 15:32:18 INFO - PROCESS | 6490 | __end_tp_report 15:32:18 INFO - PROCESS | 6490 | __start_cc_report 15:32:18 INFO - PROCESS | 6490 | _x_x_mozilla_cycle_collect,2236 15:32:18 INFO - PROCESS | 6490 | __end_cc_report 15:32:18 INFO - PROCESS | 6490 | __startTimestamp1464042738150__endTimestamp 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | ------- Summary: start ------- 15:32:18 INFO - PROCESS | 6490 | Number of tests: 14 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.50 stddev:2.10 (1.9%) stddev-sans-first:2.18 15:32:18 INFO - PROCESS | 6490 | Values: 112.0 105.0 111.0 112.0 111.0 112.0 111.0 111.0 112.0 111.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:130.50 stddev:27.59 (21.1%) stddev-sans-first:28.07 15:32:18 INFO - PROCESS | 6490 | Values: 158.0 117.0 119.0 200.0 117.0 118.0 141.0 116.0 152.0 120.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:2.00 (1.1%) stddev-sans-first:2.12 15:32:18 INFO - PROCESS | 6490 | Values: 185.0 185.0 189.0 183.0 184.0 186.0 184.0 183.0 182.0 186.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#3] audio-fft Cycles:10 Average:70.30 Median:59.00 stddev:22.09 (37.4%) stddev-sans-first:23.25 15:32:18 INFO - PROCESS | 6490 | Values: 78.0 57.0 61.0 120.0 57.0 56.0 64.0 56.0 98.0 56.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:76.00 stddev:21.71 (28.6%) stddev-sans-first:22.69 15:32:18 INFO - PROCESS | 6490 | Values: 103.0 76.0 142.0 78.0 83.0 76.0 103.0 79.0 76.0 109.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:88.00 stddev:14.79 (16.8%) stddev-sans-first:15.65 15:32:18 INFO - PROCESS | 6490 | Values: 89.0 86.0 89.0 86.0 89.0 86.0 134.0 87.0 89.0 86.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:94.00 stddev:1.35 (1.4%) stddev-sans-first:1.42 15:32:18 INFO - PROCESS | 6490 | Values: 94.0 98.0 94.0 95.0 94.0 94.0 94.0 94.0 93.0 94.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:2.02 (2.4%) stddev-sans-first:2.11 15:32:18 INFO - PROCESS | 6490 | Values: 84.0 85.0 81.0 87.0 86.0 85.0 84.0 84.0 88.0 87.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:57.00 stddev:19.09 (33.5%) stddev-sans-first:20.13 15:32:18 INFO - PROCESS | 6490 | Values: 57.0 58.0 59.0 57.0 58.0 56.0 56.0 117.0 55.0 55.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.50 stddev:3.41 (4.7%) stddev-sans-first:2.68 15:32:18 INFO - PROCESS | 6490 | Values: 65.0 67.0 74.0 72.0 77.0 72.0 71.0 73.0 71.0 73.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:85.00 stddev:6.16 (7.3%) stddev-sans-first:6.53 15:32:18 INFO - PROCESS | 6490 | Values: 86.0 82.0 85.0 99.0 98.0 84.0 84.0 83.0 85.0 84.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:140.50 stddev:23.91 (17.0%) stddev-sans-first:25.25 15:32:18 INFO - PROCESS | 6490 | Values: 140.0 141.0 137.0 138.0 143.0 142.0 135.0 135.0 214.0 139.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:166.00 stddev:2.10 (1.3%) stddev-sans-first:2.22 15:32:18 INFO - PROCESS | 6490 | Values: 165.0 165.0 163.0 167.0 166.0 164.0 168.0 166.0 161.0 167.0 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - PROCESS | 6490 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:72.50 stddev:8.09 (11.2%) stddev-sans-first:8.24 15:32:18 INFO - PROCESS | 6490 | Values: 66.0 68.0 77.0 67.0 65.0 65.0 67.0 84.0 83.0 82.0 15:32:18 INFO - PROCESS | 6490 | -------- Summary: end -------- 15:32:18 INFO - PROCESS | 6490 | 15:32:18 INFO - TEST-INFO | 6490: exit 0 15:32:18 INFO - TEST-OK | kraken | took 60622ms 15:32:18 INFO - SUITE-END | took 1176s 15:32:19 INFO - Completed test suite (00:19:36) 15:32:19 INFO - javascript benchmark 15:32:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3457.4640930655755, 3461.2558299965503, 3464.099334173115, 3473.992542162772, 3543.255916257586, 5696.914546281799, 5748.9651862664705, 5749.626274292168, 5853.385394533571, 5899.970500147499, 5730.169125476803, 5765.45228203321, 5843.912341314884, 5871.178035075082, 5888.793892213774, 5301.549368303689, 5369.382521010075, 5374.408815030343, 5408.215808707735, 5409.729513524328, 5286.814961476349, 5321.494458026481, 5328.440513746059, 5382.703951282678, 5394.298741163663, 5237.79048838046, 5407.432219616942, 5456.0, 5469.042917489443, 5477.726113694319, 5214.32213812202, 5255.842324730264, 5330.573554115662, 5354.651947623412, 5369.731513424353, 5149.0, 5190.584753219771, 5242.55438287746, 5257.658252213594, 5300.708461034642, 5630.15547667849, 5740.82777516671, 5776.36459989405, 5792.131180322943, 5836.007878660638, 6105.095857753072, 6259.530535209878, 6313.684315784239, 6314.494840412756, 6337.87324253517, 6234.251889773211, 6245.000799872044, 6280.120783090325, 6292.339304373067, 6307.9684601577155, 5915.497182739516, 5965.403459654043, 5979.372165922602, 5980.372060933626, 5992.850178745479, 6186.226721659793, 6213.782517611952, 6263.686815659199, 6329.240491141092, 6334.524910631629, 6217.751289948352, 6221.253449586078, 6227.937720622826, 6229.657368844666, 6248.250209974832, 5317.9202311965355, 6265.373462653698, 6275.466585340298, 6278.6860656966965, 6294.653794041372, 6028.8794224116145, 6137.568252096927, 6180.196574445294, 6239.388373132141, 6262.2172228471445, 6288.748450061947, 6340.239171299474, 6342.8414289642205, 6345.143405640272, 6347.5239357047485, 5021.648484606042, 5025.221090730943, 5028.421731500851, 5036.093503169392, 5051.949480505148, 4386.385905973167, 4396.076823867023, 4520.2315606347, 4535.569120933506, 4542.704724192917, 5717.42825717425, 5762.769489220468, 5771.9711401443565, 5819.418058194231, 5823.708814559255, 6265.843353916189, 6289.779857704958, 6310.52671049664, 6323.399277068671, 6325.7785977491485, 5663.377028526858, 5723.055695810144, 5725.484706376363, 5755.539556835527, 5762.4525670061275, 5459.508644222039, 5459.590530710213, 5478.972605136949, 5489.58828087887, 5525.336959564878, 6320.494360451153, 6332.651704156224, 6375.6812159392775, 6387.201599800025, 6390.297067322498, 6177.2587289525545, 6224.1597384354345, 6274.215723034621, 6311.463525600376, 6334.588251763621, 6332.778352757631, 6336.112944187909, 6337.334579869232, 6349.0, 6358.650274234961, 6047.697615119138, 6211.81364559064, 6221.0, 6224.035274532454, 6245.906311405423, 5494.230807687007, 6200.596961197508, 6209.59637623553, 6265.0, 6265.49876009909, 6131.816045518642, 6169.290531588746, 6186.81439556814, 6187.0, 6200.596961197508, 6010.879782404415, 6195.915714749991, 6196.287426945959, 6198.411150940653, 6216.689165541795, 5864.179014937827, 5966.850828729316, 5970.283565972111, 6019.277686677627, 6062.080953563447, 6155.538334625011, 6161.045038019114, 6166.969165154145, 6169.413905678954, 6173.81478555644, 5851.1515830205235, 6035.668038257762, 6039.914398048932, 6043.214382130295, 6085.66528840918], "value": 5769.156735181352, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.4970331448664, 480.0926249388654, 486.81014404382273, 491.3416022529809, 523.9808572326823, 29779.851100744494, 33661.83169084154, 43242.9189270268, 44069.0, 44338.556614433925, 37973.81013094937, 45517.31724024142, 46026.539734602644, 46138.0, 46164.76917615407, 8231.876521852148, 9499.952500237507, 9745.561449734743, 9749.805003899917, 9769.413835169913, 8557.786055348604, 11511.884881151207, 11775.057995360394, 11776.352300623463, 11889.286642801417, 10455.95440455956, 11451.427428628534, 11698.590549330775, 11823.586174483895, 11834.93485586297, 8556.18716221958, 10435.37387756737, 11220.765715771258, 11413.086953043736, 11743.119266054995, 11453.541858325658, 11685.006774424184, 11729.648110556696, 11739.47172377245, 11741.647750567497, 41359.793201033805, 41476.792616037026, 41597.376039359435, 42134.15731685349, 42179.57820421818, 2668.2262143978223, 3088.366884788613, 3136.796108252979, 3217.710406063442, 3390.5083762854497, 29694.109176724734, 29829.552556711667, 29915.165798684855, 30399.848000760077, 30457.847710761525, 2718.836869787799, 2731.8634068296706, 2778.0693467688147, 2794.273488892882, 2835.6880743118445, 430042.0, 509855.90144098457, 509931.90068099217, 511365.9999999963, 511497.0, 64645.03032454517, 65425.0, 66422.0, 66496.66751666211, 67022.0, 35425.822870886, 35956.460653090224, 36452.0, 37801.43297850534, 37833.0, 16657.50027499177, 16939.830601694073, 17152.88506247115, 17187.91406042962, 17334.393296234823, 11600.6519804406, 11849.881501185051, 12741.49034038628, 12883.033772467103, 13052.869471305165, 19557.546009816728, 19731.704024439645, 19879.00604969746, 20101.899490502747, 20207.0, 7861.331786798072, 7950.681972721142, 7961.960190199012, 8011.719589814328, 8029.076656184614, 909.2998391238709, 967.9177269931994, 997.670768646345, 1058.9523471443804, 1060.125396547851, 24396.51206975886, 30010.549841752392, 30084.796608135428, 30635.2341191472, 31286.68713312883, 979.9167070799062, 1053.6417618009914, 1069.2996087562658, 1080.9027187553158, 1093.3822390349428, 15266.931314808078, 15343.846561534465, 15592.298346574431, 15664.060156390653, 15726.764098538531, 684810.0, 689838.0, 689867.0, 691201.0, 691552.0, 61395.69302153461, 61504.0, 62556.0, 63204.683976580745, 63298.73402531924, 1276.5914907229596, 1421.1260075053767, 1439.9136051836924, 1472.1829384691384, 1475.1591592792006, 30522.84738576293, 30583.0, 30792.692073078983, 31234.843825780725, 31425.74297028093, 51981.7400912993, 52268.21597676114, 52792.73603631957, 52950.470495294554, 53053.46946530485, 468978.0, 548912.2554387202, 552658.0, 553372.0, 554172.0, 23284.185053523044, 25733.84197711145, 26571.7342826577, 26652.60021099646, 26885.327866802705, 47091.76454117707, 47491.762541187076, 47796.0, 48265.75867120642, 48770.26844597405, 30952.535711963887, 35625.643743562236, 38571.614283857925, 38629.22741545209, 39556.60443395645, 37133.07167320839, 37729.81135094307, 37955.24089518249, 38157.85526434211, 38817.0, 36099.63900360963, 38096.80951595224, 38811.417828732025, 39028.6097139025, 39268.01829954274, 31793.682063179072, 33397.332053359285, 34149.31701366008, 34285.82857085699, 34485.482717759754, 24719.134830280855, 24982.62526062147, 24991.500169996863, 25344.239672809847, 25522.0], "value": 20070.671335504157, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.93341331733654, 388.1150975775233, 391.1629113696688, 401.8372559113556, 404.58125839755843, 19906.601867962632, 22066.007029683664, 22208.222712205075, 22340.329790106283, 22404.0, 20379.0, 21405.250816221436, 21497.92510374479, 21500.462488437795, 22397.888010559924, 5222.9477705223035, 5413.729313534327, 5579.218909352694, 5584.329880414356, 5593.216949627055, 3908.237893610733, 4111.629953304202, 5484.204790305404, 5834.854128646796, 6158.56890017699, 6183.845403864894, 6191.969040154815, 6194.070889366586, 6196.070589411579, 6202.19371481709, 6012.609180403259, 6046.848828779272, 6058.878822423571, 6075.939240607604, 6190.442860142563, 3067.9079627610986, 5614.775408983635, 5896.646201227897, 5976.910346344765, 5986.551008674323, 13477.460901563925, 13578.321083945862, 13764.793528097087, 21188.470288242763, 22885.885570572205, 2050.456628993318, 2071.533904871407, 2071.554615757604, 2237.6084185267678, 2445.29086564896, 8599.398042137052, 8707.25988290996, 9853.753656158582, 9891.258155638285, 10108.039736225046, 1749.7550342951997, 1772.1316554888012, 1812.3747307179003, 1825.1421831739062, 1825.3063835742466, 125102.37448812788, 128806.71193288134, 129371.70628293786, 130291.34854325763, 131109.34445327808, 9806.607735690492, 10954.39750813697, 11573.854191403307, 11630.592929247605, 11811.645650630495, 14822.629434264014, 14895.553133406016, 14897.031692939925, 14915.925420372829, 14990.100593964396, 7277.59973201479, 7692.192319806451, 8249.381296402793, 8694.217520423066, 8700.695475658322, 8550.273226775793, 8587.355948303903, 8689.131086891386, 8696.04343522212, 8807.735767926972, 4510.571495707903, 4568.977155114203, 4609.985803123308, 4635.351050852883, 4690.53094690535, 1741.2512619573704, 1797.6038447665787, 1814.1745505794831, 1862.1899473728972, 1894.5547796267865, 844.8225872566707, 914.3919293669635, 943.9244860411151, 1002.9218590015762, 1009.3540134314045, 20127.39617811468, 21020.0, 21242.78757212439, 21265.68101478479, 21272.468188295108, 903.9186473217442, 913.3697748553477, 978.3054031637618, 981.2444417798398, 1025.4308858583559, 10735.409552474533, 10991.0, 11141.777164456666, 11170.553177872795, 11194.832077518844, 617356.0, 621719.0, 626376.0, 626793.0, 637062.0, 126400.0, 132899.3355033238, 135077.32461337827, 135389.0, 135787.0, 979.3487330924997, 980.2648013989508, 994.2940512236248, 994.393420013791, 996.3274789517047, 18336.81663183351, 18349.54126146857, 18385.632287354445, 18468.44594662162, 18494.815051849848, 51525.7423712879, 51843.481565183865, 52260.21609675934, 53128.468715313895, 53194.468055318954, 77178.0, 78127.0, 78433.60783196047, 79606.81572737257, 81389.0, 6443.548951573345, 6966.79099627012, 8087.757367278991, 8310.667573297242, 8534.658613655385, 10681.635284885548, 12698.873011269769, 12965.546205882749, 13050.15174013686, 13229.536966206137, 10309.639162629272, 10823.458827058457, 10829.0, 11081.27971681838, 11169.0, 8679.739607811776, 10735.248532602644, 10911.0, 11138.164637652371, 11309.547618095185, 5923.111533270044, 9121.680741174026, 10123.79752404933, 10238.744031398977, 11447.942760286145, 8106.837863242584, 8152.347812174893, 8158.673653053811, 8209.466384684976, 8446.07093219733, 8046.798830029296, 9933.552990115317, 10409.11522520564, 10946.288491248044, 11428.714282143013], "value": 9353.649779863386, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1392.8746412822843, 1510.2222355486924, 1545.8531439513247, 1787.54417623506, 1931.4398824340951, 7709.614519274042, 8457.323414126871, 8695.91304086959, 8705.65177392905, 8782.560871956393, 8498.107698691641, 8614.396992210532, 8703.43427677202, 8735.868961965576, 8747.693830715927, 2102.8317734581274, 2227.2984010036866, 2301.4131396493954, 2346.8826558672085, 2379.048380647746, 2387.797037251827, 2419.620816134804, 2423.2487928742057, 2427.647991041298, 2428.18655750323, 2333.5099629077913, 2382.17814853876, 2383.5947888858936, 2397.940051498718, 2402.8558286502866, 2075.813176814094, 2252.335561009506, 2339.2982105368405, 2368.46709490365, 2370.952580948371, 2360.7167139943144, 2387.140629373424, 2389.8446600970883, 2389.892454839536, 2390.748971358, 8440.28257598105, 8495.108013658539, 8496.70261540843, 8546.273566746833, 8546.700865469678, 5032.2703208035, 5041.798328066873, 5196.246544251062, 5203.947960520384, 5210.6613070150315, 3421.794692318469, 3487.424574945131, 3546.042568506489, 3552.520409744677, 3555.3422616815974, 998.9001099889953, 1025.4052649463292, 1026.6098882424706, 1040.287403128859, 1044.1333693034762, 11148.665540033811, 11342.432878356132, 11348.262362946465, 11375.203735738502, 11433.314001159873, 4592.265237561974, 4699.835505757282, 4776.331313616097, 4778.737169455713, 4781.139394908881, 9925.15636170919, 10009.749756256006, 10014.54934527948, 10028.598856045823, 10037.69886903394, 4530.6602004849765, 4679.3916790816975, 5350.759215835296, 5356.892862142735, 5370.785168593291, 4293.549177336397, 4440.289553671461, 4481.2381895077915, 4829.758512074382, 4833.951660483421, 3867.187890542961, 3923.6272554107886, 3931.6461518463475, 3994.6005399460405, 4050.817713202854, 1037.3309215555923, 1114.7993361194901, 1184.7452797648375, 1188.2395267029108, 1204.5964601858475, 818.7800177735251, 833.6998680475024, 891.7235656946367, 940.3323640215386, 951.9666811661558, 5520.365158006881, 5544.91682624761, 5604.495595396434, 5615.578831587647, 5839.9124013139835, 435.2187822857978, 437.8051766963671, 443.79288335727415, 446.63376031653735, 449.97300161990387, 3750.774953502799, 3819.866304679323, 3823.5220597425323, 3851.9614803852164, 3880.41793730937, 10145.08694217524, 10529.684109476728, 10639.574417023387, 10651.520681569193, 10651.73370665724, 9835.409875407498, 10388.948055259676, 10569.365838049742, 10580.629677961235, 10598.25812193139, 6242.344553821874, 7992.960035199787, 8060.435769496196, 8150.551719655476, 8152.551609661526, 5751.654900705971, 5775.884482310246, 5942.970285148546, 5981.1028345746745, 6024.337322894566, 9385.624575016924, 9456.432614043179, 9971.850422243528, 9984.900150998397, 9988.850167247643, 9485.383450075724, 9957.004299569984, 9970.40177589347, 10147.594096236067, 10173.84739228927, 6616.735330586723, 7218.422526197996, 7376.852462950818, 7492.737754178577, 7556.508826926232, 8257.05043919957, 8279.172082791672, 8315.916840831514, 8330.125336839787, 8366.0, 7204.71181152748, 7247.456440767069, 7278.199398066315, 7408.0, 7451.962740186264, 6910.792676219721, 7131.0, 7131.0, 7201.567905925661, 7224.096987876515, 6765.154355705537, 6880.105586273952, 6880.518363714492, 6884.51808373409, 6899.103116594811, 4381.53993830656, 4688.413948256468, 4761.381020467317, 4889.9022019560125, 4921.63087768426, 4824.927626085681, 4841.636877234292, 4889.217725163957, 4894.412670479565, 4946.530079642428], "value": 4406.229620987369, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.8070825026108, 303.9073082709774, 317.61251273446396, 321.8760777100816, 321.90825614699816, 4300.8709738707885, 4680.859574212779, 5485.670859748418, 5510.641808282464, 5531.419200983894, 4621.722696638196, 4672.649551283659, 5233.345831771028, 5369.067135831097, 5384.596155288359, 3712.350338690718, 4156.896077598054, 4159.604837540444, 4160.292750232467, 4180.3520454329555, 3701.666849983501, 3725.627437256266, 3800.8099595020276, 3987.541432735229, 4095.0858969237033, 3401.727861771052, 3606.9639303607023, 3930.3121953658137, 4043.6360727534516, 4099.770068979295, 3614.331348700499, 4071.857484988026, 4111.383292506118, 4140.730852494578, 4182.247195504808, 3600.72994525409, 3643.4716966039778, 3643.635636436362, 3649.178934739689, 3671.880076576643, 4011.097503061817, 4862.984949331437, 5067.746612669352, 5114.565261952738, 5165.199394093921, 4669.6731228814, 4689.9765501172615, 4719.9292010619865, 4732.692374995614, 4742.786574604151, 5626.859328516779, 5679.829605111853, 5680.943190568083, 5709.543236541108, 5734.770609175628, 3594.023287192034, 3730.776153430803, 3936.4095385692376, 3950.980245098785, 3956.3867600521676, 4792.952070479286, 5108.4125325587665, 5858.179854820329, 5948.40515948406, 5981.431763982415, 3444.345574340867, 3902.297586434412, 4047.919041619151, 4051.493563304587, 4064.3700226464944, 3398.6431424700545, 3698.7965661888315, 4023.4970628671417, 4103.425520427142, 4118.4671427072135, 3950.064468410458, 4097.1191193893455, 4375.47494300686, 4648.349231107648, 4671.04243630055, 4626.537346265414, 5789.681567513827, 5813.82558523245, 5823.359430462646, 5880.8235752927485, 1238.6717519857245, 1270.8157317188957, 1292.7414517096618, 1329.1891945912985, 1358.395513996282, 831.1771346367053, 876.6324533727405, 910.3354551177678, 919.7792529792802, 921.4885738415244, 5292.353823088456, 5293.444188360244, 5299.258103865462, 5303.15149576066, 5306.389765176977, 5826.504747096459, 5826.708664566755, 5839.0, 5844.298684157929, 5873.647581145145, 4690.742009189527, 4958.206686930074, 5265.3944796348915, 5281.603879709037, 5287.814926477555, 2416.66166736657, 2458.139651122093, 2620.6724159480063, 2629.3303752117163, 2633.0126202673996, 4259.063006138645, 4389.385486031958, 4698.613908896884, 5101.744912754348, 5178.56607394524, 4589.862304130881, 4678.929816052762, 4720.929186062212, 5330.893382132335, 5997.4119798354295, 6147.170132032208, 6203.2245969253845, 6208.627482351073, 6215.720292586844, 6229.657368844757, 5657.575681823962, 5948.0, 5981.7308221131025, 5982.7307771149535, 6027.909581356371, 5083.644144909968, 5194.7922083116255, 5863.501602363676, 5867.5305975521005, 5928.318243401892, 5740.68426236561, 6188.628682279078, 6204.937950620617, 6206.727620837808, 6242.659002873922, 3277.3281477297573, 3897.8111675939113, 3943.82252798619, 3951.3085210087543, 3965.464662270672, 4771.284307353924, 5009.824656137018, 5117.2068329409, 5778.942210578009, 5934.584579079596, 4547.795349209359, 5211.374635043819, 5632.746526406403, 5729.252577963761, 5774.711264436845, 5290.550303224269, 5724.883647688661, 5732.484076433141, 5762.481376676119, 5777.566682498746, 5132.5124113209185, 5376.301080859464, 5663.603547751618, 5667.574931880208, 5667.659940403589, 4869.222733702244, 5479.369872464717, 5604.271444712162, 5711.772408760669, 5758.942410575841, 5197.8440646780655, 5618.769207705667, 5655.830325090254, 5671.347795003628, 5673.489385955284], "value": 4089.3361953729154, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.934206579342, 680.1905732178707, 693.4798900824381, 721.9964249692928, 736.929991650793, 15029.473968411108, 15372.769408458867, 15609.609759756011, 15631.609209769733, 15656.452024179142, 13996.300184990761, 14290.999630025903, 14330.0, 14345.497907573237, 14435.989480736353, 698.9720411183572, 730.4740586777511, 866.5840396609631, 883.9071897450772, 901.197933838884, 901.715959472764, 910.713125365511, 911.4804561400005, 912.1608120529096, 912.4160537256162, 928.6842473558988, 947.1239103828965, 960.1694534227878, 963.1764841060897, 965.8213230552335, 741.0847603210032, 907.2333877873186, 922.8523436250168, 949.3354651743807, 953.2374100719431, 705.8552996635677, 706.4030893894702, 707.2785758526275, 708.9967695710563, 709.6664567653195, 14620.561383158521, 14696.26518674062, 14719.779203311959, 14767.335469903881, 14770.778438323434, 11706.0, 12185.878141218564, 12451.066170037284, 12590.496380144783, 12625.0, 10716.464176791207, 11015.0, 11069.225154239206, 11177.385243811586, 11552.249640763568, 4007.158496715693, 4034.132661477767, 4067.715259931806, 4175.498940127174, 4216.7680777557225, 82699.3460130794, 83867.5806620963, 83952.58023709903, 84366.57816710939, 84656.73014904781, 9173.816523669622, 9354.064593540728, 9365.0, 9390.62437502506, 9529.714108576754, 19502.219911203676, 20228.393148205578, 20252.493687657927, 20253.189872405233, 20262.98685065741, 6781.6609169541325, 6827.726890924308, 6983.196932352743, 7002.894956575656, 7036.894446583306, 3494.807785571767, 3987.1626958338493, 4030.7984600769846, 4067.5525692173837, 4136.8138433770555, 3316.701496865294, 3376.6623337666038, 3439.8796042138906, 3444.5866496020135, 3446.6553344665836, 583.3553922915203, 591.5563327504372, 599.430540986065, 610.1579819848669, 631.823089534931, 496.89813588214327, 509.6789022915539, 559.2673597587093, 567.9062954612442, 572.7222297185862, 7978.401619878532, 8055.919440805634, 8114.229148230908, 8242.917570824215, 8392.16078392156, 517.7928828468642, 562.9155626656035, 599.1252770954368, 603.9033754599243, 604.1391017799618, 3631.9818400908357, 3887.676066868035, 3944.3886197639413, 3970.2456533258587, 3977.284088864032, 82686.17313826905, 96714.51642741742, 97560.0243997551, 97780.02219977851, 98213.0, 77319.0, 80075.59962200269, 83448.0, 83965.0, 84932.30135397258, 75637.62181189058, 75702.62148689221, 76564.61717691488, 76696.61651691818, 77472.61263693645, 7097.787066388017, 7563.205863384485, 7818.765437036898, 7841.372690184658, 8049.597520124087, 37586.24827503489, 38730.61269387383, 39022.4146637795, 39166.41250381304, 39382.81851544458, 50448.73878153075, 56952.0, 58371.4162858383, 62302.37697622966, 63567.91456683362, 10708.785824283315, 11136.777264454828, 11296.717582060513, 11449.0, 11456.541738330374, 12090.637280881587, 12196.085293603193, 12385.133040687426, 12421.0, 13038.739225215631, 11376.919192676682, 11502.769944601228, 11736.647900562997, 11898.464569094238, 11941.104417168573, 11553.590940697195, 11712.70718232017, 11715.648530544097, 11795.705107372385, 11873.228240164362, 11013.724656883642, 11092.33445993243, 11150.665480035612, 11165.665030049111, 11339.546418143182, 9052.456852588866, 9379.484128373006, 9546.570404331684, 9610.471424071744, 9630.2777291702, 9568.76078098053, 9596.424214547149, 9617.51912404363, 9684.225261979165, 9833.901660983585], "value": 6814.509746452036, "unit": "score"}], "name": "dromaeo_css", "value": 7144.571202431698, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 111.0, 112.0, 111.0, 112.0, 111.0, 111.0, 112.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 117.0, 119.0, 200.0, 117.0, 118.0, 141.0, 116.0, 152.0, 120.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 189.0, 183.0, 184.0, 186.0, 184.0, 183.0, 182.0, 186.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 61.0, 120.0, 57.0, 56.0, 64.0, 56.0, 98.0, 56.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 76.0, 142.0, 78.0, 83.0, 76.0, 103.0, 79.0, 76.0, 109.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 89.0, 86.0, 134.0, 87.0, 89.0, 86.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 98.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 81.0, 87.0, 86.0, 85.0, 84.0, 84.0, 88.0, 87.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 59.0, 57.0, 58.0, 56.0, 56.0, 117.0, 55.0, 55.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 74.0, 72.0, 77.0, 72.0, 71.0, 73.0, 71.0, 73.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 85.0, 99.0, 98.0, 84.0, 84.0, 83.0, 85.0, 84.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 137.0, 138.0, 143.0, 142.0, 135.0, 135.0, 214.0, 139.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 163.0, 167.0, 166.0, 164.0, 168.0, 166.0, 161.0, 167.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 77.0, 67.0, 65.0, 65.0, 67.0, 84.0, 83.0, 82.0], "value": 72.4, "unit": "score"}], "name": "kraken", "value": 1471.0000000000002, "alertThreshold": 2.0}]} 15:32:19 INFO - Return code: 0 15:32:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:32:19 ERROR - # TBPL SUCCESS # 15:32:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:32:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:32:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:32:19 INFO - 2016-05-23 15:32:19.438 screenresolution[6494:160522] starting screenresolution argv=screenresolution get 15:32:19 INFO - 2016-05-23 15:32:19.440 screenresolution[6494:160522] Display 0: 1600x1200x32@60 15:32:19 INFO - 2016-05-23 15:32:19.446 screenresolution[6495:160525] starting screenresolution argv=screenresolution list 15:32:19 INFO - Available Modes on Display 0 15:32:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:32:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:32:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:32:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:32:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:32:19 INFO - Intel Iris: 15:32:19 INFO - Chipset Model: Intel Iris 15:32:19 INFO - Type: GPU 15:32:19 INFO - Bus: Built-In 15:32:19 INFO - VRAM (Dynamic, Max): 1536 MB 15:32:19 INFO - Vendor: Intel (0x8086) 15:32:19 INFO - Device ID: 0x0a2e 15:32:19 INFO - Revision ID: 0x0009 15:32:19 INFO - Displays: 15:32:19 INFO - Display: 15:32:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:32:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:32:19 INFO - Display Serial Number: 1600x1200 60 15:32:19 INFO - Main Display: Yes 15:32:19 INFO - Mirror: Off 15:32:19 INFO - Online: Yes 15:32:19 INFO - Rotation: Supported 15:32:19 INFO - Return code: 0 15:32:19 INFO - Running post-action listener: _resource_record_post_action 15:32:19 INFO - ##### 15:32:19 INFO - ##### Finished run-tests step (success) 15:32:19 INFO - ##### 15:32:19 INFO - Running post-run listener: _resource_record_post_run 15:32:19 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 0; Write bytes: 694511616; Read time: 0; Write time: 1934 15:32:19 INFO - TinderboxPrint: CPU usage
54.0% 15:32:19 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:32:19 INFO - TinderboxPrint: I/O write bytes / time
694,511,616 / 1,934 15:32:19 INFO - TinderboxPrint: CPU idle
2,226.8 (46.0%) 15:32:19 INFO - TinderboxPrint: CPU system
975.1 (20.1%) 15:32:19 INFO - TinderboxPrint: CPU user
1,643.8 (33.9%) 15:32:19 INFO - TinderboxPrint: Swap in / out
395,587,584 / 0 15:32:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 377403904; Write bytes: 371230720; Read time: 28159; Write time: 785 15:32:19 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 0; Write bytes: 296218624; Read time: 0; Write time: 1081 15:32:20 INFO - Running post-run listener: _upload_blobber_files 15:32:20 INFO - Blob upload gear active. 15:32:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:32:20 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 15:32:20 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'] 15:32:20 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 15:32:20 INFO - (blobuploader) - INFO - Open directory for files ... 15:32:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:32:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:32:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:21 INFO - (blobuploader) - INFO - Done attempting. 15:32:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:32:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:32:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:22 INFO - (blobuploader) - INFO - Done attempting. 15:32:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:32:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:32:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:24 INFO - (blobuploader) - INFO - Done attempting. 15:32:24 INFO - (blobuploader) - INFO - Iteration through files over. 15:32:24 INFO - Return code: 0 15:32:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:32:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:32:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3b04703572998cc22b623f498d400c22b89c1b649ec011fb29cf0ac6d6092f6efa19fc383c98b912d729cc287e07f6aa8756c05a50da7b5e7c69af469f59aa84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/241f5a8b67d7006783126f9457b0ef18d6cc8ca51c16ac26689d1633cc3ef6d5e41b3c2578439c43268fb8c4747c52693326e11dc1e42531c15d23be67ad393b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f74546215c33da0f496b0a5a0aea5239487125d5032373d4456f5c0a2d36f85d8026af13cbf44332f43c8a134c286e4420196db85b2f63901d43dbb0d908ac18"} 15:32:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:32:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:32:24 INFO - Contents: 15:32:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3b04703572998cc22b623f498d400c22b89c1b649ec011fb29cf0ac6d6092f6efa19fc383c98b912d729cc287e07f6aa8756c05a50da7b5e7c69af469f59aa84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/241f5a8b67d7006783126f9457b0ef18d6cc8ca51c16ac26689d1633cc3ef6d5e41b3c2578439c43268fb8c4747c52693326e11dc1e42531c15d23be67ad393b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f74546215c33da0f496b0a5a0aea5239487125d5032373d4456f5c0a2d36f85d8026af13cbf44332f43c8a134c286e4420196db85b2f63901d43dbb0d908ac18"} 15:32:24 INFO - Running post-run listener: copy_logs_to_upload_dir 15:32:24 INFO - Copying logs to upload dir... 15:32:24 INFO - mkdir: /builds/slave/test/build/upload/logs 15:32:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.033499 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-23 15:32:24.654211) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 15:32:24.657426) ========= 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.faWIfklJcx/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.8f3uXUui34/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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/3b04703572998cc22b623f498d400c22b89c1b649ec011fb29cf0ac6d6092f6efa19fc383c98b912d729cc287e07f6aa8756c05a50da7b5e7c69af469f59aa84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/241f5a8b67d7006783126f9457b0ef18d6cc8ca51c16ac26689d1633cc3ef6d5e41b3c2578439c43268fb8c4747c52693326e11dc1e42531c15d23be67ad393b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f74546215c33da0f496b0a5a0aea5239487125d5032373d4456f5c0a2d36f85d8026af13cbf44332f43c8a134c286e4420196db85b2f63901d43dbb0d908ac18"} build_url:https://queue.taskcluster.net/v1/task/V2-j6-XhQ5-s7FlM14-3OA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/V2-j6-XhQ5-s7FlM14-3OA/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/3b04703572998cc22b623f498d400c22b89c1b649ec011fb29cf0ac6d6092f6efa19fc383c98b912d729cc287e07f6aa8756c05a50da7b5e7c69af469f59aa84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/241f5a8b67d7006783126f9457b0ef18d6cc8ca51c16ac26689d1633cc3ef6d5e41b3c2578439c43268fb8c4747c52693326e11dc1e42531c15d23be67ad393b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f74546215c33da0f496b0a5a0aea5239487125d5032373d4456f5c0a2d36f85d8026af13cbf44332f43c8a134c286e4420196db85b2f63901d43dbb0d908ac18"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 15:32:24.692116) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:32:24.692510) ========= 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.faWIfklJcx/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.8f3uXUui34/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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.004840 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:32:24.741819) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 15:32:24.742221) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 15:32:24.742691) ========= ========= Total master_lag: 0.18 =========