builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0206 starttime: 1464953533.02 results: success (0) buildid: 20160603033811 builduid: c57d571a48bc47a6877d9d4832f08942 revision: 4874ff5d90f81aca0bb57d2f74586210cbee3cf6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:13.023364) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:13.023873) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:13.024171) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:13.049407) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:13.049854) ========= 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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 04:32:13-- 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% 138M=0s 2016-06-03 04:32:13 (138 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.529043 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:13.600065) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:13.600376) ========= 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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.025455 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:13.648860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-03 04:32:13.649214) ========= 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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 04:32:13,731 truncating revision to first 12 chars 2016-06-03 04:32:13,731 Setting DEBUG logging. 2016-06-03 04:32:13,731 attempt 1/10 2016-06-03 04:32:13,731 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 04:32:15,727 unpacking tar archive at: fx-team-4874ff5d90f8/testing/mozharness/ program finished with exit code 0 elapsedTime=2.288330 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-03 04:32:15.957422) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:15.957805) ========= script_repo_revision: 4874ff5d90f81aca0bb57d2f74586210cbee3cf6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:15.958289) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:15.958557) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 04:32:15.975801) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-06-03 04:32:15.976193) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BN7Dqa1ANG/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.lYwbAcMHem/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:32:16 INFO - MultiFileLogger online at 20160603 04:32:16 in /builds/slave/test 04:32:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:32:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:32:16 INFO - {'append_to_log': False, 04:32:16 INFO - 'base_work_dir': '/builds/slave/test', 04:32:16 INFO - 'blob_upload_branch': 'Fx-Team', 04:32:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:32:16 INFO - 'branch': 'Fx-Team', 04:32:16 INFO - 'buildbot_json_path': 'buildprops.json', 04:32:16 INFO - 'config_files': ('talos/mac_config.py',), 04:32:16 INFO - 'default_actions': ('clobber', 04:32:16 INFO - 'read-buildbot-config', 04:32:16 INFO - 'download-and-extract', 04:32:16 INFO - 'populate-webroot', 04:32:16 INFO - 'create-virtualenv', 04:32:16 INFO - 'install', 04:32:16 INFO - 'run-tests'), 04:32:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:32:16 INFO - 'download_minidump_stackwalk': True, 04:32:16 INFO - 'download_symbols': 'ondemand', 04:32:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:32:16 INFO - 'tooltool.py': '/tools/tooltool.py', 04:32:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:32:16 INFO - '/tools/misc-python/virtualenv.py')}, 04:32:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:32:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:32:16 INFO - 'installer_path': 'installer.exe', 04:32:16 INFO - 'log_level': 'info', 04:32:16 INFO - 'log_name': 'talos', 04:32:16 INFO - 'log_to_console': True, 04:32:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:32:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:32:16 INFO - 'opt_config_files': (), 04:32:16 INFO - 'pip_index': False, 04:32:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:32:16 INFO - 'cmd': ('bash', 04:32:16 INFO - '-c', 04:32:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:32:16 INFO - 'enabled': True, 04:32:16 INFO - 'halt_on_failure': False, 04:32:16 INFO - 'name': 'check_screen_resolution'},), 04:32:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:32:16 INFO - 'cmd': ('bash', 04:32:16 INFO - '-c', 04:32:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:32:16 INFO - 'enabled': True, 04:32:16 INFO - 'halt_on_failure': False, 04:32:16 INFO - 'name': 'check_screen_resolution'},), 04:32:16 INFO - 'run_cmd_checks_enabled': True, 04:32:16 INFO - 'sps_profile': False, 04:32:16 INFO - 'sps_profile_interval': 0, 04:32:16 INFO - 'suite': 'dromaeojs', 04:32:16 INFO - 'system_bits': '32', 04:32:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:32:16 INFO - 'title': 't-yosemite-r7-0206', 04:32:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:32:16 INFO - 'use_talos_json': True, 04:32:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:32:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:32:16 INFO - 'work_dir': 'build'} 04:32:16 INFO - ##### 04:32:16 INFO - ##### Running clobber step. 04:32:16 INFO - ##### 04:32:16 INFO - Running pre-action listener: _resource_record_pre_action 04:32:16 INFO - Running main action method: clobber 04:32:16 INFO - rmtree: /builds/slave/test/build 04:32:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:32:17 INFO - Running post-action listener: _resource_record_post_action 04:32:17 INFO - ##### 04:32:17 INFO - ##### Finished clobber step (success) 04:32:17 INFO - ##### 04:32:17 INFO - ##### 04:32:17 INFO - ##### Running read-buildbot-config step. 04:32:17 INFO - ##### 04:32:17 INFO - Running pre-action listener: _resource_record_pre_action 04:32:17 INFO - Running main action method: read_buildbot_config 04:32:17 INFO - Using buildbot properties: 04:32:17 INFO - { 04:32:17 INFO - "project": "", 04:32:17 INFO - "product": "firefox", 04:32:17 INFO - "script_repo_revision": "production", 04:32:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:32:17 INFO - "repository": "", 04:32:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:32:17 INFO - "buildid": "20160603033811", 04:32:17 INFO - "pgo_build": "False", 04:32:17 INFO - "basedir": "/builds/slave/test", 04:32:17 INFO - "buildnumber": 748, 04:32:17 INFO - "slavename": "t-yosemite-r7-0206", 04:32:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:32:17 INFO - "platform": "yosemite_r7", 04:32:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:32:17 INFO - "branch": "fx-team", 04:32:17 INFO - "repo_path": "integration/fx-team", 04:32:17 INFO - "revision": "4874ff5d90f81aca0bb57d2f74586210cbee3cf6", 04:32:17 INFO - "stage_platform": "macosx64", 04:32:17 INFO - "builduid": "c57d571a48bc47a6877d9d4832f08942", 04:32:18 INFO - "slavebuilddir": "test" 04:32:18 INFO - } 04:32:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:32:18 INFO - Running post-action listener: _resource_record_post_action 04:32:18 INFO - ##### 04:32:18 INFO - ##### Finished read-buildbot-config step (success) 04:32:18 INFO - ##### 04:32:18 INFO - ##### 04:32:18 INFO - ##### Running download-and-extract step. 04:32:18 INFO - ##### 04:32:18 INFO - Running pre-action listener: _resource_record_pre_action 04:32:18 INFO - Running main action method: download_and_extract 04:32:18 INFO - mkdir: /builds/slave/test/build/tests 04:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:18 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 04:32:18 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 04:32:18 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 04:32:18 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 04:32:18 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 04:32:21 INFO - Downloaded 1199 bytes. 04:32:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:32:21 INFO - Using the following test package requirements: 04:32:21 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:32:21 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:32:21 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:32:21 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:32:21 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:32:21 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:32:21 INFO - u'jsshell-mac.zip'], 04:32:21 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:32:21 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:32:21 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:32:21 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:32:21 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:32:21 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:32:21 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:32:21 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:32:21 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:32:21 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:32:21 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:32:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32: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 04:32: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 04:32: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 04:32: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 04:32: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 04:32:23 INFO - Downloaded 18713579 bytes. 04:32:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:32:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:32:26 INFO - Return code: 0 04:32:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:26 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 04:32:26 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 04:32:26 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 04:32:26 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 04:32:26 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 04:32:29 INFO - Downloaded 18713579 bytes. 04:32:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:32:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:32:33 INFO - Return code: 0 04:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:33 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 04:32:33 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 04:32:33 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 04:32:33 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 04:32:33 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 04:32:34 INFO - Downloaded 10834055 bytes. 04:32:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:32:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:32:34 INFO - Return code: 0 04:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:34 INFO - https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:32:34 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 04:32:34 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 04:32:34 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 04:32:34 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 04:32:38 INFO - Downloaded 111083490 bytes. 04:32:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:32:38 INFO - mkdir: /builds/slave/test/properties 04:32:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:32:38 INFO - Writing to file /builds/slave/test/properties/build_url 04:32:38 INFO - Contents: 04:32:38 INFO - build_url:https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:32:39 INFO - Running post-action listener: _resource_record_post_action 04:32:39 INFO - Running post-action listener: set_extra_try_arguments 04:32:39 INFO - ##### 04:32:39 INFO - ##### Finished download-and-extract step (success) 04:32:39 INFO - ##### 04:32:39 INFO - ##### 04:32:39 INFO - ##### Running populate-webroot step. 04:32:39 INFO - ##### 04:32:39 INFO - Running pre-action listener: _resource_record_pre_action 04:32:39 INFO - Running main action method: populate_webroot 04:32:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:32:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:32:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:32:39 INFO - '--tppagecycles', 04:32:39 INFO - '7'], 04:32:39 INFO - 'tests': ['tsvgm']}}, 04:32:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:32:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:32:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:32:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:32:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:32:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:32:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:39 INFO - 'talos_options': ['--disable-e10s'], 04:32:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:32:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:32:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:39 INFO - 'talos_options': ['--disable-e10s'], 04:32:39 INFO - 'tests': ['damp', 'tps']}, 04:32:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:39 INFO - 'tests': ['damp', 'tps']}, 04:32:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:32:39 INFO - 'tests': ['dromaeo_dom']}, 04:32:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:32:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:32:39 INFO - 'tests': ['a11yr', 04:32:39 INFO - 'ts_paint', 04:32:39 INFO - 'tpaint', 04:32:39 INFO - 'sessionrestore', 04:32:39 INFO - 'sessionrestore_no_auto_restore', 04:32:39 INFO - 'tabpaint']}, 04:32:39 INFO - 'other-e10s': {'tests': ['a11yr', 04:32:39 INFO - 'ts_paint', 04:32:39 INFO - 'tpaint', 04:32:39 INFO - 'sessionrestore', 04:32:39 INFO - 'sessionrestore_no_auto_restore', 04:32:39 INFO - 'tabpaint']}, 04:32:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:32:39 INFO - 'ts_paint', 04:32:39 INFO - 'tpaint', 04:32:39 INFO - 'sessionrestore', 04:32:39 INFO - 'sessionrestore_no_auto_restore', 04:32:39 INFO - 'tabpaint']}, 04:32:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:32:39 INFO - 'ts_paint', 04:32:39 INFO - 'tpaint', 04:32:39 INFO - 'sessionrestore', 04:32:39 INFO - 'sessionrestore_no_auto_restore', 04:32:39 INFO - 'tabpaint']}, 04:32:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:32:39 INFO - 'tests': ['a11yr', 04:32:39 INFO - 'ts_paint', 04:32:39 INFO - 'tpaint', 04:32:39 INFO - 'sessionrestore', 04:32:39 INFO - 'sessionrestore_no_auto_restore', 04:32:39 INFO - 'tabpaint']}, 04:32:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:32:39 INFO - 'tests': ['a11yr', 04:32:39 INFO - 'ts_paint', 04:32:39 INFO - 'tpaint', 04:32:39 INFO - 'sessionrestore', 04:32:39 INFO - 'sessionrestore_no_auto_restore', 04:32:39 INFO - 'tabpaint']}, 04:32:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:32:39 INFO - 'tests': ['tsvgx', 04:32:39 INFO - 'tsvgr_opacity', 04:32:39 INFO - 'tart', 04:32:39 INFO - 'tscrollx', 04:32:39 INFO - 'cart']}, 04:32:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:32:39 INFO - 'tsvgr_opacity', 04:32:39 INFO - 'tart', 04:32:39 INFO - 'tscrollx', 04:32:39 INFO - 'cart']}, 04:32:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:39 INFO - 'talos_options': ['--disable-e10s'], 04:32:39 INFO - 'tests': ['tp5o']}, 04:32:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:39 INFO - 'tests': ['tp5o']}, 04:32:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:39 INFO - 'talos_options': ['--disable-e10s', 04:32:39 INFO - '--xperf_path', 04:32:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:32:39 INFO - 'tests': ['tp5n']}, 04:32:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:39 INFO - 'talos_options': ['--xperf_path', 04:32:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:32:39 INFO - 'tests': ['tp5n']}}, 04:32:39 INFO - 'talos.zip': {'path': '', 04:32:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:32:39 INFO - Running post-action listener: _resource_record_post_action 04:32:39 INFO - ##### 04:32:39 INFO - ##### Finished populate-webroot step (success) 04:32:39 INFO - ##### 04:32:39 INFO - ##### 04:32:39 INFO - ##### Running create-virtualenv step. 04:32:39 INFO - ##### 04:32:39 INFO - Running pre-action listener: _resource_record_pre_action 04:32:39 INFO - Running main action method: create_virtualenv 04:32:39 INFO - Creating virtualenv /builds/slave/test/build/venv 04:32:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:32:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:32:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:32:39 INFO - Using real prefix '/tools/python27' 04:32:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:32:40 INFO - Installing distribute.............................................................................................................................................................................................done. 04:32:43 INFO - Installing pip.................done. 04:32:43 INFO - Return code: 0 04:32:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:32:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:43 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 0x108b17a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b1d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb61481ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bbd30>, '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 0x1087dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2b20>, '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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:32:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:32:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:32:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BN7Dqa1ANG/Render', 04:32:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:43 INFO - 'HOME': '/Users/cltbld', 04:32:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:43 INFO - 'LOGNAME': 'cltbld', 04:32:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:43 INFO - 'MOZ_NO_REMOTE': '1', 04:32:43 INFO - 'NO_EM_RESTART': '1', 04:32:43 INFO - 'PAGER': '/bin/cat', 04:32:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:43 INFO - 'PWD': '/builds/slave/test', 04:32:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:43 INFO - 'SHELL': '/bin/bash', 04:32:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lYwbAcMHem/Listeners', 04:32:43 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 04:32:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:43 INFO - 'USER': 'cltbld', 04:32:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:43 INFO - 'XPC_FLAGS': '0x0', 04:32:43 INFO - 'XPC_SERVICE_NAME': '0', 04:32:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:32:43 INFO - Cleaning up... 04:32:43 INFO - Return code: 0 04:32:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:32:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:43 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 0x108b17a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b1d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb61481ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bbd30>, '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 0x1087dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2b20>, '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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:32:43 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 04:32:43 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 04:32:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BN7Dqa1ANG/Render', 04:32:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:43 INFO - 'HOME': '/Users/cltbld', 04:32:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:43 INFO - 'LOGNAME': 'cltbld', 04:32:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:43 INFO - 'MOZ_NO_REMOTE': '1', 04:32:43 INFO - 'NO_EM_RESTART': '1', 04:32:43 INFO - 'PAGER': '/bin/cat', 04:32:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:43 INFO - 'PWD': '/builds/slave/test', 04:32:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:43 INFO - 'SHELL': '/bin/bash', 04:32:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lYwbAcMHem/Listeners', 04:32:43 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 04:32:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:43 INFO - 'USER': 'cltbld', 04:32:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:43 INFO - 'XPC_FLAGS': '0x0', 04:32:43 INFO - 'XPC_SERVICE_NAME': '0', 04:32:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:43 INFO - Downloading/unpacking psutil>=3.1.1 04:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:32:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:32:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:32:46 INFO - Installing collected packages: psutil 04:32:46 INFO - Running setup.py install for psutil 04:32:46 INFO - building 'psutil._psutil_osx' extension 04:32:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:32:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:32:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:32:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:32:47 INFO - building 'psutil._psutil_posix' extension 04:32:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:32:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:32:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:32:47 INFO - ^ 04:32:47 INFO - 1 warning generated. 04:32:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:32:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:32:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:32:47 INFO - Successfully installed psutil 04:32:47 INFO - Cleaning up... 04:32:47 INFO - Return code: 0 04:32:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:47 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 0x108b17a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b1d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb61481ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bbd30>, '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 0x1087dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2b20>, '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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:32:47 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 04:32:47 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 04:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BN7Dqa1ANG/Render', 04:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:47 INFO - 'HOME': '/Users/cltbld', 04:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:47 INFO - 'LOGNAME': 'cltbld', 04:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:47 INFO - 'MOZ_NO_REMOTE': '1', 04:32:47 INFO - 'NO_EM_RESTART': '1', 04:32:47 INFO - 'PAGER': '/bin/cat', 04:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:47 INFO - 'PWD': '/builds/slave/test', 04:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:47 INFO - 'SHELL': '/bin/bash', 04:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lYwbAcMHem/Listeners', 04:32:47 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 04:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:47 INFO - 'USER': 'cltbld', 04:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:47 INFO - 'XPC_FLAGS': '0x0', 04:32:47 INFO - 'XPC_SERVICE_NAME': '0', 04:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:32:50 INFO - Installing collected packages: mozsystemmonitor 04:32:50 INFO - Running setup.py install for mozsystemmonitor 04:32:50 INFO - Successfully installed mozsystemmonitor 04:32:50 INFO - Cleaning up... 04:32:51 INFO - Return code: 0 04:32:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:32: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')]} 04:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32: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')]} 04:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108b17a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b1d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb61481ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bbd30>, '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 0x1087dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2b20>, '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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BN7Dqa1ANG/Render', 04:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:51 INFO - 'HOME': '/Users/cltbld', 04:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:51 INFO - 'LOGNAME': 'cltbld', 04:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:51 INFO - 'MOZ_NO_REMOTE': '1', 04:32:51 INFO - 'NO_EM_RESTART': '1', 04:32:51 INFO - 'PAGER': '/bin/cat', 04:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:51 INFO - 'PWD': '/builds/slave/test', 04:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:51 INFO - 'SHELL': '/bin/bash', 04:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lYwbAcMHem/Listeners', 04:32:51 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 04:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:51 INFO - 'USER': 'cltbld', 04:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:51 INFO - 'XPC_FLAGS': '0x0', 04:32:51 INFO - 'XPC_SERVICE_NAME': '0', 04:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:51 INFO - Downloading/unpacking blobuploader==1.2.4 04:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:53 INFO - Downloading blobuploader-1.2.4.tar.gz 04:32:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:32:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:32: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 04:32: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 04:32: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 04:32: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 04:32: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 04:32: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 04:32:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:32:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:55 INFO - Downloading docopt-0.6.1.tar.gz 04:32:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:32:55 INFO - Installing collected packages: blobuploader, requests, docopt 04:32:55 INFO - Running setup.py install for blobuploader 04:32:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:32:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:32:55 INFO - Running setup.py install for requests 04:32:55 INFO - Running setup.py install for docopt 04:32:56 INFO - Successfully installed blobuploader requests docopt 04:32:56 INFO - Cleaning up... 04:32:56 INFO - Return code: 0 04:32:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:56 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 0x108b17a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b1d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb61481ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bbd30>, '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 0x1087dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2b20>, '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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:32:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:32:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BN7Dqa1ANG/Render', 04:32:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:32:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:32:56 INFO - 'HOME': '/Users/cltbld', 04:32:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:32:56 INFO - 'LOGNAME': 'cltbld', 04:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:56 INFO - 'MOZ_NO_REMOTE': '1', 04:32:56 INFO - 'NO_EM_RESTART': '1', 04:32:56 INFO - 'PAGER': '/bin/cat', 04:32:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:56 INFO - 'PWD': '/builds/slave/test', 04:32:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:32:56 INFO - 'SHELL': '/bin/bash', 04:32:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lYwbAcMHem/Listeners', 04:32:56 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 04:32:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:32:56 INFO - 'USER': 'cltbld', 04:32:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:32:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:56 INFO - 'XPC_FLAGS': '0x0', 04:32:56 INFO - 'XPC_SERVICE_NAME': '0', 04:32:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:32:56 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-YrbT62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:32:56 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ONhlnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:32:56 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Tg1pk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:32:56 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-msIlmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:32:56 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-7aWNoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:32:56 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-tFFjzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:32:56 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-LaD5Uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:32:57 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-gxMJSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:32:57 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-wG6wVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:32:57 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-VfCcit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:32:57 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-fiWZeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:32:57 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-POfcwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:32:57 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-A_Aus9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:32:57 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-PEQpZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:57 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-SDvXMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:32:57 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-M6CZIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:32:58 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-MpTmry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:32:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:32:58 INFO - Running setup.py install for manifestparser 04:32:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:32:58 INFO - Running setup.py install for mozcrash 04:32:58 INFO - Running setup.py install for mozdebug 04:32:58 INFO - Running setup.py install for mozdevice 04:32:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:32:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:32:58 INFO - Running setup.py install for mozfile 04:32:58 INFO - Running setup.py install for mozhttpd 04:32:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:32:58 INFO - Running setup.py install for mozinfo 04:32:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:32:59 INFO - Running setup.py install for mozInstall 04:32:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:32:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:32:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:32:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:32:59 INFO - Running setup.py install for mozleak 04:32:59 INFO - Running setup.py install for mozlog 04:32:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:32:59 INFO - Running setup.py install for moznetwork 04:32:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:32:59 INFO - Running setup.py install for mozprocess 04:32:59 INFO - Running setup.py install for mozprofile 04:32:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:32:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:32:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:33:00 INFO - Running setup.py install for mozrunner 04:33:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:33:00 INFO - Running setup.py install for mozscreenshot 04:33:00 INFO - Running setup.py install for moztest 04:33:00 INFO - Running setup.py install for mozversion 04:33:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:33:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:33:00 INFO - Cleaning up... 04:33:00 INFO - Return code: 0 04:33:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x108b17a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b1d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb61481ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bbd30>, '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 0x1087dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2b20>, '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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BN7Dqa1ANG/Render', 04:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:00 INFO - 'HOME': '/Users/cltbld', 04:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:00 INFO - 'LOGNAME': 'cltbld', 04:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:00 INFO - 'MOZ_NO_REMOTE': '1', 04:33:00 INFO - 'NO_EM_RESTART': '1', 04:33:00 INFO - 'PAGER': '/bin/cat', 04:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:00 INFO - 'PWD': '/builds/slave/test', 04:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:00 INFO - 'SHELL': '/bin/bash', 04:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lYwbAcMHem/Listeners', 04:33:00 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 04:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:00 INFO - 'USER': 'cltbld', 04:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:00 INFO - 'XPC_FLAGS': '0x0', 04:33:00 INFO - 'XPC_SERVICE_NAME': '0', 04:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:33:00 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-zp6Rig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:33:01 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-3lm170-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:33:01 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-cHp24o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:33:01 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Kjyc_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:33:01 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-iI3chS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:33:01 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-IBncEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:33:01 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-AdMwKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:33:01 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-nfKP__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:33:01 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-0lJr0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:33:01 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-owSWFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:33:01 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)) 04:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:33:01 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-D5brEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:33:02 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-P5L27r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:33:02 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Mbkove-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:33:02 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-0BjVha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:33:02 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-dQfF_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:33:02 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-nK3Z6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:33:02 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-LF71UN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:33:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:05 INFO - Downloading blessings-1.6.tar.gz 04:33:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:33:05 INFO - Installing collected packages: blessings 04:33:05 INFO - Running setup.py install for blessings 04:33:05 INFO - Successfully installed blessings 04:33:05 INFO - Cleaning up... 04:33:05 INFO - Return code: 0 04:33:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:33:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:33:05 INFO - Reading from file tmpfile_stdout 04:33:05 INFO - Current package versions: 04:33:05 INFO - blessings == 1.6 04:33:05 INFO - blobuploader == 1.2.4 04:33:05 INFO - docopt == 0.6.1 04:33:05 INFO - manifestparser == 1.1 04:33:05 INFO - mozInstall == 1.12 04:33:05 INFO - mozcrash == 0.17 04:33:05 INFO - mozdebug == 0.1 04:33:05 INFO - mozdevice == 0.48 04:33:05 INFO - mozfile == 1.2 04:33:05 INFO - mozhttpd == 0.7 04:33:05 INFO - mozinfo == 0.9 04:33:05 INFO - mozleak == 0.1 04:33:05 INFO - mozlog == 3.2 04:33:05 INFO - moznetwork == 0.27 04:33:05 INFO - mozprocess == 0.22 04:33:05 INFO - mozprofile == 0.28 04:33:05 INFO - mozrunner == 6.11 04:33:05 INFO - mozscreenshot == 0.1 04:33:05 INFO - mozsystemmonitor == 0.3 04:33:05 INFO - moztest == 0.7 04:33:05 INFO - mozversion == 1.4 04:33:05 INFO - psutil == 3.1.1 04:33:05 INFO - requests == 1.2.3 04:33:05 INFO - wsgiref == 0.1.2 04:33:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:05 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 0x108b17a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b1d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb61481ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bbd30>, '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 0x1087dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2b20>, '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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:33:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BN7Dqa1ANG/Render', 04:33:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:05 INFO - 'HOME': '/Users/cltbld', 04:33:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:05 INFO - 'LOGNAME': 'cltbld', 04:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:05 INFO - 'MOZ_NO_REMOTE': '1', 04:33:05 INFO - 'NO_EM_RESTART': '1', 04:33:05 INFO - 'PAGER': '/bin/cat', 04:33:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:05 INFO - 'PWD': '/builds/slave/test', 04:33:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:05 INFO - 'SHELL': '/bin/bash', 04:33:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lYwbAcMHem/Listeners', 04:33:05 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 04:33:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:05 INFO - 'USER': 'cltbld', 04:33:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:05 INFO - 'XPC_FLAGS': '0x0', 04:33:05 INFO - 'XPC_SERVICE_NAME': '0', 04:33:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:33:05 INFO - Cleaning up... 04:33:05 INFO - Return code: 0 04:33:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:05 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 0x108b17a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b1d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb61481ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bbd30>, '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 0x1087dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2b20>, '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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:33:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BN7Dqa1ANG/Render', 04:33:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:05 INFO - 'HOME': '/Users/cltbld', 04:33:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:05 INFO - 'LOGNAME': 'cltbld', 04:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:05 INFO - 'MOZ_NO_REMOTE': '1', 04:33:05 INFO - 'NO_EM_RESTART': '1', 04:33:05 INFO - 'PAGER': '/bin/cat', 04:33:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:05 INFO - 'PWD': '/builds/slave/test', 04:33:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:05 INFO - 'SHELL': '/bin/bash', 04:33:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lYwbAcMHem/Listeners', 04:33:05 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 04:33:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:05 INFO - 'USER': 'cltbld', 04:33:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:05 INFO - 'XPC_FLAGS': '0x0', 04:33:05 INFO - 'XPC_SERVICE_NAME': '0', 04:33:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:06 INFO - Downloading/unpacking jsonschema 04:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:33:08 INFO - Downloading/unpacking functools32 (from jsonschema) 04:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:09 INFO - Downloading functools32-3.2.3-2.tar.gz 04:33:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:33:09 INFO - warning: no files found matching '*.txt' 04:33:09 INFO - no previously-included directories found matching 'build' 04:33:09 INFO - no previously-included directories found matching 'dist' 04:33:09 INFO - no previously-included directories found matching '.git*' 04:33:09 INFO - Installing collected packages: jsonschema, functools32 04:33:09 INFO - Running setup.py install for functools32 04:33:09 INFO - warning: no files found matching '*.txt' 04:33:09 INFO - no previously-included directories found matching 'build' 04:33:09 INFO - no previously-included directories found matching 'dist' 04:33:09 INFO - no previously-included directories found matching '.git*' 04:33:09 INFO - Successfully installed jsonschema functools32 04:33:09 INFO - Cleaning up... 04:33:09 INFO - Return code: 0 04:33:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:33:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:09 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 0x108b17a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b1d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb61481ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bbd30>, '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 0x1087dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2b20>, '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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:33:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:33:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BN7Dqa1ANG/Render', 04:33:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:09 INFO - 'HOME': '/Users/cltbld', 04:33:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:09 INFO - 'LOGNAME': 'cltbld', 04:33:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:09 INFO - 'MOZ_NO_REMOTE': '1', 04:33:09 INFO - 'NO_EM_RESTART': '1', 04:33:09 INFO - 'PAGER': '/bin/cat', 04:33:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:09 INFO - 'PWD': '/builds/slave/test', 04:33:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:09 INFO - 'SHELL': '/bin/bash', 04:33:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lYwbAcMHem/Listeners', 04:33:09 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 04:33:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:09 INFO - 'USER': 'cltbld', 04:33:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:09 INFO - 'XPC_FLAGS': '0x0', 04:33:09 INFO - 'XPC_SERVICE_NAME': '0', 04:33:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:09 INFO - Downloading/unpacking flake8 04:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:33:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:33:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:33:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:33:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:33:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:33:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:33:13 INFO - no previously-included directories found matching 'docs/_build' 04:33:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:33:13 INFO - Running setup.py install for pep8 04:33:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:33:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:33:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:33:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:33:13 INFO - no previously-included directories found matching 'docs/_build' 04:33:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:33:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:33:13 INFO - Cleaning up... 04:33:13 INFO - Return code: 0 04:33:13 INFO - Running post-action listener: _resource_record_post_action 04:33:13 INFO - Running post-action listener: _start_resource_monitoring 04:33:13 INFO - Starting resource monitoring. 04:33:13 INFO - ##### 04:33:13 INFO - ##### Finished create-virtualenv step (success) 04:33:13 INFO - ##### 04:33:13 INFO - ##### 04:33:13 INFO - ##### Running install step. 04:33:13 INFO - ##### 04:33:13 INFO - Running pre-action listener: _resource_record_pre_action 04:33:13 INFO - Running main action method: install 04:33:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:33:14 INFO - Reading from file tmpfile_stdout 04:33:14 INFO - Detecting whether we're running mozinstall >=1.0... 04:33:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:33:14 INFO - Reading from file tmpfile_stdout 04:33:14 INFO - Output received: 04:33:14 INFO - Usage: mozinstall [options] installer 04:33:14 INFO - Options: 04:33:14 INFO - -h, --help show this help message and exit 04:33:14 INFO - -d DEST, --destination=DEST 04:33:14 INFO - Directory to install application into. [default: 04:33:14 INFO - "/builds/slave/test"] 04:33:14 INFO - --app=APP Application being installed. [default: firefox] 04:33:14 INFO - mkdir: /builds/slave/test/build/application 04:33:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:33:48 INFO - Reading from file tmpfile_stdout 04:33:48 INFO - Output received: 04:33:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:33:48 INFO - Running post-action listener: _resource_record_post_action 04:33:48 INFO - ##### 04:33:48 INFO - ##### Finished install step (success) 04:33:48 INFO - ##### 04:33:48 INFO - ##### 04:33:48 INFO - ##### Running run-tests step. 04:33:48 INFO - ##### 04:33:48 INFO - Running pre-action listener: _resource_record_pre_action 04:33:48 INFO - Running main action method: run_tests 04:33:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:33:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:33:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:33:48 INFO - 2016-06-03 04:33:48.176 screenresolution[1643:7864] starting screenresolution argv=screenresolution get 04:33:48 INFO - 2016-06-03 04:33:48.179 screenresolution[1643:7864] Display 0: 1600x1200x32@60 04:33:48 INFO - 2016-06-03 04:33:48.185 screenresolution[1644:7866] starting screenresolution argv=screenresolution list 04:33:48 INFO - Available Modes on Display 0 04:33:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:33:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:33:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:33:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:33:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:33:48 INFO - Intel Iris: 04:33:48 INFO - Chipset Model: Intel Iris 04:33:48 INFO - Type: GPU 04:33:48 INFO - Bus: Built-In 04:33:48 INFO - VRAM (Dynamic, Max): 1536 MB 04:33:48 INFO - Vendor: Intel (0x8086) 04:33:48 INFO - Device ID: 0x0a2e 04:33:48 INFO - Revision ID: 0x0009 04:33:48 INFO - Displays: 04:33:48 INFO - Display: 04:33:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:33:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:33:48 INFO - Display Serial Number: 1600x1200 60 04:33:48 INFO - Main Display: Yes 04:33:48 INFO - Mirror: Off 04:33:48 INFO - Online: Yes 04:33:48 INFO - Rotation: Supported 04:33:48 INFO - Return code: 0 04:33:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:33:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:33:48 INFO - Python 2.7.3 04:33:48 INFO - Return code: 0 04:33:48 INFO - grabbing minidump binary from tooltool 04:33:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:48 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 0x1089bbd30>, '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 0x1087dfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:33:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:33:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:33:48 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 04:33:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:33:48 INFO - Return code: 0 04:33:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:33:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:33:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:33:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:33:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:33:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:33:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:33:49 INFO - Return code: 0 04:33:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0206 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:33:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BN7Dqa1ANG/Render', 04:33:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:49 INFO - 'HOME': '/Users/cltbld', 04:33:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:49 INFO - 'LOGNAME': 'cltbld', 04:33:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:33:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:33:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:49 INFO - 'MOZ_NO_REMOTE': '1', 04:33:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:33:49 INFO - 'NO_EM_RESTART': '1', 04:33:49 INFO - 'PAGER': '/bin/cat', 04:33:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:49 INFO - 'PWD': '/builds/slave/test', 04:33:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:33:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:49 INFO - 'SHELL': '/bin/bash', 04:33:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lYwbAcMHem/Listeners', 04:33:49 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 04:33:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:49 INFO - 'USER': 'cltbld', 04:33:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:49 INFO - 'XPC_FLAGS': '0x0', 04:33:49 INFO - 'XPC_SERVICE_NAME': '0', 04:33:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:33:49 INFO - mozversion application_buildid: 20160603033811 04:33:49 INFO - mozversion application_changeset: 4874ff5d90f81aca0bb57d2f74586210cbee3cf6 04:33:49 INFO - mozversion application_display_name: Nightly 04:33:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:33:49 INFO - mozversion application_name: Firefox 04:33:49 INFO - mozversion application_remotingname: firefox 04:33:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:33:49 INFO - mozversion application_vendor: Mozilla 04:33:49 INFO - mozversion application_version: 49.0a1 04:33:49 INFO - mozversion platform_buildid: 20160603033811 04:33:49 INFO - mozversion platform_changeset: 4874ff5d90f81aca0bb57d2f74586210cbee3cf6 04:33:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:33:49 INFO - mozversion platform_version: 49.0a1 04:33:49 INFO - using testdate: 1464953629 04:33:49 INFO - actual date: 1464953629 04:33:49 INFO - starting webserver on 'localhost:49278' 04:33:49 INFO - SUITE-START | Running 2 tests 04:33:49 INFO - TEST-START | dromaeo_css 04:33:49 INFO - operating with platform_type : mac_ 04:33:49 INFO - Initialising browser for dromaeo_css test... 04:33:49 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpsTwEAr/profile http://localhost:49278/getInfo.html) 04:33:52 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 04:33:52 INFO - PROCESS | 1662 | colorDepth:24 04:33:52 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 04:33:52 INFO - PROCESS | 1662 | __metrics 04:33:52 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:33:52 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:33:52 INFO - TEST-INFO | 1662: exit 0 04:33:52 INFO - Browser initialized. 04:33:52 INFO - Running cycle 1/1 for dromaeo_css test... 04:33:52 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpsTwEAr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:34:05 INFO - PROCESS | 1664 | RSS: Main: 242098176 04:34:05 INFO - PROCESS | 1664 | 04:34:56 INFO - PROCESS | 1664 | 1464953696792 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:37:07 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 04:37:09 INFO - PROCESS | 1664 | RSS: Main: 406376448 04:37:09 INFO - PROCESS | 1664 | 04:40:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 04:40:12 INFO - PROCESS | 1664 | RSS: Main: 382611456 04:40:12 INFO - PROCESS | 1664 | 04:43:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 04:43:15 INFO - PROCESS | 1664 | RSS: Main: 375132160 04:43:15 INFO - PROCESS | 1664 | 04:46:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 04:46:18 INFO - PROCESS | 1664 | RSS: Main: 373350400 04:46:18 INFO - PROCESS | 1664 | 04:49:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 04:49:22 INFO - PROCESS | 1664 | RSS: Main: 345812992 04:49:22 INFO - PROCESS | 1664 | 04:52:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 04:52:26 INFO - PROCESS | 1664 | RSS: Main: 392896512 04:52:26 INFO - PROCESS | 1664 | 04:52:26 INFO - PROCESS | 1664 | __start_tp_report 04:52:26 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 04:52:26 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 04:52:26 INFO - PROCESS | 1664 | |i|pagename|runs| 04:52:26 INFO - PROCESS | 1664 | |0;dojo.html;3455.360758259722;3467.6358982306842;3491.3366460372545;3497.1606814364554;3541.309444658291;5587.469190426913;5672.234248376468;5672.943270567293;5681.772729090837;5714.514266287365;5665.405132461094;5713.971430142854;5724.398938111501;5727.513161381287;5746.138079288109;5155.072087024325;5206.401263854648;5212.791488340462;5212.973935130319;5275.525202731754;5255.1329030709885;5272.182811664198;5276.841694749163;5278.973605131969;5286.524212820846;5154.948450515484;5163.535281824635;5177.938522602857;5178.378594568657;5262.210668399733;5274.630775845692;5287.894242115136;5299.49654782795;5302.125149350352;5346.679199248058;5076.670016448919;5085.491450854922;5103.055934652101;5205;5210.895782084375;5488.4785945335125;5515.558755299567;5580.079286917654;5587.38538760736;5615.241942337814;6147.907781383283;6159.168512250833;6163.568550201488;6168.75324987;6169.444749972479;6122.295935967376;6175.722092505848;6219.564630475868;6241.313455519889;6249.87500249997;5785.913211301834;5812.767489300423;5818.21454103699;5834.445727655951;5840.065589505702;6279.7488100476385;6293.96853015732;6301.590396624205;6305.14880491137;6312.873742525124;5952.196453478811;6001.5798894077;6050.606710563799;6116.327204007555;6141.84645383869;5375.435579264199;6236.937630623635;6237.75048998035;6261.186045814106;6270.216222972129;6193.102000209944;6197.845053873689;6206.689665516797;6224.533160012927;6226.439620434092;6093.927390448627;6102.938970610326;6109.95128740435;6114.479856340571;6148.754049837957;4895.657303988686;4909.975450122726;4944.579710724629;4952.455229924705;4976.377952755905;4409.36064270679;4413.911721765547;4418.734875907456;4423.712458690175;4442.200403927324;5516.393196748354;5593.804216852472;5647.519960803295;5655.915161272585;5661;6147.401675564296;6216.285127210338;6223.751049958041;6228.750849966042;6294.244690637152;5630.380658127603;5636.718164091861;5648.548116150698;5650.4349565043985;5689.7724091036725;5259.027079990214;5336.146216605324;5397.271368365299;5397.811076612378;5402.594805389612;6273.592216505913;6294.685265736786;6299.212598425289;6309.7160627770945;6327.841803954938;6162.414570615784;6170.5372097092;6191.25704915413;6240.063990401476;6240.656763878031;6302.747890084529;6309.558330916791;6314.936850631619;6328.493720502441;6333.303336633059;6185.690715464299;6196.907046394217;6203.348648392034;6216.409441103269;6218.906716399167;6120.17377654011;6145.863015342176;6146.125431743182;6189.071639254148;6196.1635179252025;6072.592135021756;6076.969615151896;6093.634381937098;6096.859313929741;6141.84645383869;5781.508571771446;6076.817695469143;6081.908771368521;6082.239720034996;6089.665068421102;5763.9135412968;5816.651000939957;5830.854228644318;5891.587588868738;5894.469497745224;5873.647581145145;5925.881482370242;6072.368781196679;6128.938710612837;6134.938650613615;4491.899484626288;5744.597878148489;5882.794102206402;5900;5909.734061967136 04:52:26 INFO - PROCESS | 1664 | |1;ext.html;493.8518444466661;501.9247112933062;503.9794416306978;505.28249885163075;517.2344929504334;30138.698613013865;32434.837825810868;33754;42426.78786606063;43599.78200109003;45646.8588285293;45985.77007114968;46063.76968115155;46089.76955115228;46265.30602040964;9580.041995800417;9854.40873547586;9857.556409961533;9864.556094975744;9886.851697224547;8637.309015278794;8904.198622124008;9544.188743956773;9873.654422095227;11941.402929853473;11918.225315354473;12015.879841201564;12025.158238923279;12029.398530073506;12032.217905836093;8611.56942152896;11928.284302941807;12018.87981120195;12034.879651203551;12042.976347010515;11426;11831.467583958742;11831.585894493652;12094.576689815814;12097.879021209763;42428.363574546405;42740.358894616606;42793.57206427927;42806.357904631455;42822.357664635056;3512.244867353506;3573.4103872860946;4009.3585026395635;4263.317869140954;4285.957140428618;25763.84791952768;32701.18247043843;32824.83587582071;33197.51833502494;33220.33559328824;2922.722341377587;3240.756943229267;3345.5818022747158;3370.140614143402;3413.726901847871;478904;481066;481221;484864;485230;64746;65275;65284.34715652878;65284.673576631816;65326;68128;68756.31243687498;68776.96834547486;68781.65609171923;68834.62330753457;16544.255508502145;16580.253888575044;16759.664806703797;16779.077150756824;16806.495805125865;11591.594294199662;11864.703382415337;12901.225926444256;12974.610761677164;12976.740465190644;14669.92665036668;18140.002299873635;18355.35756248525;18400.723989140173;18408.26366945334;6948.131483564554;7030.507864449542;7081.645917704197;7111.53775004623;7124.503854190574;905.185333200112;928.832810094176;1051.9631812886664;1097.8627671541058;1120.912714666772;27027.729722702523;30863;31226.843865780527;31517.212069697984;32117.67882321147;1094.518411898762;1101.8181999970075;1170.8946194842506;1189.2448295332354;1198.370855300974;15874.855156431027;16108.033517988959;16187.757183642254;16279.348826046826;16426.75359869603;651884;652309;658230;660005;660528.6973565198;60988.08517872147;61032.69483652553;61116;61134;61171.38828611657;1308.358904136985;1446.5443385333797;1463.8389777124507;1493.2085994422973;1500.3398504657916;31752.364952700354;31962.52524646598;31985.8400707995;32594.674053259165;32867.01398958035;53591;54193.45806542042;54230.18654720103;54318.642033949465;54349;541778;544205;544320;544475;544502;24393.634095488225;25911.761029871264;26967.190984270503;27038.324041898322;28858.567121493616;49899.00201996012;50928.74535627446;51321.23018154656;51563;51652;39263.607363927134;40164.59835401725;40905.38641920314;41395;42274.788626056674;40000.19997600307;40523.826846125485;40559.3916091253;41061.794691026356;41405.378919316834;31441.21396965094;39251.21497569975;39878.80060599678;39953.80023099866;40027.999300017735;34706;34723.4791478123;35718.64281357153;35778.92663220107;36348.45477317895;24418.51162976695;25242.621360679237;25693.743062569134;25830.095946641777;26353.736462635894 04:52:26 INFO - PROCESS | 1664 | |2;jquery.html;382.7999222437658;383.4229484625639;384.28331162382165;397.2630769921793;409.08773435239414;19635;21467.785322146774;21728.89135554324;21798.564028719436;21834.235801746923;20393.694094588554;21056.263030793925;21095.578088438222;21432.99999999996;21637.45906352338;5307.655002424849;5672.886542269152;5903.380145084769;5913.379095195007;5915.556333275;4040.393940908858;4300.655947524207;4305.332673435622;5750.798722044729;5788.363280039192;4206.705530612859;6044.697765111749;6375.489960803125;6377.872442551146;6431.581947173442;6274.686265686743;6311.968440157792;6394.360563943615;6406.103145559602;6413.358664133596;3593.874214402483;5583.804566840141;5596.748146333384;5600.915986260209;5610.579206559524;21622.675659865115;24566.754332456625;25379.365515862068;26630;26739;2222.2444368914485;2941.6617089034607;3078.8768449262;3090.6600273969843;3166.2347719893887;15640.296186671514;15680.059196448135;15763.896527243098;15850.286737096743;15899.951785190584;1635.5911022244438;1843.8432733217692;1846.9261229550802;1849.2048419179882;1880.4828672115277;82748.58625706894;121454.39272803579;122377;123328;123431;9415.811683766286;10798.406087665255;11031.669049928514;11243.212975091828;11341.149413793999;14067.085639433404;14126.293685315693;14127.293635318192;14145.646358841112;14148.717025659635;8519.148085191555;8700.434471759441;8712.69505567302;8807.207351338411;8807.911920880837;8604.31165506758;8624.913750862412;8652.351073669373;8761.474311541328;8778.29773618109;6722.932770672231;6819.863602727918;6823.420009299166;6832.829179270558;6858.897116543256;1549.7133030389414;1683.335082642374;1751.702210624197;1844.7601811764653;1910.1404368034162;929.242667226222;964.2912459342377;985.2265971212564;997.8004399120205;1027.5889644142253;21060.20988215988;21526.13895444196;21698.783012169675;21703.34889953304;21737.456563586038;977.8044391121662;1006.7332156978407;1039.2361614213546;1092.5138313450439;1104.917131215162;11161.888381116247;11448.969592736694;11649.301041937511;11682;11706.531738730526;574468;579769;588692;610587.9470602707;611340;86537;123465.99999999821;123639.38180309041;123951.3802430982;127784;993.9503024848873;1000.68478429296;1022.1209759606733;1025.702546261576;1028.4138041316528;15050.774238386652;17867.463976080737;19251.807481925563;20097.19611215535;20138.89930550338;42137.789311054476;48089.75955120202;49601.50398495969;50505.98988020146;50913.47259582218;69588.60822783617;69973.95039074316;70587.64706176642;70651.64674176803;70749;8093.919060809552;8181.345492360478;8307.6676932922;8502.532360720219;8521.573921303787;11632.302061876315;11708.765824683289;12274.631761047183;12291.385430728249;12382.876171238173;10582.523786429476;10744.51649675782;11066.059384952046;11255.437228138397;11614.477348519506;8167.632456539352;11317.773644527228;11433.942830285796;11584.652460426201;11728.41357932117;7932.206779322252;11534.826977595176;11792.823107653221;11863.525458981889;12016.459259333526;8130;8141.959290203748;8224.218699223804;8257.050439199329;8425.031121421114;11567.826482602599;11633.011194048262;11692.766144677229;11745.47145378477;11778.293302401884 04:52:26 INFO - PROCESS | 1664 | |3;mootools.html;1398.2449477282269;1556.3930067273766;1569.0115227406732;1815.2557451444911;1955.8826470411768;7768.57272849993;8490.745277641672;8754.080821513744;8761.649534018648;8875.156860098281;8511.27654149398;8735.825283494327;8740.4755714657;8756.518391488466;8776.385653004292;2341.730700969385;2370.96443553346;2373.976260237401;2374.3589230907646;2376.4652953085506;2322.384568089458;2360.1385494294545;2367.490989437271;2371.905123795055;2374.8218883583713;2060.186226440555;2329.1731435340466;2343.191598898386;2377.5126099149634;2382.630692242705;2306.7231932168247;2336.859788412692;2344.5193735284224;2344.7772461616205;2360.834741568091;2002;2325.9881951351217;2333.56828986638;2337.836351455399;2338.9181378651665;8502.659893604248;8637.39538232324;8704.738857834274;8723.25852302555;8802.735917922408;5255.159174532057;5397.082495975655;5411.161270003156;5412.972935135338;5425.511703946664;3352.2289873329028;3584.8386822592784;3661.029827095822;3671.04552816267;3692.409214525663;1059.4861492176287;1071.560660129351;1077.466654006272;1077.9946100269526;1083.6748975307455;11465.369404682739;11594.246373985749;11611.419429028514;11617.954384105471;11625.360605166712;3825.2923209206388;4463.598276155161;4600.723956562617;4611.8847028823875;4613.930791038137;9082.954585227164;9089.409188402733;9093.40892841963;9094.045125261884;9098.818023639622;5171.637985341065;5232.529072383504;5243.947560524422;5247.632665713363;5251.159814429673;4375.256206444912;4531.8187272509385;4705.55297246761;4851.733154676456;4863.076015557103;3733.6266373362446;3744.3821769408287;3825.1967086911504;3865.420186971977;3907.4529565860803;1156.6356597671706;1165.248414772467;1168.9941550292194;1181.2676140792764;1182.131133616791;646.945009674183;801.7314199743034;848.2069265237024;873.5937788928179;990.0693348252763;5720.799772007959;5832.241808564944;5910.970445147746;5976.282846058501;5986.700664966821;953.8616900549521;972.8492083727034;975.7463059604412;1003.3428104591504;1026.0867827633413;3870.787106709102;3889.9416508752392;3959.544652365015;3977.423273625308;4027.2549578327516;10321.277510574188;10723.088537474401;10731;10732.73168170802;10743.83884241737;10530.57877684884;10595.735106622396;10638.627648032281;10658.253922225525;10697.144228461704;8113.18868113184;8115.553644549606;8218.21926916942;8241.423100382915;8264.21489958453;5447.972760136174;5671.829845104653;5683.488486036276;5946.43508866657;6055.333913269449;9749.317547771872;9906.900930990887;10036.89963100389;10053.095221430107;10064.194864410683;8099.676012959416;10189.490525473548;10274.280800343904;10274.691759247235;10278.229132814919;7410.592417417094;7570.091589009354;7580.3556697681315;7581;7588.696452141852;7982.321502672338;8046.275835174863;8129.79675508117;8145.063317718538;8181.304589109992;6919.550229235084;7426.443016773872;7514.774556763306;7524.548527088392;7545.868119782077;7368.37368823656;7385.483016188815;7413.703451861865;7506.3994880408345;7527.473076884566;6554.983977483497;6595;6952.788848571347;7016.684249208696;7117.6085315308155;4955.876103097451;4977.875553111201;5006.949930500648;5030.396352437585;5110.9233361500355;5019.347484826949;5031.899362012812;5077.8730531737;5087.058894104603;5187.740612969261 04:52:26 INFO - PROCESS | 1664 | |4;prototype.html;306.1977618639166;313.3279116295545;315.56136969612237;321.75225076690947;325.78986553672866;5061.898762024757;5353.678779273247;5359;5382.38102618199;5415.512603865652;4506.918339598497;4656.464506581744;4813.157697402949;4869.5373939475785;5266.34170728659;3318.950215746766;3347.882824101157;3772.245550889826;3928.7446315989514;3939.960600393988;3566.910827229327;3851;3926;4037.6164264394833;4062.8577999770014;3915.7846318452475;3961.247363001035;3989.421533877586;4028.436018957348;4028.7179897407195;4007.6393124618926;4026.7382620129597;4037.6971727120435;4043.353063509839;4063.2482990646827;2947.2337192329933;2947.513660246057;2995.3709720958623;3293.6541663125267;3499.6325385834375;4956.330895329121;4994.375703037121;5009.84773502093;5014.448410674823;5017.67385119966;4594;4617.3304870793545;4620;4671.743054132022;4761.880952976203;4952.62855285855;5527.474889885454;5559.221708960748;5560.4161563035705;5562.582806289504;3333.7498438085718;3850.961490385116;3887.7278590498677;3898.368601194211;3912.002439377969;4344.89137771555;5082.339295891547;5407.593620838728;5696.487316141484;5747.942520574783;3648.3615367310613;3832.310184166821;3873.535175778868;3948.4669569607736;4000.5199376075057;3867.245887052054;4010.578889216649;4021.718479706451;4034.9798251008556;4052.5339585947404;3911.4523966644692;4538.005058535226;4608.5161058088415;4648.1168577970075;4671.557635354774;5175.068487672255;5477;5580.9441905580425;5618.775248989995;5621.494065534122;1257.4278703190027;1343.7245364700213;1375.4429456070307;1386.6810633554137;1387.3548799808138;806.2944923192207;835.9874601880977;849.2781136034322;865.4767609008065;867.483847110968;3756.4553139795125;3962.7027972902147;4139.627433530996;4337.221739086959;4393.165298593257;4315.784210789448;4695.882602934954;4716.938688794994;4760.9525904301;4896.485868983706;3991.9002024949027;4287.849925252601;4347.043830276389;4360.476742790822;4362.629176519968;2239.6080685879906;2242.876641784685;2432.598621227513;2551.4259291659228;2570.8200425970003;4202.516710578261;4385.386045953569;4477.865664030084;4528.920334172728;4593.28804035375;4596.644449551779;4600.6549508786975;4893.95106048942;4941.530554597308;4977.386871892286;4803.807847686124;4854.417469903634;4878.804847806048;5055.974720126449;6048.183495228176;5074.797008119782;5330.62685612019;5507.421720719267;5750.884982300414;5787.392323806108;5050.787810925425;5185.688858668492;5413.971345444353;5728.283964504437;5751.836489930605;4752.453467851241;5705.457981491918;5793.219254757656;6003.6397816131175;6021.458068773832;3245.350929814094;3767.2277183176834;3767.2842159989514;3786.659200671953;3798.7910664913693;4953.23224900141;5276.709780962084;5732.283464566929;5774.566907481872;5825.271841019872;5291.756437237324;5427.402985671494;5551.944480555304;5581.246531718166;5615.326160860723;4532.7280363178315;5090.007448547498;5133.973205359018;5158.071547121554;5221.921671175011;5154.329937108302;5225.477452254744;5477.028269822781;5497.697626630574;5506.889862202814;4876.707397556158;5564.777408903598;5573.191887176417;5588.273524441796;5590.748416321357;5381.381141168816;5427.538659213853;5455.863603409946;5466.535344495763;5477.822268212353 04:52:26 INFO - PROCESS | 1664 | |5;yui.html;712.4585315160476;722.1731117870039;725.4559080689482;751.3238085722849;841.718024461805;14912.552623421288;15385.461508847193;15554;15590.844091559095;15611.375544978187;14265.930055245846;14413.423463061465;14428.422863085463;14445.205513696768;14494.985351025432;698.3644883156329;707.5825263094799;893.4862454088909;916.839553078212;923.5059243304834;874.6107981948004;925.8055808280269;931.0224264522225;931.7297983584753;931.9440833549975;950.1638558068925;963.1139351796364;965.8599503072086;967.8644989701447;968.1722127580922;755.2711633273863;778.5095389904324;782.1904329019494;960.6493629825098;962.9181519570844;577.9508741756956;578.1299144787068;686.6841253023615;710.069808550795;713.0658836923595;15423.768643470357;15456.845431545653;15460.845391546052;15495.225238738018;15501.534953951399;13988.440462381492;14030.92984535081;14125.858741412558;14233.217173055478;14319.856801431957;12010.9190172884;12416.875831241752;12901.22592644435;13103.934480327633;13440.596782096552;4525.9547404525865;4591.954080459186;4804.1352556539805;4842.346283251751;4854.2961270615915;99107.50446247794;99752;99922.50038749832;99936;100147.49926250322;10897.618583349546;10994.890051099546;11119.054880335261;11418.714532136597;11492.54029838797;21504.677429838564;22307.10771569119;22350.55298894013;22351.21770738049;22355;7821.882671759929;8209.507429554245;8231.382646301552;8234.05308389531;8252.092269850311;4440.4005459263235;4504.997809724811;4632.745199014086;4668.159731248408;4670.929936050962;3480.077779388464;3583.33708263972;3587.6412358763914;3590.3716849551224;3592.4790905319105;637.2703254773352;657.4214691071921;664.3622122762106;674.9122614060238;679.0696745458655;621.3631028196135;627.2567008095422;633.5106303876947;645.1033064040987;658.344946777959;8167.877481837777;8686.174813392718;8879.600417981206;9009.954950225207;9228.986425921945;571.0811672779457;577.6014549960514;624.1386886097157;642.7172044300493;661.24287690594;4353;4360.127974405132;4367.497737760198;4369.104333611602;4398.494173170063;89437;91097.54451227702;123498;131363.34318328346;141225.64484598217;109973.45013274883;124883;125743.371283143;127101;127195.72804272023;83444.58277708573;84607;101981;107333.46333268283;107954.46022769836;7617.352525035212;8430.199131082538;8540.957295213484;8864.955675221583;9083;50068.749656251486;60278.69860650669;60733;61781;64743.02885456816;92730.53634731783;93424.53287733518;94010;94300;96502.51748741211;11630.418479076181;11681.357525336189;11697.356645384585;11858.525658973544;12167;13881.79177312321;14426.639334016734;14531.055481393676;14660.926695366881;14741.410343586136;13673.384697688429;13861.237631929936;13989.790153147915;14032.158070515801;14391.064580802215;12989.935050324688;13310.40103195378;13432.462701491831;13574.98187635951;13597.184168949894;13459.596212113653;13467.595972120853;13482.865171348161;13491.460341586228;13850.307484625528;10968.835467468154;11112.055475284691;11466.564555992873;11592.826107608562;11689.766204675689;11273.830892536454;11289.379084150121;11301.71745706364;11552.191346605658;11573.132015098732 04:52:26 INFO - PROCESS | 1664 | __end_tp_report 04:52:26 INFO - PROCESS | 1664 | __start_cc_report 04:52:26 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1759 04:52:26 INFO - PROCESS | 1664 | __end_cc_report 04:52:26 INFO - PROCESS | 1664 | __startTimestamp1464954746005__endTimestamp 04:52:26 INFO - PROCESS | 1664 | 04:52:26 INFO - PROCESS | 1664 | ------- Summary: start ------- 04:52:26 INFO - PROCESS | 1664 | Number of tests: 6 04:52:26 INFO - PROCESS | 1664 | 04:52:26 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5710.10 Median:5873.65 stddev:618.75 (10.5%) stddev-sans-first:594.83 04:52:26 INFO - PROCESS | 1664 | Values: 3455.4 3467.6 3491.3 3497.2 3541.3 5587.5 5672.2 5672.9 5681.8 5714.5 5665.4 5714.0 5724.4 5727.5 5746.1 5155.1 5206.4 5212.8 5213.0 5275.5 5255.1 5272.2 5276.8 5279.0 5286.5 5154.9 5163.5 5177.9 5178.4 5262.2 5274.6 5287.9 5299.5 5302.1 5346.7 5076.7 5085.5 5103.1 5205.0 5210.9 5488.5 5515.6 5580.1 5587.4 5615.2 6147.9 6159.2 6163.6 6168.8 6169.4 6122.3 6175.7 6219.6 6241.3 6249.9 5785.9 5812.8 5818.2 5834.4 5840.1 6279.7 6294.0 6301.6 6305.1 6312.9 5952.2 6001.6 6050.6 6116.3 6141.8 5375.4 6236.9 6237.8 6261.2 6270.2 6193.1 6197.8 6206.7 6224.5 6226.4 6093.9 6102.9 6110.0 6114.5 6148.8 4895.7 4910.0 4944.6 4952.5 4976.4 4409.4 4413.9 4418.7 4423.7 4442.2 5516.4 5593.8 5647.5 5655.9 5661.0 6147.4 6216.3 6223.8 6228.8 6294.2 5630.4 5636.7 5648.5 5650.4 5689.8 5259.0 5336.1 5397.3 5397.8 5402.6 6273.6 6294.7 6299.2 6309.7 6327.8 6162.4 6170.5 6191.3 6240.1 6240.7 6302.7 6309.6 6314.9 6328.5 6333.3 6185.7 6196.9 6203.3 6216.4 6218.9 6120.2 6145.9 6146.1 6189.1 6196.2 6072.6 6077.0 6093.6 6096.9 6141.8 5781.5 6076.8 6081.9 6082.2 6089.7 5763.9 5816.7 5830.9 5891.6 5894.5 5873.6 5925.9 6072.4 6128.9 6134.9 4491.9 5744.6 5882.8 5900.0 5909.7 04:52:26 INFO - PROCESS | 1664 | 04:52:26 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:70712.52 Median:39571.20 stddev:150890.61 (381.3%) stddev-sans-first:151221.80 04:52:26 INFO - PROCESS | 1664 | Values: 493.9 501.9 504.0 505.3 517.2 30138.7 32434.8 33754.0 42426.8 43599.8 45646.9 45985.8 46063.8 46089.8 46265.3 9580.0 9854.4 9857.6 9864.6 9886.9 8637.3 8904.2 9544.2 9873.7 11941.4 11918.2 12015.9 12025.2 12029.4 12032.2 8611.6 11928.3 12018.9 12034.9 12043.0 11426.0 11831.5 11831.6 12094.6 12097.9 42428.4 42740.4 42793.6 42806.4 42822.4 3512.2 3573.4 4009.4 4263.3 4286.0 25763.8 32701.2 32824.8 33197.5 33220.3 2922.7 3240.8 3345.6 3370.1 3413.7 478904.0 481066.0 481221.0 484864.0 485230.0 64746.0 65275.0 65284.3 65284.7 65326.0 68128.0 68756.3 68777.0 68781.7 68834.6 16544.3 16580.3 16759.7 16779.1 16806.5 11591.6 11864.7 12901.2 12974.6 12976.7 14669.9 18140.0 18355.4 18400.7 18408.3 6948.1 7030.5 7081.6 7111.5 7124.5 905.2 928.8 1052.0 1097.9 1120.9 27027.7 30863.0 31226.8 31517.2 32117.7 1094.5 1101.8 1170.9 1189.2 1198.4 15874.9 16108.0 16187.8 16279.3 16426.8 651884.0 652309.0 658230.0 660005.0 660528.7 60988.1 61032.7 61116.0 61134.0 61171.4 1308.4 1446.5 1463.8 1493.2 1500.3 31752.4 31962.5 31985.8 32594.7 32867.0 53591.0 54193.5 54230.2 54318.6 54349.0 541778.0 544205.0 544320.0 544475.0 544502.0 24393.6 25911.8 26967.2 27038.3 28858.6 49899.0 50928.7 51321.2 51563.0 51652.0 39263.6 40164.6 40905.4 41395.0 42274.8 40000.2 40523.8 40559.4 41061.8 41405.4 31441.2 39251.2 39878.8 39953.8 40028.0 34706.0 34723.5 35718.6 35778.9 36348.5 24418.5 25242.6 25693.7 25830.1 26353.7 04:52:26 INFO - PROCESS | 1664 | 04:52:26 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34395.42 Median:13394.50 stddev:98605.75 (736.2%) stddev-sans-first:98849.29 04:52:26 INFO - PROCESS | 1664 | Values: 382.8 383.4 384.3 397.3 409.1 19635.0 21467.8 21728.9 21798.6 21834.2 20393.7 21056.3 21095.6 21433.0 21637.5 5307.7 5672.9 5903.4 5913.4 5915.6 4040.4 4300.7 4305.3 5750.8 5788.4 4206.7 6044.7 6375.5 6377.9 6431.6 6274.7 6312.0 6394.4 6406.1 6413.4 3593.9 5583.8 5596.7 5600.9 5610.6 21622.7 24566.8 25379.4 26630.0 26739.0 2222.2 2941.7 3078.9 3090.7 3166.2 15640.3 15680.1 15763.9 15850.3 15900.0 1635.6 1843.8 1846.9 1849.2 1880.5 82748.6 121454.4 122377.0 123328.0 123431.0 9415.8 10798.4 11031.7 11243.2 11341.1 14067.1 14126.3 14127.3 14145.6 14148.7 8519.1 8700.4 8712.7 8807.2 8807.9 8604.3 8624.9 8652.4 8761.5 8778.3 6722.9 6819.9 6823.4 6832.8 6858.9 1549.7 1683.3 1751.7 1844.8 1910.1 929.2 964.3 985.2 997.8 1027.6 21060.2 21526.1 21698.8 21703.3 21737.5 977.8 1006.7 1039.2 1092.5 1104.9 11161.9 11449.0 11649.3 11682.0 11706.5 574468.0 579769.0 588692.0 610587.9 611340.0 86537.0 123466.0 123639.4 123951.4 127784.0 994.0 1000.7 1022.1 1025.7 1028.4 15050.8 17867.5 19251.8 20097.2 20138.9 42137.8 48089.8 49601.5 50506.0 50913.5 69588.6 69974.0 70587.6 70651.6 70749.0 8093.9 8181.3 8307.7 8502.5 8521.6 11632.3 11708.8 12274.6 12291.4 12382.9 10582.5 10744.5 11066.1 11255.4 11614.5 8167.6 11317.8 11433.9 11584.7 11728.4 7932.2 11534.8 11792.8 11863.5 12016.5 8130.0 8142.0 8224.2 8257.1 8425.0 11567.8 11633.0 11692.8 11745.5 11778.3 04:52:26 INFO - PROCESS | 1664 | 04:52:26 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5647.19 Median:4992.41 stddev:3152.63 (63.1%) stddev-sans-first:3145.31 04:52:26 INFO - PROCESS | 1664 | Values: 1398.2 1556.4 1569.0 1815.3 1955.9 7768.6 8490.7 8754.1 8761.6 8875.2 8511.3 8735.8 8740.5 8756.5 8776.4 2341.7 2371.0 2374.0 2374.4 2376.5 2322.4 2360.1 2367.5 2371.9 2374.8 2060.2 2329.2 2343.2 2377.5 2382.6 2306.7 2336.9 2344.5 2344.8 2360.8 2002.0 2326.0 2333.6 2337.8 2338.9 8502.7 8637.4 8704.7 8723.3 8802.7 5255.2 5397.1 5411.2 5413.0 5425.5 3352.2 3584.8 3661.0 3671.0 3692.4 1059.5 1071.6 1077.5 1078.0 1083.7 11465.4 11594.2 11611.4 11618.0 11625.4 3825.3 4463.6 4600.7 4611.9 4613.9 9083.0 9089.4 9093.4 9094.0 9098.8 5171.6 5232.5 5243.9 5247.6 5251.2 4375.3 4531.8 4705.6 4851.7 4863.1 3733.6 3744.4 3825.2 3865.4 3907.5 1156.6 1165.2 1169.0 1181.3 1182.1 646.9 801.7 848.2 873.6 990.1 5720.8 5832.2 5911.0 5976.3 5986.7 953.9 972.8 975.7 1003.3 1026.1 3870.8 3889.9 3959.5 3977.4 4027.3 10321.3 10723.1 10731.0 10732.7 10743.8 10530.6 10595.7 10638.6 10658.3 10697.1 8113.2 8115.6 8218.2 8241.4 8264.2 5448.0 5671.8 5683.5 5946.4 6055.3 9749.3 9906.9 10036.9 10053.1 10064.2 8099.7 10189.5 10274.3 10274.7 10278.2 7410.6 7570.1 7580.4 7581.0 7588.7 7982.3 8046.3 8129.8 8145.1 8181.3 6919.6 7426.4 7514.8 7524.5 7545.9 7368.4 7385.5 7413.7 7506.4 7527.5 6555.0 6595.0 6952.8 7016.7 7117.6 4955.9 4977.9 5006.9 5030.4 5110.9 5019.3 5031.9 5077.9 5087.1 5187.7 04:52:26 INFO - PROCESS | 1664 | 04:52:26 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4324.22 Median:4683.81 stddev:1330.51 (28.4%) stddev-sans-first:1299.61 04:52:26 INFO - PROCESS | 1664 | Values: 306.2 313.3 315.6 321.8 325.8 5061.9 5353.7 5359.0 5382.4 5415.5 4506.9 4656.5 4813.2 4869.5 5266.3 3319.0 3347.9 3772.2 3928.7 3940.0 3566.9 3851.0 3926.0 4037.6 4062.9 3915.8 3961.2 3989.4 4028.4 4028.7 4007.6 4026.7 4037.7 4043.4 4063.2 2947.2 2947.5 2995.4 3293.7 3499.6 4956.3 4994.4 5009.8 5014.4 5017.7 4594.0 4617.3 4620.0 4671.7 4761.9 4952.6 5527.5 5559.2 5560.4 5562.6 3333.7 3851.0 3887.7 3898.4 3912.0 4344.9 5082.3 5407.6 5696.5 5747.9 3648.4 3832.3 3873.5 3948.5 4000.5 3867.2 4010.6 4021.7 4035.0 4052.5 3911.5 4538.0 4608.5 4648.1 4671.6 5175.1 5477.0 5580.9 5618.8 5621.5 1257.4 1343.7 1375.4 1386.7 1387.4 806.3 836.0 849.3 865.5 867.5 3756.5 3962.7 4139.6 4337.2 4393.2 4315.8 4695.9 4716.9 4761.0 4896.5 3991.9 4287.8 4347.0 4360.5 4362.6 2239.6 2242.9 2432.6 2551.4 2570.8 4202.5 4385.4 4477.9 4528.9 4593.3 4596.6 4600.7 4894.0 4941.5 4977.4 4803.8 4854.4 4878.8 5056.0 6048.2 5074.8 5330.6 5507.4 5750.9 5787.4 5050.8 5185.7 5414.0 5728.3 5751.8 4752.5 5705.5 5793.2 6003.6 6021.5 3245.4 3767.2 3767.3 3786.7 3798.8 4953.2 5276.7 5732.3 5774.6 5825.3 5291.8 5427.4 5551.9 5581.2 5615.3 4532.7 5090.0 5134.0 5158.1 5221.9 5154.3 5225.5 5477.0 5497.7 5506.9 4876.7 5564.8 5573.2 5588.3 5590.7 5381.4 5427.5 5455.9 5466.5 5477.8 04:52:26 INFO - PROCESS | 1664 | 04:52:26 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:23164.67 Median:4383.80 stddev:35320.88 (805.7%) stddev-sans-first:35379.74 04:52:26 INFO - PROCESS | 1664 | Values: 712.5 722.2 725.5 751.3 841.7 14912.6 15385.5 15554.0 15590.8 15611.4 14265.9 14413.4 14428.4 14445.2 14495.0 698.4 707.6 893.5 916.8 923.5 874.6 925.8 931.0 931.7 931.9 950.2 963.1 965.9 967.9 968.2 755.3 778.5 782.2 960.6 962.9 578.0 578.1 686.7 710.1 713.1 15423.8 15456.8 15460.8 15495.2 15501.5 13988.4 14030.9 14125.9 14233.2 14319.9 12010.9 12416.9 12901.2 13103.9 13440.6 4526.0 4592.0 4804.1 4842.3 4854.3 99107.5 99752.0 99922.5 99936.0 100147.5 10897.6 10994.9 11119.1 11418.7 11492.5 21504.7 22307.1 22350.6 22351.2 22355.0 7821.9 8209.5 8231.4 8234.1 8252.1 4440.4 4505.0 4632.7 4668.2 4670.9 3480.1 3583.3 3587.6 3590.4 3592.5 637.3 657.4 664.4 674.9 679.1 621.4 627.3 633.5 645.1 658.3 8167.9 8686.2 8879.6 9010.0 9229.0 571.1 577.6 624.1 642.7 661.2 4353.0 4360.1 4367.5 4369.1 4398.5 89437.0 91097.5 123498.0 131363.3 141225.6 109973.5 124883.0 125743.4 127101.0 127195.7 83444.6 84607.0 101981.0 107333.5 107954.5 7617.4 8430.2 8541.0 8865.0 9083.0 50068.7 60278.7 60733.0 61781.0 64743.0 92730.5 93424.5 94010.0 94300.0 96502.5 11630.4 11681.4 11697.4 11858.5 12167.0 13881.8 14426.6 14531.1 14660.9 14741.4 13673.4 13861.2 13989.8 14032.2 14391.1 12989.9 13310.4 13432.5 13575.0 13597.2 13459.6 13467.6 13482.9 13491.5 13850.3 10968.8 11112.1 11466.6 11592.8 11689.8 11273.8 11289.4 11301.7 11552.2 11573.1 04:52:26 INFO - PROCESS | 1664 | -------- Summary: end -------- 04:52:26 INFO - PROCESS | 1664 | 04:52:26 INFO - TEST-INFO | 1664: exit 0 04:52:26 INFO - TEST-OK | dromaeo_css | took 1116762ms 04:52:26 INFO - TEST-START | kraken 04:52:26 INFO - operating with platform_type : mac_ 04:52:26 INFO - Initialising browser for kraken test... 04:52:26 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpTcx0Q3/profile http://localhost:49278/getInfo.html) 04:52:28 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 04:52:28 INFO - PROCESS | 1670 | colorDepth:24 04:52:28 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 04:52:28 INFO - PROCESS | 1670 | __metrics 04:52:28 INFO - PROCESS | 1670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:52:28 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:52:29 INFO - TEST-INFO | 1670: exit 0 04:52:29 INFO - Browser initialized. 04:52:29 INFO - Running cycle 1/1 for kraken test... 04:52:29 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpTcx0Q3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:52:41 INFO - PROCESS | 1671 | RSS: Main: 242212864 04:52:41 INFO - PROCESS | 1671 | 04:52:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 04:52:45 INFO - PROCESS | 1671 | RSS: Main: 291471360 04:52:45 INFO - PROCESS | 1671 | 04:52:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 04:52:48 INFO - PROCESS | 1671 | RSS: Main: 302301184 04:52:48 INFO - PROCESS | 1671 | 04:52:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 04:52:52 INFO - PROCESS | 1671 | RSS: Main: 386932736 04:52:52 INFO - PROCESS | 1671 | 04:52:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 04:52:55 INFO - PROCESS | 1671 | RSS: Main: 288411648 04:52:55 INFO - PROCESS | 1671 | 04:52:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:52:57 INFO - PROCESS | 1671 | RSS: Main: 293179392 04:52:57 INFO - PROCESS | 1671 | 04:53:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 04:53:01 INFO - PROCESS | 1671 | RSS: Main: 329625600 04:53:01 INFO - PROCESS | 1671 | 04:53:04 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 04:53:04 INFO - PROCESS | 1671 | RSS: Main: 331329536 04:53:04 INFO - PROCESS | 1671 | 04:53:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 04:53:08 INFO - PROCESS | 1671 | RSS: Main: 327786496 04:53:08 INFO - PROCESS | 1671 | 04:53:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:53:10 INFO - PROCESS | 1671 | RSS: Main: 310083584 04:53:10 INFO - PROCESS | 1671 | 04:53:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:53:12 INFO - PROCESS | 1671 | RSS: Main: 317071360 04:53:12 INFO - PROCESS | 1671 | 04:53:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:53:15 INFO - PROCESS | 1671 | RSS: Main: 320151552 04:53:15 INFO - PROCESS | 1671 | 04:53:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:53:19 INFO - PROCESS | 1671 | RSS: Main: 323379200 04:53:19 INFO - PROCESS | 1671 | 04:53:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:53:22 INFO - PROCESS | 1671 | RSS: Main: 323555328 04:53:22 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 04:53:24 INFO - PROCESS | 1671 | RSS: Main: 367714304 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | __start_tp_report 04:53:24 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 04:53:24 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 04:53:24 INFO - PROCESS | 1671 | |i|pagename|runs| 04:53:24 INFO - PROCESS | 1671 | |0;ai-astar;111;106;113;113;111;114;112;113;112;112 04:53:24 INFO - PROCESS | 1671 | |1;audio-beat-detection;159;124;118;169;117;118;141;118;149;126 04:53:24 INFO - PROCESS | 1671 | |2;audio-dft;184;184;184;183;186;181;182;184;186;182 04:53:24 INFO - PROCESS | 1671 | |3;audio-fft;77;73;71;112;80;57;64;60;96;57 04:53:24 INFO - PROCESS | 1671 | |4;audio-oscillator;85;92;93;71;76;70;95;72;68;109 04:53:24 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;87;86;88;85;87;84;90;85;87;84 04:53:24 INFO - PROCESS | 1671 | |6;imaging-darkroom;94;100;141;95;129;118;95;95;94;93 04:53:24 INFO - PROCESS | 1671 | |7;imaging-desaturate;85;84;83;84;85;84;85;83;84;86 04:53:24 INFO - PROCESS | 1671 | |8;json-parse-financial;55;55;55;55;55;55;56;54;54;53 04:53:24 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;67;66;82;78;73;69;69;72;70;69 04:53:24 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;87;88;105;107;86;85;85;88;122;87 04:53:24 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;141;139;141;147;128;141;139;144;150;142 04:53:24 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;163;165;167;168;167;166;164;164;168;164 04:53:24 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;66;67;66;66;67;65;66;65;66;66 04:53:24 INFO - PROCESS | 1671 | __end_tp_report 04:53:24 INFO - PROCESS | 1671 | __start_cc_report 04:53:24 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2059 04:53:24 INFO - PROCESS | 1671 | __end_cc_report 04:53:24 INFO - PROCESS | 1671 | __startTimestamp1464954804875__endTimestamp 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | ------- Summary: start ------- 04:53:24 INFO - PROCESS | 1671 | Number of tests: 14 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.50 stddev:2.21 (2.0%) stddev-sans-first:2.33 04:53:24 INFO - PROCESS | 1671 | Values: 111.0 106.0 113.0 113.0 111.0 114.0 112.0 113.0 112.0 112.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:133.90 Median:133.50 stddev:19.28 (14.4%) stddev-sans-first:18.18 04:53:24 INFO - PROCESS | 1671 | Values: 159.0 124.0 118.0 169.0 117.0 118.0 141.0 118.0 149.0 126.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.65 (0.9%) stddev-sans-first:1.74 04:53:24 INFO - PROCESS | 1671 | Values: 184.0 184.0 184.0 183.0 186.0 181.0 182.0 184.0 186.0 182.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:74.70 Median:72.00 stddev:17.80 (24.7%) stddev-sans-first:18.86 04:53:24 INFO - PROCESS | 1671 | Values: 77.0 73.0 71.0 112.0 80.0 57.0 64.0 60.0 96.0 57.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:83.10 Median:80.50 stddev:13.80 (17.1%) stddev-sans-first:14.62 04:53:24 INFO - PROCESS | 1671 | Values: 85.0 92.0 93.0 71.0 76.0 70.0 95.0 72.0 68.0 109.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.89 (2.2%) stddev-sans-first:1.99 04:53:24 INFO - PROCESS | 1671 | Values: 87.0 86.0 88.0 85.0 87.0 84.0 90.0 85.0 87.0 84.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:105.40 Median:94.00 stddev:17.48 (18.6%) stddev-sans-first:18.05 04:53:24 INFO - PROCESS | 1671 | Values: 94.0 100.0 141.0 95.0 129.0 118.0 95.0 95.0 94.0 93.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.50 stddev:0.95 (1.1%) stddev-sans-first:0.97 04:53:24 INFO - PROCESS | 1671 | Values: 85.0 84.0 83.0 84.0 85.0 84.0 85.0 83.0 84.0 86.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:0.82 (1.5%) stddev-sans-first:0.87 04:53:24 INFO - PROCESS | 1671 | Values: 55.0 55.0 55.0 55.0 55.0 55.0 56.0 54.0 54.0 53.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.00 stddev:5.02 (7.1%) stddev-sans-first:5.05 04:53:24 INFO - PROCESS | 1671 | Values: 67.0 66.0 82.0 78.0 73.0 69.0 69.0 72.0 70.0 69.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:94.00 Median:86.50 stddev:12.78 (14.8%) stddev-sans-first:13.30 04:53:24 INFO - PROCESS | 1671 | Values: 87.0 88.0 105.0 107.0 86.0 85.0 85.0 88.0 122.0 87.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:141.50 stddev:5.81 (4.1%) stddev-sans-first:6.16 04:53:24 INFO - PROCESS | 1671 | Values: 141.0 139.0 141.0 147.0 128.0 141.0 139.0 144.0 150.0 142.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:166.50 stddev:1.84 (1.1%) stddev-sans-first:1.69 04:53:24 INFO - PROCESS | 1671 | Values: 163.0 165.0 167.0 168.0 167.0 166.0 164.0 164.0 168.0 164.0 04:53:24 INFO - PROCESS | 1671 | 04:53:24 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 04:53:24 INFO - PROCESS | 1671 | Values: 66.0 67.0 66.0 66.0 67.0 65.0 66.0 65.0 66.0 66.0 04:53:24 INFO - PROCESS | 1671 | -------- Summary: end -------- 04:53:24 INFO - PROCESS | 1671 | 04:53:25 INFO - TEST-INFO | 1671: exit 0 04:53:25 INFO - TEST-OK | kraken | took 59050ms 04:53:25 INFO - SUITE-END | took 1175s 04:53:26 INFO - Completed test suite (00:19:36) 04:53:26 INFO - javascript benchmark 04:53:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3455.360758259722, 3467.6358982306842, 3491.3366460372545, 3497.1606814364554, 3541.309444658291, 5587.469190426913, 5672.234248376468, 5672.943270567293, 5681.772729090837, 5714.514266287365, 5665.405132461094, 5713.971430142854, 5724.398938111501, 5727.513161381287, 5746.138079288109, 5155.072087024325, 5206.401263854648, 5212.791488340462, 5212.973935130319, 5275.525202731754, 5255.1329030709885, 5272.182811664198, 5276.841694749163, 5278.973605131969, 5286.524212820846, 5154.948450515484, 5163.535281824635, 5177.938522602857, 5178.378594568657, 5262.210668399733, 5274.630775845692, 5287.894242115136, 5299.49654782795, 5302.125149350352, 5346.679199248058, 5076.670016448919, 5085.491450854922, 5103.055934652101, 5205.0, 5210.895782084375, 5488.4785945335125, 5515.558755299567, 5580.079286917654, 5587.38538760736, 5615.241942337814, 6147.907781383283, 6159.168512250833, 6163.568550201488, 6168.75324987, 6169.444749972479, 6122.295935967376, 6175.722092505848, 6219.564630475868, 6241.313455519889, 6249.87500249997, 5785.913211301834, 5812.767489300423, 5818.21454103699, 5834.445727655951, 5840.065589505702, 6279.7488100476385, 6293.96853015732, 6301.590396624205, 6305.14880491137, 6312.873742525124, 5952.196453478811, 6001.5798894077, 6050.606710563799, 6116.327204007555, 6141.84645383869, 5375.435579264199, 6236.937630623635, 6237.75048998035, 6261.186045814106, 6270.216222972129, 6193.102000209944, 6197.845053873689, 6206.689665516797, 6224.533160012927, 6226.439620434092, 6093.927390448627, 6102.938970610326, 6109.95128740435, 6114.479856340571, 6148.754049837957, 4895.657303988686, 4909.975450122726, 4944.579710724629, 4952.455229924705, 4976.377952755905, 4409.36064270679, 4413.911721765547, 4418.734875907456, 4423.712458690175, 4442.200403927324, 5516.393196748354, 5593.804216852472, 5647.519960803295, 5655.915161272585, 5661.0, 6147.401675564296, 6216.285127210338, 6223.751049958041, 6228.750849966042, 6294.244690637152, 5630.380658127603, 5636.718164091861, 5648.548116150698, 5650.4349565043985, 5689.7724091036725, 5259.027079990214, 5336.146216605324, 5397.271368365299, 5397.811076612378, 5402.594805389612, 6273.592216505913, 6294.685265736786, 6299.212598425289, 6309.7160627770945, 6327.841803954938, 6162.414570615784, 6170.5372097092, 6191.25704915413, 6240.063990401476, 6240.656763878031, 6302.747890084529, 6309.558330916791, 6314.936850631619, 6328.493720502441, 6333.303336633059, 6185.690715464299, 6196.907046394217, 6203.348648392034, 6216.409441103269, 6218.906716399167, 6120.17377654011, 6145.863015342176, 6146.125431743182, 6189.071639254148, 6196.1635179252025, 6072.592135021756, 6076.969615151896, 6093.634381937098, 6096.859313929741, 6141.84645383869, 5781.508571771446, 6076.817695469143, 6081.908771368521, 6082.239720034996, 6089.665068421102, 5763.9135412968, 5816.651000939957, 5830.854228644318, 5891.587588868738, 5894.469497745224, 5873.647581145145, 5925.881482370242, 6072.368781196679, 6128.938710612837, 6134.938650613615, 4491.899484626288, 5744.597878148489, 5882.794102206402, 5900.0, 5909.734061967136], "value": 5672.697013461938, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.8518444466661, 501.9247112933062, 503.9794416306978, 505.28249885163075, 517.2344929504334, 30138.698613013865, 32434.837825810868, 33754.0, 42426.78786606063, 43599.78200109003, 45646.8588285293, 45985.77007114968, 46063.76968115155, 46089.76955115228, 46265.30602040964, 9580.041995800417, 9854.40873547586, 9857.556409961533, 9864.556094975744, 9886.851697224547, 8637.309015278794, 8904.198622124008, 9544.188743956773, 9873.654422095227, 11941.402929853473, 11918.225315354473, 12015.879841201564, 12025.158238923279, 12029.398530073506, 12032.217905836093, 8611.56942152896, 11928.284302941807, 12018.87981120195, 12034.879651203551, 12042.976347010515, 11426.0, 11831.467583958742, 11831.585894493652, 12094.576689815814, 12097.879021209763, 42428.363574546405, 42740.358894616606, 42793.57206427927, 42806.357904631455, 42822.357664635056, 3512.244867353506, 3573.4103872860946, 4009.3585026395635, 4263.317869140954, 4285.957140428618, 25763.84791952768, 32701.18247043843, 32824.83587582071, 33197.51833502494, 33220.33559328824, 2922.722341377587, 3240.756943229267, 3345.5818022747158, 3370.140614143402, 3413.726901847871, 478904.0, 481066.0, 481221.0, 484864.0, 485230.0, 64746.0, 65275.0, 65284.34715652878, 65284.673576631816, 65326.0, 68128.0, 68756.31243687498, 68776.96834547486, 68781.65609171923, 68834.62330753457, 16544.255508502145, 16580.253888575044, 16759.664806703797, 16779.077150756824, 16806.495805125865, 11591.594294199662, 11864.703382415337, 12901.225926444256, 12974.610761677164, 12976.740465190644, 14669.92665036668, 18140.002299873635, 18355.35756248525, 18400.723989140173, 18408.26366945334, 6948.131483564554, 7030.507864449542, 7081.645917704197, 7111.53775004623, 7124.503854190574, 905.185333200112, 928.832810094176, 1051.9631812886664, 1097.8627671541058, 1120.912714666772, 27027.729722702523, 30863.0, 31226.843865780527, 31517.212069697984, 32117.67882321147, 1094.518411898762, 1101.8181999970075, 1170.8946194842506, 1189.2448295332354, 1198.370855300974, 15874.855156431027, 16108.033517988959, 16187.757183642254, 16279.348826046826, 16426.75359869603, 651884.0, 652309.0, 658230.0, 660005.0, 660528.6973565198, 60988.08517872147, 61032.69483652553, 61116.0, 61134.0, 61171.38828611657, 1308.358904136985, 1446.5443385333797, 1463.8389777124507, 1493.2085994422973, 1500.3398504657916, 31752.364952700354, 31962.52524646598, 31985.8400707995, 32594.674053259165, 32867.01398958035, 53591.0, 54193.45806542042, 54230.18654720103, 54318.642033949465, 54349.0, 541778.0, 544205.0, 544320.0, 544475.0, 544502.0, 24393.634095488225, 25911.761029871264, 26967.190984270503, 27038.324041898322, 28858.567121493616, 49899.00201996012, 50928.74535627446, 51321.23018154656, 51563.0, 51652.0, 39263.607363927134, 40164.59835401725, 40905.38641920314, 41395.0, 42274.788626056674, 40000.19997600307, 40523.826846125485, 40559.3916091253, 41061.794691026356, 41405.378919316834, 31441.21396965094, 39251.21497569975, 39878.80060599678, 39953.80023099866, 40027.999300017735, 34706.0, 34723.4791478123, 35718.64281357153, 35778.92663220107, 36348.45477317895, 24418.51162976695, 25242.621360679237, 25693.743062569134, 25830.095946641777, 26353.736462635894], "value": 20862.422863515025, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.7999222437658, 383.4229484625639, 384.28331162382165, 397.2630769921793, 409.08773435239414, 19635.0, 21467.785322146774, 21728.89135554324, 21798.564028719436, 21834.235801746923, 20393.694094588554, 21056.263030793925, 21095.578088438222, 21432.99999999996, 21637.45906352338, 5307.655002424849, 5672.886542269152, 5903.380145084769, 5913.379095195007, 5915.556333275, 4040.393940908858, 4300.655947524207, 4305.332673435622, 5750.798722044729, 5788.363280039192, 4206.705530612859, 6044.697765111749, 6375.489960803125, 6377.872442551146, 6431.581947173442, 6274.686265686743, 6311.968440157792, 6394.360563943615, 6406.103145559602, 6413.358664133596, 3593.874214402483, 5583.804566840141, 5596.748146333384, 5600.915986260209, 5610.579206559524, 21622.675659865115, 24566.754332456625, 25379.365515862068, 26630.0, 26739.0, 2222.2444368914485, 2941.6617089034607, 3078.8768449262, 3090.6600273969843, 3166.2347719893887, 15640.296186671514, 15680.059196448135, 15763.896527243098, 15850.286737096743, 15899.951785190584, 1635.5911022244438, 1843.8432733217692, 1846.9261229550802, 1849.2048419179882, 1880.4828672115277, 82748.58625706894, 121454.39272803579, 122377.0, 123328.0, 123431.0, 9415.811683766286, 10798.406087665255, 11031.669049928514, 11243.212975091828, 11341.149413793999, 14067.085639433404, 14126.293685315693, 14127.293635318192, 14145.646358841112, 14148.717025659635, 8519.148085191555, 8700.434471759441, 8712.69505567302, 8807.207351338411, 8807.911920880837, 8604.31165506758, 8624.913750862412, 8652.351073669373, 8761.474311541328, 8778.29773618109, 6722.932770672231, 6819.863602727918, 6823.420009299166, 6832.829179270558, 6858.897116543256, 1549.7133030389414, 1683.335082642374, 1751.702210624197, 1844.7601811764653, 1910.1404368034162, 929.242667226222, 964.2912459342377, 985.2265971212564, 997.8004399120205, 1027.5889644142253, 21060.20988215988, 21526.13895444196, 21698.783012169675, 21703.34889953304, 21737.456563586038, 977.8044391121662, 1006.7332156978407, 1039.2361614213546, 1092.5138313450439, 1104.917131215162, 11161.888381116247, 11448.969592736694, 11649.301041937511, 11682.0, 11706.531738730526, 574468.0, 579769.0, 588692.0, 610587.9470602707, 611340.0, 86537.0, 123465.99999999821, 123639.38180309041, 123951.3802430982, 127784.0, 993.9503024848873, 1000.68478429296, 1022.1209759606733, 1025.702546261576, 1028.4138041316528, 15050.774238386652, 17867.463976080737, 19251.807481925563, 20097.19611215535, 20138.89930550338, 42137.789311054476, 48089.75955120202, 49601.50398495969, 50505.98988020146, 50913.47259582218, 69588.60822783617, 69973.95039074316, 70587.64706176642, 70651.64674176803, 70749.0, 8093.919060809552, 8181.345492360478, 8307.6676932922, 8502.532360720219, 8521.573921303787, 11632.302061876315, 11708.765824683289, 12274.631761047183, 12291.385430728249, 12382.876171238173, 10582.523786429476, 10744.51649675782, 11066.059384952046, 11255.437228138397, 11614.477348519506, 8167.632456539352, 11317.773644527228, 11433.942830285796, 11584.652460426201, 11728.41357932117, 7932.206779322252, 11534.826977595176, 11792.823107653221, 11863.525458981889, 12016.459259333526, 8130.0, 8141.959290203748, 8224.218699223804, 8257.050439199329, 8425.031121421114, 11567.826482602599, 11633.011194048262, 11692.766144677229, 11745.47145378477, 11778.293302401884], "value": 9778.080237909844, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.2449477282269, 1556.3930067273766, 1569.0115227406732, 1815.2557451444911, 1955.8826470411768, 7768.57272849993, 8490.745277641672, 8754.080821513744, 8761.649534018648, 8875.156860098281, 8511.27654149398, 8735.825283494327, 8740.4755714657, 8756.518391488466, 8776.385653004292, 2341.730700969385, 2370.96443553346, 2373.976260237401, 2374.3589230907646, 2376.4652953085506, 2322.384568089458, 2360.1385494294545, 2367.490989437271, 2371.905123795055, 2374.8218883583713, 2060.186226440555, 2329.1731435340466, 2343.191598898386, 2377.5126099149634, 2382.630692242705, 2306.7231932168247, 2336.859788412692, 2344.5193735284224, 2344.7772461616205, 2360.834741568091, 2002.0, 2325.9881951351217, 2333.56828986638, 2337.836351455399, 2338.9181378651665, 8502.659893604248, 8637.39538232324, 8704.738857834274, 8723.25852302555, 8802.735917922408, 5255.159174532057, 5397.082495975655, 5411.161270003156, 5412.972935135338, 5425.511703946664, 3352.2289873329028, 3584.8386822592784, 3661.029827095822, 3671.04552816267, 3692.409214525663, 1059.4861492176287, 1071.560660129351, 1077.466654006272, 1077.9946100269526, 1083.6748975307455, 11465.369404682739, 11594.246373985749, 11611.419429028514, 11617.954384105471, 11625.360605166712, 3825.2923209206388, 4463.598276155161, 4600.723956562617, 4611.8847028823875, 4613.930791038137, 9082.954585227164, 9089.409188402733, 9093.40892841963, 9094.045125261884, 9098.818023639622, 5171.637985341065, 5232.529072383504, 5243.947560524422, 5247.632665713363, 5251.159814429673, 4375.256206444912, 4531.8187272509385, 4705.55297246761, 4851.733154676456, 4863.076015557103, 3733.6266373362446, 3744.3821769408287, 3825.1967086911504, 3865.420186971977, 3907.4529565860803, 1156.6356597671706, 1165.248414772467, 1168.9941550292194, 1181.2676140792764, 1182.131133616791, 646.945009674183, 801.7314199743034, 848.2069265237024, 873.5937788928179, 990.0693348252763, 5720.799772007959, 5832.241808564944, 5910.970445147746, 5976.282846058501, 5986.700664966821, 953.8616900549521, 972.8492083727034, 975.7463059604412, 1003.3428104591504, 1026.0867827633413, 3870.787106709102, 3889.9416508752392, 3959.544652365015, 3977.423273625308, 4027.2549578327516, 10321.277510574188, 10723.088537474401, 10731.0, 10732.73168170802, 10743.83884241737, 10530.57877684884, 10595.735106622396, 10638.627648032281, 10658.253922225525, 10697.144228461704, 8113.18868113184, 8115.553644549606, 8218.21926916942, 8241.423100382915, 8264.21489958453, 5447.972760136174, 5671.829845104653, 5683.488486036276, 5946.43508866657, 6055.333913269449, 9749.317547771872, 9906.900930990887, 10036.89963100389, 10053.095221430107, 10064.194864410683, 8099.676012959416, 10189.490525473548, 10274.280800343904, 10274.691759247235, 10278.229132814919, 7410.592417417094, 7570.091589009354, 7580.3556697681315, 7581.0, 7588.696452141852, 7982.321502672338, 8046.275835174863, 8129.79675508117, 8145.063317718538, 8181.304589109992, 6919.550229235084, 7426.443016773872, 7514.774556763306, 7524.548527088392, 7545.868119782077, 7368.37368823656, 7385.483016188815, 7413.703451861865, 7506.3994880408345, 7527.473076884566, 6554.983977483497, 6595.0, 6952.788848571347, 7016.684249208696, 7117.6085315308155, 4955.876103097451, 4977.875553111201, 5006.949930500648, 5030.396352437585, 5110.9233361500355, 5019.347484826949, 5031.899362012812, 5077.8730531737, 5087.058894104603, 5187.740612969261], "value": 4539.381992510579, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.1977618639166, 313.3279116295545, 315.56136969612237, 321.75225076690947, 325.78986553672866, 5061.898762024757, 5353.678779273247, 5359.0, 5382.38102618199, 5415.512603865652, 4506.918339598497, 4656.464506581744, 4813.157697402949, 4869.5373939475785, 5266.34170728659, 3318.950215746766, 3347.882824101157, 3772.245550889826, 3928.7446315989514, 3939.960600393988, 3566.910827229327, 3851.0, 3926.0, 4037.6164264394833, 4062.8577999770014, 3915.7846318452475, 3961.247363001035, 3989.421533877586, 4028.436018957348, 4028.7179897407195, 4007.6393124618926, 4026.7382620129597, 4037.6971727120435, 4043.353063509839, 4063.2482990646827, 2947.2337192329933, 2947.513660246057, 2995.3709720958623, 3293.6541663125267, 3499.6325385834375, 4956.330895329121, 4994.375703037121, 5009.84773502093, 5014.448410674823, 5017.67385119966, 4594.0, 4617.3304870793545, 4620.0, 4671.743054132022, 4761.880952976203, 4952.62855285855, 5527.474889885454, 5559.221708960748, 5560.4161563035705, 5562.582806289504, 3333.7498438085718, 3850.961490385116, 3887.7278590498677, 3898.368601194211, 3912.002439377969, 4344.89137771555, 5082.339295891547, 5407.593620838728, 5696.487316141484, 5747.942520574783, 3648.3615367310613, 3832.310184166821, 3873.535175778868, 3948.4669569607736, 4000.5199376075057, 3867.245887052054, 4010.578889216649, 4021.718479706451, 4034.9798251008556, 4052.5339585947404, 3911.4523966644692, 4538.005058535226, 4608.5161058088415, 4648.1168577970075, 4671.557635354774, 5175.068487672255, 5477.0, 5580.9441905580425, 5618.775248989995, 5621.494065534122, 1257.4278703190027, 1343.7245364700213, 1375.4429456070307, 1386.6810633554137, 1387.3548799808138, 806.2944923192207, 835.9874601880977, 849.2781136034322, 865.4767609008065, 867.483847110968, 3756.4553139795125, 3962.7027972902147, 4139.627433530996, 4337.221739086959, 4393.165298593257, 4315.784210789448, 4695.882602934954, 4716.938688794994, 4760.9525904301, 4896.485868983706, 3991.9002024949027, 4287.849925252601, 4347.043830276389, 4360.476742790822, 4362.629176519968, 2239.6080685879906, 2242.876641784685, 2432.598621227513, 2551.4259291659228, 2570.8200425970003, 4202.516710578261, 4385.386045953569, 4477.865664030084, 4528.920334172728, 4593.28804035375, 4596.644449551779, 4600.6549508786975, 4893.95106048942, 4941.530554597308, 4977.386871892286, 4803.807847686124, 4854.417469903634, 4878.804847806048, 5055.974720126449, 6048.183495228176, 5074.797008119782, 5330.62685612019, 5507.421720719267, 5750.884982300414, 5787.392323806108, 5050.787810925425, 5185.688858668492, 5413.971345444353, 5728.283964504437, 5751.836489930605, 4752.453467851241, 5705.457981491918, 5793.219254757656, 6003.6397816131175, 6021.458068773832, 3245.350929814094, 3767.2277183176834, 3767.2842159989514, 3786.659200671953, 3798.7910664913693, 4953.23224900141, 5276.709780962084, 5732.283464566929, 5774.566907481872, 5825.271841019872, 5291.756437237324, 5427.402985671494, 5551.944480555304, 5581.246531718166, 5615.326160860723, 4532.7280363178315, 5090.007448547498, 5133.973205359018, 5158.071547121554, 5221.921671175011, 5154.329937108302, 5225.477452254744, 5477.028269822781, 5497.697626630574, 5506.889862202814, 4876.707397556158, 5564.777408903598, 5573.191887176417, 5588.273524441796, 5590.748416321357, 5381.381141168816, 5427.538659213853, 5455.863603409946, 5466.535344495763, 5477.822268212353], "value": 3908.715091592651, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.4585315160476, 722.1731117870039, 725.4559080689482, 751.3238085722849, 841.718024461805, 14912.552623421288, 15385.461508847193, 15554.0, 15590.844091559095, 15611.375544978187, 14265.930055245846, 14413.423463061465, 14428.422863085463, 14445.205513696768, 14494.985351025432, 698.3644883156329, 707.5825263094799, 893.4862454088909, 916.839553078212, 923.5059243304834, 874.6107981948004, 925.8055808280269, 931.0224264522225, 931.7297983584753, 931.9440833549975, 950.1638558068925, 963.1139351796364, 965.8599503072086, 967.8644989701447, 968.1722127580922, 755.2711633273863, 778.5095389904324, 782.1904329019494, 960.6493629825098, 962.9181519570844, 577.9508741756956, 578.1299144787068, 686.6841253023615, 710.069808550795, 713.0658836923595, 15423.768643470357, 15456.845431545653, 15460.845391546052, 15495.225238738018, 15501.534953951399, 13988.440462381492, 14030.92984535081, 14125.858741412558, 14233.217173055478, 14319.856801431957, 12010.9190172884, 12416.875831241752, 12901.22592644435, 13103.934480327633, 13440.596782096552, 4525.9547404525865, 4591.954080459186, 4804.1352556539805, 4842.346283251751, 4854.2961270615915, 99107.50446247794, 99752.0, 99922.50038749832, 99936.0, 100147.49926250322, 10897.618583349546, 10994.890051099546, 11119.054880335261, 11418.714532136597, 11492.54029838797, 21504.677429838564, 22307.10771569119, 22350.55298894013, 22351.21770738049, 22355.0, 7821.882671759929, 8209.507429554245, 8231.382646301552, 8234.05308389531, 8252.092269850311, 4440.4005459263235, 4504.997809724811, 4632.745199014086, 4668.159731248408, 4670.929936050962, 3480.077779388464, 3583.33708263972, 3587.6412358763914, 3590.3716849551224, 3592.4790905319105, 637.2703254773352, 657.4214691071921, 664.3622122762106, 674.9122614060238, 679.0696745458655, 621.3631028196135, 627.2567008095422, 633.5106303876947, 645.1033064040987, 658.344946777959, 8167.877481837777, 8686.174813392718, 8879.600417981206, 9009.954950225207, 9228.986425921945, 571.0811672779457, 577.6014549960514, 624.1386886097157, 642.7172044300493, 661.24287690594, 4353.0, 4360.127974405132, 4367.497737760198, 4369.104333611602, 4398.494173170063, 89437.0, 91097.54451227702, 123498.0, 131363.34318328346, 141225.64484598217, 109973.45013274883, 124883.0, 125743.371283143, 127101.0, 127195.72804272023, 83444.58277708573, 84607.0, 101981.0, 107333.46333268283, 107954.46022769836, 7617.352525035212, 8430.199131082538, 8540.957295213484, 8864.955675221583, 9083.0, 50068.749656251486, 60278.69860650669, 60733.0, 61781.0, 64743.02885456816, 92730.53634731783, 93424.53287733518, 94010.0, 94300.0, 96502.51748741211, 11630.418479076181, 11681.357525336189, 11697.356645384585, 11858.525658973544, 12167.0, 13881.79177312321, 14426.639334016734, 14531.055481393676, 14660.926695366881, 14741.410343586136, 13673.384697688429, 13861.237631929936, 13989.790153147915, 14032.158070515801, 14391.064580802215, 12989.935050324688, 13310.40103195378, 13432.462701491831, 13574.98187635951, 13597.184168949894, 13459.596212113653, 13467.595972120853, 13482.865171348161, 13491.460341586228, 13850.307484625528, 10968.835467468154, 11112.055475284691, 11466.564555992873, 11592.826107608562, 11689.766204675689, 11273.830892536454, 11289.379084150121, 11301.71745706364, 11552.191346605658, 11573.132015098732], "value": 7761.627254977122, "unit": "score"}], "name": "dromaeo_css", "value": 7363.320103877546, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 113.0, 113.0, 111.0, 114.0, 112.0, 113.0, 112.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 124.0, 118.0, 169.0, 117.0, 118.0, 141.0, 118.0, 149.0, 126.0], "value": 133.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 183.0, 186.0, 181.0, 182.0, 184.0, 186.0, 182.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 71.0, 112.0, 80.0, 57.0, 64.0, 60.0, 96.0, 57.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 92.0, 93.0, 71.0, 76.0, 70.0, 95.0, 72.0, 68.0, 109.0], "value": 83.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 85.0, 87.0, 84.0, 90.0, 85.0, 87.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 100.0, 141.0, 95.0, 129.0, 118.0, 95.0, 95.0, 94.0, 93.0], "value": 105.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 84.0, 85.0, 84.0, 85.0, 83.0, 84.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0, 54.0, 54.0, 53.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 82.0, 78.0, 73.0, 69.0, 69.0, 72.0, 70.0, 69.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 105.0, 107.0, 86.0, 85.0, 85.0, 88.0, 122.0, 87.0], "value": 94.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 141.0, 147.0, 128.0, 141.0, 139.0, 144.0, 150.0, 142.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 167.0, 168.0, 167.0, 166.0, 164.0, 164.0, 168.0, 164.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 67.0, 65.0, 66.0, 65.0, 66.0, 66.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1455.9999999999998, "alertThreshold": 2.0}]} 04:53:26 INFO - Return code: 0 04:53:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:53:26 ERROR - # TBPL SUCCESS # 04:53:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:53:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:53:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:53:26 INFO - 2016-06-03 04:53:26.173 screenresolution[1675:10164] starting screenresolution argv=screenresolution get 04:53:26 INFO - 2016-06-03 04:53:26.175 screenresolution[1675:10164] Display 0: 1600x1200x32@60 04:53:26 INFO - 2016-06-03 04:53:26.181 screenresolution[1676:10167] starting screenresolution argv=screenresolution list 04:53:26 INFO - Available Modes on Display 0 04:53:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:53:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:53:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:53:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:53:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:53:26 INFO - Intel Iris: 04:53:26 INFO - Chipset Model: Intel Iris 04:53:26 INFO - Type: GPU 04:53:26 INFO - Bus: Built-In 04:53:26 INFO - VRAM (Dynamic, Max): 1536 MB 04:53:26 INFO - Vendor: Intel (0x8086) 04:53:26 INFO - Device ID: 0x0a2e 04:53:26 INFO - Revision ID: 0x0009 04:53:26 INFO - Displays: 04:53:26 INFO - Display: 04:53:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:53:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:53:26 INFO - Display Serial Number: 1600x1200 60 04:53:26 INFO - Main Display: Yes 04:53:26 INFO - Mirror: Off 04:53:26 INFO - Online: Yes 04:53:26 INFO - Rotation: Supported 04:53:26 INFO - Return code: 0 04:53:26 INFO - Running post-action listener: _resource_record_post_action 04:53:26 INFO - ##### 04:53:26 INFO - ##### Finished run-tests step (success) 04:53:26 INFO - ##### 04:53:26 INFO - Running post-run listener: _resource_record_post_run 04:53:26 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31596032; Write bytes: 695155200; Read time: 196; Write time: 1944 04:53:26 INFO - TinderboxPrint: CPU usage
53.7% 04:53:26 INFO - TinderboxPrint: I/O read bytes / time
31,596,032 / 196 04:53:26 INFO - TinderboxPrint: I/O write bytes / time
695,155,200 / 1,944 04:53:26 INFO - TinderboxPrint: CPU idle
2,245.5 (46.3%) 04:53:26 INFO - TinderboxPrint: CPU system
962.5 (19.9%) 04:53:26 INFO - TinderboxPrint: CPU user
1,637.6 (33.8%) 04:53:26 INFO - TinderboxPrint: Swap in / out
427,069,440 / 0 04:53:26 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400656896; Write bytes: 396969472; Read time: 29880; Write time: 844 04:53:26 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29814272; Write bytes: 292152320; Read time: 181; Write time: 1083 04:53:26 INFO - Running post-run listener: _upload_blobber_files 04:53:26 INFO - Blob upload gear active. 04:53:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:53:26 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 04:53:26 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'] 04:53:26 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 04:53:27 INFO - (blobuploader) - INFO - Open directory for files ... 04:53:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:53:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:53:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:28 INFO - (blobuploader) - INFO - Done attempting. 04:53:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:53:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:53:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:28 INFO - (blobuploader) - INFO - Done attempting. 04:53:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:53:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:53:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:31 INFO - (blobuploader) - INFO - Done attempting. 04:53:31 INFO - (blobuploader) - INFO - Iteration through files over. 04:53:31 INFO - Return code: 0 04:53:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:53:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:53:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc5a708fe9f8ce21f7b05a8157b13a0eab8247105f7ebcd5537d99a7ae0d7f3414b0bcc47dab55ca8559e440d61f8650580f657909d1e98b4477005b238310e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55dfd485b74868700894be4e7d42e392aea4cf77f96a87e1cce28a2aa28b216574d4df89ecf39cb3218fa66cbad1275c05b8996da5682b302cb3051e0a2accad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc898a5795c974dad0d2581355b6e2acb31986e04f2b5956dcfbc8cd3dab32b497fb1fcf51588e9e69b26800a088af6181daa578c0fed3527a615eff44f9fcc6"} 04:53:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:53:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:53:31 INFO - Contents: 04:53:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc5a708fe9f8ce21f7b05a8157b13a0eab8247105f7ebcd5537d99a7ae0d7f3414b0bcc47dab55ca8559e440d61f8650580f657909d1e98b4477005b238310e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55dfd485b74868700894be4e7d42e392aea4cf77f96a87e1cce28a2aa28b216574d4df89ecf39cb3218fa66cbad1275c05b8996da5682b302cb3051e0a2accad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc898a5795c974dad0d2581355b6e2acb31986e04f2b5956dcfbc8cd3dab32b497fb1fcf51588e9e69b26800a088af6181daa578c0fed3527a615eff44f9fcc6"} 04:53:31 INFO - Running post-run listener: copy_logs_to_upload_dir 04:53:31 INFO - Copying logs to upload dir... 04:53:31 INFO - mkdir: /builds/slave/test/build/upload/logs 04:53:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.305064 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-06-03 04:53:31.318122) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 04:53:31.321202) ========= 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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc5a708fe9f8ce21f7b05a8157b13a0eab8247105f7ebcd5537d99a7ae0d7f3414b0bcc47dab55ca8559e440d61f8650580f657909d1e98b4477005b238310e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55dfd485b74868700894be4e7d42e392aea4cf77f96a87e1cce28a2aa28b216574d4df89ecf39cb3218fa66cbad1275c05b8996da5682b302cb3051e0a2accad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc898a5795c974dad0d2581355b6e2acb31986e04f2b5956dcfbc8cd3dab32b497fb1fcf51588e9e69b26800a088af6181daa578c0fed3527a615eff44f9fcc6"} 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.011148 build_url: 'https://queue.taskcluster.net/v1/task/IFkmDtNETaWukOs7Mi72Rg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc5a708fe9f8ce21f7b05a8157b13a0eab8247105f7ebcd5537d99a7ae0d7f3414b0bcc47dab55ca8559e440d61f8650580f657909d1e98b4477005b238310e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55dfd485b74868700894be4e7d42e392aea4cf77f96a87e1cce28a2aa28b216574d4df89ecf39cb3218fa66cbad1275c05b8996da5682b302cb3051e0a2accad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc898a5795c974dad0d2581355b6e2acb31986e04f2b5956dcfbc8cd3dab32b497fb1fcf51588e9e69b26800a088af6181daa578c0fed3527a615eff44f9fcc6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 04:53:31.354428) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:53:31.355080) ========= 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.BN7Dqa1ANG/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.lYwbAcMHem/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.005278 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:53:31.404556) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 04:53:31.404888) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 04:53:31.405246) ========= ========= Total master_lag: 0.17 =========