builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0107 starttime: 1464956949.11 results: success (0) buildid: 20160603033811 builduid: c57d571a48bc47a6877d9d4832f08942 revision: 4874ff5d90f81aca0bb57d2f74586210cbee3cf6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:09.114531) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:09.114950) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:09.115243) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:09.141330) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:09.141606) ========= 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.N1Hgt5mPKk/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.ZPhG67o444/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-03 05:29:09-- 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% 17.6M=0.001s 2016-06-03 05:29:09 (17.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.571054 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:09.733312) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:09.733640) ========= 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.N1Hgt5mPKk/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.ZPhG67o444/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.028033 ========= master_lag: 0.13 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:09.889849) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:09.890199) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4874ff5d90f81aca0bb57d2f74586210cbee3cf6 --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 4874ff5d90f81aca0bb57d2f74586210cbee3cf6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.N1Hgt5mPKk/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.ZPhG67o444/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-03 05:29:09,966 truncating revision to first 12 chars 2016-06-03 05:29:09,966 Setting DEBUG logging. 2016-06-03 05:29:09,966 attempt 1/10 2016-06-03 05:29:09,966 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4874ff5d90f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 05:29:10,608 unpacking tar archive at: fx-team-4874ff5d90f8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.902268 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:10.812360) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:10.812697) ========= script_repo_revision: 4874ff5d90f81aca0bb57d2f74586210cbee3cf6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:10.813145) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:10.813416) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 05:29:10.831180) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-06-03 05:29:10.831454) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.N1Hgt5mPKk/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.ZPhG67o444/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:29:11 INFO - MultiFileLogger online at 20160603 05:29:11 in /builds/slave/test 05:29:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:29:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:29:11 INFO - {'append_to_log': False, 05:29:11 INFO - 'base_work_dir': '/builds/slave/test', 05:29:11 INFO - 'blob_upload_branch': 'Fx-Team', 05:29:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:29:11 INFO - 'branch': 'Fx-Team', 05:29:11 INFO - 'buildbot_json_path': 'buildprops.json', 05:29:11 INFO - 'config_files': ('talos/mac_config.py',), 05:29:11 INFO - 'default_actions': ('clobber', 05:29:11 INFO - 'read-buildbot-config', 05:29:11 INFO - 'download-and-extract', 05:29:11 INFO - 'populate-webroot', 05:29:11 INFO - 'create-virtualenv', 05:29:11 INFO - 'install', 05:29:11 INFO - 'run-tests'), 05:29:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:29:11 INFO - 'download_minidump_stackwalk': True, 05:29:11 INFO - 'download_symbols': 'ondemand', 05:29:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:29:11 INFO - 'tooltool.py': '/tools/tooltool.py', 05:29:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:29:11 INFO - '/tools/misc-python/virtualenv.py')}, 05:29:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:29:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:29:11 INFO - 'installer_path': 'installer.exe', 05:29:11 INFO - 'log_level': 'info', 05:29:11 INFO - 'log_name': 'talos', 05:29:11 INFO - 'log_to_console': True, 05:29:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:29:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:29:11 INFO - 'opt_config_files': (), 05:29:11 INFO - 'pip_index': False, 05:29:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:29:11 INFO - 'cmd': ('bash', 05:29:11 INFO - '-c', 05:29:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:29:11 INFO - 'enabled': True, 05:29:11 INFO - 'halt_on_failure': False, 05:29:11 INFO - 'name': 'check_screen_resolution'},), 05:29:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:29:11 INFO - 'cmd': ('bash', 05:29:11 INFO - '-c', 05:29:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:29:11 INFO - 'enabled': True, 05:29:11 INFO - 'halt_on_failure': False, 05:29:11 INFO - 'name': 'check_screen_resolution'},), 05:29:11 INFO - 'run_cmd_checks_enabled': True, 05:29:11 INFO - 'sps_profile': False, 05:29:11 INFO - 'sps_profile_interval': 0, 05:29:11 INFO - 'suite': 'dromaeojs-e10s', 05:29:11 INFO - 'system_bits': '32', 05:29:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:29:11 INFO - 'title': 't-yosemite-r7-0107', 05:29:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:29:11 INFO - 'use_talos_json': True, 05:29:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:29:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:29:11 INFO - 'work_dir': 'build'} 05:29:11 INFO - ##### 05:29:11 INFO - ##### Running clobber step. 05:29:11 INFO - ##### 05:29:11 INFO - Running pre-action listener: _resource_record_pre_action 05:29:11 INFO - Running main action method: clobber 05:29:11 INFO - rmtree: /builds/slave/test/build 05:29:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:29:12 INFO - Running post-action listener: _resource_record_post_action 05:29:12 INFO - ##### 05:29:12 INFO - ##### Finished clobber step (success) 05:29:12 INFO - ##### 05:29:12 INFO - ##### 05:29:12 INFO - ##### Running read-buildbot-config step. 05:29:12 INFO - ##### 05:29:12 INFO - Running pre-action listener: _resource_record_pre_action 05:29:12 INFO - Running main action method: read_buildbot_config 05:29:12 INFO - Using buildbot properties: 05:29:12 INFO - { 05:29:12 INFO - "project": "", 05:29:12 INFO - "product": "firefox", 05:29:12 INFO - "script_repo_revision": "production", 05:29:12 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:29:12 INFO - "repository": "", 05:29:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:29:12 INFO - "buildid": "20160603033811", 05:29:12 INFO - "pgo_build": "False", 05:29:12 INFO - "basedir": "/builds/slave/test", 05:29:12 INFO - "buildnumber": 687, 05:29:12 INFO - "slavename": "t-yosemite-r7-0107", 05:29:12 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:29:12 INFO - "platform": "yosemite_r7", 05:29:12 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:29:12 INFO - "branch": "fx-team", 05:29:12 INFO - "repo_path": "integration/fx-team", 05:29:12 INFO - "revision": "4874ff5d90f81aca0bb57d2f74586210cbee3cf6", 05:29:12 INFO - "stage_platform": "macosx64", 05:29:12 INFO - "builduid": "c57d571a48bc47a6877d9d4832f08942", 05:29:12 INFO - "slavebuilddir": "test" 05:29:12 INFO - } 05:29:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:29:12 INFO - Running post-action listener: _resource_record_post_action 05:29:12 INFO - ##### 05:29:12 INFO - ##### Finished read-buildbot-config step (success) 05:29:12 INFO - ##### 05:29:12 INFO - ##### 05:29:12 INFO - ##### Running download-and-extract step. 05:29:12 INFO - ##### 05:29:12 INFO - Running pre-action listener: _resource_record_pre_action 05:29:12 INFO - Running main action method: download_and_extract 05:29:12 INFO - mkdir: /builds/slave/test/build/tests 05:29: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')]} 05:29:12 INFO - https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:29:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:29:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:29:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/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 05:29:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/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 05:29:16 INFO - Downloaded 1199 bytes. 05:29:16 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:29:16 INFO - Using the following test package requirements: 05:29:16 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:29:16 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:29:16 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:29:16 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:29:16 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:29:16 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:29:16 INFO - u'jsshell-mac.zip'], 05:29:16 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:29:16 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:29:16 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:29:16 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:29:16 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:29:16 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:29:16 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:29:16 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:29:16 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:29:16 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:29:16 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:29:16 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:29:16 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')]} 05:29:16 INFO - https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:29:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:29:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:29:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/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 05:29:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/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 05:29:18 INFO - Downloaded 18713579 bytes. 05:29:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:29:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:29:21 INFO - Return code: 0 05:29:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:21 INFO - https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:29:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:29:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:29:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/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 05:29:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/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 05:29:24 INFO - Downloaded 18713579 bytes. 05:29:24 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'] 05:29:24 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 05:29:28 INFO - Return code: 0 05:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:28 INFO - https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:29:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:29:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:29:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/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 05:29:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/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 05:29:32 INFO - Downloaded 10834055 bytes. 05:29:32 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'] 05:29:32 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 05:29:32 INFO - Return code: 0 05:29:32 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')]} 05:29:32 INFO - https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:29:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:29:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:29:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:29:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IFkmDtNETaWukOs7Mi72Rg/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 05:29:40 INFO - Downloaded 111083490 bytes. 05:29:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:29:40 INFO - mkdir: /builds/slave/test/properties 05:29:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:29:40 INFO - Writing to file /builds/slave/test/properties/build_url 05:29:40 INFO - Contents: 05:29:40 INFO - build_url:https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:29:41 INFO - Running post-action listener: _resource_record_post_action 05:29:41 INFO - Running post-action listener: set_extra_try_arguments 05:29:41 INFO - ##### 05:29:41 INFO - ##### Finished download-and-extract step (success) 05:29:41 INFO - ##### 05:29:41 INFO - ##### 05:29:41 INFO - ##### Running populate-webroot step. 05:29:41 INFO - ##### 05:29:41 INFO - Running pre-action listener: _resource_record_pre_action 05:29:41 INFO - Running main action method: populate_webroot 05:29:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:29:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:29:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:29:41 INFO - '--tppagecycles', 05:29:41 INFO - '7'], 05:29:41 INFO - 'tests': ['tsvgm']}}, 05:29:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:29:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:29:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:29:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:29:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:29:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:29:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:41 INFO - 'talos_options': ['--disable-e10s'], 05:29:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:41 INFO - 'talos_options': ['--disable-e10s'], 05:29:41 INFO - 'tests': ['damp', 'tps']}, 05:29:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:41 INFO - 'tests': ['damp', 'tps']}, 05:29:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:29:41 INFO - 'tests': ['dromaeo_dom']}, 05:29:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:29:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:29:41 INFO - 'tests': ['a11yr', 05:29:41 INFO - 'ts_paint', 05:29:41 INFO - 'tpaint', 05:29:41 INFO - 'sessionrestore', 05:29:41 INFO - 'sessionrestore_no_auto_restore', 05:29:41 INFO - 'tabpaint']}, 05:29:41 INFO - 'other-e10s': {'tests': ['a11yr', 05:29:41 INFO - 'ts_paint', 05:29:41 INFO - 'tpaint', 05:29:41 INFO - 'sessionrestore', 05:29:41 INFO - 'sessionrestore_no_auto_restore', 05:29:41 INFO - 'tabpaint']}, 05:29:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:29:41 INFO - 'ts_paint', 05:29:41 INFO - 'tpaint', 05:29:41 INFO - 'sessionrestore', 05:29:41 INFO - 'sessionrestore_no_auto_restore', 05:29:41 INFO - 'tabpaint']}, 05:29:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:29:41 INFO - 'ts_paint', 05:29:41 INFO - 'tpaint', 05:29:41 INFO - 'sessionrestore', 05:29:41 INFO - 'sessionrestore_no_auto_restore', 05:29:41 INFO - 'tabpaint']}, 05:29:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:29:41 INFO - 'tests': ['a11yr', 05:29:41 INFO - 'ts_paint', 05:29:41 INFO - 'tpaint', 05:29:41 INFO - 'sessionrestore', 05:29:41 INFO - 'sessionrestore_no_auto_restore', 05:29:41 INFO - 'tabpaint']}, 05:29:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:29:41 INFO - 'tests': ['a11yr', 05:29:41 INFO - 'ts_paint', 05:29:41 INFO - 'tpaint', 05:29:41 INFO - 'sessionrestore', 05:29:41 INFO - 'sessionrestore_no_auto_restore', 05:29:41 INFO - 'tabpaint']}, 05:29:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:29:41 INFO - 'tests': ['tsvgx', 05:29:41 INFO - 'tsvgr_opacity', 05:29:41 INFO - 'tart', 05:29:41 INFO - 'tscrollx', 05:29:41 INFO - 'cart']}, 05:29:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:29:41 INFO - 'tsvgr_opacity', 05:29:41 INFO - 'tart', 05:29:41 INFO - 'tscrollx', 05:29:41 INFO - 'cart']}, 05:29:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:41 INFO - 'talos_options': ['--disable-e10s'], 05:29:41 INFO - 'tests': ['tp5o']}, 05:29:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:41 INFO - 'tests': ['tp5o']}, 05:29:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:41 INFO - 'talos_options': ['--disable-e10s', 05:29:41 INFO - '--xperf_path', 05:29:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:41 INFO - 'tests': ['tp5n']}, 05:29:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:41 INFO - 'talos_options': ['--xperf_path', 05:29:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:41 INFO - 'tests': ['tp5n']}}, 05:29:41 INFO - 'talos.zip': {'path': '', 05:29:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:29:41 INFO - Running post-action listener: _resource_record_post_action 05:29:41 INFO - ##### 05:29:41 INFO - ##### Finished populate-webroot step (success) 05:29:41 INFO - ##### 05:29:41 INFO - ##### 05:29:41 INFO - ##### Running create-virtualenv step. 05:29:41 INFO - ##### 05:29:41 INFO - Running pre-action listener: _resource_record_pre_action 05:29:41 INFO - Running main action method: create_virtualenv 05:29:41 INFO - Creating virtualenv /builds/slave/test/build/venv 05:29:41 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 05:29:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:29:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:29:41 INFO - Using real prefix '/tools/python27' 05:29:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:29:42 INFO - Installing distribute.............................................................................................................................................................................................done. 05:29:45 INFO - Installing pip.................done. 05:29:45 INFO - Return code: 0 05:29:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc605a5560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13fd30>, '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 0x10df63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e136b20>, '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.N1Hgt5mPKk/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.ZPhG67o444/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:29:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N1Hgt5mPKk/Render', 05:29:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:45 INFO - 'HOME': '/Users/cltbld', 05:29:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:45 INFO - 'LOGNAME': 'cltbld', 05:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:45 INFO - 'MOZ_NO_REMOTE': '1', 05:29:45 INFO - 'NO_EM_RESTART': '1', 05:29:45 INFO - 'PAGER': '/bin/cat', 05:29:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:45 INFO - 'PWD': '/builds/slave/test', 05:29:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:45 INFO - 'SHELL': '/bin/bash', 05:29:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZPhG67o444/Listeners', 05:29:45 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:29:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:45 INFO - 'USER': 'cltbld', 05:29:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:45 INFO - 'XPC_FLAGS': '0x0', 05:29:45 INFO - 'XPC_SERVICE_NAME': '0', 05:29:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:45 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 05:29:45 INFO - Cleaning up... 05:29:45 INFO - Return code: 0 05:29:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e29ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc605a5560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13fd30>, '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 0x10df63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e136b20>, '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.N1Hgt5mPKk/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.ZPhG67o444/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:29:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N1Hgt5mPKk/Render', 05:29:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:45 INFO - 'HOME': '/Users/cltbld', 05:29:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:45 INFO - 'LOGNAME': 'cltbld', 05:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:45 INFO - 'MOZ_NO_REMOTE': '1', 05:29:45 INFO - 'NO_EM_RESTART': '1', 05:29:45 INFO - 'PAGER': '/bin/cat', 05:29:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:45 INFO - 'PWD': '/builds/slave/test', 05:29:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:45 INFO - 'SHELL': '/bin/bash', 05:29:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZPhG67o444/Listeners', 05:29:45 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:29:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:45 INFO - 'USER': 'cltbld', 05:29:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:45 INFO - 'XPC_FLAGS': '0x0', 05:29:45 INFO - 'XPC_SERVICE_NAME': '0', 05:29:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:45 INFO - Downloading/unpacking psutil>=3.1.1 05:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:29:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:49 INFO - Installing collected packages: psutil 05:29:49 INFO - Running setup.py install for psutil 05:29:49 INFO - building 'psutil._psutil_osx' extension 05:29:49 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 05:29:50 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 05:29:50 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 05:29:50 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 05:29:50 INFO - building 'psutil._psutil_posix' extension 05:29:50 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 05:29:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:29:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:29:50 INFO - ^ 05:29:50 INFO - 1 warning generated. 05:29:50 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 05:29:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:50 INFO - Successfully installed psutil 05:29:50 INFO - Cleaning up... 05:29:50 INFO - Return code: 0 05:29:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc605a5560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13fd30>, '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 0x10df63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e136b20>, '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.N1Hgt5mPKk/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.ZPhG67o444/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:29:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N1Hgt5mPKk/Render', 05:29:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:50 INFO - 'HOME': '/Users/cltbld', 05:29:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:50 INFO - 'LOGNAME': 'cltbld', 05:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:50 INFO - 'MOZ_NO_REMOTE': '1', 05:29:50 INFO - 'NO_EM_RESTART': '1', 05:29:50 INFO - 'PAGER': '/bin/cat', 05:29:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:50 INFO - 'PWD': '/builds/slave/test', 05:29:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:50 INFO - 'SHELL': '/bin/bash', 05:29:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZPhG67o444/Listeners', 05:29:50 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:29:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:50 INFO - 'USER': 'cltbld', 05:29:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:50 INFO - 'XPC_FLAGS': '0x0', 05:29:50 INFO - 'XPC_SERVICE_NAME': '0', 05:29:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:29:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:29:53 INFO - Installing collected packages: mozsystemmonitor 05:29:53 INFO - Running setup.py install for mozsystemmonitor 05:29:53 INFO - Successfully installed mozsystemmonitor 05:29:53 INFO - Cleaning up... 05:29:53 INFO - Return code: 0 05:29:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:29: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')]} 05:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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')]} 05:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:53 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 0x10e29ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc605a5560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13fd30>, '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 0x10df63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e136b20>, '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.N1Hgt5mPKk/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.ZPhG67o444/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:29:53 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 05:29:53 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 05:29:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N1Hgt5mPKk/Render', 05:29:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:53 INFO - 'HOME': '/Users/cltbld', 05:29:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:53 INFO - 'LOGNAME': 'cltbld', 05:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:53 INFO - 'MOZ_NO_REMOTE': '1', 05:29:53 INFO - 'NO_EM_RESTART': '1', 05:29:53 INFO - 'PAGER': '/bin/cat', 05:29:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:53 INFO - 'PWD': '/builds/slave/test', 05:29:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:53 INFO - 'SHELL': '/bin/bash', 05:29:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZPhG67o444/Listeners', 05:29:53 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:29:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:53 INFO - 'USER': 'cltbld', 05:29:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:53 INFO - 'XPC_FLAGS': '0x0', 05:29:53 INFO - 'XPC_SERVICE_NAME': '0', 05:29:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:53 INFO - Downloading/unpacking blobuploader==1.2.4 05:29:53 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 05:29:53 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 05:29:53 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 05:29:53 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 05:29:53 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 05:29:53 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 05:29:56 INFO - Downloading blobuploader-1.2.4.tar.gz 05:29:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:29:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:29:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:57 INFO - Downloading docopt-0.6.1.tar.gz 05:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:29:57 INFO - Installing collected packages: blobuploader, requests, docopt 05:29:57 INFO - Running setup.py install for blobuploader 05:29:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:29:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:29:57 INFO - Running setup.py install for requests 05:29:58 INFO - Running setup.py install for docopt 05:29:58 INFO - Successfully installed blobuploader requests docopt 05:29:58 INFO - Cleaning up... 05:29:58 INFO - Return code: 0 05:29:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:58 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 0x10e29ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc605a5560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13fd30>, '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 0x10df63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e136b20>, '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.N1Hgt5mPKk/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.ZPhG67o444/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:29:58 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 05:29:58 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 05:29:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N1Hgt5mPKk/Render', 05:29:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:58 INFO - 'HOME': '/Users/cltbld', 05:29:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:58 INFO - 'LOGNAME': 'cltbld', 05:29:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:58 INFO - 'MOZ_NO_REMOTE': '1', 05:29:58 INFO - 'NO_EM_RESTART': '1', 05:29:58 INFO - 'PAGER': '/bin/cat', 05:29:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:58 INFO - 'PWD': '/builds/slave/test', 05:29:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:58 INFO - 'SHELL': '/bin/bash', 05:29:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZPhG67o444/Listeners', 05:29:58 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:29:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:58 INFO - 'USER': 'cltbld', 05:29:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:58 INFO - 'XPC_FLAGS': '0x0', 05:29:58 INFO - 'XPC_SERVICE_NAME': '0', 05:29:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:29:58 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-byMvbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:29:58 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-ZTFELk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:29:58 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-y1X2KI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:29:59 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-M_fjHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:29:59 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-D5Pteq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:29:59 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-oLiod0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:29:59 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-OxEl40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:29:59 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-LR6cGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:29:59 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-nSeXgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:29:59 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-2twmU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:29:59 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-uyr63u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:29:59 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-SQZN3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:00 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-1J41d2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:00 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-Z8VCk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:00 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-0KlEgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:00 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-pghkKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:00 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-RbZMsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:30:00 INFO - Running setup.py install for manifestparser 05:30:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:30:00 INFO - Running setup.py install for mozcrash 05:30:00 INFO - Running setup.py install for mozdebug 05:30:00 INFO - Running setup.py install for mozdevice 05:30:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:30:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:30:01 INFO - Running setup.py install for mozfile 05:30:01 INFO - Running setup.py install for mozhttpd 05:30:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:30:01 INFO - Running setup.py install for mozinfo 05:30:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:30:01 INFO - Running setup.py install for mozInstall 05:30:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:30:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:30:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:30:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:30:01 INFO - Running setup.py install for mozleak 05:30:01 INFO - Running setup.py install for mozlog 05:30:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:30:02 INFO - Running setup.py install for moznetwork 05:30:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:30:02 INFO - Running setup.py install for mozprocess 05:30:02 INFO - Running setup.py install for mozprofile 05:30:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:30:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:30:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:30:02 INFO - Running setup.py install for mozrunner 05:30:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:30:02 INFO - Running setup.py install for mozscreenshot 05:30:02 INFO - Running setup.py install for moztest 05:30:02 INFO - Running setup.py install for mozversion 05:30:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:30:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:30:03 INFO - Cleaning up... 05:30:03 INFO - Return code: 0 05:30:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:03 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 0x10e29ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc605a5560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13fd30>, '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 0x10df63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e136b20>, '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.N1Hgt5mPKk/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.ZPhG67o444/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:30:03 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 05:30:03 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 05:30:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N1Hgt5mPKk/Render', 05:30:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:03 INFO - 'HOME': '/Users/cltbld', 05:30:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:03 INFO - 'LOGNAME': 'cltbld', 05:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:03 INFO - 'MOZ_NO_REMOTE': '1', 05:30:03 INFO - 'NO_EM_RESTART': '1', 05:30:03 INFO - 'PAGER': '/bin/cat', 05:30:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:03 INFO - 'PWD': '/builds/slave/test', 05:30:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:03 INFO - 'SHELL': '/bin/bash', 05:30:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZPhG67o444/Listeners', 05:30:03 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:30:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:03 INFO - 'USER': 'cltbld', 05:30:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:03 INFO - 'XPC_FLAGS': '0x0', 05:30:03 INFO - 'XPC_SERVICE_NAME': '0', 05:30:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:03 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-yQpoQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:03 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)) 05:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:03 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-4i8ko3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:03 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)) 05:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:03 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-ZDwDqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:03 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)) 05:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:03 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-HgUTYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:03 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)) 05:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:03 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-1lv7W9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:03 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)) 05:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:03 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-OIzjMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:03 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)) 05:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:03 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-cfF0XY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:04 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)) 05:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:04 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-D7SIg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:04 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)) 05:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:04 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-A8hHwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:04 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)) 05:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:04 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-1ilSp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:04 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)) 05:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:04 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-QN0DQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:04 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)) 05:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:04 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-hLM5vu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:04 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)) 05:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:04 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-v0KZ9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:04 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)) 05:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:04 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-ausDn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:04 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)) 05:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:04 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-HKfAmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:04 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)) 05:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:04 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-2llKOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:05 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)) 05:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:05 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-WDChiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:05 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)) 05:30:05 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)) 05:30:05 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)) 05:30:05 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)) 05:30:05 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)) 05:30:05 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)) 05:30:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:30: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 05:30: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 05:30: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 05:30: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 05:30: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 05:30: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 05:30:07 INFO - Downloading blessings-1.6.tar.gz 05:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:30:07 INFO - Installing collected packages: blessings 05:30:07 INFO - Running setup.py install for blessings 05:30:07 INFO - Successfully installed blessings 05:30:07 INFO - Cleaning up... 05:30:07 INFO - Return code: 0 05:30:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:30:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:08 INFO - Reading from file tmpfile_stdout 05:30:08 INFO - Current package versions: 05:30:08 INFO - blessings == 1.6 05:30:08 INFO - blobuploader == 1.2.4 05:30:08 INFO - docopt == 0.6.1 05:30:08 INFO - manifestparser == 1.1 05:30:08 INFO - mozInstall == 1.12 05:30:08 INFO - mozcrash == 0.17 05:30:08 INFO - mozdebug == 0.1 05:30:08 INFO - mozdevice == 0.48 05:30:08 INFO - mozfile == 1.2 05:30:08 INFO - mozhttpd == 0.7 05:30:08 INFO - mozinfo == 0.9 05:30:08 INFO - mozleak == 0.1 05:30:08 INFO - mozlog == 3.2 05:30:08 INFO - moznetwork == 0.27 05:30:08 INFO - mozprocess == 0.22 05:30:08 INFO - mozprofile == 0.28 05:30:08 INFO - mozrunner == 6.11 05:30:08 INFO - mozscreenshot == 0.1 05:30:08 INFO - mozsystemmonitor == 0.3 05:30:08 INFO - moztest == 0.7 05:30:08 INFO - mozversion == 1.4 05:30:08 INFO - psutil == 3.1.1 05:30:08 INFO - requests == 1.2.3 05:30:08 INFO - wsgiref == 0.1.2 05:30:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:08 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')]} 05:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:08 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')]} 05:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:08 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 0x10e29ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc605a5560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13fd30>, '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 0x10df63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e136b20>, '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.N1Hgt5mPKk/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.ZPhG67o444/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:30:08 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 05:30:08 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 05:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N1Hgt5mPKk/Render', 05:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:08 INFO - 'HOME': '/Users/cltbld', 05:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:08 INFO - 'LOGNAME': 'cltbld', 05:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:08 INFO - 'MOZ_NO_REMOTE': '1', 05:30:08 INFO - 'NO_EM_RESTART': '1', 05:30:08 INFO - 'PAGER': '/bin/cat', 05:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:08 INFO - 'PWD': '/builds/slave/test', 05:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:08 INFO - 'SHELL': '/bin/bash', 05:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZPhG67o444/Listeners', 05:30:08 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:08 INFO - 'USER': 'cltbld', 05:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:08 INFO - 'XPC_FLAGS': '0x0', 05:30:08 INFO - 'XPC_SERVICE_NAME': '0', 05:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:08 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)) 05:30:08 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)) 05:30:08 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)) 05:30:08 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)) 05:30:08 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)) 05:30:08 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)) 05:30:08 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)) 05:30:08 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)) 05:30:08 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)) 05:30:08 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)) 05:30:08 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)) 05:30:08 INFO - Cleaning up... 05:30:08 INFO - Return code: 0 05:30:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:30:08 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')]} 05:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:08 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')]} 05:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:08 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 0x10e29ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc605a5560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13fd30>, '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 0x10df63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e136b20>, '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.N1Hgt5mPKk/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.ZPhG67o444/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:30:08 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 05:30:08 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 05:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N1Hgt5mPKk/Render', 05:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:08 INFO - 'HOME': '/Users/cltbld', 05:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:08 INFO - 'LOGNAME': 'cltbld', 05:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:08 INFO - 'MOZ_NO_REMOTE': '1', 05:30:08 INFO - 'NO_EM_RESTART': '1', 05:30:08 INFO - 'PAGER': '/bin/cat', 05:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:08 INFO - 'PWD': '/builds/slave/test', 05:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:08 INFO - 'SHELL': '/bin/bash', 05:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZPhG67o444/Listeners', 05:30:08 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:08 INFO - 'USER': 'cltbld', 05:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:08 INFO - 'XPC_FLAGS': '0x0', 05:30:08 INFO - 'XPC_SERVICE_NAME': '0', 05:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:08 INFO - Downloading/unpacking jsonschema 05:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:30:11 INFO - Downloading/unpacking functools32 (from jsonschema) 05:30:11 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 05:30:11 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 05:30:11 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 05:30:11 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 05:30:11 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 05:30:11 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 05:30:11 INFO - Downloading functools32-3.2.3-2.tar.gz 05:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:30:11 INFO - warning: no files found matching '*.txt' 05:30:11 INFO - no previously-included directories found matching 'build' 05:30:11 INFO - no previously-included directories found matching 'dist' 05:30:11 INFO - no previously-included directories found matching '.git*' 05:30:11 INFO - Installing collected packages: jsonschema, functools32 05:30:11 INFO - Running setup.py install for functools32 05:30:11 INFO - warning: no files found matching '*.txt' 05:30:11 INFO - no previously-included directories found matching 'build' 05:30:11 INFO - no previously-included directories found matching 'dist' 05:30:11 INFO - no previously-included directories found matching '.git*' 05:30:11 INFO - Successfully installed jsonschema functools32 05:30:11 INFO - Cleaning up... 05:30:11 INFO - Return code: 0 05:30:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:11 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 0x10e29ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc605a5560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13fd30>, '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 0x10df63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e136b20>, '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.N1Hgt5mPKk/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.ZPhG67o444/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:30:11 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 05:30:11 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 05:30:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N1Hgt5mPKk/Render', 05:30:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:11 INFO - 'HOME': '/Users/cltbld', 05:30:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:11 INFO - 'LOGNAME': 'cltbld', 05:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:11 INFO - 'MOZ_NO_REMOTE': '1', 05:30:11 INFO - 'NO_EM_RESTART': '1', 05:30:11 INFO - 'PAGER': '/bin/cat', 05:30:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:11 INFO - 'PWD': '/builds/slave/test', 05:30:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:11 INFO - 'SHELL': '/bin/bash', 05:30:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZPhG67o444/Listeners', 05:30:11 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:30:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:11 INFO - 'USER': 'cltbld', 05:30:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:11 INFO - 'XPC_FLAGS': '0x0', 05:30:11 INFO - 'XPC_SERVICE_NAME': '0', 05:30:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:12 INFO - Downloading/unpacking flake8 05:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:30:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:30:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:30:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:30:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:30:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:30:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:30:15 INFO - no previously-included directories found matching 'docs/_build' 05:30:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:30:16 INFO - Running setup.py install for pep8 05:30:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:30:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:30:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:30:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:30:16 INFO - no previously-included directories found matching 'docs/_build' 05:30:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:30:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:30:16 INFO - Cleaning up... 05:30:16 INFO - Return code: 0 05:30:16 INFO - Running post-action listener: _resource_record_post_action 05:30:16 INFO - Running post-action listener: _start_resource_monitoring 05:30:16 INFO - Starting resource monitoring. 05:30:16 INFO - ##### 05:30:16 INFO - ##### Finished create-virtualenv step (success) 05:30:16 INFO - ##### 05:30:16 INFO - ##### 05:30:16 INFO - ##### Running install step. 05:30:16 INFO - ##### 05:30:16 INFO - Running pre-action listener: _resource_record_pre_action 05:30:16 INFO - Running main action method: install 05:30:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:16 INFO - Reading from file tmpfile_stdout 05:30:16 INFO - Detecting whether we're running mozinstall >=1.0... 05:30:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:30:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:30:16 INFO - Reading from file tmpfile_stdout 05:30:16 INFO - Output received: 05:30:16 INFO - Usage: mozinstall [options] installer 05:30:16 INFO - Options: 05:30:16 INFO - -h, --help show this help message and exit 05:30:16 INFO - -d DEST, --destination=DEST 05:30:16 INFO - Directory to install application into. [default: 05:30:16 INFO - "/builds/slave/test"] 05:30:16 INFO - --app=APP Application being installed. [default: firefox] 05:30:16 INFO - mkdir: /builds/slave/test/build/application 05:30:16 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'] 05:30:16 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 05:30:51 INFO - Reading from file tmpfile_stdout 05:30:51 INFO - Output received: 05:30:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:30:51 INFO - Running post-action listener: _resource_record_post_action 05:30:51 INFO - ##### 05:30:51 INFO - ##### Finished install step (success) 05:30:51 INFO - ##### 05:30:51 INFO - ##### 05:30:51 INFO - ##### Running run-tests step. 05:30:51 INFO - ##### 05:30:51 INFO - Running pre-action listener: _resource_record_pre_action 05:30:51 INFO - Running main action method: run_tests 05:30:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:30:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:30:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:30:51 INFO - 2016-06-03 05:30:51.114 screenresolution[1645:8113] starting screenresolution argv=screenresolution get 05:30:51 INFO - 2016-06-03 05:30:51.117 screenresolution[1645:8113] Display 0: 1600x1200x32@60 05:30:51 INFO - 2016-06-03 05:30:51.123 screenresolution[1646:8115] starting screenresolution argv=screenresolution list 05:30:51 INFO - Available Modes on Display 0 05:30:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:30:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:30:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:30:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:30:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:30:51 INFO - Intel Iris: 05:30:51 INFO - Chipset Model: Intel Iris 05:30:51 INFO - Type: GPU 05:30:51 INFO - Bus: Built-In 05:30:51 INFO - VRAM (Dynamic, Max): 1536 MB 05:30:51 INFO - Vendor: Intel (0x8086) 05:30:51 INFO - Device ID: 0x0a2e 05:30:51 INFO - Revision ID: 0x0009 05:30:51 INFO - Displays: 05:30:51 INFO - Display: 05:30:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:30:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:30:51 INFO - Display Serial Number: 1600x1200 60 05:30:51 INFO - Main Display: Yes 05:30:51 INFO - Mirror: Off 05:30:51 INFO - Online: Yes 05:30:51 INFO - Rotation: Supported 05:30:51 INFO - Return code: 0 05:30:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:30:51 INFO - Python 2.7.3 05:30:51 INFO - Return code: 0 05:30:51 INFO - grabbing minidump binary from tooltool 05:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:51 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 0x10e13fd30>, '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 0x10df63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e136b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:30:51 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 05:30:51 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 05:30:51 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 05:30:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:30:51 INFO - Return code: 0 05:30:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:30:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:30:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:30:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:30:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:30:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:30:52 INFO - Return code: 0 05:30:52 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:30:52 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0107 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:30:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N1Hgt5mPKk/Render', 05:30:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:52 INFO - 'HOME': '/Users/cltbld', 05:30:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:52 INFO - 'LOGNAME': 'cltbld', 05:30:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:30:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:52 INFO - 'MOZ_NO_REMOTE': '1', 05:30:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:30:52 INFO - 'NO_EM_RESTART': '1', 05:30:52 INFO - 'PAGER': '/bin/cat', 05:30:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:52 INFO - 'PWD': '/builds/slave/test', 05:30:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:30:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:52 INFO - 'SHELL': '/bin/bash', 05:30:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZPhG67o444/Listeners', 05:30:52 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:30:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:52 INFO - 'USER': 'cltbld', 05:30:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:52 INFO - 'XPC_FLAGS': '0x0', 05:30:52 INFO - 'XPC_SERVICE_NAME': '0', 05:30:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:30:52 INFO - mozversion application_buildid: 20160603033811 05:30:52 INFO - mozversion application_changeset: 4874ff5d90f81aca0bb57d2f74586210cbee3cf6 05:30:52 INFO - mozversion application_display_name: Nightly 05:30:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:30:52 INFO - mozversion application_name: Firefox 05:30:52 INFO - mozversion application_remotingname: firefox 05:30:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:30:52 INFO - mozversion application_vendor: Mozilla 05:30:52 INFO - mozversion application_version: 49.0a1 05:30:52 INFO - mozversion platform_buildid: 20160603033811 05:30:52 INFO - mozversion platform_changeset: 4874ff5d90f81aca0bb57d2f74586210cbee3cf6 05:30:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:30:52 INFO - mozversion platform_version: 49.0a1 05:30:52 INFO - using testdate: 1464957052 05:30:52 INFO - actual date: 1464957052 05:30:52 INFO - starting webserver on 'localhost:49277' 05:30:52 INFO - SUITE-START | Running 2 tests 05:30:52 INFO - TEST-START | dromaeo_css 05:30:52 INFO - operating with platform_type : mac_ 05:30:52 INFO - Initialising browser for dromaeo_css test... 05:30:52 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpAEwP8p/profile http://localhost:49277/getInfo.html) 05:30:55 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 05:30:55 INFO - PROCESS | 1664 | colorDepth:24 05:30:55 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 05:30:55 INFO - PROCESS | 1664 | __metrics 05:30:55 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 05:30:56 INFO - TEST-INFO | 1664: exit 0 05:30:56 INFO - Browser initialized. 05:30:56 INFO - Running cycle 1/1 for dromaeo_css test... 05:30:56 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpAEwP8p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:31:08 INFO - PROCESS | 1668 | RSS: Main: 253177856 05:31:08 INFO - PROCESS | 1668 | 05:31:58 INFO - PROCESS | 1668 | 1464957118500 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] 05:34:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 05:34:10 INFO - PROCESS | 1668 | RSS: Main: 267235328 05:34:10 INFO - PROCESS | 1668 | 05:37:12 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 05:37:13 INFO - PROCESS | 1668 | RSS: Main: 278720512 05:37:13 INFO - PROCESS | 1668 | 05:40:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 05:40:15 INFO - PROCESS | 1668 | RSS: Main: 280866816 05:40:15 INFO - PROCESS | 1668 | 05:43:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 05:43:18 INFO - PROCESS | 1668 | RSS: Main: 282132480 05:43:18 INFO - PROCESS | 1668 | 05:46:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 05:46:21 INFO - PROCESS | 1668 | RSS: Main: 285986816 05:46:21 INFO - PROCESS | 1668 | 05:49:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 05:49:24 INFO - PROCESS | 1668 | RSS: Main: 286154752 05:49:24 INFO - PROCESS | 1668 | 05:49:24 INFO - PROCESS | 1668 | __start_tp_report 05:49:24 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 05:49:24 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 05:49:24 INFO - PROCESS | 1668 | |i|pagename|runs| 05:49:24 INFO - PROCESS | 1668 | |0;dojo.html;3328.983355083225;3478.0435380270437;3491.1097670094123;3530.7704999175053;3543.645635436455;5707.115397113444;5747.913781293273;5748.827535173941;5821.8835623287505;5855.619384739989;5670.943290567104;5749.56878234132;5770.7691692332255;5802.796902108427;5899.764009439617;5166.870828229296;5212.609054320941;5266.420693723707;5267.736613169345;5312.442193569677;264.3264960879678;5109.974450127763;5261.158214685651;5270.49930256625;5299.443558426367;5258.92111618326;5260.34245719287;5285.762140703678;5302.92045619316;5313.282706834567;5086.135356989321;5282.366116066097;5323.760430780624;5330.76011579476;5333.386660534011;5203.973980130113;5206.114960456694;5237.423883372826;5275.920861187085;5283.524482796528;5621.184928185431;5665.716714164317;5728.026235539968;5730.08318669011;5748.770049198027;5261.921071183935;6152.477039451652;6246.969250073777;6335.5248356372995;6336.271328797156;6201.410865967771;6256.499480041586;6280.497560195174;6298.68506574674;6306.936930630681;5912.260967379078;5949.315828679714;5973.850653733692;5974.4324289192455;5995.160677505153;5708.71456427177;6205.937940620536;6326.68366581669;6326.810195694136;6373.075903993895;6150.661713605705;6159.84600384994;6185.938140618537;6199.163112979781;6206.410391012847;6205.503559715212;6283.6858157091965;6312.558120931582;6321.273053598895;6322.462590679844;4573.839915602938;6215.81352559424;6225.408586184305;6281.403266689657;6322.114903913456;6296.874062518816;6307.905381419282;6327.209098862642;6330.525210609223;6331.335209802996;4948.851534453972;5012.674176178537;5020.949790502048;5025.120603894304;5056.266841308064;4345.196138714348;4445.933311000337;4451.376807246988;4497.302918047708;4542.250528662799;5777.537796976232;5792.304923409133;5853.1220316952795;5877;5888.998870192077;5487.122060470386;6224.470919971763;6262.029385445172;6266.874662506724;6270.905936410957;5716.399778023331;5840.445157710011;5894.616849904743;5913.876363491009;5922.555808314393;5464.071107911585;5490.039243132515;5504.119340905436;5521.917171242435;5533.806316778893;6236.34518375564;6460.257070436866;6463.127477790438;6475.32009139043;6494.64279464634;6199.721012554536;6248.718807653758;6264.373562643699;6268.843278917881;6304.148939893239;5370.9731451342495;6387.712552935036;6395;6417.550771445954;6598.736050558116;6152.323244443018;6172.8765424690355;6210.099535567408;6290.2766181889665;6298.118263443213;6058.969705151446;6226.906596401148;6276.780312689034;6288.591241569284;6310.242770867525;6204.5967012144065;6225.750969961151;6226.1905952225925;6240.469560087262;6249.406306400884;6093.421124993119;6148.754049838135;6191.19514463117;6220.191375121386;6239.594426362272;4769.475357710579;5867.973104706744;6130.329445263203;6148.016317389196;6151.769646070714;6223.004319308889;6232.501399888089;6241;6265.561410701207;6280.403361680633;5903.350631430625;6038.818835434944;6119.654885716854;6125.9693701531205;6158.168647232746 05:49:24 INFO - PROCESS | 1668 | |1;ext.html;497.1225786486849;514.9227615857618;522.4853519283505;526.0636067799318;567.6962824888685;32243.677563224363;34253.972380828556;37930.0517487063;41015.58984410155;41127.38308925365;40619.593804062024;43672.56327436725;43769.343459848125;43832;43836.780816095874;7982.96008519958;9637.747092877917;9728.805423891517;9750.609975600968;9769.071938165864;8252.546109963949;9674.951625241863;9684.467354295512;9695.272854535902;9699.854502182472;9670.9516452418;9713.46575938323;9717.56270967808;9757.853632195523;9769.364991275545;8483.74548763538;9557.569909354095;9667.178289845337;9713.805723885484;9725.173360264314;9502.952485237529;9526.142647161789;9572.760680982961;9587.568559414844;9600.855987160197;40195.799021005;40612.18775624471;40614;40695.7965210175;40709.389359159635;3060.8010479318996;3067.9846600766855;3374.4263475209273;3505.614382417932;3558.5373901392913;30466;30614;30882.691173088206;31011.565853948876;31289.217769555715;3114.5639610454555;3148.8583013764437;3191.48936170214;3208.3262514871903;3222.951655725166;467361;468889;470748.64625676995;472560;472858;34358.48462273068;34842.30315393729;34878.65121348754;34892.12769680778;34947.4757878632;32656.020319390456;33577.83211083978;34587;36175.4573681395;36624;16783;16795.16024198785;16875;16960.406385776685;17085.402010929807;12962.416691248916;13049.347532623331;13117.212967221998;13159.934200328937;13169.868301316865;16440.83559164417;16563.6687266254;19861.801381986283;20081.39755807328;20137.597248054957;7962.283394494523;8097.109317975018;8108.675652973933;8126;8222.944263934016;875.3216257400566;929.2705226397246;1010.9494525273707;1090.8909109089186;1165.6677846813618;30270;30661;30739.692603073683;31305.37389252202;31489;972.1687956796837;1011.489197955028;1039.5010395010438;1104.304288298383;1122.3882983773863;13096;14666.120032798066;15402.152881591395;15511.379544818306;15718.449854255046;614679;627309;627622;631441;699470;42590.57409425866;59464.513387165665;59522;59862;59988.40011599915;1343.2410687961467;1344.6369480240476;1356.389624668903;1410.2525661399475;1421.8862491000696;29037.85481072581;29087.563686544294;29199.978000769872;29958.400831982803;29987.400251995274;51963.22055169251;52534.47465525449;52694;52707;52737.7363113182;491531;549760;614979.8502015101;615615.9219204055;615780;26689.466210676066;26913.73086269112;26993.730062699906;27101.1869643911;27260.318492037066;47079;47869.04261914673;47943.52056479391;48591;48922.266166006826;39058;39461.2107757849;39546.60453395429;40242.59757402389;40424.595754042086;32129;38662.613373865905;38819.02952426212;39257.803710981265;39505.80247098746;32399.51400729038;32738.836305818317;37728.81135594304;38719;39724.60275397209;33209.66790332066;34377.484337735455;35330.82334588311;35444.46833297554;35954.8202258987;25050.3737406559;25645.358866027753;25936.351591210372;25961;26406.339841504116 05:49:24 INFO - PROCESS | 1668 | |2;jquery.html;380.60227062719474;380.70114959756586;385.36414915389605;397.8110397561157;404.094827586207;19366.12852421641;21106.472338191554;21934.341969740894;22033.33899982999;22097.558048839055;11491.942540287288;12164.391780410988;12168.634940951786;21517.81652009139;22039.33881983539;5287.867803304919;5449.945500544984;5486.478784515464;5491.780328786864;5687.886242275173;5209.21861720743;5796.478316951474;5994.940050599482;6079.270487541501;6107.81676549702;5715.428457154293;5949.970250148744;5991.1612374267415;6034.547408944325;6060.999935010718;5948.762049518035;5970.761169553212;6000.849978750544;6004.909926351108;6036.96981515094;5073.315102461194;5261.631685781997;5263.421023687391;5295.2586637870345;5296.020236256305;13122.737545249138;13507.25710085945;13509.05436619437;24283.392915177086;25421.745782542308;2054.681524363726;2095.413284280404;2100.12844669462;2234.72065991751;2340.789328960385;10033.749156271151;10187.694369168938;10392.688219353431;10401.323913945569;11897.881021189763;1856.60083082138;1887.2073729033839;1917.9712304315447;1921.5196200949763;1949.064449064443;88035.11964880333;122498;123090.38454807758;123395;123781.57310561305;11520.481578328996;11831.76336473283;11852.170348075724;11986.520539178335;12004.939975300067;14752.114873107434;14813;14817.851411545265;14817.925910370379;14833.258337083103;8597.484150950962;8636.17956294151;8663.523506207219;8705.434146780439;8813.867791983126;8638.09300023501;8659.437136586102;8722.34582406322;8786.98949620789;8828.558572071372;4456.643468522575;4566.246569316092;4576.222042252825;4598.126355992351;4660.021395506914;1748.8370233794637;1903.1340739963284;1939.156466936887;1973.3882496426154;2009.5880344529337;383.2239714578002;391.29566779796255;824.9298809601131;901.9864702029474;1048.8741351037772;11476.827847582294;11915.046796256278;11999.980001699952;12000.1599888007;12213.45039473226;426.81133044471;428.5928368050365;435.2231267188054;447.78058751212325;471.44369643819937;6053.182820319289;6161.045038019114;6233;6241.469475094668;6250.312465628777;618164;621139;623690;624640;631814;108213;143385;144293;145763;150155;433.65091101663467;445.4476449202965;445.61231728395853;446.66276960894965;447.8052047359409;9344.392614480037;10855.511501982588;11428.599998999995;11443.885561144281;11493.540258389905;55314.16028534954;56213.15680264881;56231.15653265286;56548.86902261849;58424.12363814631;85105.14894850973;85394.57302713447;85766.64935480084;85799.1420085816;86212.56893715491;7992.6802927884555;8129.601767478726;8177.223163799429;8292.33661307106;8333.416660833684;11564.710882228012;12807.935960320512;12945.741085178432;13080;13124.34378281063;10058.849117263393;10461.320014199053;11084.279521831055;11230.438478076227;11276.32342059479;6277.780277690259;8445.282151016987;10877.945610271898;11025.338479690923;11400.600978965696;9618.422894626345;9788.216942644429;11201;11449.427528623703;11573.363465009505;6670.866582668416;7274.272572742895;7507.737229196952;8106.716264930699;8353.874691879506;10212.234082443994;10668.466576671291;11052.447377631248;11618.419079046183;11752.059835213333 05:49:24 INFO - PROCESS | 1668 | |3;mootools.html;1518.0512179887573;1573.315607710646;1745.9214335354916;1846.261495401839;1915.0903320922573;7881;8386.37102217333;8410.873836892444;8914.465132092071;8954.86567701485;8514.020887597928;8517.446365986207;8691.174338437853;8699.173578510046;8704.91295087049;2322.895469703863;2340.414896275931;2346.6128088865316;2354.2584086012935;2358.127492827656;2333.288347054148;2333.474968132165;2343.121329501437;2386.1290628920406;2391.497785465054;2319.5592837360846;2345.847519911209;2350.6003979323473;2360.9055637774554;2372.988135059331;2334.323046316566;2340.0990618611786;2354.434935615457;2357.4931389751287;2359.1271229645054;2312.5259321838985;2338.1933233034574;2348.9295321140394;2363.6336367862837;2367.112332875172;7363.558186508827;8687;8701.651933922636;8707.216350528483;8741.73774786751;5147.382314122292;5270.183121616155;5290.709011004393;5301.3373328333955;5307.734613269321;3487.7907325560545;3593.425051991695;3625.5830579483168;3643.7813731176216;3660.3228402745576;1069.4171676436283;1074.785042991405;1078.8921107889228;1080.1628737728245;1087.2226358153878;11155.38645374504;11176.552937882476;11279.774404511863;11310.604128855533;11310.830337544943;4514.329698011757;4617.676762626584;4726.905461890812;4727.510834087251;4737.38414006177;9543.713688589354;9679.274054445945;9691.27315451344;9713.465759383302;9723.61105555784;4434.26834572292;5242.633015688942;5272.420033796356;5299.6025298102795;5301.0723123453245;4365.738055716603;4700.435947686299;4837.95162048375;4848.224284114524;4849.951500485021;3883.669888059547;3939.92120157601;3985.3025720498795;4012.0170558213426;4058.350663893763;1265.3736400481637;1267.3916520070234;1272.179444258448;1273.6688461000206;1274.808778683205;794.9284564389233;889.6485888074175;911.5761171055359;945.3713280668416;962.3936919740519;5389.487998640123;5703.515201207943;5840.620359676607;5870.677112758839;5969.373215812364;419.57203652274507;439.98240070397463;453.64162311773987;454.7703409778041;456.1606643775469;3859.6630879294935;3932.5477570079233;3987.80060996949;3999.320115580358;4002.959970400317;10162.03460671235;10511;10542.73643158912;10556.47217639115;10586.258961872594;10387.636432724817;10411.895881041244;10488.947555262175;10537.473126343652;10551.577936882439;8089.716859909875;8104;8151.347892168731;8186.467879587808;8203.71287004952;5697.7151142443545;5746.885062298814;5757.251557297524;5823.388544202798;5968.641881487125;7722.652480638497;9722.979087195743;9765.560549775419;9768.804623907625;9800.754981125529;9520.19116367888;9714.368566043184;9754.512274386394;9876.654317098866;9889.604415823575;6938.548994315353;7154;7349.853002939804;7431.591262480615;7552.773416797505;6988.8951665724035;7896.6446509906045;7985.001874765654;8017.879731804144;8088.9595552021865;7079.787606371817;7204.135503739585;7214.567125972459;7301.598412087386;7317.2316906726155;7051.365377116084;7062.823429414306;7105.182903965903;7162.606056666725;7180.892286615601;6652.401283884474;6835.692393842389;6843.657817109224;6869.175698916162;6875.518713690194;4119.567445418188;4784.95215047859;4846.612271018381;4859.611231101433;4947.332110165082;4169.708120431541;4873.829415970424;4914.262860570943;4927.088488629615;5029.723365214948 05:49:24 INFO - PROCESS | 1668 | |4;prototype.html;268.45369672716026;299.2862024072586;315.722900865997;318.4602099441445;321.5145130852412;5039.44566097729;5324.254604355393;5429.7285135743205;5515.448455154483;5591.720413979305;5111.693298402099;5350.464953504647;5474.589405794562;5497.39528651848;5529.253550770644;4159.417681524581;4172.248995180867;4185.246655602005;4195.391668208108;4207.537170911197;3802.030482227028;4098.7540747555095;4140.213359461708;4191.979040104795;4220.345846393814;3456.861725530976;3995.942089823743;4007.138465229975;4011.057401510657;4035.4552135461777;3758.7932663703486;4005.4792876926103;4048.6558642515274;4048.6558642515274;4066.857659981916;3416.367971925202;3508.6666766657127;3528.929421411583;3536.2043540203504;3542.167590616203;3895.18201177753;4188.120494696117;4849.5635392814465;5075.314832497603;5197.324347834795;3743.606921273254;4629.398178236807;4663.743494107823;4717.410323709536;4753.619710423158;4971.651984361096;5020.949790502084;5169.147090730025;5450.5367043801325;5725.885482290372;3449.9482507762405;3573.4818451324672;3860.227954409101;3862.3820188769664;3875.0312421894528;5509.311336082989;5516.420775818561;5546.77812887484;6049.364816694228;6076.179715738319;3653.825973545736;3888.191990724327;3889.4554762333296;3906.8632597858937;3906.980465097656;3236.7248783853165;3531;3813.809309534512;3993.600639935983;3996.7402118862183;4356.910772306924;4545.499975013729;4584.472785629695;4591.724496530219;4649.302604609268;4662.53374662531;5280.339957505312;5612.663240205601;5744.167095771089;5787.88424231513;1169.9707507312385;1240.4418011894504;1301.9218846869028;1344.9529266475627;1350.750111229406;718.5616773767997;743.5984568333148;824.6660102658433;826.1160558202665;829.1832544943226;3889.377699568112;4489.349044388546;4518.977405112953;4539.638108567483;4545.931811022837;4238.87283381499;4635.814567417333;5015.244664367515;5015.244664367515;5024.84925452237;3761.680257178116;3869.1681288523105;4267.743935363888;4505.189065968158;4543.840965566189;2361.090979972723;2436.7197772256063;2460.7785299323145;2515.811244217506;2583.984492096168;4591.494935557086;4806.951930480651;4881.048195601895;5234.842954711365;5254.97372513135;4483.955160448419;4492.303692927601;4644.584815895715;4961.776720047606;5138.97430512845;4380.970471939092;4553.567411095941;4650.488446270908;4986.975065124651;5232.88068332326;4339.978300108479;4575.392026544971;4977.104121258209;4978.266300274794;5056.926660069354;4288.571142885687;4414.867553973386;4463.196624607602;4780.832670856504;4925.315702941977;4366;4609.041157507979;4848.527251101893;5227.660202086852;5296.285001524745;3144.9371012580077;3195.9613125734695;3394.8981530554124;3429.3141371726256;3479.739019573593;4474.082813023388;4521.457425109008;4798.568128868418;5025.874353141055;5621.775128994795;4717.985633088902;5268.947310526845;5560.694161821139;5723;5737.655740655574;4797.840971562741;5738.254026976467;5750.338711048283;5797.884042319214;5814.94185058144;4576;4790.520947905321;5069.8732531687;5225.6603320784025;5407.324084489439;4672.7429991349445;4756.476787553297;5388.811391601275;5655.519280861008;5729;4306.935395969001;4819.518048195152;5654.038813401649;5664.376918538875;5693.629914055573 05:49:24 INFO - PROCESS | 1668 | |5;yui.html;724.8948902409151;730.466759265736;753.3596443023428;788.235411650699;808.2765924497578;12047.75904481911;15466.690666186669;15553;15739.763903541427;15774.842251577495;14254.429822807102;14338.641533961656;14341.426342946295;14365;14386.856131438682;671.6775947545182;856.6915910272297;856.9657213711475;863.0721973878075;879.134052957836;873.8995015573195;880.7842078690729;882.8499155143609;886.7339798060589;890.1721399098835;902.9051949545302;928.3408779766374;932.2914584915445;949.3164921256684;950.6150009246267;911.0980129671643;920.102899672817;945.3382632157449;945.3524335829978;945.4752612300205;685.5201359048685;694.4756708684961;700.2087640965727;700.8843540815759;702.0907924238124;14458.783118253235;14544.345504452325;14684.118952862756;14709;14721;14395.424183032666;14449.49426770058;14462.855371446256;14472.348744306531;14508.637284067878;11179.273347232403;11605;11633.069354451623;11716.765664686658;11761.411929403495;4538.364628951949;4591.380163677927;4627.190241707722;4782.330473733646;4792.568668819823;100762;102106;104596;105311.47344263307;105805.47097264542;10962.506687199095;10964.56141754337;10981.61564345244;11087.72280692989;11269.21115521922;20814.85518296478;20862.374128776162;20862.582748344947;20871.060802263935;20881.26915557948;7395.593242371614;7417.851642967218;7496.362809161174;7706.190849960785;7733.188015258429;4054.3107671695293;4473.150101480708;4493.977530112394;4519.728816271035;4696.671233013724;3406.625271220164;3447.1209841490763;3506.3688536063755;3515.5254040704176;3571.785692858437;661.4576047640888;666.9066330713704;667.1427216027381;671.2750229751856;673.7709178879106;572.4447286132445;596.4900010490968;620.7925584737732;637.7417146055855;638.6647010319526;8867.157620026217;8912.955435222912;8988.685396011108;9011.143941325563;9116.8632470513;510.4308695804226;621.3041393639153;630.501903496242;633.1863555331469;633.5818359882547;4391.7584532851;4410.316400957857;4424.579664931826;4458.242098843204;4467.597916187558;138507;139593;141437;144397;147520;72626.63686681533;118129;121556.39221804011;121879.39060304819;124011.3799430997;75635;99573.50213249032;100221;101328;104620.43069353966;8247.546384948886;8285.212904774036;8431.40980131385;8437.240648341678;8491.95754021226;58325.83348333094;58523;59913;60932;61294;78355.21644783707;79207;83748.58125709576;89739.55130224307;91013.08986910045;13343;13378.063535552417;13508;13807.309634518435;14087.436502539784;14624.268786560842;14627.92686036563;14709.957831986243;14944.775828362366;15203.163825989677;13584.456621735415;13844.653883652989;13885.791713124514;13917;13920.303984800517;13650.93174534121;13843.307834608431;13897.374618142007;14450.527573163365;14623.634409139857;12998.220106793622;13309.20547562761;13424.463021479032;13477.258750768802;13904.72190556163;10403.635872744419;11208.991190792867;11421.714457138638;11575.710607234887;11701.979785142757;9628.47043412619;10988.725281868017;11341.149413794163;11344.992494850909;11552.711182220175 05:49:24 INFO - PROCESS | 1668 | __end_tp_report 05:49:24 INFO - PROCESS | 1668 | __start_cc_report 05:49:24 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,432 05:49:24 INFO - PROCESS | 1668 | __end_cc_report 05:49:24 INFO - PROCESS | 1668 | __startTimestamp1464958164902__endTimestamp 05:49:24 INFO - PROCESS | 1668 | 05:49:24 INFO - PROCESS | 1668 | ------- Summary: start ------- 05:49:24 INFO - PROCESS | 1668 | Number of tests: 6 05:49:24 INFO - PROCESS | 1668 | 05:49:24 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5754.27 Median:5995.16 stddev:775.87 (12.9%) stddev-sans-first:754.56 05:49:24 INFO - PROCESS | 1668 | Values: 3329.0 3478.0 3491.1 3530.8 3543.6 5707.1 5747.9 5748.8 5821.9 5855.6 5670.9 5749.6 5770.8 5802.8 5899.8 5166.9 5212.6 5266.4 5267.7 5312.4 264.3 5110.0 5261.2 5270.5 5299.4 5258.9 5260.3 5285.8 5302.9 5313.3 5086.1 5282.4 5323.8 5330.8 5333.4 5204.0 5206.1 5237.4 5275.9 5283.5 5621.2 5665.7 5728.0 5730.1 5748.8 5261.9 6152.5 6247.0 6335.5 6336.3 6201.4 6256.5 6280.5 6298.7 6306.9 5912.3 5949.3 5973.9 5974.4 5995.2 5708.7 6205.9 6326.7 6326.8 6373.1 6150.7 6159.8 6185.9 6199.2 6206.4 6205.5 6283.7 6312.6 6321.3 6322.5 4573.8 6215.8 6225.4 6281.4 6322.1 6296.9 6307.9 6327.2 6330.5 6331.3 4948.9 5012.7 5020.9 5025.1 5056.3 4345.2 4445.9 4451.4 4497.3 4542.3 5777.5 5792.3 5853.1 5877.0 5889.0 5487.1 6224.5 6262.0 6266.9 6270.9 5716.4 5840.4 5894.6 5913.9 5922.6 5464.1 5490.0 5504.1 5521.9 5533.8 6236.3 6460.3 6463.1 6475.3 6494.6 6199.7 6248.7 6264.4 6268.8 6304.1 5371.0 6387.7 6395.0 6417.6 6598.7 6152.3 6172.9 6210.1 6290.3 6298.1 6059.0 6226.9 6276.8 6288.6 6310.2 6204.6 6225.8 6226.2 6240.5 6249.4 6093.4 6148.8 6191.2 6220.2 6239.6 4769.5 5868.0 6130.3 6148.0 6151.8 6223.0 6232.5 6241.0 6265.6 6280.4 5903.4 6038.8 6119.7 6126.0 6158.2 05:49:24 INFO - PROCESS | 1668 | 05:49:24 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:68267.28 Median:38769.01 stddev:152005.11 (392.1%) stddev-sans-first:152346.36 05:49:24 INFO - PROCESS | 1668 | Values: 497.1 514.9 522.5 526.1 567.7 32243.7 34254.0 37930.1 41015.6 41127.4 40619.6 43672.6 43769.3 43832.0 43836.8 7983.0 9637.7 9728.8 9750.6 9769.1 8252.5 9675.0 9684.5 9695.3 9699.9 9671.0 9713.5 9717.6 9757.9 9769.4 8483.7 9557.6 9667.2 9713.8 9725.2 9503.0 9526.1 9572.8 9587.6 9600.9 40195.8 40612.2 40614.0 40695.8 40709.4 3060.8 3068.0 3374.4 3505.6 3558.5 30466.0 30614.0 30882.7 31011.6 31289.2 3114.6 3148.9 3191.5 3208.3 3223.0 467361.0 468889.0 470748.6 472560.0 472858.0 34358.5 34842.3 34878.7 34892.1 34947.5 32656.0 33577.8 34587.0 36175.5 36624.0 16783.0 16795.2 16875.0 16960.4 17085.4 12962.4 13049.3 13117.2 13159.9 13169.9 16440.8 16563.7 19861.8 20081.4 20137.6 7962.3 8097.1 8108.7 8126.0 8222.9 875.3 929.3 1010.9 1090.9 1165.7 30270.0 30661.0 30739.7 31305.4 31489.0 972.2 1011.5 1039.5 1104.3 1122.4 13096.0 14666.1 15402.2 15511.4 15718.4 614679.0 627309.0 627622.0 631441.0 699470.0 42590.6 59464.5 59522.0 59862.0 59988.4 1343.2 1344.6 1356.4 1410.3 1421.9 29037.9 29087.6 29200.0 29958.4 29987.4 51963.2 52534.5 52694.0 52707.0 52737.7 491531.0 549760.0 614979.9 615615.9 615780.0 26689.5 26913.7 26993.7 27101.2 27260.3 47079.0 47869.0 47943.5 48591.0 48922.3 39058.0 39461.2 39546.6 40242.6 40424.6 32129.0 38662.6 38819.0 39257.8 39505.8 32399.5 32738.8 37728.8 38719.0 39724.6 33209.7 34377.5 35330.8 35444.5 35954.8 25050.4 25645.4 25936.4 25961.0 26406.3 05:49:24 INFO - PROCESS | 1668 | 05:49:24 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:35309.90 Median:400.95 stddev:104496.92 (26062.1%) stddev-sans-first:104757.15 05:49:24 INFO - PROCESS | 1668 | Values: 380.6 380.7 385.4 397.8 404.1 19366.1 21106.5 21934.3 22033.3 22097.6 11491.9 12164.4 12168.6 21517.8 22039.3 5287.9 5449.9 5486.5 5491.8 5687.9 5209.2 5796.5 5994.9 6079.3 6107.8 5715.4 5950.0 5991.2 6034.5 6061.0 5948.8 5970.8 6000.8 6004.9 6037.0 5073.3 5261.6 5263.4 5295.3 5296.0 13122.7 13507.3 13509.1 24283.4 25421.7 2054.7 2095.4 2100.1 2234.7 2340.8 10033.7 10187.7 10392.7 10401.3 11897.9 1856.6 1887.2 1918.0 1921.5 1949.1 88035.1 122498.0 123090.4 123395.0 123781.6 11520.5 11831.8 11852.2 11986.5 12004.9 14752.1 14813.0 14817.9 14817.9 14833.3 8597.5 8636.2 8663.5 8705.4 8813.9 8638.1 8659.4 8722.3 8787.0 8828.6 4456.6 4566.2 4576.2 4598.1 4660.0 1748.8 1903.1 1939.2 1973.4 2009.6 383.2 391.3 824.9 902.0 1048.9 11476.8 11915.0 12000.0 12000.2 12213.5 426.8 428.6 435.2 447.8 471.4 6053.2 6161.0 6233.0 6241.5 6250.3 618164.0 621139.0 623690.0 624640.0 631814.0 108213.0 143385.0 144293.0 145763.0 150155.0 433.7 445.4 445.6 446.7 447.8 9344.4 10855.5 11428.6 11443.9 11493.5 55314.2 56213.2 56231.2 56548.9 58424.1 85105.1 85394.6 85766.6 85799.1 86212.6 7992.7 8129.6 8177.2 8292.3 8333.4 11564.7 12807.9 12945.7 13080.0 13124.3 10058.8 10461.3 11084.3 11230.4 11276.3 6277.8 8445.3 10877.9 11025.3 11400.6 9618.4 9788.2 11201.0 11449.4 11573.4 6670.9 7274.3 7507.7 8106.7 8353.9 10212.2 10668.5 11052.4 11618.4 11752.1 05:49:24 INFO - PROCESS | 1668 | 05:49:24 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5567.45 Median:4866.72 stddev:3099.49 (63.7%) stddev-sans-first:3093.25 05:49:24 INFO - PROCESS | 1668 | Values: 1518.1 1573.3 1745.9 1846.3 1915.1 7881.0 8386.4 8410.9 8914.5 8954.9 8514.0 8517.4 8691.2 8699.2 8704.9 2322.9 2340.4 2346.6 2354.3 2358.1 2333.3 2333.5 2343.1 2386.1 2391.5 2319.6 2345.8 2350.6 2360.9 2373.0 2334.3 2340.1 2354.4 2357.5 2359.1 2312.5 2338.2 2348.9 2363.6 2367.1 7363.6 8687.0 8701.7 8707.2 8741.7 5147.4 5270.2 5290.7 5301.3 5307.7 3487.8 3593.4 3625.6 3643.8 3660.3 1069.4 1074.8 1078.9 1080.2 1087.2 11155.4 11176.6 11279.8 11310.6 11310.8 4514.3 4617.7 4726.9 4727.5 4737.4 9543.7 9679.3 9691.3 9713.5 9723.6 4434.3 5242.6 5272.4 5299.6 5301.1 4365.7 4700.4 4838.0 4848.2 4850.0 3883.7 3939.9 3985.3 4012.0 4058.4 1265.4 1267.4 1272.2 1273.7 1274.8 794.9 889.6 911.6 945.4 962.4 5389.5 5703.5 5840.6 5870.7 5969.4 419.6 440.0 453.6 454.8 456.2 3859.7 3932.5 3987.8 3999.3 4003.0 10162.0 10511.0 10542.7 10556.5 10586.3 10387.6 10411.9 10488.9 10537.5 10551.6 8089.7 8104.0 8151.3 8186.5 8203.7 5697.7 5746.9 5757.3 5823.4 5968.6 7722.7 9723.0 9765.6 9768.8 9800.8 9520.2 9714.4 9754.5 9876.7 9889.6 6938.5 7154.0 7349.9 7431.6 7552.8 6988.9 7896.6 7985.0 8017.9 8089.0 7079.8 7204.1 7214.6 7301.6 7317.2 7051.4 7062.8 7105.2 7162.6 7180.9 6652.4 6835.7 6843.7 6869.2 6875.5 4119.6 4785.0 4846.6 4859.6 4947.3 4169.7 4873.8 4914.3 4927.1 5029.7 05:49:24 INFO - PROCESS | 1668 | 05:49:24 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4261.94 Median:4587.98 stddev:1298.50 (28.3%) stddev-sans-first:1267.07 05:49:24 INFO - PROCESS | 1668 | Values: 268.5 299.3 315.7 318.5 321.5 5039.4 5324.3 5429.7 5515.4 5591.7 5111.7 5350.5 5474.6 5497.4 5529.3 4159.4 4172.2 4185.2 4195.4 4207.5 3802.0 4098.8 4140.2 4192.0 4220.3 3456.9 3995.9 4007.1 4011.1 4035.5 3758.8 4005.5 4048.7 4048.7 4066.9 3416.4 3508.7 3528.9 3536.2 3542.2 3895.2 4188.1 4849.6 5075.3 5197.3 3743.6 4629.4 4663.7 4717.4 4753.6 4971.7 5020.9 5169.1 5450.5 5725.9 3449.9 3573.5 3860.2 3862.4 3875.0 5509.3 5516.4 5546.8 6049.4 6076.2 3653.8 3888.2 3889.5 3906.9 3907.0 3236.7 3531.0 3813.8 3993.6 3996.7 4356.9 4545.5 4584.5 4591.7 4649.3 4662.5 5280.3 5612.7 5744.2 5787.9 1170.0 1240.4 1301.9 1345.0 1350.8 718.6 743.6 824.7 826.1 829.2 3889.4 4489.3 4519.0 4539.6 4545.9 4238.9 4635.8 5015.2 5015.2 5024.8 3761.7 3869.2 4267.7 4505.2 4543.8 2361.1 2436.7 2460.8 2515.8 2584.0 4591.5 4807.0 4881.0 5234.8 5255.0 4484.0 4492.3 4644.6 4961.8 5139.0 4381.0 4553.6 4650.5 4987.0 5232.9 4340.0 4575.4 4977.1 4978.3 5056.9 4288.6 4414.9 4463.2 4780.8 4925.3 4366.0 4609.0 4848.5 5227.7 5296.3 3144.9 3196.0 3394.9 3429.3 3479.7 4474.1 4521.5 4798.6 5025.9 5621.8 4718.0 5268.9 5560.7 5723.0 5737.7 4797.8 5738.3 5750.3 5797.9 5814.9 4576.0 4790.5 5069.9 5225.7 5407.3 4672.7 4756.5 5388.8 5655.5 5729.0 4306.9 4819.5 5654.0 5664.4 5693.6 05:49:24 INFO - PROCESS | 1668 | 05:49:24 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:23356.86 Median:3813.05 stddev:36383.19 (954.2%) stddev-sans-first:36445.58 05:49:24 INFO - PROCESS | 1668 | Values: 724.9 730.5 753.4 788.2 808.3 12047.8 15466.7 15553.0 15739.8 15774.8 14254.4 14338.6 14341.4 14365.0 14386.9 671.7 856.7 857.0 863.1 879.1 873.9 880.8 882.8 886.7 890.2 902.9 928.3 932.3 949.3 950.6 911.1 920.1 945.3 945.4 945.5 685.5 694.5 700.2 700.9 702.1 14458.8 14544.3 14684.1 14709.0 14721.0 14395.4 14449.5 14462.9 14472.3 14508.6 11179.3 11605.0 11633.1 11716.8 11761.4 4538.4 4591.4 4627.2 4782.3 4792.6 100762.0 102106.0 104596.0 105311.5 105805.5 10962.5 10964.6 10981.6 11087.7 11269.2 20814.9 20862.4 20862.6 20871.1 20881.3 7395.6 7417.9 7496.4 7706.2 7733.2 4054.3 4473.2 4494.0 4519.7 4696.7 3406.6 3447.1 3506.4 3515.5 3571.8 661.5 666.9 667.1 671.3 673.8 572.4 596.5 620.8 637.7 638.7 8867.2 8913.0 8988.7 9011.1 9116.9 510.4 621.3 630.5 633.2 633.6 4391.8 4410.3 4424.6 4458.2 4467.6 138507.0 139593.0 141437.0 144397.0 147520.0 72626.6 118129.0 121556.4 121879.4 124011.4 75635.0 99573.5 100221.0 101328.0 104620.4 8247.5 8285.2 8431.4 8437.2 8492.0 58325.8 58523.0 59913.0 60932.0 61294.0 78355.2 79207.0 83748.6 89739.6 91013.1 13343.0 13378.1 13508.0 13807.3 14087.4 14624.3 14627.9 14710.0 14944.8 15203.2 13584.5 13844.7 13885.8 13917.0 13920.3 13650.9 13843.3 13897.4 14450.5 14623.6 12998.2 13309.2 13424.5 13477.3 13904.7 10403.6 11209.0 11421.7 11575.7 11702.0 9628.5 10988.7 11341.1 11345.0 11552.7 05:49:24 INFO - PROCESS | 1668 | -------- Summary: end -------- 05:49:24 INFO - PROCESS | 1668 | 05:49:25 INFO - TEST-INFO | 1668: exit 0 05:49:25 INFO - TEST-OK | dromaeo_css | took 1112745ms 05:49:25 INFO - TEST-START | kraken 05:49:25 INFO - operating with platform_type : mac_ 05:49:25 INFO - Initialising browser for kraken test... 05:49:25 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpplz4Ss/profile http://localhost:49277/getInfo.html) 05:49:28 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 05:49:28 INFO - PROCESS | 1675 | colorDepth:24 05:49:28 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 05:49:28 INFO - PROCESS | 1675 | __metrics 05:49:28 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 05:49:29 INFO - TEST-INFO | 1675: exit 0 05:49:29 INFO - Browser initialized. 05:49:29 INFO - Running cycle 1/1 for kraken test... 05:49:29 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpplz4Ss/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:41 INFO - PROCESS | 1677 | RSS: Main: 253247488 05:49:41 INFO - PROCESS | 1677 | 05:49:44 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 05:49:45 INFO - PROCESS | 1677 | RSS: Main: 265809920 05:49:45 INFO - PROCESS | 1677 | 05:49:47 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 05:49:48 INFO - PROCESS | 1677 | RSS: Main: 269885440 05:49:48 INFO - PROCESS | 1677 | 05:49:51 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 05:49:52 INFO - PROCESS | 1677 | RSS: Main: 270516224 05:49:52 INFO - PROCESS | 1677 | 05:49:54 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 05:49:55 INFO - PROCESS | 1677 | RSS: Main: 271355904 05:49:55 INFO - PROCESS | 1677 | 05:49:57 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:49:57 INFO - PROCESS | 1677 | RSS: Main: 271863808 05:49:57 INFO - PROCESS | 1677 | 05:50:00 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 05:50:01 INFO - PROCESS | 1677 | RSS: Main: 272564224 05:50:01 INFO - PROCESS | 1677 | 05:50:04 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 05:50:05 INFO - PROCESS | 1677 | RSS: Main: 272736256 05:50:05 INFO - PROCESS | 1677 | 05:50:08 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 05:50:08 INFO - PROCESS | 1677 | RSS: Main: 272818176 05:50:08 INFO - PROCESS | 1677 | 05:50:10 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:50:10 INFO - PROCESS | 1677 | RSS: Main: 272703488 05:50:10 INFO - PROCESS | 1677 | 05:50:12 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:50:13 INFO - PROCESS | 1677 | RSS: Main: 272400384 05:50:13 INFO - PROCESS | 1677 | 05:50:15 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:50:16 INFO - PROCESS | 1677 | RSS: Main: 272670720 05:50:16 INFO - PROCESS | 1677 | 05:50:19 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:50:20 INFO - PROCESS | 1677 | RSS: Main: 272826368 05:50:20 INFO - PROCESS | 1677 | 05:50:23 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:50:23 INFO - PROCESS | 1677 | RSS: Main: 272711680 05:50:23 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 05:50:25 INFO - PROCESS | 1677 | RSS: Main: 273539072 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | __start_tp_report 05:50:25 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 05:50:25 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 05:50:25 INFO - PROCESS | 1677 | |i|pagename|runs| 05:50:25 INFO - PROCESS | 1677 | |0;ai-astar;116;110;116;116;116;119;117;116;120;116 05:50:25 INFO - PROCESS | 1677 | |1;audio-beat-detection;158;122;145;167;123;148;143;121;177;134 05:50:25 INFO - PROCESS | 1677 | |2;audio-dft;193;202;205;255;254;264;197;198;194;199 05:50:25 INFO - PROCESS | 1677 | |3;audio-fft;69;81;77;140;58;58;59;64;129;57 05:50:25 INFO - PROCESS | 1677 | |4;audio-oscillator;70;71;116;72;72;79;115;71;72;116 05:50:25 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;91;86;90;88;91;73;135;132;91;87 05:50:25 INFO - PROCESS | 1677 | |6;imaging-darkroom;96;97;104;96;150;99;105;148;147;97 05:50:25 INFO - PROCESS | 1677 | |7;imaging-desaturate;95;80;98;93;122;102;89;146;76;150 05:50:25 INFO - PROCESS | 1677 | |8;json-parse-financial;58;57;56;58;59;57;58;58;59;58 05:50:25 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;64;87;63;103;77;82;64;87;103;104 05:50:25 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;90;88;90;91;89;89;88;76;125;112 05:50:25 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;149;147;135;138;138;139;139;143;159;142 05:50:25 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;167;202;227;171;168;169;168;179;210;226 05:50:25 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;60;65;77;62;63;58;60;81;80;86 05:50:25 INFO - PROCESS | 1677 | __end_tp_report 05:50:25 INFO - PROCESS | 1677 | __start_cc_report 05:50:25 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1103 05:50:25 INFO - PROCESS | 1677 | __end_cc_report 05:50:25 INFO - PROCESS | 1677 | __startTimestamp1464958225791__endTimestamp 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | ------- Summary: start ------- 05:50:25 INFO - PROCESS | 1677 | Number of tests: 14 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:116.20 Median:116.00 stddev:2.62 (2.3%) stddev-sans-first:2.77 05:50:25 INFO - PROCESS | 1677 | Values: 116.0 110.0 116.0 116.0 116.0 119.0 117.0 116.0 120.0 116.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:146.50 stddev:19.40 (13.2%) stddev-sans-first:19.88 05:50:25 INFO - PROCESS | 1677 | Values: 158.0 122.0 145.0 167.0 123.0 148.0 143.0 121.0 177.0 134.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:216.10 Median:203.50 stddev:29.01 (14.3%) stddev-sans-first:29.54 05:50:25 INFO - PROCESS | 1677 | Values: 193.0 202.0 205.0 255.0 254.0 264.0 197.0 198.0 194.0 199.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:79.20 Median:61.50 stddev:30.40 (49.4%) stddev-sans-first:32.02 05:50:25 INFO - PROCESS | 1677 | Values: 69.0 81.0 77.0 140.0 58.0 58.0 59.0 64.0 129.0 57.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:85.40 Median:71.50 stddev:21.03 (29.4%) stddev-sans-first:21.55 05:50:25 INFO - PROCESS | 1677 | Values: 70.0 71.0 116.0 72.0 72.0 79.0 115.0 71.0 72.0 116.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:89.00 stddev:20.28 (22.8%) stddev-sans-first:21.41 05:50:25 INFO - PROCESS | 1677 | Values: 91.0 86.0 90.0 88.0 91.0 73.0 135.0 132.0 91.0 87.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:113.90 Median:96.00 stddev:23.97 (25.0%) stddev-sans-first:24.54 05:50:25 INFO - PROCESS | 1677 | Values: 96.0 97.0 104.0 96.0 150.0 99.0 105.0 148.0 147.0 97.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:105.10 Median:84.50 stddev:25.86 (30.6%) stddev-sans-first:27.17 05:50:25 INFO - PROCESS | 1677 | Values: 95.0 80.0 98.0 93.0 122.0 102.0 89.0 146.0 76.0 150.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:58.00 stddev:0.92 (1.6%) stddev-sans-first:0.97 05:50:25 INFO - PROCESS | 1677 | Values: 58.0 57.0 56.0 58.0 59.0 57.0 58.0 58.0 59.0 58.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:83.40 Median:70.50 stddev:16.43 (23.3%) stddev-sans-first:15.86 05:50:25 INFO - PROCESS | 1677 | Values: 64.0 87.0 63.0 103.0 77.0 82.0 64.0 87.0 103.0 104.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:93.80 Median:89.00 stddev:14.03 (15.8%) stddev-sans-first:14.81 05:50:25 INFO - PROCESS | 1677 | Values: 90.0 88.0 90.0 91.0 89.0 89.0 88.0 76.0 125.0 112.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:142.50 stddev:7.11 (5.0%) stddev-sans-first:7.19 05:50:25 INFO - PROCESS | 1677 | Values: 149.0 147.0 135.0 138.0 138.0 139.0 139.0 143.0 159.0 142.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.70 Median:190.50 stddev:24.97 (13.1%) stddev-sans-first:25.22 05:50:25 INFO - PROCESS | 1677 | Values: 167.0 202.0 227.0 171.0 168.0 169.0 168.0 179.0 210.0 226.0 05:50:25 INFO - PROCESS | 1677 | 05:50:25 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:71.00 stddev:10.55 (14.9%) stddev-sans-first:10.65 05:50:25 INFO - PROCESS | 1677 | Values: 60.0 65.0 77.0 62.0 63.0 58.0 60.0 81.0 80.0 86.0 05:50:25 INFO - PROCESS | 1677 | -------- Summary: end -------- 05:50:25 INFO - PROCESS | 1677 | 05:50:26 INFO - TEST-INFO | 1677: exit 0 05:50:26 INFO - TEST-OK | kraken | took 60989ms 05:50:26 INFO - SUITE-END | took 1173s 05:50:26 INFO - Completed test suite (00:19:34) 05:50:26 INFO - javascript benchmark 05:50:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7073.321329604089, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3328.983355083225, 3478.0435380270437, 3491.1097670094123, 3530.7704999175053, 3543.645635436455, 5707.115397113444, 5747.913781293273, 5748.827535173941, 5821.8835623287505, 5855.619384739989, 5670.943290567104, 5749.56878234132, 5770.7691692332255, 5802.796902108427, 5899.764009439617, 5166.870828229296, 5212.609054320941, 5266.420693723707, 5267.736613169345, 5312.442193569677, 264.3264960879678, 5109.974450127763, 5261.158214685651, 5270.49930256625, 5299.443558426367, 5258.92111618326, 5260.34245719287, 5285.762140703678, 5302.92045619316, 5313.282706834567, 5086.135356989321, 5282.366116066097, 5323.760430780624, 5330.76011579476, 5333.386660534011, 5203.973980130113, 5206.114960456694, 5237.423883372826, 5275.920861187085, 5283.524482796528, 5621.184928185431, 5665.716714164317, 5728.026235539968, 5730.08318669011, 5748.770049198027, 5261.921071183935, 6152.477039451652, 6246.969250073777, 6335.5248356372995, 6336.271328797156, 6201.410865967771, 6256.499480041586, 6280.497560195174, 6298.68506574674, 6306.936930630681, 5912.260967379078, 5949.315828679714, 5973.850653733692, 5974.4324289192455, 5995.160677505153, 5708.71456427177, 6205.937940620536, 6326.68366581669, 6326.810195694136, 6373.075903993895, 6150.661713605705, 6159.84600384994, 6185.938140618537, 6199.163112979781, 6206.410391012847, 6205.503559715212, 6283.6858157091965, 6312.558120931582, 6321.273053598895, 6322.462590679844, 4573.839915602938, 6215.81352559424, 6225.408586184305, 6281.403266689657, 6322.114903913456, 6296.874062518816, 6307.905381419282, 6327.209098862642, 6330.525210609223, 6331.335209802996, 4948.851534453972, 5012.674176178537, 5020.949790502048, 5025.120603894304, 5056.266841308064, 4345.196138714348, 4445.933311000337, 4451.376807246988, 4497.302918047708, 4542.250528662799, 5777.537796976232, 5792.304923409133, 5853.1220316952795, 5877.0, 5888.998870192077, 5487.122060470386, 6224.470919971763, 6262.029385445172, 6266.874662506724, 6270.905936410957, 5716.399778023331, 5840.445157710011, 5894.616849904743, 5913.876363491009, 5922.555808314393, 5464.071107911585, 5490.039243132515, 5504.119340905436, 5521.917171242435, 5533.806316778893, 6236.34518375564, 6460.257070436866, 6463.127477790438, 6475.32009139043, 6494.64279464634, 6199.721012554536, 6248.718807653758, 6264.373562643699, 6268.843278917881, 6304.148939893239, 5370.9731451342495, 6387.712552935036, 6395.0, 6417.550771445954, 6598.736050558116, 6152.323244443018, 6172.8765424690355, 6210.099535567408, 6290.2766181889665, 6298.118263443213, 6058.969705151446, 6226.906596401148, 6276.780312689034, 6288.591241569284, 6310.242770867525, 6204.5967012144065, 6225.750969961151, 6226.1905952225925, 6240.469560087262, 6249.406306400884, 6093.421124993119, 6148.754049838135, 6191.19514463117, 6220.191375121386, 6239.594426362272, 4769.475357710579, 5867.973104706744, 6130.329445263203, 6148.016317389196, 6151.769646070714, 6223.004319308889, 6232.501399888089, 6241.0, 6265.561410701207, 6280.403361680633, 5903.350631430625, 6038.818835434944, 6119.654885716854, 6125.9693701531205, 6158.168647232746], "value": 5709.3052444872765, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.1225786486849, 514.9227615857618, 522.4853519283505, 526.0636067799318, 567.6962824888685, 32243.677563224363, 34253.972380828556, 37930.0517487063, 41015.58984410155, 41127.38308925365, 40619.593804062024, 43672.56327436725, 43769.343459848125, 43832.0, 43836.780816095874, 7982.96008519958, 9637.747092877917, 9728.805423891517, 9750.609975600968, 9769.071938165864, 8252.546109963949, 9674.951625241863, 9684.467354295512, 9695.272854535902, 9699.854502182472, 9670.9516452418, 9713.46575938323, 9717.56270967808, 9757.853632195523, 9769.364991275545, 8483.74548763538, 9557.569909354095, 9667.178289845337, 9713.805723885484, 9725.173360264314, 9502.952485237529, 9526.142647161789, 9572.760680982961, 9587.568559414844, 9600.855987160197, 40195.799021005, 40612.18775624471, 40614.0, 40695.7965210175, 40709.389359159635, 3060.8010479318996, 3067.9846600766855, 3374.4263475209273, 3505.614382417932, 3558.5373901392913, 30466.0, 30614.0, 30882.691173088206, 31011.565853948876, 31289.217769555715, 3114.5639610454555, 3148.8583013764437, 3191.48936170214, 3208.3262514871903, 3222.951655725166, 467361.0, 468889.0, 470748.64625676995, 472560.0, 472858.0, 34358.48462273068, 34842.30315393729, 34878.65121348754, 34892.12769680778, 34947.4757878632, 32656.020319390456, 33577.83211083978, 34587.0, 36175.4573681395, 36624.0, 16783.0, 16795.16024198785, 16875.0, 16960.406385776685, 17085.402010929807, 12962.416691248916, 13049.347532623331, 13117.212967221998, 13159.934200328937, 13169.868301316865, 16440.83559164417, 16563.6687266254, 19861.801381986283, 20081.39755807328, 20137.597248054957, 7962.283394494523, 8097.109317975018, 8108.675652973933, 8126.0, 8222.944263934016, 875.3216257400566, 929.2705226397246, 1010.9494525273707, 1090.8909109089186, 1165.6677846813618, 30270.0, 30661.0, 30739.692603073683, 31305.37389252202, 31489.0, 972.1687956796837, 1011.489197955028, 1039.5010395010438, 1104.304288298383, 1122.3882983773863, 13096.0, 14666.120032798066, 15402.152881591395, 15511.379544818306, 15718.449854255046, 614679.0, 627309.0, 627622.0, 631441.0, 699470.0, 42590.57409425866, 59464.513387165665, 59522.0, 59862.0, 59988.40011599915, 1343.2410687961467, 1344.6369480240476, 1356.389624668903, 1410.2525661399475, 1421.8862491000696, 29037.85481072581, 29087.563686544294, 29199.978000769872, 29958.400831982803, 29987.400251995274, 51963.22055169251, 52534.47465525449, 52694.0, 52707.0, 52737.7363113182, 491531.0, 549760.0, 614979.8502015101, 615615.9219204055, 615780.0, 26689.466210676066, 26913.73086269112, 26993.730062699906, 27101.1869643911, 27260.318492037066, 47079.0, 47869.04261914673, 47943.52056479391, 48591.0, 48922.266166006826, 39058.0, 39461.2107757849, 39546.60453395429, 40242.59757402389, 40424.595754042086, 32129.0, 38662.613373865905, 38819.02952426212, 39257.803710981265, 39505.80247098746, 32399.51400729038, 32738.836305818317, 37728.81135594304, 38719.0, 39724.60275397209, 33209.66790332066, 34377.484337735455, 35330.82334588311, 35444.46833297554, 35954.8202258987, 25050.3737406559, 25645.358866027753, 25936.351591210372, 25961.0, 26406.339841504116], "value": 19390.518933746305, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.60227062719474, 380.70114959756586, 385.36414915389605, 397.8110397561157, 404.094827586207, 19366.12852421641, 21106.472338191554, 21934.341969740894, 22033.33899982999, 22097.558048839055, 11491.942540287288, 12164.391780410988, 12168.634940951786, 21517.81652009139, 22039.33881983539, 5287.867803304919, 5449.945500544984, 5486.478784515464, 5491.780328786864, 5687.886242275173, 5209.21861720743, 5796.478316951474, 5994.940050599482, 6079.270487541501, 6107.81676549702, 5715.428457154293, 5949.970250148744, 5991.1612374267415, 6034.547408944325, 6060.999935010718, 5948.762049518035, 5970.761169553212, 6000.849978750544, 6004.909926351108, 6036.96981515094, 5073.315102461194, 5261.631685781997, 5263.421023687391, 5295.2586637870345, 5296.020236256305, 13122.737545249138, 13507.25710085945, 13509.05436619437, 24283.392915177086, 25421.745782542308, 2054.681524363726, 2095.413284280404, 2100.12844669462, 2234.72065991751, 2340.789328960385, 10033.749156271151, 10187.694369168938, 10392.688219353431, 10401.323913945569, 11897.881021189763, 1856.60083082138, 1887.2073729033839, 1917.9712304315447, 1921.5196200949763, 1949.064449064443, 88035.11964880333, 122498.0, 123090.38454807758, 123395.0, 123781.57310561305, 11520.481578328996, 11831.76336473283, 11852.170348075724, 11986.520539178335, 12004.939975300067, 14752.114873107434, 14813.0, 14817.851411545265, 14817.925910370379, 14833.258337083103, 8597.484150950962, 8636.17956294151, 8663.523506207219, 8705.434146780439, 8813.867791983126, 8638.09300023501, 8659.437136586102, 8722.34582406322, 8786.98949620789, 8828.558572071372, 4456.643468522575, 4566.246569316092, 4576.222042252825, 4598.126355992351, 4660.021395506914, 1748.8370233794637, 1903.1340739963284, 1939.156466936887, 1973.3882496426154, 2009.5880344529337, 383.2239714578002, 391.29566779796255, 824.9298809601131, 901.9864702029474, 1048.8741351037772, 11476.827847582294, 11915.046796256278, 11999.980001699952, 12000.1599888007, 12213.45039473226, 426.81133044471, 428.5928368050365, 435.2231267188054, 447.78058751212325, 471.44369643819937, 6053.182820319289, 6161.045038019114, 6233.0, 6241.469475094668, 6250.312465628777, 618164.0, 621139.0, 623690.0, 624640.0, 631814.0, 108213.0, 143385.0, 144293.0, 145763.0, 150155.0, 433.65091101663467, 445.4476449202965, 445.61231728395853, 446.66276960894965, 447.8052047359409, 9344.392614480037, 10855.511501982588, 11428.599998999995, 11443.885561144281, 11493.540258389905, 55314.16028534954, 56213.15680264881, 56231.15653265286, 56548.86902261849, 58424.12363814631, 85105.14894850973, 85394.57302713447, 85766.64935480084, 85799.1420085816, 86212.56893715491, 7992.6802927884555, 8129.601767478726, 8177.223163799429, 8292.33661307106, 8333.416660833684, 11564.710882228012, 12807.935960320512, 12945.741085178432, 13080.0, 13124.34378281063, 10058.849117263393, 10461.320014199053, 11084.279521831055, 11230.438478076227, 11276.32342059479, 6277.780277690259, 8445.282151016987, 10877.945610271898, 11025.338479690923, 11400.600978965696, 9618.422894626345, 9788.216942644429, 11201.0, 11449.427528623703, 11573.363465009505, 6670.866582668416, 7274.272572742895, 7507.737229196952, 8106.716264930699, 8353.874691879506, 10212.234082443994, 10668.466576671291, 11052.447377631248, 11618.419079046183, 11752.059835213333], "value": 8568.424146974767, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1518.0512179887573, 1573.315607710646, 1745.9214335354916, 1846.261495401839, 1915.0903320922573, 7881.0, 8386.37102217333, 8410.873836892444, 8914.465132092071, 8954.86567701485, 8514.020887597928, 8517.446365986207, 8691.174338437853, 8699.173578510046, 8704.91295087049, 2322.895469703863, 2340.414896275931, 2346.6128088865316, 2354.2584086012935, 2358.127492827656, 2333.288347054148, 2333.474968132165, 2343.121329501437, 2386.1290628920406, 2391.497785465054, 2319.5592837360846, 2345.847519911209, 2350.6003979323473, 2360.9055637774554, 2372.988135059331, 2334.323046316566, 2340.0990618611786, 2354.434935615457, 2357.4931389751287, 2359.1271229645054, 2312.5259321838985, 2338.1933233034574, 2348.9295321140394, 2363.6336367862837, 2367.112332875172, 7363.558186508827, 8687.0, 8701.651933922636, 8707.216350528483, 8741.73774786751, 5147.382314122292, 5270.183121616155, 5290.709011004393, 5301.3373328333955, 5307.734613269321, 3487.7907325560545, 3593.425051991695, 3625.5830579483168, 3643.7813731176216, 3660.3228402745576, 1069.4171676436283, 1074.785042991405, 1078.8921107889228, 1080.1628737728245, 1087.2226358153878, 11155.38645374504, 11176.552937882476, 11279.774404511863, 11310.604128855533, 11310.830337544943, 4514.329698011757, 4617.676762626584, 4726.905461890812, 4727.510834087251, 4737.38414006177, 9543.713688589354, 9679.274054445945, 9691.27315451344, 9713.465759383302, 9723.61105555784, 4434.26834572292, 5242.633015688942, 5272.420033796356, 5299.6025298102795, 5301.0723123453245, 4365.738055716603, 4700.435947686299, 4837.95162048375, 4848.224284114524, 4849.951500485021, 3883.669888059547, 3939.92120157601, 3985.3025720498795, 4012.0170558213426, 4058.350663893763, 1265.3736400481637, 1267.3916520070234, 1272.179444258448, 1273.6688461000206, 1274.808778683205, 794.9284564389233, 889.6485888074175, 911.5761171055359, 945.3713280668416, 962.3936919740519, 5389.487998640123, 5703.515201207943, 5840.620359676607, 5870.677112758839, 5969.373215812364, 419.57203652274507, 439.98240070397463, 453.64162311773987, 454.7703409778041, 456.1606643775469, 3859.6630879294935, 3932.5477570079233, 3987.80060996949, 3999.320115580358, 4002.959970400317, 10162.03460671235, 10511.0, 10542.73643158912, 10556.47217639115, 10586.258961872594, 10387.636432724817, 10411.895881041244, 10488.947555262175, 10537.473126343652, 10551.577936882439, 8089.716859909875, 8104.0, 8151.347892168731, 8186.467879587808, 8203.71287004952, 5697.7151142443545, 5746.885062298814, 5757.251557297524, 5823.388544202798, 5968.641881487125, 7722.652480638497, 9722.979087195743, 9765.560549775419, 9768.804623907625, 9800.754981125529, 9520.19116367888, 9714.368566043184, 9754.512274386394, 9876.654317098866, 9889.604415823575, 6938.548994315353, 7154.0, 7349.853002939804, 7431.591262480615, 7552.773416797505, 6988.8951665724035, 7896.6446509906045, 7985.001874765654, 8017.879731804144, 8088.9595552021865, 7079.787606371817, 7204.135503739585, 7214.567125972459, 7301.598412087386, 7317.2316906726155, 7051.365377116084, 7062.823429414306, 7105.182903965903, 7162.606056666725, 7180.892286615601, 6652.401283884474, 6835.692393842389, 6843.657817109224, 6869.175698916162, 6875.518713690194, 4119.567445418188, 4784.95215047859, 4846.612271018381, 4859.611231101433, 4947.332110165082, 4169.708120431541, 4873.829415970424, 4914.262860570943, 4927.088488629615, 5029.723365214948], "value": 4430.36675483113, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.45369672716026, 299.2862024072586, 315.722900865997, 318.4602099441445, 321.5145130852412, 5039.44566097729, 5324.254604355393, 5429.7285135743205, 5515.448455154483, 5591.720413979305, 5111.693298402099, 5350.464953504647, 5474.589405794562, 5497.39528651848, 5529.253550770644, 4159.417681524581, 4172.248995180867, 4185.246655602005, 4195.391668208108, 4207.537170911197, 3802.030482227028, 4098.7540747555095, 4140.213359461708, 4191.979040104795, 4220.345846393814, 3456.861725530976, 3995.942089823743, 4007.138465229975, 4011.057401510657, 4035.4552135461777, 3758.7932663703486, 4005.4792876926103, 4048.6558642515274, 4048.6558642515274, 4066.857659981916, 3416.367971925202, 3508.6666766657127, 3528.929421411583, 3536.2043540203504, 3542.167590616203, 3895.18201177753, 4188.120494696117, 4849.5635392814465, 5075.314832497603, 5197.324347834795, 3743.606921273254, 4629.398178236807, 4663.743494107823, 4717.410323709536, 4753.619710423158, 4971.651984361096, 5020.949790502084, 5169.147090730025, 5450.5367043801325, 5725.885482290372, 3449.9482507762405, 3573.4818451324672, 3860.227954409101, 3862.3820188769664, 3875.0312421894528, 5509.311336082989, 5516.420775818561, 5546.77812887484, 6049.364816694228, 6076.179715738319, 3653.825973545736, 3888.191990724327, 3889.4554762333296, 3906.8632597858937, 3906.980465097656, 3236.7248783853165, 3531.0, 3813.809309534512, 3993.600639935983, 3996.7402118862183, 4356.910772306924, 4545.499975013729, 4584.472785629695, 4591.724496530219, 4649.302604609268, 4662.53374662531, 5280.339957505312, 5612.663240205601, 5744.167095771089, 5787.88424231513, 1169.9707507312385, 1240.4418011894504, 1301.9218846869028, 1344.9529266475627, 1350.750111229406, 718.5616773767997, 743.5984568333148, 824.6660102658433, 826.1160558202665, 829.1832544943226, 3889.377699568112, 4489.349044388546, 4518.977405112953, 4539.638108567483, 4545.931811022837, 4238.87283381499, 4635.814567417333, 5015.244664367515, 5015.244664367515, 5024.84925452237, 3761.680257178116, 3869.1681288523105, 4267.743935363888, 4505.189065968158, 4543.840965566189, 2361.090979972723, 2436.7197772256063, 2460.7785299323145, 2515.811244217506, 2583.984492096168, 4591.494935557086, 4806.951930480651, 4881.048195601895, 5234.842954711365, 5254.97372513135, 4483.955160448419, 4492.303692927601, 4644.584815895715, 4961.776720047606, 5138.97430512845, 4380.970471939092, 4553.567411095941, 4650.488446270908, 4986.975065124651, 5232.88068332326, 4339.978300108479, 4575.392026544971, 4977.104121258209, 4978.266300274794, 5056.926660069354, 4288.571142885687, 4414.867553973386, 4463.196624607602, 4780.832670856504, 4925.315702941977, 4366.0, 4609.041157507979, 4848.527251101893, 5227.660202086852, 5296.285001524745, 3144.9371012580077, 3195.9613125734695, 3394.8981530554124, 3429.3141371726256, 3479.739019573593, 4474.082813023388, 4521.457425109008, 4798.568128868418, 5025.874353141055, 5621.775128994795, 4717.985633088902, 5268.947310526845, 5560.694161821139, 5723.0, 5737.655740655574, 4797.840971562741, 5738.254026976467, 5750.338711048283, 5797.884042319214, 5814.94185058144, 4576.0, 4790.520947905321, 5069.8732531687, 5225.6603320784025, 5407.324084489439, 4672.7429991349445, 4756.476787553297, 5388.811391601275, 5655.519280861008, 5729.0, 4306.935395969001, 4819.518048195152, 5654.038813401649, 5664.376918538875, 5693.629914055573], "value": 3851.7940813903465, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.8948902409151, 730.466759265736, 753.3596443023428, 788.235411650699, 808.2765924497578, 12047.75904481911, 15466.690666186669, 15553.0, 15739.763903541427, 15774.842251577495, 14254.429822807102, 14338.641533961656, 14341.426342946295, 14365.0, 14386.856131438682, 671.6775947545182, 856.6915910272297, 856.9657213711475, 863.0721973878075, 879.134052957836, 873.8995015573195, 880.7842078690729, 882.8499155143609, 886.7339798060589, 890.1721399098835, 902.9051949545302, 928.3408779766374, 932.2914584915445, 949.3164921256684, 950.6150009246267, 911.0980129671643, 920.102899672817, 945.3382632157449, 945.3524335829978, 945.4752612300205, 685.5201359048685, 694.4756708684961, 700.2087640965727, 700.8843540815759, 702.0907924238124, 14458.783118253235, 14544.345504452325, 14684.118952862756, 14709.0, 14721.0, 14395.424183032666, 14449.49426770058, 14462.855371446256, 14472.348744306531, 14508.637284067878, 11179.273347232403, 11605.0, 11633.069354451623, 11716.765664686658, 11761.411929403495, 4538.364628951949, 4591.380163677927, 4627.190241707722, 4782.330473733646, 4792.568668819823, 100762.0, 102106.0, 104596.0, 105311.47344263307, 105805.47097264542, 10962.506687199095, 10964.56141754337, 10981.61564345244, 11087.72280692989, 11269.21115521922, 20814.85518296478, 20862.374128776162, 20862.582748344947, 20871.060802263935, 20881.26915557948, 7395.593242371614, 7417.851642967218, 7496.362809161174, 7706.190849960785, 7733.188015258429, 4054.3107671695293, 4473.150101480708, 4493.977530112394, 4519.728816271035, 4696.671233013724, 3406.625271220164, 3447.1209841490763, 3506.3688536063755, 3515.5254040704176, 3571.785692858437, 661.4576047640888, 666.9066330713704, 667.1427216027381, 671.2750229751856, 673.7709178879106, 572.4447286132445, 596.4900010490968, 620.7925584737732, 637.7417146055855, 638.6647010319526, 8867.157620026217, 8912.955435222912, 8988.685396011108, 9011.143941325563, 9116.8632470513, 510.4308695804226, 621.3041393639153, 630.501903496242, 633.1863555331469, 633.5818359882547, 4391.7584532851, 4410.316400957857, 4424.579664931826, 4458.242098843204, 4467.597916187558, 138507.0, 139593.0, 141437.0, 144397.0, 147520.0, 72626.63686681533, 118129.0, 121556.39221804011, 121879.39060304819, 124011.3799430997, 75635.0, 99573.50213249032, 100221.0, 101328.0, 104620.43069353966, 8247.546384948886, 8285.212904774036, 8431.40980131385, 8437.240648341678, 8491.95754021226, 58325.83348333094, 58523.0, 59913.0, 60932.0, 61294.0, 78355.21644783707, 79207.0, 83748.58125709576, 89739.55130224307, 91013.08986910045, 13343.0, 13378.063535552417, 13508.0, 13807.309634518435, 14087.436502539784, 14624.268786560842, 14627.92686036563, 14709.957831986243, 14944.775828362366, 15203.163825989677, 13584.456621735415, 13844.653883652989, 13885.791713124514, 13917.0, 13920.303984800517, 13650.93174534121, 13843.307834608431, 13897.374618142007, 14450.527573163365, 14623.634409139857, 12998.220106793622, 13309.20547562761, 13424.463021479032, 13477.258750768802, 13904.72190556163, 10403.635872744419, 11208.991190792867, 11421.714457138638, 11575.710607234887, 11701.979785142757, 9628.47043412619, 10988.725281868017, 11341.149413794163, 11344.992494850909, 11552.711182220175], "value": 7735.996665990118, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 110.0, 116.0, 116.0, 116.0, 119.0, 117.0, 116.0, 120.0, 116.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 122.0, 145.0, 167.0, 123.0, 148.0, 143.0, 121.0, 177.0, 134.0], "value": 143.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 202.0, 205.0, 255.0, 254.0, 264.0, 197.0, 198.0, 194.0, 199.0], "value": 216.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 81.0, 77.0, 140.0, 58.0, 58.0, 59.0, 64.0, 129.0, 57.0], "value": 79.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 116.0, 72.0, 72.0, 79.0, 115.0, 71.0, 72.0, 116.0], "value": 85.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 90.0, 88.0, 91.0, 73.0, 135.0, 132.0, 91.0, 87.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 104.0, 96.0, 150.0, 99.0, 105.0, 148.0, 147.0, 97.0], "value": 113.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 80.0, 98.0, 93.0, 122.0, 102.0, 89.0, 146.0, 76.0, 150.0], "value": 105.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 58.0, 59.0, 57.0, 58.0, 58.0, 59.0, 58.0], "value": 57.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 87.0, 63.0, 103.0, 77.0, 82.0, 64.0, 87.0, 103.0, 104.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 91.0, 89.0, 89.0, 88.0, 76.0, 125.0, 112.0], "value": 93.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 135.0, 138.0, 138.0, 139.0, 139.0, 143.0, 159.0, 142.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 202.0, 227.0, 171.0, 168.0, 169.0, 168.0, 179.0, 210.0, 226.0], "value": 188.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 65.0, 77.0, 62.0, 63.0, 58.0, 60.0, 81.0, 80.0, 86.0], "value": 69.2, "unit": "score"}]}]} 05:50:27 INFO - Return code: 0 05:50:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:50:27 ERROR - # TBPL SUCCESS # 05:50:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:50:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:50:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:50:27 INFO - 2016-06-03 05:50:27.099 screenresolution[1682:10890] starting screenresolution argv=screenresolution get 05:50:27 INFO - 2016-06-03 05:50:27.102 screenresolution[1682:10890] Display 0: 1600x1200x32@60 05:50:27 INFO - 2016-06-03 05:50:27.108 screenresolution[1683:10893] starting screenresolution argv=screenresolution list 05:50:27 INFO - Available Modes on Display 0 05:50:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:50:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:50:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:50:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:50:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:50:27 INFO - Intel Iris: 05:50:27 INFO - Chipset Model: Intel Iris 05:50:27 INFO - Type: GPU 05:50:27 INFO - Bus: Built-In 05:50:27 INFO - VRAM (Dynamic, Max): 1536 MB 05:50:27 INFO - Vendor: Intel (0x8086) 05:50:27 INFO - Device ID: 0x0a2e 05:50:27 INFO - Revision ID: 0x0009 05:50:27 INFO - Displays: 05:50:27 INFO - Display: 05:50:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:50:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:50:27 INFO - Display Serial Number: 1600x1200 60 05:50:27 INFO - Main Display: Yes 05:50:27 INFO - Mirror: Off 05:50:27 INFO - Online: Yes 05:50:27 INFO - Rotation: Supported 05:50:27 INFO - Return code: 0 05:50:27 INFO - Running post-action listener: _resource_record_post_action 05:50:27 INFO - ##### 05:50:27 INFO - ##### Finished run-tests step (success) 05:50:27 INFO - ##### 05:50:27 INFO - Running post-run listener: _resource_record_post_run 05:50:27 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 31448576; Write bytes: 700311552; Read time: 364; Write time: 2085 05:50:27 INFO - TinderboxPrint: CPU usage
54.6% 05:50:27 INFO - TinderboxPrint: I/O read bytes / time
31,448,576 / 364 05:50:27 INFO - TinderboxPrint: I/O write bytes / time
700,311,552 / 2,085 05:50:27 INFO - TinderboxPrint: CPU idle
2,197.1 (45.4%) 05:50:27 INFO - TinderboxPrint: CPU system
973.8 (20.1%) 05:50:27 INFO - TinderboxPrint: CPU user
1,670.8 (34.5%) 05:50:27 INFO - TinderboxPrint: Swap in / out
427,032,576 / 0 05:50:27 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 383095296; Write bytes: 377382400; Read time: 29268; Write time: 739 05:50:27 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 30522880; Write bytes: 297808384; Read time: 347; Write time: 1296 05:50:27 INFO - Running post-run listener: _upload_blobber_files 05:50:27 INFO - Blob upload gear active. 05:50:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:50:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:50:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:50:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:50:28 INFO - (blobuploader) - INFO - Open directory for files ... 05:50:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:50:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:50:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:29 INFO - (blobuploader) - INFO - Done attempting. 05:50:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:50:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:50:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:30 INFO - (blobuploader) - INFO - Done attempting. 05:50:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:50:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:50:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:32 INFO - (blobuploader) - INFO - Done attempting. 05:50:32 INFO - (blobuploader) - INFO - Iteration through files over. 05:50:32 INFO - Return code: 0 05:50:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:50:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:50:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/28ab13cd4cc3a4e6b1d6918603c7621de895f21811d18eec62914556eaadac974a3423d4743b2f3cb4df950ab53611541874a323fb1101fb0a95aaa43dbd1b7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c0ce68a054a3d8701eb5c257358a01acb56c63c4acd48397e9002a0840f0e10f5af4fae9cb884cca1a85c4060e1e15bd67d372dc11daaf5c7174f2f60b95e69e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a833362902c204d1209b50c1bb21da0cd852f2983c04f50ea679e67714f98959d54e445a72b935cd7da2266d19b708b99dad3d21ed017bd75fa638399809f2bf"} 05:50:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:50:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:50:32 INFO - Contents: 05:50:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/28ab13cd4cc3a4e6b1d6918603c7621de895f21811d18eec62914556eaadac974a3423d4743b2f3cb4df950ab53611541874a323fb1101fb0a95aaa43dbd1b7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c0ce68a054a3d8701eb5c257358a01acb56c63c4acd48397e9002a0840f0e10f5af4fae9cb884cca1a85c4060e1e15bd67d372dc11daaf5c7174f2f60b95e69e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a833362902c204d1209b50c1bb21da0cd852f2983c04f50ea679e67714f98959d54e445a72b935cd7da2266d19b708b99dad3d21ed017bd75fa638399809f2bf"} 05:50:32 INFO - Running post-run listener: copy_logs_to_upload_dir 05:50:32 INFO - Copying logs to upload dir... 05:50:32 INFO - mkdir: /builds/slave/test/build/upload/logs 05:50:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.973615 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-06-03 05:50:32.839434) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 05:50:32.843140) ========= 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.N1Hgt5mPKk/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.ZPhG67o444/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/28ab13cd4cc3a4e6b1d6918603c7621de895f21811d18eec62914556eaadac974a3423d4743b2f3cb4df950ab53611541874a323fb1101fb0a95aaa43dbd1b7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c0ce68a054a3d8701eb5c257358a01acb56c63c4acd48397e9002a0840f0e10f5af4fae9cb884cca1a85c4060e1e15bd67d372dc11daaf5c7174f2f60b95e69e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a833362902c204d1209b50c1bb21da0cd852f2983c04f50ea679e67714f98959d54e445a72b935cd7da2266d19b708b99dad3d21ed017bd75fa638399809f2bf"} build_url:https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011205 build_url: 'https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/28ab13cd4cc3a4e6b1d6918603c7621de895f21811d18eec62914556eaadac974a3423d4743b2f3cb4df950ab53611541874a323fb1101fb0a95aaa43dbd1b7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c0ce68a054a3d8701eb5c257358a01acb56c63c4acd48397e9002a0840f0e10f5af4fae9cb884cca1a85c4060e1e15bd67d372dc11daaf5c7174f2f60b95e69e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a833362902c204d1209b50c1bb21da0cd852f2983c04f50ea679e67714f98959d54e445a72b935cd7da2266d19b708b99dad3d21ed017bd75fa638399809f2bf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 05:50:32.873493) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 05:50:32.873836) ========= 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.N1Hgt5mPKk/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.ZPhG67o444/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.004937 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 05:50:32.922407) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 05:50:32.922712) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 05:50:32.923030) ========= ========= Total master_lag: 0.27 =========