builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0246 starttime: 1463710988.21 results: success (0) buildid: 20160519173313 builduid: c60de48900454dc8870c6eb9adfdce9c revision: 2013e1255bf82c59a3d90ebc69895f62e9a180f7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:08.211141) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:08.211530) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:08.211815) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:08.242466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:08.242805) ========= 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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-19 19:23:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.6M=0.001s 2016-05-19 19:23:08 (19.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.589733 ========= master_lag: 0.39 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:09.225163) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:09.225483) ========= 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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.020041 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:09.273637) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 19:23:09.273946) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2013e1255bf82c59a3d90ebc69895f62e9a180f7 --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 2013e1255bf82c59a3d90ebc69895f62e9a180f7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iw2ufHf1Nu/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.QaT2SlaQra/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-19 19:23:09,344 truncating revision to first 12 chars 2016-05-19 19:23:09,344 Setting DEBUG logging. 2016-05-19 19:23:09,344 attempt 1/10 2016-05-19 19:23:09,344 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2013e1255bf8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 19:23:10,517 unpacking tar archive at: fx-team-2013e1255bf8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.422734 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 19:23:10.716852) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:10.717137) ========= script_repo_revision: 2013e1255bf82c59a3d90ebc69895f62e9a180f7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:10.717497) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:10.717775) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 19:23:10.732097) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-19 19:23:10.732363) ========= /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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 19:23:10 INFO - MultiFileLogger online at 20160519 19:23:10 in /builds/slave/test 19:23:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 19:23:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:23:10 INFO - {'append_to_log': False, 19:23:10 INFO - 'base_work_dir': '/builds/slave/test', 19:23:10 INFO - 'blob_upload_branch': 'Fx-Team', 19:23:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:23:10 INFO - 'branch': 'Fx-Team', 19:23:10 INFO - 'buildbot_json_path': 'buildprops.json', 19:23:10 INFO - 'config_files': ('talos/mac_config.py',), 19:23:10 INFO - 'default_actions': ('clobber', 19:23:10 INFO - 'read-buildbot-config', 19:23:10 INFO - 'download-and-extract', 19:23:10 INFO - 'populate-webroot', 19:23:10 INFO - 'create-virtualenv', 19:23:10 INFO - 'install', 19:23:10 INFO - 'run-tests'), 19:23:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:23:10 INFO - 'download_minidump_stackwalk': True, 19:23:10 INFO - 'download_symbols': 'ondemand', 19:23:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:23:10 INFO - 'tooltool.py': '/tools/tooltool.py', 19:23:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:23:10 INFO - '/tools/misc-python/virtualenv.py')}, 19:23:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:23:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:23:10 INFO - 'installer_path': 'installer.exe', 19:23:10 INFO - 'log_level': 'info', 19:23:10 INFO - 'log_name': 'talos', 19:23:10 INFO - 'log_to_console': True, 19:23:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:23:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:23:10 INFO - 'opt_config_files': (), 19:23:10 INFO - 'pip_index': False, 19:23:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:23:10 INFO - 'cmd': ('bash', 19:23:10 INFO - '-c', 19:23:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:23:10 INFO - 'enabled': True, 19:23:10 INFO - 'halt_on_failure': False, 19:23:10 INFO - 'name': 'check_screen_resolution'},), 19:23:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:23:10 INFO - 'cmd': ('bash', 19:23:10 INFO - '-c', 19:23:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:23:10 INFO - 'enabled': True, 19:23:10 INFO - 'halt_on_failure': False, 19:23:10 INFO - 'name': 'check_screen_resolution'},), 19:23:10 INFO - 'run_cmd_checks_enabled': True, 19:23:10 INFO - 'sps_profile': False, 19:23:10 INFO - 'sps_profile_interval': 0, 19:23:10 INFO - 'suite': 'dromaeojs', 19:23:10 INFO - 'system_bits': '32', 19:23:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:23:10 INFO - 'title': 't-yosemite-r7-0246', 19:23:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:23:10 INFO - 'use_talos_json': True, 19:23:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:23:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:23:10 INFO - 'work_dir': 'build'} 19:23:10 INFO - ##### 19:23:10 INFO - ##### Running clobber step. 19:23:10 INFO - ##### 19:23:10 INFO - Running pre-action listener: _resource_record_pre_action 19:23:10 INFO - Running main action method: clobber 19:23:10 INFO - rmtree: /builds/slave/test/build 19:23:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:23:11 INFO - Running post-action listener: _resource_record_post_action 19:23:11 INFO - ##### 19:23:11 INFO - ##### Finished clobber step (success) 19:23:11 INFO - ##### 19:23:11 INFO - ##### 19:23:11 INFO - ##### Running read-buildbot-config step. 19:23:11 INFO - ##### 19:23:11 INFO - Running pre-action listener: _resource_record_pre_action 19:23:11 INFO - Running main action method: read_buildbot_config 19:23:11 INFO - Using buildbot properties: 19:23:11 INFO - { 19:23:11 INFO - "project": "", 19:23:11 INFO - "product": "firefox", 19:23:11 INFO - "script_repo_revision": "production", 19:23:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 19:23:11 INFO - "repository": "", 19:23:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 19:23:11 INFO - "buildid": "20160519173313", 19:23:11 INFO - "pgo_build": "False", 19:23:11 INFO - "basedir": "/builds/slave/test", 19:23:11 INFO - "buildnumber": 711, 19:23:11 INFO - "slavename": "t-yosemite-r7-0246", 19:23:11 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:23:11 INFO - "platform": "yosemite_r7", 19:23:11 INFO - "scheduler": "tests-fx-team-macosx64-talos", 19:23:11 INFO - "branch": "fx-team", 19:23:11 INFO - "repo_path": "integration/fx-team", 19:23:11 INFO - "revision": "2013e1255bf82c59a3d90ebc69895f62e9a180f7", 19:23:11 INFO - "stage_platform": "macosx64", 19:23:11 INFO - "builduid": "c60de48900454dc8870c6eb9adfdce9c", 19:23:11 INFO - "slavebuilddir": "test" 19:23:11 INFO - } 19:23:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:23:11 INFO - Running post-action listener: _resource_record_post_action 19:23:11 INFO - ##### 19:23:11 INFO - ##### Finished read-buildbot-config step (success) 19:23:11 INFO - ##### 19:23:11 INFO - ##### 19:23:11 INFO - ##### Running download-and-extract step. 19:23:11 INFO - ##### 19:23:11 INFO - Running pre-action listener: _resource_record_pre_action 19:23:11 INFO - Running main action method: download_and_extract 19:23:11 INFO - mkdir: /builds/slave/test/build/tests 19:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:11 INFO - https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:23:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:23:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:23:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/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 19:23:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/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 19:23:12 INFO - Downloaded 1199 bytes. 19:23:12 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:23:12 INFO - Using the following test package requirements: 19:23:12 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:23:12 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:23:12 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:23:12 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:23:12 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:23:12 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:23:12 INFO - u'jsshell-mac.zip'], 19:23:12 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:23:12 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:23:12 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:23:12 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:23:12 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:23:12 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:23:12 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:23:12 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:23:12 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:23:12 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:23:12 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:23:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:12 INFO - https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:23:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:23:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:23:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/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 19:23:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/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 19:23:14 INFO - Downloaded 18630423 bytes. 19:23:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:23:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:23:17 INFO - Return code: 0 19:23:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:17 INFO - https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:23:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:23:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:23:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/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 19:23:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/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 19:23:23 INFO - Downloaded 18630423 bytes. 19:23: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'] 19:23: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 19:23:27 INFO - Return code: 0 19:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:27 INFO - https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:23:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:23:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:23:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/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 19:23:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/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 19:23:28 INFO - Downloaded 10840359 bytes. 19:23:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:23:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:23:28 INFO - Return code: 0 19:23:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:28 INFO - https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:23:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:23:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:23:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:23:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SJSfHfPUTmO3tJvN3onC8Q/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 19:23:31 INFO - Downloaded 110149145 bytes. 19:23:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:23:31 INFO - mkdir: /builds/slave/test/properties 19:23:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:23:31 INFO - Writing to file /builds/slave/test/properties/build_url 19:23:31 INFO - Contents: 19:23:31 INFO - build_url:https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:23:33 INFO - Running post-action listener: _resource_record_post_action 19:23:33 INFO - Running post-action listener: set_extra_try_arguments 19:23:33 INFO - ##### 19:23:33 INFO - ##### Finished download-and-extract step (success) 19:23:33 INFO - ##### 19:23:33 INFO - ##### 19:23:33 INFO - ##### Running populate-webroot step. 19:23:33 INFO - ##### 19:23:33 INFO - Running pre-action listener: _resource_record_pre_action 19:23:33 INFO - Running main action method: populate_webroot 19:23:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:23:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:23:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:23:33 INFO - '--tppagecycles', 19:23:33 INFO - '7'], 19:23:33 INFO - 'tests': ['tsvgm']}}, 19:23:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:23:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:23:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:23:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:23:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:23:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:23:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:23:33 INFO - 'talos_options': ['--disable-e10s'], 19:23:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:23:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:23:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:23:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:23:33 INFO - 'talos_options': ['--disable-e10s'], 19:23:33 INFO - 'tests': ['damp', 'tps']}, 19:23:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:23:33 INFO - 'tests': ['damp', 'tps']}, 19:23:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:23:33 INFO - 'tests': ['dromaeo_dom']}, 19:23:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:23:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:23:33 INFO - 'tests': ['a11yr', 19:23:33 INFO - 'ts_paint', 19:23:33 INFO - 'tpaint', 19:23:33 INFO - 'sessionrestore', 19:23:33 INFO - 'sessionrestore_no_auto_restore', 19:23:33 INFO - 'tabpaint']}, 19:23:33 INFO - 'other-e10s': {'tests': ['a11yr', 19:23:33 INFO - 'ts_paint', 19:23:33 INFO - 'tpaint', 19:23:33 INFO - 'sessionrestore', 19:23:33 INFO - 'sessionrestore_no_auto_restore', 19:23:33 INFO - 'tabpaint']}, 19:23:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:23:33 INFO - 'ts_paint', 19:23:33 INFO - 'tpaint', 19:23:33 INFO - 'sessionrestore', 19:23:33 INFO - 'sessionrestore_no_auto_restore', 19:23:33 INFO - 'tabpaint']}, 19:23:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:23:33 INFO - 'ts_paint', 19:23:33 INFO - 'tpaint', 19:23:33 INFO - 'sessionrestore', 19:23:33 INFO - 'sessionrestore_no_auto_restore', 19:23:33 INFO - 'tabpaint']}, 19:23:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:23:33 INFO - 'tests': ['a11yr', 19:23:33 INFO - 'ts_paint', 19:23:33 INFO - 'tpaint', 19:23:33 INFO - 'sessionrestore', 19:23:33 INFO - 'sessionrestore_no_auto_restore', 19:23:33 INFO - 'tabpaint']}, 19:23:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:23:33 INFO - 'tests': ['a11yr', 19:23:33 INFO - 'ts_paint', 19:23:33 INFO - 'tpaint', 19:23:33 INFO - 'sessionrestore', 19:23:33 INFO - 'sessionrestore_no_auto_restore', 19:23:33 INFO - 'tabpaint']}, 19:23:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:23:33 INFO - 'tests': ['tsvgx', 19:23:33 INFO - 'tsvgr_opacity', 19:23:33 INFO - 'tart', 19:23:33 INFO - 'tscrollx', 19:23:33 INFO - 'cart']}, 19:23:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:23:33 INFO - 'tsvgr_opacity', 19:23:33 INFO - 'tart', 19:23:33 INFO - 'tscrollx', 19:23:33 INFO - 'cart']}, 19:23:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:23:33 INFO - 'talos_options': ['--disable-e10s'], 19:23:33 INFO - 'tests': ['tp5o']}, 19:23:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:23:33 INFO - 'tests': ['tp5o']}, 19:23:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:23:33 INFO - 'talos_options': ['--disable-e10s', 19:23:33 INFO - '--xperf_path', 19:23:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:23:33 INFO - 'tests': ['tp5n']}, 19:23:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:23:33 INFO - 'talos_options': ['--xperf_path', 19:23:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:23:33 INFO - 'tests': ['tp5n']}}, 19:23:33 INFO - 'talos.zip': {'path': '', 19:23:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:23:33 INFO - Running post-action listener: _resource_record_post_action 19:23:33 INFO - ##### 19:23:33 INFO - ##### Finished populate-webroot step (success) 19:23:33 INFO - ##### 19:23:33 INFO - ##### 19:23:33 INFO - ##### Running create-virtualenv step. 19:23:33 INFO - ##### 19:23:33 INFO - Running pre-action listener: _resource_record_pre_action 19:23:33 INFO - Running main action method: create_virtualenv 19:23:33 INFO - Creating virtualenv /builds/slave/test/build/venv 19:23:33 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 19:23:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:23:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:23:33 INFO - Using real prefix '/tools/python27' 19:23:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:23:33 INFO - Installing distribute.............................................................................................................................................................................................done. 19:23:36 INFO - Installing pip.................done. 19:23:36 INFO - Return code: 0 19:23:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b419a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b41f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc710c480c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2bdd30>, '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 0x10b0e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 19:23:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:23:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iw2ufHf1Nu/Render', 19:23:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:36 INFO - 'HOME': '/Users/cltbld', 19:23:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:36 INFO - 'LOGNAME': 'cltbld', 19:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:36 INFO - 'MOZ_NO_REMOTE': '1', 19:23:36 INFO - 'NO_EM_RESTART': '1', 19:23:36 INFO - 'PAGER': '/bin/cat', 19:23:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:36 INFO - 'PWD': '/builds/slave/test', 19:23:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:36 INFO - 'SHELL': '/bin/bash', 19:23:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QaT2SlaQra/Listeners', 19:23:36 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 19:23:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:36 INFO - 'USER': 'cltbld', 19:23:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:36 INFO - 'XPC_FLAGS': '0x0', 19:23:36 INFO - 'XPC_SERVICE_NAME': '0', 19:23:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:37 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 19:23:37 INFO - Cleaning up... 19:23:37 INFO - Return code: 0 19:23:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b419a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b41f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc710c480c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2bdd30>, '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 0x10b0e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 19:23:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iw2ufHf1Nu/Render', 19:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:37 INFO - 'HOME': '/Users/cltbld', 19:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:37 INFO - 'LOGNAME': 'cltbld', 19:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:37 INFO - 'MOZ_NO_REMOTE': '1', 19:23:37 INFO - 'NO_EM_RESTART': '1', 19:23:37 INFO - 'PAGER': '/bin/cat', 19:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:37 INFO - 'PWD': '/builds/slave/test', 19:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:37 INFO - 'SHELL': '/bin/bash', 19:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QaT2SlaQra/Listeners', 19:23:37 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 19:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:37 INFO - 'USER': 'cltbld', 19:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:37 INFO - 'XPC_FLAGS': '0x0', 19:23:37 INFO - 'XPC_SERVICE_NAME': '0', 19:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:37 INFO - Downloading/unpacking psutil>=3.1.1 19:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:23:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:23:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:23:40 INFO - Installing collected packages: psutil 19:23:40 INFO - Running setup.py install for psutil 19:23:40 INFO - building 'psutil._psutil_osx' extension 19:23:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:23:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:23:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:23:40 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 19:23:40 INFO - building 'psutil._psutil_posix' extension 19:23:40 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 19:23:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:23:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:23:40 INFO - ^ 19:23:40 INFO - 1 warning generated. 19:23:40 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 19:23:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:23:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:23:41 INFO - Successfully installed psutil 19:23:41 INFO - Cleaning up... 19:23:41 INFO - Return code: 0 19:23:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b419a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b41f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc710c480c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2bdd30>, '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 0x10b0e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 19:23:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:23:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iw2ufHf1Nu/Render', 19:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:41 INFO - 'HOME': '/Users/cltbld', 19:23:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:41 INFO - 'LOGNAME': 'cltbld', 19:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:41 INFO - 'MOZ_NO_REMOTE': '1', 19:23:41 INFO - 'NO_EM_RESTART': '1', 19:23:41 INFO - 'PAGER': '/bin/cat', 19:23:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:41 INFO - 'PWD': '/builds/slave/test', 19:23:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:41 INFO - 'SHELL': '/bin/bash', 19:23:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QaT2SlaQra/Listeners', 19:23:41 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 19:23:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:41 INFO - 'USER': 'cltbld', 19:23:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:41 INFO - 'XPC_FLAGS': '0x0', 19:23:41 INFO - 'XPC_SERVICE_NAME': '0', 19:23:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:23:44 INFO - Installing collected packages: mozsystemmonitor 19:23:44 INFO - Running setup.py install for mozsystemmonitor 19:23:44 INFO - Successfully installed mozsystemmonitor 19:23:44 INFO - Cleaning up... 19:23:44 INFO - Return code: 0 19:23:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:23:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b419a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b41f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc710c480c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2bdd30>, '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 0x10b0e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 19:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iw2ufHf1Nu/Render', 19:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:44 INFO - 'HOME': '/Users/cltbld', 19:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:44 INFO - 'LOGNAME': 'cltbld', 19:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:44 INFO - 'MOZ_NO_REMOTE': '1', 19:23:44 INFO - 'NO_EM_RESTART': '1', 19:23:44 INFO - 'PAGER': '/bin/cat', 19:23:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:44 INFO - 'PWD': '/builds/slave/test', 19:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:44 INFO - 'SHELL': '/bin/bash', 19:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QaT2SlaQra/Listeners', 19:23:44 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 19:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:44 INFO - 'USER': 'cltbld', 19:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:44 INFO - 'XPC_FLAGS': '0x0', 19:23:44 INFO - 'XPC_SERVICE_NAME': '0', 19:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:44 INFO - Downloading/unpacking blobuploader==1.2.4 19:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:46 INFO - Downloading blobuploader-1.2.4.tar.gz 19:23:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:23:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:23:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:48 INFO - Downloading docopt-0.6.1.tar.gz 19:23:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:23:48 INFO - Installing collected packages: blobuploader, requests, docopt 19:23:48 INFO - Running setup.py install for blobuploader 19:23:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:23:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:23:48 INFO - Running setup.py install for requests 19:23:48 INFO - Running setup.py install for docopt 19:23:48 INFO - Successfully installed blobuploader requests docopt 19:23:48 INFO - Cleaning up... 19:23:49 INFO - Return code: 0 19:23:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:23:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b419a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b41f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc710c480c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2bdd30>, '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 0x10b0e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 19:23:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:23:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:23:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iw2ufHf1Nu/Render', 19:23:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:49 INFO - 'HOME': '/Users/cltbld', 19:23:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:49 INFO - 'LOGNAME': 'cltbld', 19:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:49 INFO - 'MOZ_NO_REMOTE': '1', 19:23:49 INFO - 'NO_EM_RESTART': '1', 19:23:49 INFO - 'PAGER': '/bin/cat', 19:23:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:49 INFO - 'PWD': '/builds/slave/test', 19:23:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:49 INFO - 'SHELL': '/bin/bash', 19:23:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QaT2SlaQra/Listeners', 19:23:49 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 19:23:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:49 INFO - 'USER': 'cltbld', 19:23:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:49 INFO - 'XPC_FLAGS': '0x0', 19:23:49 INFO - 'XPC_SERVICE_NAME': '0', 19:23:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:23:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-mf2uq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:23:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-3KfQfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:23:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-D8jY4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:23:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-oz_j52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:23:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Okmcub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:23:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-FiGYxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:23:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-8wuOR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:23:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-akS5HD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:23:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-1NnzPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:23:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-cdinIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:23:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-PW1_Ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:23:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-UwyJor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:23:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-VuexyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:23:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-1N22eU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-tlnBBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:23:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-8K9wXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:23:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-m4Qd_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:23:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:23:51 INFO - Running setup.py install for manifestparser 19:23:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:23:51 INFO - Running setup.py install for mozcrash 19:23:51 INFO - Running setup.py install for mozdebug 19:23:51 INFO - Running setup.py install for mozdevice 19:23:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:23:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:23:51 INFO - Running setup.py install for mozfile 19:23:51 INFO - Running setup.py install for mozhttpd 19:23:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:23:51 INFO - Running setup.py install for mozinfo 19:23:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:23:52 INFO - Running setup.py install for mozInstall 19:23:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:23:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:23:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:23:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:23:52 INFO - Running setup.py install for mozleak 19:23:52 INFO - Running setup.py install for mozlog 19:23:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:23:52 INFO - Running setup.py install for moznetwork 19:23:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:23:52 INFO - Running setup.py install for mozprocess 19:23:52 INFO - Running setup.py install for mozprofile 19:23:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:23:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:23:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:23:53 INFO - Running setup.py install for mozrunner 19:23:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:23:53 INFO - Running setup.py install for mozscreenshot 19:23:53 INFO - Running setup.py install for moztest 19:23:53 INFO - Running setup.py install for mozversion 19:23:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:23:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:23:53 INFO - Cleaning up... 19:23:53 INFO - Return code: 0 19:23:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:23:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b419a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b41f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc710c480c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2bdd30>, '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 0x10b0e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 19:23:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:23:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:23:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iw2ufHf1Nu/Render', 19:23:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:53 INFO - 'HOME': '/Users/cltbld', 19:23:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:53 INFO - 'LOGNAME': 'cltbld', 19:23:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:53 INFO - 'MOZ_NO_REMOTE': '1', 19:23:53 INFO - 'NO_EM_RESTART': '1', 19:23:53 INFO - 'PAGER': '/bin/cat', 19:23:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:53 INFO - 'PWD': '/builds/slave/test', 19:23:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:53 INFO - 'SHELL': '/bin/bash', 19:23:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QaT2SlaQra/Listeners', 19:23:53 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 19:23:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:53 INFO - 'USER': 'cltbld', 19:23:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:53 INFO - 'XPC_FLAGS': '0x0', 19:23:53 INFO - 'XPC_SERVICE_NAME': '0', 19:23:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:23:53 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-DHJ4a8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:23:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Gqb6Dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:23:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-QItjGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:23:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-2AweyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:23:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-MiMAjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:23:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-8DPfid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:23:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-hkqhMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:23:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-6n8m5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:23:54 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)) 19:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:23:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Sx8StB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:23:54 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)) 19:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:23:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-OhnbOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:23:54 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)) 19:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:23:54 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-8OtghJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:23:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-2mMwUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:23:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-sZjaO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:23:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Jm4eVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-7LhSn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:23:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-QpWJb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:23:55 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ZW_Yrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:23:55 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)) 19:23:55 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)) 19:23:55 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)) 19:23:55 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)) 19:23:55 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)) 19:23:55 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)) 19:23:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:23:55 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 19:23:55 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 19:23:55 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 19:23:55 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 19:23:55 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 19:23:55 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 19:23:58 INFO - Downloading blessings-1.6.tar.gz 19:23:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:23:58 INFO - Installing collected packages: blessings 19:23:58 INFO - Running setup.py install for blessings 19:23:58 INFO - Successfully installed blessings 19:23:58 INFO - Cleaning up... 19:23:58 INFO - Return code: 0 19:23:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:23:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:23:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:23:58 INFO - Reading from file tmpfile_stdout 19:23:58 INFO - Current package versions: 19:23:58 INFO - blessings == 1.6 19:23:58 INFO - blobuploader == 1.2.4 19:23:58 INFO - docopt == 0.6.1 19:23:58 INFO - manifestparser == 1.1 19:23:58 INFO - mozInstall == 1.12 19:23:58 INFO - mozcrash == 0.17 19:23:58 INFO - mozdebug == 0.1 19:23:58 INFO - mozdevice == 0.48 19:23:58 INFO - mozfile == 1.2 19:23:58 INFO - mozhttpd == 0.7 19:23:58 INFO - mozinfo == 0.9 19:23:58 INFO - mozleak == 0.1 19:23:58 INFO - mozlog == 3.2 19:23:58 INFO - moznetwork == 0.27 19:23:58 INFO - mozprocess == 0.22 19:23:58 INFO - mozprofile == 0.28 19:23:58 INFO - mozrunner == 6.11 19:23:58 INFO - mozscreenshot == 0.1 19:23:58 INFO - mozsystemmonitor == 0.3 19:23:58 INFO - moztest == 0.7 19:23:58 INFO - mozversion == 1.4 19:23:58 INFO - psutil == 3.1.1 19:23:58 INFO - requests == 1.2.3 19:23:58 INFO - wsgiref == 0.1.2 19:23:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:23:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:58 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 0x10b419a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b41f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc710c480c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2bdd30>, '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 0x10b0e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 19:23:58 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 19:23:58 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 19:23:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iw2ufHf1Nu/Render', 19:23:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:58 INFO - 'HOME': '/Users/cltbld', 19:23:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:58 INFO - 'LOGNAME': 'cltbld', 19:23:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:58 INFO - 'MOZ_NO_REMOTE': '1', 19:23:58 INFO - 'NO_EM_RESTART': '1', 19:23:58 INFO - 'PAGER': '/bin/cat', 19:23:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:58 INFO - 'PWD': '/builds/slave/test', 19:23:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:58 INFO - 'SHELL': '/bin/bash', 19:23:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QaT2SlaQra/Listeners', 19:23:58 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 19:23:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:58 INFO - 'USER': 'cltbld', 19:23:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:58 INFO - 'XPC_FLAGS': '0x0', 19:23:58 INFO - 'XPC_SERVICE_NAME': '0', 19:23:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:23:59 INFO - Cleaning up... 19:23:59 INFO - Return code: 0 19:23:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b419a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b41f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc710c480c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2bdd30>, '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 0x10b0e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 19:23:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:23:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:23:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iw2ufHf1Nu/Render', 19:23:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:59 INFO - 'HOME': '/Users/cltbld', 19:23:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:59 INFO - 'LOGNAME': 'cltbld', 19:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:59 INFO - 'MOZ_NO_REMOTE': '1', 19:23:59 INFO - 'NO_EM_RESTART': '1', 19:23:59 INFO - 'PAGER': '/bin/cat', 19:23:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:59 INFO - 'PWD': '/builds/slave/test', 19:23:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:59 INFO - 'SHELL': '/bin/bash', 19:23:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QaT2SlaQra/Listeners', 19:23:59 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 19:23:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:59 INFO - 'USER': 'cltbld', 19:23:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:59 INFO - 'XPC_FLAGS': '0x0', 19:23:59 INFO - 'XPC_SERVICE_NAME': '0', 19:23:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:59 INFO - Downloading/unpacking jsonschema 19:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:24:01 INFO - Downloading/unpacking functools32 (from jsonschema) 19:24:01 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 19:24:01 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 19:24:01 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 19:24:01 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 19:24:01 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 19:24:01 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 19:24:02 INFO - Downloading functools32-3.2.3-2.tar.gz 19:24:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:24:02 INFO - warning: no files found matching '*.txt' 19:24:02 INFO - no previously-included directories found matching 'build' 19:24:02 INFO - no previously-included directories found matching 'dist' 19:24:02 INFO - no previously-included directories found matching '.git*' 19:24:02 INFO - Installing collected packages: jsonschema, functools32 19:24:02 INFO - Running setup.py install for functools32 19:24:02 INFO - warning: no files found matching '*.txt' 19:24:02 INFO - no previously-included directories found matching 'build' 19:24:02 INFO - no previously-included directories found matching 'dist' 19:24:02 INFO - no previously-included directories found matching '.git*' 19:24:02 INFO - Successfully installed jsonschema functools32 19:24:02 INFO - Cleaning up... 19:24:02 INFO - Return code: 0 19:24:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b419a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b41f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc710c480c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2bdd30>, '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 0x10b0e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 19:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 19:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 19:24:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iw2ufHf1Nu/Render', 19:24:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:24:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:24:02 INFO - 'HOME': '/Users/cltbld', 19:24:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:24:02 INFO - 'LOGNAME': 'cltbld', 19:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:02 INFO - 'MOZ_NO_REMOTE': '1', 19:24:02 INFO - 'NO_EM_RESTART': '1', 19:24:02 INFO - 'PAGER': '/bin/cat', 19:24:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:02 INFO - 'PWD': '/builds/slave/test', 19:24:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:24:02 INFO - 'SHELL': '/bin/bash', 19:24:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QaT2SlaQra/Listeners', 19:24:02 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 19:24:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:24:02 INFO - 'USER': 'cltbld', 19:24:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:24:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:02 INFO - 'XPC_FLAGS': '0x0', 19:24:02 INFO - 'XPC_SERVICE_NAME': '0', 19:24:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:02 INFO - Downloading/unpacking flake8 19:24: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 19:24: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 19:24: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 19:24: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 19:24: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 19:24: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 19:24:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:24:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:24:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:24: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 19:24: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 19:24: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 19:24: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 19:24: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 19:24: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 19:24:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:24:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:24:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:24:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:24:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:24:06 INFO - no previously-included directories found matching 'docs/_build' 19:24:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:24:06 INFO - Running setup.py install for pep8 19:24:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:24:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:24:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:24:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:24:06 INFO - no previously-included directories found matching 'docs/_build' 19:24:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:24:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:24:06 INFO - Cleaning up... 19:24:06 INFO - Return code: 0 19:24:06 INFO - Running post-action listener: _resource_record_post_action 19:24:06 INFO - Running post-action listener: _start_resource_monitoring 19:24:06 INFO - Starting resource monitoring. 19:24:06 INFO - ##### 19:24:06 INFO - ##### Finished create-virtualenv step (success) 19:24:06 INFO - ##### 19:24:06 INFO - ##### 19:24:06 INFO - ##### Running install step. 19:24:06 INFO - ##### 19:24:06 INFO - Running pre-action listener: _resource_record_pre_action 19:24:06 INFO - Running main action method: install 19:24:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:24:07 INFO - Reading from file tmpfile_stdout 19:24:07 INFO - Detecting whether we're running mozinstall >=1.0... 19:24:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:24:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:24:07 INFO - Reading from file tmpfile_stdout 19:24:07 INFO - Output received: 19:24:07 INFO - Usage: mozinstall [options] installer 19:24:07 INFO - Options: 19:24:07 INFO - -h, --help show this help message and exit 19:24:07 INFO - -d DEST, --destination=DEST 19:24:07 INFO - Directory to install application into. [default: 19:24:07 INFO - "/builds/slave/test"] 19:24:07 INFO - --app=APP Application being installed. [default: firefox] 19:24:07 INFO - mkdir: /builds/slave/test/build/application 19:24:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:24:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:24:40 INFO - Reading from file tmpfile_stdout 19:24:40 INFO - Output received: 19:24:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:24:40 INFO - Running post-action listener: _resource_record_post_action 19:24:40 INFO - ##### 19:24:40 INFO - ##### Finished install step (success) 19:24:40 INFO - ##### 19:24:40 INFO - ##### 19:24:40 INFO - ##### Running run-tests step. 19:24:40 INFO - ##### 19:24:40 INFO - Running pre-action listener: _resource_record_pre_action 19:24:40 INFO - Running main action method: run_tests 19:24:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:24:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:24:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:24:40 INFO - 2016-05-19 19:24:40.809 screenresolution[1914:11084] starting screenresolution argv=screenresolution get 19:24:40 INFO - 2016-05-19 19:24:40.812 screenresolution[1914:11084] Display 0: 1600x1200x32@60 19:24:40 INFO - 2016-05-19 19:24:40.818 screenresolution[1915:11086] starting screenresolution argv=screenresolution list 19:24:40 INFO - Available Modes on Display 0 19:24:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:24:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:24:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:24:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:24:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:24:41 INFO - Intel Iris: 19:24:41 INFO - Chipset Model: Intel Iris 19:24:41 INFO - Type: GPU 19:24:41 INFO - Bus: Built-In 19:24:41 INFO - VRAM (Dynamic, Max): 1536 MB 19:24:41 INFO - Vendor: Intel (0x8086) 19:24:41 INFO - Device ID: 0x0a2e 19:24:41 INFO - Revision ID: 0x0009 19:24:41 INFO - Displays: 19:24:41 INFO - Display: 19:24:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:24:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:24:41 INFO - Display Serial Number: 1600x1200 60 19:24:41 INFO - Main Display: Yes 19:24:41 INFO - Mirror: Off 19:24:41 INFO - Online: Yes 19:24:41 INFO - Rotation: Supported 19:24:41 INFO - Return code: 0 19:24:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:24:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:24:41 INFO - Python 2.7.3 19:24:41 INFO - Return code: 0 19:24:41 INFO - grabbing minidump binary from tooltool 19:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:24:41 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2bdd30>, '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 0x10b0e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:24:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:24:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:24:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:24:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:24:41 INFO - Return code: 0 19:24:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:24:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:24:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:24:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:24:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:24:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:24:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:24:42 INFO - Return code: 0 19:24:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 19:24:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0246 --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 19:24:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iw2ufHf1Nu/Render', 19:24:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:24:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:24:42 INFO - 'HOME': '/Users/cltbld', 19:24:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:24:42 INFO - 'LOGNAME': 'cltbld', 19:24:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:24:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:42 INFO - 'MOZ_NO_REMOTE': '1', 19:24:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:24:42 INFO - 'NO_EM_RESTART': '1', 19:24:42 INFO - 'PAGER': '/bin/cat', 19:24:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:42 INFO - 'PWD': '/builds/slave/test', 19:24:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:24:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:24:42 INFO - 'SHELL': '/bin/bash', 19:24:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QaT2SlaQra/Listeners', 19:24:42 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 19:24:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:24:42 INFO - 'USER': 'cltbld', 19:24:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:24:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:42 INFO - 'XPC_FLAGS': '0x0', 19:24:42 INFO - 'XPC_SERVICE_NAME': '0', 19:24:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:24:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 19:24:42 INFO - mozversion application_buildid: 20160519173313 19:24:42 INFO - mozversion application_changeset: 2013e1255bf82c59a3d90ebc69895f62e9a180f7 19:24:42 INFO - mozversion application_display_name: Nightly 19:24:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:24:42 INFO - mozversion application_name: Firefox 19:24:42 INFO - mozversion application_remotingname: firefox 19:24:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:24:42 INFO - mozversion application_vendor: Mozilla 19:24:42 INFO - mozversion application_version: 49.0a1 19:24:42 INFO - mozversion platform_buildid: 20160519173313 19:24:42 INFO - mozversion platform_changeset: 2013e1255bf82c59a3d90ebc69895f62e9a180f7 19:24:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:24:42 INFO - mozversion platform_version: 49.0a1 19:24:42 INFO - using testdate: 1463711082 19:24:42 INFO - actual date: 1463711082 19:24:42 INFO - starting webserver on 'localhost:49474' 19:24:42 INFO - SUITE-START | Running 2 tests 19:24:42 INFO - TEST-START | dromaeo_css 19:24:42 INFO - operating with platform_type : mac_ 19:24:42 INFO - Initialising browser for dromaeo_css test... 19:24:42 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpDiFbM3/profile http://localhost:49474/getInfo.html) 19:24:44 INFO - PROCESS | 1933 | __metrics Screen width/height:1600/1200 19:24:44 INFO - PROCESS | 1933 | colorDepth:24 19:24:44 INFO - PROCESS | 1933 | Browser inner width/height: 1024/689 19:24:44 INFO - PROCESS | 1933 | __metrics 19:24:44 INFO - PROCESS | 1933 | 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" 19:24:44 ERROR - PROCESS | 1933 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:24:45 INFO - TEST-INFO | 1933: exit 0 19:24:45 INFO - Browser initialized. 19:24:45 INFO - Running cycle 1/1 for dromaeo_css test... 19:24:45 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpDiFbM3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:24:57 INFO - PROCESS | 1935 | RSS: Main: 238891008 19:24:57 INFO - PROCESS | 1935 | 19:25:48 INFO - PROCESS | 1935 | 1463711148414 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] 19:27:59 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49474/tests/dromaeo/cssquery-ext.html) 19:28:01 INFO - PROCESS | 1935 | RSS: Main: 342466560 19:28:01 INFO - PROCESS | 1935 | 19:31:04 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-ext.html (next: http://localhost:49474/tests/dromaeo/cssquery-jquery.html) 19:31:04 INFO - PROCESS | 1935 | RSS: Main: 375939072 19:31:04 INFO - PROCESS | 1935 | 19:34:06 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49474/tests/dromaeo/cssquery-mootools.html) 19:34:07 INFO - PROCESS | 1935 | RSS: Main: 367894528 19:34:07 INFO - PROCESS | 1935 | 19:37:09 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49474/tests/dromaeo/cssquery-prototype.html) 19:37:10 INFO - PROCESS | 1935 | RSS: Main: 368791552 19:37:10 INFO - PROCESS | 1935 | 19:40:13 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49474/tests/dromaeo/cssquery-yui.html) 19:40:14 INFO - PROCESS | 1935 | RSS: Main: 335052800 19:40:14 INFO - PROCESS | 1935 | 19:43:17 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49474/tests/dromaeo/cssquery-yui.html (next: http://localhost:49474/tests/dromaeo/cssquery-dojo.html) 19:43:17 INFO - PROCESS | 1935 | RSS: Main: 381054976 19:43:17 INFO - PROCESS | 1935 | 19:43:17 INFO - PROCESS | 1935 | __start_tp_report 19:43:17 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 19:43:17 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 19:43:17 INFO - PROCESS | 1935 | |i|pagename|runs| 19:43:17 INFO - PROCESS | 1935 | |0;dojo.html;3407.574053243344;3419.281950790333;3426.537417448645;3470.028392050227;3506.964930350697;5576.972115139423;5579.079451890433;5643.153526970947;5662.943370566299;5703.144528320749;5626.887462250753;5657.660540367581;5697.857553561163;5743.167240750089;5774.5091667208335;5052.040112378655;5057.519535644108;5122.795088196468;5133.870548479328;5191.610629202806;5214.973925130369;5228.973855130738;5241.554467870235;5256.500632439931;5307.548858347045;5062.190049592047;5114.846554603368;5129.358830146231;5147.124988751922;5168.508991645787;5053.418856831475;5147.6911385317;5150.995555866608;5152.278680984665;5168.147255702842;5065.46812584677;5072.6195535335;5094.6688465249645;5154.226865970097;5176.922346164811;5538.280023596947;5665.121906104519;5666.9716651416475;5670.262865827457;5678.914816277759;6080.695965201722;6182.96908515459;6209.16176316196;6209.441150296495;6214.627122372672;6108.949222962717;6135.814547850547;6195.659238741868;6209;6216.067589861512;5798.391168927202;5830.067189249699;5837.241158649434;5837.50381217597;5848.093545500412;6090.451859332681;6108.259146143328;6115.848865095124;6156;6178.073289006685;6131.9269127903335;6136.631802091889;6141.263048434127;6147.739713358751;6148.831721972811;6187.721552530147;6225.28409232935;6226.782062627786;6233.407826256498;6243.032329988859;5871.882562348815;5919.792807251725;6088.726007329681;6150.446459818549;6187.721552530147;6150.523874270143;6181.134641150243;6187.907181392283;6198.690065496708;6214.4407003369915;4906.067847109038;4907.55831975124;4907.803687852517;4909.7790599423115;4927.728974906415;4370.825166993348;4377.387165796791;4403.515613282536;4427.2252355838245;4444.644428445717;4751.263554149113;5658.066419040894;5662.68855212959;5676.4323567642905;5687.914681279784;6199.3490683477585;6221.657808820468;6254.781082662176;6255.74977000924;6267.185265915402;5551.2228288039705;5590.776368945278;5603.915941260884;5640.210370548127;5643.209950606918;5313.787448502017;5352.812651557177;5354.812581559627;5361.51746342831;5395.946040539623;5740.880528296942;6188.690565471798;6194.008958566607;6210.285817130997;6214;6110.205673262625;6139.324674285915;6159.106929495288;6180.412860778219;6201.782937597162;6172.907406388818;6185.969070154621;6192.721327540181;6196.256449225942;6196.752129914753;6063.333033366414;6098.26820781509;6099.573029888043;6133.84665383369;6136.110264011782;5990.760369585342;6044.637321760709;6116.579253789288;6189.1954045975535;6200.3179650239335;5989.131575921554;6034.818955431344;6049.455549000611;6053;6111.84720381994;5285.577153827761;5889.263842019747;6030.005049166838;6059.848503787441;6068.51451883857;5804.245448091721;5874.148248504029;5888.587798854039;5898.528117750656;5911.261092363455;6104.532665215114;6126.426068710427;6130.1878025749575;6130.52043589939;6138.996717675081;5805.332386775575;5819.79630712923;5824.417558244142;5980.641161530322;6031.939680603138 19:43:17 INFO - PROCESS | 1935 | |1;ext.html;485.04204196711487;485.5581420906975;490.9926351104732;502.63056653359797;509.31242822190035;31456.52815207774;42173.78913105434;43323.78338108305;43356.78321608395;43376.132477350475;44661.7766911165;45272.77363613177;45278.773606132;45358.86602834931;45474;8659.04750477447;9427.340086193952;9666.371685840433;9673.177779888718;9710.36882602632;11430.42847857608;11741.4716337765;11753;11972.401379931012;11973.880261197364;11490.482928268206;11533.365664888428;11533.884661153365;11588.420578971018;11608.30350178992;8602.795608614799;11504.76990460186;11633.185677002613;11691.883081169164;11758.470868810924;11702.590409335633;11798.997085247678;11819.704507387298;11820.349880756556;11872.228305160223;40831;41401.75794726163;41502.79248603768;41772.58227417717;41793.582064179274;3122.5784519089857;3181.109289398972;3200.9039728808175;3219.17910932713;3440.0367896988882;28408.573871391945;29974.85012574923;29986.55020174699;30088.398232035455;30425.847870760503;2661.5342315094977;2748.848813315267;2765.5436852919242;2797.510435673749;2833.7874659400627;372467;374428.2557174421;517339.41330293484;517543;518443;34392;35395.64604353924;60438;62097.72169365564;62357;32538.34923301568;35204.823975879954;36260;36416.27167456689;36447.088822779115;14605.78091328631;14864.554063378117;15102.697946041018;15132.773008404884;15166.31751571182;11437.885621143849;11675.007624351856;12249.265044097383;12406.13157079014;12535.686607834878;7777.844443111106;7974.4417890748255;7980.720674776471;8036.4374493786045;8046.436749427484;2839.5030869597736;2900.9564856527168;3248.6101667800017;3280.8851690190727;3281.6718328166994;859.3984211052164;983.0808194338316;988.5057471264367;995.1392045880406;1044.9968030690623;29287.414251715272;29353.412931741674;29681;30082.247943801143;30215.199785890534;1025.5897640943683;1041.6979076068008;1062.2351906627216;1102.691246450995;1120.93834839083;13318.667033324244;15049.871259655569;15192.696146077016;15292.847071529364;15568.922155389377;691031;695141;699361.5031924808;699875;703718;60390.09414858781;60946.0858087129;61525.69237153875;61686;61712.38287617156;1285.3637449462576;1353.749556332062;1409.9013069085065;1454.7817827325987;1455.046944251517;28375;29957.70042299549;30184.698153019068;30380.0505934236;31249.687503125588;51826;51880;52491;52518;52563.737181313845;563698;564293;564497;565345;565605;22907.31278061661;25259.368515787253;25948;26259.737402626495;27045.45909081768;44632.77683611561;45832.541674582826;46079.76960115178;46238.306425402974;47185.52814471949;31355.686443135277;32120.839395802872;36211;37273;37574.87275381743;31786.36427271488;35258.647413525534;36931.26137477289;37699.05752356213;37853.81073094617;36281.81859090688;36880.07799805026;37085.62914370822;37626.05934851651;38434.42348364721;32454.513182302755;33211.16972075621;33860.830695846365;34221.48667769936;34748;24118.03527858866;24628.630570541816;25125;25263.621045684697;25342.239732808044 19:43:17 INFO - PROCESS | 1935 | |2;jquery.html;383.65854389593267;383.9328117579423;391.23513531046825;404.15934855500564;411.50824764406525;19160.616787664258;20506.69239961398;20802.375928722144;20902.790972090275;21491.92540372979;20244;20474.590508189827;20488.078036488358;20839.47901302468;21483.355499335004;5170.53465188133;5747.166660834187;5789.768409263625;5946.078357854518;5962.642241465526;5743.4543718346695;5833.3874943130995;5889.734961926702;5942.346341902387;5943.851403714899;5671.7731290748325;5802.477777000069;5830.3586605473365;5833.037548804464;5851.8537036574;5722.971385143048;5787.04513755232;5824.504917082053;5833.737481813286;5839.737211825478;4947.406311242638;5070.974645126787;5111.744412779346;5111.897762044776;5122.462141475166;24684.25947221586;25111.748882511125;25529.361765956;25694.614580781305;25930.962761489518;1891.4798430431604;2116.65075262583;2188.5841690078832;2230;2657.906973255946;9686.854697179548;9849.359791613522;10003.549840257134;10060.39637621745;10274.178065754722;1693.254967814158;1716.1333526569003;1730.3424698614572;1737.1401156427523;1778.6531626332871;89708.20583588292;115659;116484.41757791156;122890.38554807169;132850.3357483216;9805.607775689035;10950.809838032266;11355.94322028401;11480.483378247998;11571.247868888495;14373.928130359282;14495.20276384809;14571.271436428136;14574.635634109232;14590.56228313131;7455.701771929171;7920.485168464032;7920.5247685139075;8204.794880128045;8482.703105391405;8502.234798868132;8525.360597955054;8588.355873309401;8615.22462978335;8724.69463568772;4447.066116115588;4525.330055267342;4563.06457176278;4567.771611419415;4595.770211489412;1591.5304985029445;1757.7714897063556;1819.2813838533705;1944.656688690125;1969.8621096523393;879.784452809053;928.865314529403;934.6541779541477;939.056248470283;1011.8482227665909;19233.8076619232;20288.695669564968;20403.795962040487;20619.07214175366;20645.89677051605;943.990560094404;956.8947415784259;989.4162444157981;1020.3316827478013;1036.124474818777;9693.757656058513;10257.74355641115;11019.669409917715;11022.283551569122;11023.72440688973;413278;559570;603522;606803;608395.9580202071;111012.4449377748;128349.35825320813;147560.26219868832;148344;150387;940.8353538130937;994.9701508954743;1057.1014637558014;1071.9464026798628;1072.871255449351;13973.650658733206;17101.57246068858;18610.255589776258;19306.227750889808;19354.838709677286;52855.735721322686;54251;54609;55102.72448637731;55766;59159.704201478715;81288.78066828883;81842.59078704569;82116.58941705493;83227.5838620803;7744.380449563909;8024.358051355995;8097.554634495159;8140.83718325642;8394.328453723809;9208.12522810332;11744.88255117472;12544.184627999151;12742.554010609585;12755.362231888554;10594.47027648599;10622.415767132881;10839.945800270949;11066.446677666245;11246.718832028937;8848.247898928406;10760.085392741705;10871.347719136877;10889;10943.945280273547;9132.680356187502;11080.279781814157;11137.164712646745;11293.322400655987;11328.773424531628;8000.5599692017495;8089.8382032360205;8184.2224988625985;8193.672253110048;8540.14598540141;10862.837057444303;10930.01629853317;10947.507362168562;11183.217174797686;11297.604583839526 19:43:17 INFO - PROCESS | 1935 | |3;mootools.html;1457.031074335567;1615.9192040397984;1644.6875093732192;1917.731517587538;2042.8161465468115;7762.844743105142;8675.566221688921;8922.598483068261;8932.821343573132;9048.321375896801;8610.52642104684;8717.520536370497;8758.562071896396;8847.646094156225;8852.981907080672;2083.312506872736;2222.8107962240206;2292.2321022457495;2338.8830558472096;2378.0963233971065;2235.854669446489;2384.95230095398;2387.510560335127;2387.9761202388013;2390.796782273509;2308.3075077476774;2350.9882450587725;2351.071326825903;2352.8353015288935;2355.3993731598416;2045.2125931516398;2253.5605556916407;2297.8046866016407;2326.174208156114;2333.9649905251435;2327.301809457156;2351.7413084560685;2353.188150088234;2353.694019777435;2355.4582446037334;8661.30709543235;8697.652093916236;8745.912540874573;8766.824663506757;8806.515641639708;5148.459411761749;5218.269442278046;5323.600729945269;5323.813666521692;5341.412444631087;3281.786683865541;3337.516060171286;3502.7548071634997;3512.1570823002553;3536.4341705327283;1003.7540802503361;1004.9045340692695;1005.0552480668149;1010.6664800615779;1021.8927012663712;11873.881261187364;11898.881011189864;11900.464479098462;11943.283402995849;11948.044156467462;4586.656000799953;4741.478437371887;4746.169420351424;4791.808327666924;4816.34979277793;9745.512724363753;9925.702228933143;9994.900050999542;9998.100170984646;10018;4700.918788678555;5296.417394086648;5344.946550534522;5352.2506849041165;5375.381831089397;4350.825966961286;4525.841595544206;4749.667523273407;4890.755462226874;4901.681390709592;3883.786391748481;3943.6056394360908;3983.7410568312966;4025.134596061861;4064.695147863922;1190.9047276217882;1273.0961017677553;1286.9742605147844;1296.604535616646;1305.8367704036996;792.2513225002433;898.5552151684958;920.6041402196986;938.9154976052051;953;5505.752241149158;5698.6580805151;5809;5894.705264736746;5914.964881145782;428.97855107244516;436.0907507846176;442.3055802390281;443.7071532788344;456.0946521155504;3835.175437280998;3837.194189220295;3842.3275926712713;3855.7300988930506;3928.273269445162;10459.267851250492;10757.623483178051;10764;10877.292975956538;10902.454877256265;9424.246060315158;10451.268411211295;10565.9471702641;10570.101541368917;10820.945895270474;7884.64519096642;8078.798030049178;8258.298044666151;8290.46112002718;8295.626696798538;5579.776808927598;5683.8010669626365;5709.7494120996425;5746.9712651436475;5805.564582656233;9853.310268281151;9902.207823373968;9908.653197138066;10150.949245254022;10172.898271017195;9768.413895166314;10083.798324033332;10190.235732319958;10255.128314093387;10294.742631434274;6833.214180369321;7067.964660176666;7294.562326260441;7504.324610784837;7603.695852165852;7486.812829679302;8303.750887473385;8460.450070745383;8505.574721264036;8523.105073967145;6266.498680105672;7476.289752473506;7500.887486687813;7575.015248017943;7643.273888980538;6984.301569842975;7177.7487787927175;7182.748603798842;7283.490155689263;7435;6787.253402125861;6824.829379265359;6912.827179320558;6913.0667359905765;6914.308569143045;4661.417322834645;4732.597729193058;4791.95208047929;4853.0779151961015;4958.900821983468;4762.571368576845;4763.54746299101;4796;4821.831235906727;4894.95105048945 19:43:17 INFO - PROCESS | 1935 | |4;prototype.html;300.1146617478438;303.97872148949574;317.4365501235307;321.25148404218174;322.922498600336;4614.469336026358;4818.27725841124;5288.603354748395;5366.651167674098;5366.865828354293;5026.8384594624295;5214;5223.555997740187;5223.876866473707;5235.502627250415;3986.1230529283657;4004.899877503057;4011.638952494275;4020.6582440492593;4037.656799172074;3403.319336132771;3781.5819067849557;3876.3991581304945;4041.595250825754;4041.6766658667234;3800.9547374471963;4007.338789099795;4022.939655905164;4030.576789437111;4073.63337299643;3265.918352041206;3882.8446862125766;3978.8607398740905;4022.4915656628764;4034.3141665916864;3211.2614098757185;3374.358871814372;3484.61669216386;3498.6326435724136;3506.2987402519598;4756.025014871978;5075.796968121271;5078.974605126988;5139.537441630234;5219.5041471060185;3943.881683549498;4032.5765794591734;4341.413909122291;4486.343629941587;4523.5024147343765;4724.283536966258;5513.476219759116;5551.83545379134;5570;5577.6852606168395;3614.6023937366867;3775.9811200944096;3778.4521244419407;3780.716446266541;3800.467934489146;5282.286891269668;5631.746571404296;5684.777772778831;5737.368889422202;5742.425757424266;3350.447176215946;3830.75100118497;3838.7504812187117;3841.9231615367535;3846.1730727893078;3424.417848965632;3466.5493485846678;3693.90765230866;3849.749766265184;3889.474920885644;3861.38217885137;4446.599806017474;4464.441944756905;4477.007731559424;4505.88735281614;4874.5856602189215;5084.542391184811;5533.806316778893;5553.861153471195;5559.305086864142;1167.96496105117;1185.3717529709268;1244.595506460404;1264.5647190438735;1341.9865801341862;787.5117427195175;803.3252068262688;817;822.3010441125116;822.5722624235484;4277.465316835395;4365.80353884076;4424.80088396023;4435.844745433894;4472.306792447176;4056.8782936511952;4584.4498660161;4821.614270858323;4886.705023168863;5132.999065182254;4114.362273847559;4313.741175529478;4322.416473776063;4328.632066274339;4396.406120388986;2187.8687278763327;2409.7590240976115;2439.9756002440104;2440.7437219092094;2565.3586603349163;4231.830726770956;4504.707194032378;4915.729634870045;4945.975270123627;4978.352814134212;4292.260416204254;4529.071540334224;4578.946842226306;4880.536349046835;4941.777620007108;4211;4890.33980412654;4921.966387058757;4942.9752851236235;4949.208126699711;4072.450219220367;4287.0847823480335;4325.983393902493;4708.0461608432115;4727.57451829337;4223.676977662212;4313.799962019455;4390.560943905712;4729.753367150576;4762.0713610987605;4293.638498825571;4350.238708225985;4521.91991683816;4695.929561056655;4698.707855339809;2859.5281778506314;3056.930074473916;3164.2089477630593;3302.7522935779434;3323.9833800830843;4429.911401772011;4435.379046933367;4694.6009589185305;4746.78639461232;4824.638152138674;4087.325591277406;4148.315527937977;4601.884952876205;4613.031263434582;4714.878304762939;4306.397104405323;4420.801063952065;4436.844710435119;4600.217962946374;4612.285095810154;3779.263043706451;4196.985446985525;4557.769402261437;4566;5305.585958389001;5501.367342755634;5530.096699232935;5545.390007099297;5558.3607885092115;5578.246936663661;4573.382593349856;4837.879053023703;5375.838724838261;5447.53695935834;5447.9025575153055 19:43:17 INFO - PROCESS | 1935 | |5;yui.html;679.7858674517523;688.3604505632042;717.1072015340901;748.0686545251164;759.813845607826;14196.219207943572;14199.006069575122;14223.644408889757;14251.643708907282;14825.703485930275;14031.64920876976;14057.648558786035;14253.572392828206;14357.138571685706;14405.711885762279;708.0865683268578;727.952683075601;768.7501561992361;921.9539023048854;923.852183650616;890.688259109313;911.1025639744846;913.9771505712378;920.4293338130335;921.7004473546091;925.4956048953337;935.223764275653;954.3462728031286;963.6049219819876;964.4502633498909;735.1325435985535;930.5765876526165;955.6655170690273;956.2971216156154;959.0888655777005;722.2055738687454;724.2576359231776;724.4385601159144;726.8255618651539;727.0148948175199;13055.281959492224;14389.352479138464;14948.028378155384;14986;15011.774823377658;12084.274943503418;12165.33090680021;12278.324692141929;12282.38588070593;12415.75168496625;10735.946320268427;10843.349399036084;10936;11275.548978040868;11283.266587671775;3951.6245956634075;3990.1345022816704;4011.2980228460196;4038.414429907676;4174.3947127666315;78603.60698196529;79921;80500.59749701273;80653.14448271904;80713.59643201747;9284.396514226553;9356.298277629205;9392.483413412328;9395.436273823592;9595.712128636153;19730.30943916956;20485;21570.892145539172;21627.459313517287;21671.349859503924;7420.072490938633;7442.367398771057;7502;7562.924370756332;7580.658870350848;3979.303621866162;4029.6977726670616;4065.573114822901;4110.856120035784;4330.935035974463;3261.853216605211;3403.0981789825223;3495.632958539317;3497.4753786932165;3500.7724497907557;635.8346829824279;642.2678146912524;643.2024289880515;656.7931101702949;662.9569078009914;533.8451848963834;558.4387690371151;598.2970010237952;601.2634522709733;612.4487960835195;7682.7311044113185;7975.7607271781935;8069.471346555073;8182.22268884455;8284.10619116409;449.934759459883;565.7652074389082;569.5699746691222;604.7369394313488;606.5178183344163;3747.8500859965297;3841.7118716096406;3846.653801157909;3868.7872167031082;3915.197384536163;83988.58005709933;87099;87282;91572;92491;55882.44117558772;65490;74437;79666.60166699246;82150.99772063832;67501;68453;69153;69801.41406708262;71994.28005719981;7913.9604301978125;8067.193280671886;8074.636641351036;8134.83730325402;8213.95893020531;30954.53568196524;41021.794891025354;41439.37840932328;41888.58111418847;42095.1580968385;56430.7178464105;57277;59213;59244.70377648084;59792.70103649454;11417.771644567229;11557.942210288895;11578.652640420802;11842;12091.516339346328;9745.610175592898;11748.765024699629;11967.520299812617;11999.220050696676;12014;11234.887651123385;11357.37534435614;11359.886401136213;11426.94286528562;11676.591319303783;11157.051650609465;11317.038051765692;11331.433428328715;11371.260868043551;11376.203665743318;10049.397036177561;10080.243981701255;10116.19070474375;10274.640387586398;10369.896301036893;9402.15380615748;9487.66793162236;9552.188064014637;9564.808703825745;9645.71062868115;7672.5012874163;9372.109649583279;9373.625054997723;9418.4819834907;9559.761005974628 19:43:17 INFO - PROCESS | 1935 | __end_tp_report 19:43:17 INFO - PROCESS | 1935 | __start_cc_report 19:43:17 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,1196 19:43:17 INFO - PROCESS | 1935 | __end_cc_report 19:43:17 INFO - PROCESS | 1935 | __startTimestamp1463712197300__endTimestamp 19:43:17 INFO - PROCESS | 1935 | 19:43:17 INFO - PROCESS | 1935 | ------- Summary: start ------- 19:43:17 INFO - PROCESS | 1935 | Number of tests: 6 19:43:17 INFO - PROCESS | 1935 | 19:43:17 INFO - PROCESS | 1935 | [#0] dojo.html Cycles:165 Average:5683.27 Median:5874.15 stddev:618.17 (10.5%) stddev-sans-first:593.73 19:43:17 INFO - PROCESS | 1935 | Values: 3407.6 3419.3 3426.5 3470.0 3507.0 5577.0 5579.1 5643.2 5662.9 5703.1 5626.9 5657.7 5697.9 5743.2 5774.5 5052.0 5057.5 5122.8 5133.9 5191.6 5215.0 5229.0 5241.6 5256.5 5307.5 5062.2 5114.8 5129.4 5147.1 5168.5 5053.4 5147.7 5151.0 5152.3 5168.1 5065.5 5072.6 5094.7 5154.2 5176.9 5538.3 5665.1 5667.0 5670.3 5678.9 6080.7 6183.0 6209.2 6209.4 6214.6 6108.9 6135.8 6195.7 6209.0 6216.1 5798.4 5830.1 5837.2 5837.5 5848.1 6090.5 6108.3 6115.8 6156.0 6178.1 6131.9 6136.6 6141.3 6147.7 6148.8 6187.7 6225.3 6226.8 6233.4 6243.0 5871.9 5919.8 6088.7 6150.4 6187.7 6150.5 6181.1 6187.9 6198.7 6214.4 4906.1 4907.6 4907.8 4909.8 4927.7 4370.8 4377.4 4403.5 4427.2 4444.6 4751.3 5658.1 5662.7 5676.4 5687.9 6199.3 6221.7 6254.8 6255.7 6267.2 5551.2 5590.8 5603.9 5640.2 5643.2 5313.8 5352.8 5354.8 5361.5 5395.9 5740.9 6188.7 6194.0 6210.3 6214.0 6110.2 6139.3 6159.1 6180.4 6201.8 6172.9 6186.0 6192.7 6196.3 6196.8 6063.3 6098.3 6099.6 6133.8 6136.1 5990.8 6044.6 6116.6 6189.2 6200.3 5989.1 6034.8 6049.5 6053.0 6111.8 5285.6 5889.3 6030.0 6059.8 6068.5 5804.2 5874.1 5888.6 5898.5 5911.3 6104.5 6126.4 6130.2 6130.5 6139.0 5805.3 5819.8 5824.4 5980.6 6031.9 19:43:17 INFO - PROCESS | 1935 | 19:43:17 INFO - PROCESS | 1935 | [#1] ext.html Cycles:180 Average:69332.52 Median:33536.00 stddev:156587.63 (466.9%) stddev-sans-first:156941.58 19:43:17 INFO - PROCESS | 1935 | Values: 485.0 485.6 491.0 502.6 509.3 31456.5 42173.8 43323.8 43356.8 43376.1 44661.8 45272.8 45278.8 45358.9 45474.0 8659.0 9427.3 9666.4 9673.2 9710.4 11430.4 11741.5 11753.0 11972.4 11973.9 11490.5 11533.4 11533.9 11588.4 11608.3 8602.8 11504.8 11633.2 11691.9 11758.5 11702.6 11799.0 11819.7 11820.3 11872.2 40831.0 41401.8 41502.8 41772.6 41793.6 3122.6 3181.1 3200.9 3219.2 3440.0 28408.6 29974.9 29986.6 30088.4 30425.8 2661.5 2748.8 2765.5 2797.5 2833.8 372467.0 374428.3 517339.4 517543.0 518443.0 34392.0 35395.6 60438.0 62097.7 62357.0 32538.3 35204.8 36260.0 36416.3 36447.1 14605.8 14864.6 15102.7 15132.8 15166.3 11437.9 11675.0 12249.3 12406.1 12535.7 7777.8 7974.4 7980.7 8036.4 8046.4 2839.5 2901.0 3248.6 3280.9 3281.7 859.4 983.1 988.5 995.1 1045.0 29287.4 29353.4 29681.0 30082.2 30215.2 1025.6 1041.7 1062.2 1102.7 1120.9 13318.7 15049.9 15192.7 15292.8 15568.9 691031.0 695141.0 699361.5 699875.0 703718.0 60390.1 60946.1 61525.7 61686.0 61712.4 1285.4 1353.7 1409.9 1454.8 1455.0 28375.0 29957.7 30184.7 30380.1 31249.7 51826.0 51880.0 52491.0 52518.0 52563.7 563698.0 564293.0 564497.0 565345.0 565605.0 22907.3 25259.4 25948.0 26259.7 27045.5 44632.8 45832.5 46079.8 46238.3 47185.5 31355.7 32120.8 36211.0 37273.0 37574.9 31786.4 35258.6 36931.3 37699.1 37853.8 36281.8 36880.1 37085.6 37626.1 38434.4 32454.5 33211.2 33860.8 34221.5 34748.0 24118.0 24628.6 25125.0 25263.6 25342.2 19:43:17 INFO - PROCESS | 1935 | 19:43:17 INFO - PROCESS | 1935 | [#2] jquery.html Cycles:180 Average:33985.59 Median:25812.79 stddev:94724.09 (367.0%) stddev-sans-first:94956.21 19:43:17 INFO - PROCESS | 1935 | Values: 383.7 383.9 391.2 404.2 411.5 19160.6 20506.7 20802.4 20902.8 21491.9 20244.0 20474.6 20488.1 20839.5 21483.4 5170.5 5747.2 5789.8 5946.1 5962.6 5743.5 5833.4 5889.7 5942.3 5943.9 5671.8 5802.5 5830.4 5833.0 5851.9 5723.0 5787.0 5824.5 5833.7 5839.7 4947.4 5071.0 5111.7 5111.9 5122.5 24684.3 25111.7 25529.4 25694.6 25931.0 1891.5 2116.7 2188.6 2230.0 2657.9 9686.9 9849.4 10003.5 10060.4 10274.2 1693.3 1716.1 1730.3 1737.1 1778.7 89708.2 115659.0 116484.4 122890.4 132850.3 9805.6 10950.8 11355.9 11480.5 11571.2 14373.9 14495.2 14571.3 14574.6 14590.6 7455.7 7920.5 7920.5 8204.8 8482.7 8502.2 8525.4 8588.4 8615.2 8724.7 4447.1 4525.3 4563.1 4567.8 4595.8 1591.5 1757.8 1819.3 1944.7 1969.9 879.8 928.9 934.7 939.1 1011.8 19233.8 20288.7 20403.8 20619.1 20645.9 944.0 956.9 989.4 1020.3 1036.1 9693.8 10257.7 11019.7 11022.3 11023.7 413278.0 559570.0 603522.0 606803.0 608396.0 111012.4 128349.4 147560.3 148344.0 150387.0 940.8 995.0 1057.1 1071.9 1072.9 13973.7 17101.6 18610.3 19306.2 19354.8 52855.7 54251.0 54609.0 55102.7 55766.0 59159.7 81288.8 81842.6 82116.6 83227.6 7744.4 8024.4 8097.6 8140.8 8394.3 9208.1 11744.9 12544.2 12742.6 12755.4 10594.5 10622.4 10839.9 11066.4 11246.7 8848.2 10760.1 10871.3 10889.0 10943.9 9132.7 11080.3 11137.2 11293.3 11328.8 8000.6 8089.8 8184.2 8193.7 8540.1 10862.8 10930.0 10947.5 11183.2 11297.6 19:43:17 INFO - PROCESS | 1935 | 19:43:17 INFO - PROCESS | 1935 | [#3] mootools.html Cycles:180 Average:5652.15 Median:4763.06 stddev:3226.08 (67.7%) stddev-sans-first:3219.73 19:43:17 INFO - PROCESS | 1935 | Values: 1457.0 1615.9 1644.7 1917.7 2042.8 7762.8 8675.6 8922.6 8932.8 9048.3 8610.5 8717.5 8758.6 8847.6 8853.0 2083.3 2222.8 2292.2 2338.9 2378.1 2235.9 2385.0 2387.5 2388.0 2390.8 2308.3 2351.0 2351.1 2352.8 2355.4 2045.2 2253.6 2297.8 2326.2 2334.0 2327.3 2351.7 2353.2 2353.7 2355.5 8661.3 8697.7 8745.9 8766.8 8806.5 5148.5 5218.3 5323.6 5323.8 5341.4 3281.8 3337.5 3502.8 3512.2 3536.4 1003.8 1004.9 1005.1 1010.7 1021.9 11873.9 11898.9 11900.5 11943.3 11948.0 4586.7 4741.5 4746.2 4791.8 4816.3 9745.5 9925.7 9994.9 9998.1 10018.0 4700.9 5296.4 5344.9 5352.3 5375.4 4350.8 4525.8 4749.7 4890.8 4901.7 3883.8 3943.6 3983.7 4025.1 4064.7 1190.9 1273.1 1287.0 1296.6 1305.8 792.3 898.6 920.6 938.9 953.0 5505.8 5698.7 5809.0 5894.7 5915.0 429.0 436.1 442.3 443.7 456.1 3835.2 3837.2 3842.3 3855.7 3928.3 10459.3 10757.6 10764.0 10877.3 10902.5 9424.2 10451.3 10565.9 10570.1 10820.9 7884.6 8078.8 8258.3 8290.5 8295.6 5579.8 5683.8 5709.7 5747.0 5805.6 9853.3 9902.2 9908.7 10150.9 10172.9 9768.4 10083.8 10190.2 10255.1 10294.7 6833.2 7068.0 7294.6 7504.3 7603.7 7486.8 8303.8 8460.5 8505.6 8523.1 6266.5 7476.3 7500.9 7575.0 7643.3 6984.3 7177.7 7182.7 7283.5 7435.0 6787.3 6824.8 6912.8 6913.1 6914.3 4661.4 4732.6 4792.0 4853.1 4958.9 4762.6 4763.5 4796.0 4821.8 4895.0 19:43:17 INFO - PROCESS | 1935 | 19:43:17 INFO - PROCESS | 1935 | [#4] prototype.html Cycles:180 Average:4112.98 Median:4432.65 stddev:1233.03 (27.8%) stddev-sans-first:1202.82 19:43:17 INFO - PROCESS | 1935 | Values: 300.1 304.0 317.4 321.3 322.9 4614.5 4818.3 5288.6 5366.7 5366.9 5026.8 5214.0 5223.6 5223.9 5235.5 3986.1 4004.9 4011.6 4020.7 4037.7 3403.3 3781.6 3876.4 4041.6 4041.7 3801.0 4007.3 4022.9 4030.6 4073.6 3265.9 3882.8 3978.9 4022.5 4034.3 3211.3 3374.4 3484.6 3498.6 3506.3 4756.0 5075.8 5079.0 5139.5 5219.5 3943.9 4032.6 4341.4 4486.3 4523.5 4724.3 5513.5 5551.8 5570.0 5577.7 3614.6 3776.0 3778.5 3780.7 3800.5 5282.3 5631.7 5684.8 5737.4 5742.4 3350.4 3830.8 3838.8 3841.9 3846.2 3424.4 3466.5 3693.9 3849.7 3889.5 3861.4 4446.6 4464.4 4477.0 4505.9 4874.6 5084.5 5533.8 5553.9 5559.3 1168.0 1185.4 1244.6 1264.6 1342.0 787.5 803.3 817.0 822.3 822.6 4277.5 4365.8 4424.8 4435.8 4472.3 4056.9 4584.4 4821.6 4886.7 5133.0 4114.4 4313.7 4322.4 4328.6 4396.4 2187.9 2409.8 2440.0 2440.7 2565.4 4231.8 4504.7 4915.7 4946.0 4978.4 4292.3 4529.1 4578.9 4880.5 4941.8 4211.0 4890.3 4922.0 4943.0 4949.2 4072.5 4287.1 4326.0 4708.0 4727.6 4223.7 4313.8 4390.6 4729.8 4762.1 4293.6 4350.2 4521.9 4695.9 4698.7 2859.5 3056.9 3164.2 3302.8 3324.0 4429.9 4435.4 4694.6 4746.8 4824.6 4087.3 4148.3 4601.9 4613.0 4714.9 4306.4 4420.8 4436.8 4600.2 4612.3 3779.3 4197.0 4557.8 4566.0 5305.6 5501.4 5530.1 5545.4 5558.4 5578.2 4573.4 4837.9 5375.8 5447.5 5447.9 19:43:17 INFO - PROCESS | 1935 | 19:43:17 INFO - PROCESS | 1935 | [#5] yui.html Cycles:180 Average:17350.23 Median:599.78 stddev:24165.67 (4029.1%) stddev-sans-first:24201.05 19:43:17 INFO - PROCESS | 1935 | Values: 679.8 688.4 717.1 748.1 759.8 14196.2 14199.0 14223.6 14251.6 14825.7 14031.6 14057.6 14253.6 14357.1 14405.7 708.1 728.0 768.8 922.0 923.9 890.7 911.1 914.0 920.4 921.7 925.5 935.2 954.3 963.6 964.5 735.1 930.6 955.7 956.3 959.1 722.2 724.3 724.4 726.8 727.0 13055.3 14389.4 14948.0 14986.0 15011.8 12084.3 12165.3 12278.3 12282.4 12415.8 10735.9 10843.3 10936.0 11275.5 11283.3 3951.6 3990.1 4011.3 4038.4 4174.4 78603.6 79921.0 80500.6 80653.1 80713.6 9284.4 9356.3 9392.5 9395.4 9595.7 19730.3 20485.0 21570.9 21627.5 21671.3 7420.1 7442.4 7502.0 7562.9 7580.7 3979.3 4029.7 4065.6 4110.9 4330.9 3261.9 3403.1 3495.6 3497.5 3500.8 635.8 642.3 643.2 656.8 663.0 533.8 558.4 598.3 601.3 612.4 7682.7 7975.8 8069.5 8182.2 8284.1 449.9 565.8 569.6 604.7 606.5 3747.9 3841.7 3846.7 3868.8 3915.2 83988.6 87099.0 87282.0 91572.0 92491.0 55882.4 65490.0 74437.0 79666.6 82151.0 67501.0 68453.0 69153.0 69801.4 71994.3 7914.0 8067.2 8074.6 8134.8 8214.0 30954.5 41021.8 41439.4 41888.6 42095.2 56430.7 57277.0 59213.0 59244.7 59792.7 11417.8 11557.9 11578.7 11842.0 12091.5 9745.6 11748.8 11967.5 11999.2 12014.0 11234.9 11357.4 11359.9 11426.9 11676.6 11157.1 11317.0 11331.4 11371.3 11376.2 10049.4 10080.2 10116.2 10274.6 10369.9 9402.2 9487.7 9552.2 9564.8 9645.7 7672.5 9372.1 9373.6 9418.5 9559.8 19:43:17 INFO - PROCESS | 1935 | -------- Summary: end -------- 19:43:17 INFO - PROCESS | 1935 | 19:43:17 INFO - TEST-INFO | 1935: exit 0 19:43:17 INFO - TEST-OK | dromaeo_css | took 1115370ms 19:43:17 INFO - TEST-START | kraken 19:43:17 INFO - operating with platform_type : mac_ 19:43:17 INFO - Initialising browser for kraken test... 19:43:17 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpDrYi2s/profile http://localhost:49474/getInfo.html) 19:43:20 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 19:43:20 INFO - PROCESS | 1941 | colorDepth:24 19:43:20 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 19:43:20 INFO - PROCESS | 1941 | __metrics 19:43:20 INFO - PROCESS | 1941 | 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" 19:43:20 ERROR - PROCESS | 1941 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:43:21 INFO - TEST-INFO | 1941: exit 0 19:43:21 INFO - Browser initialized. 19:43:21 INFO - Running cycle 1/1 for kraken test... 19:43:21 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpDrYi2s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:43:33 INFO - PROCESS | 1943 | RSS: Main: 239013888 19:43:33 INFO - PROCESS | 1943 | 19:43:36 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49474/tests/kraken/driver.html?testName=audio-beat-detection) 19:43:37 INFO - PROCESS | 1943 | RSS: Main: 286777344 19:43:37 INFO - PROCESS | 1943 | 19:43:39 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49474/tests/kraken/driver.html?testName=audio-dft) 19:43:40 INFO - PROCESS | 1943 | RSS: Main: 295538688 19:43:40 INFO - PROCESS | 1943 | 19:43:44 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49474/tests/kraken/driver.html?testName=audio-fft) 19:43:44 INFO - PROCESS | 1943 | RSS: Main: 383311872 19:43:44 INFO - PROCESS | 1943 | 19:43:46 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49474/tests/kraken/driver.html?testName=audio-oscillator) 19:43:47 INFO - PROCESS | 1943 | RSS: Main: 282902528 19:43:47 INFO - PROCESS | 1943 | 19:43:49 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49474/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:43:50 INFO - PROCESS | 1943 | RSS: Main: 286572544 19:43:50 INFO - PROCESS | 1943 | 19:43:53 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49474/tests/kraken/driver.html?testName=imaging-darkroom) 19:43:53 INFO - PROCESS | 1943 | RSS: Main: 325668864 19:43:53 INFO - PROCESS | 1943 | 19:43:56 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49474/tests/kraken/driver.html?testName=imaging-desaturate) 19:43:57 INFO - PROCESS | 1943 | RSS: Main: 328069120 19:43:57 INFO - PROCESS | 1943 | 19:44:00 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49474/tests/kraken/driver.html?testName=json-parse-financial) 19:44:01 INFO - PROCESS | 1943 | RSS: Main: 328220672 19:44:01 INFO - PROCESS | 1943 | 19:44:02 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49474/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:44:03 INFO - PROCESS | 1943 | RSS: Main: 315969536 19:44:03 INFO - PROCESS | 1943 | 19:44:05 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:44:06 INFO - PROCESS | 1943 | RSS: Main: 323166208 19:44:06 INFO - PROCESS | 1943 | 19:44:08 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:44:09 INFO - PROCESS | 1943 | RSS: Main: 325271552 19:44:09 INFO - PROCESS | 1943 | 19:44:12 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:44:12 INFO - PROCESS | 1943 | RSS: Main: 330203136 19:44:12 INFO - PROCESS | 1943 | 19:44:15 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:44:16 INFO - PROCESS | 1943 | RSS: Main: 327888896 19:44:16 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49474/tests/kraken/driver.html?testName=ai-astar) 19:44:18 INFO - PROCESS | 1943 | RSS: Main: 374276096 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | __start_tp_report 19:44:18 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 19:44:18 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 19:44:18 INFO - PROCESS | 1943 | |i|pagename|runs| 19:44:18 INFO - PROCESS | 1943 | |0;ai-astar;115;107;113;112;114;112;112;112;112;111 19:44:18 INFO - PROCESS | 1943 | |1;audio-beat-detection;145;119;162;244;120;139;137;116;165;116 19:44:18 INFO - PROCESS | 1943 | |2;audio-dft;185;185;183;186;181;183;185;179;183;186 19:44:18 INFO - PROCESS | 1943 | |3;audio-fft;77;72;57;125;57;63;56;60;110;66 19:44:18 INFO - PROCESS | 1943 | |4;audio-oscillator;95;99;122;80;82;76;100;78;79;101 19:44:18 INFO - PROCESS | 1943 | |5;imaging-gaussian-blur;91;85;88;85;87;84;89;85;88;84 19:44:18 INFO - PROCESS | 1943 | |6;imaging-darkroom;93;94;95;107;148;94;94;93;94;92 19:44:18 INFO - PROCESS | 1943 | |7;imaging-desaturate;85;84;83;83;84;83;85;85;84;83 19:44:18 INFO - PROCESS | 1943 | |8;json-parse-financial;57;57;57;58;56;57;58;121;59;56 19:44:18 INFO - PROCESS | 1943 | |9;json-stringify-tinderbox;64;65;79;78;74;72;67;68;68;70 19:44:18 INFO - PROCESS | 1943 | |10;stanford-crypto-aes;88;84;85;86;80;85;82;85;85;85 19:44:18 INFO - PROCESS | 1943 | |11;stanford-crypto-ccm;149;149;138;138;139;124;155;142;236;153 19:44:18 INFO - PROCESS | 1943 | |12;stanford-crypto-pbkdf2;167;169;169;166;168;165;165;164;175;165 19:44:18 INFO - PROCESS | 1943 | |13;stanford-crypto-sha256-iterative;67;59;58;68;80;83;83;81;68;66 19:44:18 INFO - PROCESS | 1943 | __end_tp_report 19:44:18 INFO - PROCESS | 1943 | __start_cc_report 19:44:18 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,2296 19:44:18 INFO - PROCESS | 1943 | __end_cc_report 19:44:18 INFO - PROCESS | 1943 | __startTimestamp1463712258403__endTimestamp 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | ------- Summary: start ------- 19:44:18 INFO - PROCESS | 1943 | Number of tests: 14 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:2.11 (1.9%) stddev-sans-first:1.94 19:44:18 INFO - PROCESS | 1943 | Values: 115.0 107.0 113.0 112.0 114.0 112.0 112.0 112.0 112.0 111.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#1] audio-beat-detection Cycles:10 Average:146.30 Median:142.00 stddev:38.81 (27.3%) stddev-sans-first:41.16 19:44:18 INFO - PROCESS | 1943 | Values: 145.0 119.0 162.0 244.0 120.0 139.0 137.0 116.0 165.0 116.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#2] audio-dft Cycles:10 Average:183.60 Median:185.00 stddev:2.27 (1.2%) stddev-sans-first:2.35 19:44:18 INFO - PROCESS | 1943 | Values: 185.0 185.0 183.0 186.0 181.0 183.0 185.0 179.0 183.0 186.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#3] audio-fft Cycles:10 Average:74.30 Median:61.50 stddev:24.02 (39.1%) stddev-sans-first:25.46 19:44:18 INFO - PROCESS | 1943 | Values: 77.0 72.0 57.0 125.0 57.0 63.0 56.0 60.0 110.0 66.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:79.50 stddev:14.76 (18.6%) stddev-sans-first:15.59 19:44:18 INFO - PROCESS | 1943 | Values: 95.0 99.0 122.0 80.0 82.0 76.0 100.0 78.0 79.0 101.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:2.37 (2.7%) stddev-sans-first:1.90 19:44:18 INFO - PROCESS | 1943 | Values: 91.0 85.0 88.0 85.0 87.0 84.0 89.0 85.0 88.0 84.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#6] imaging-darkroom Cycles:10 Average:100.40 Median:94.00 stddev:17.26 (18.4%) stddev-sans-first:18.10 19:44:18 INFO - PROCESS | 1943 | Values: 93.0 94.0 95.0 107.0 148.0 94.0 94.0 93.0 94.0 92.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.00 stddev:0.88 (1.0%) stddev-sans-first:0.83 19:44:18 INFO - PROCESS | 1943 | Values: 85.0 84.0 83.0 83.0 84.0 83.0 85.0 85.0 84.0 83.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:57.00 stddev:20.19 (35.4%) stddev-sans-first:21.27 19:44:18 INFO - PROCESS | 1943 | Values: 57.0 57.0 57.0 58.0 56.0 57.0 58.0 121.0 59.0 56.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.00 stddev:5.17 (7.3%) stddev-sans-first:4.92 19:44:18 INFO - PROCESS | 1943 | Values: 64.0 65.0 79.0 78.0 74.0 72.0 67.0 68.0 68.0 70.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:85.00 stddev:2.17 (2.6%) stddev-sans-first:1.90 19:44:18 INFO - PROCESS | 1943 | Values: 88.0 84.0 85.0 86.0 80.0 85.0 82.0 85.0 85.0 85.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:149.00 stddev:30.78 (20.7%) stddev-sans-first:32.63 19:44:18 INFO - PROCESS | 1943 | Values: 149.0 149.0 138.0 138.0 139.0 124.0 155.0 142.0 236.0 153.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:167.50 stddev:3.23 (1.9%) stddev-sans-first:3.43 19:44:18 INFO - PROCESS | 1943 | Values: 167.0 169.0 169.0 166.0 168.0 165.0 165.0 164.0 175.0 165.0 19:44:18 INFO - PROCESS | 1943 | 19:44:18 INFO - PROCESS | 1943 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:74.00 stddev:9.66 (13.1%) stddev-sans-first:10.12 19:44:18 INFO - PROCESS | 1943 | Values: 67.0 59.0 58.0 68.0 80.0 83.0 83.0 81.0 68.0 66.0 19:44:18 INFO - PROCESS | 1943 | -------- Summary: end -------- 19:44:18 INFO - PROCESS | 1943 | 19:44:19 INFO - TEST-INFO | 1943: exit 0 19:44:19 INFO - TEST-OK | kraken | took 61385ms 19:44:19 INFO - SUITE-END | took 1176s 19:44:19 INFO - Completed test suite (00:19:37) 19:44:19 INFO - javascript benchmark 19:44:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3407.574053243344, 3419.281950790333, 3426.537417448645, 3470.028392050227, 3506.964930350697, 5576.972115139423, 5579.079451890433, 5643.153526970947, 5662.943370566299, 5703.144528320749, 5626.887462250753, 5657.660540367581, 5697.857553561163, 5743.167240750089, 5774.5091667208335, 5052.040112378655, 5057.519535644108, 5122.795088196468, 5133.870548479328, 5191.610629202806, 5214.973925130369, 5228.973855130738, 5241.554467870235, 5256.500632439931, 5307.548858347045, 5062.190049592047, 5114.846554603368, 5129.358830146231, 5147.124988751922, 5168.508991645787, 5053.418856831475, 5147.6911385317, 5150.995555866608, 5152.278680984665, 5168.147255702842, 5065.46812584677, 5072.6195535335, 5094.6688465249645, 5154.226865970097, 5176.922346164811, 5538.280023596947, 5665.121906104519, 5666.9716651416475, 5670.262865827457, 5678.914816277759, 6080.695965201722, 6182.96908515459, 6209.16176316196, 6209.441150296495, 6214.627122372672, 6108.949222962717, 6135.814547850547, 6195.659238741868, 6209.0, 6216.067589861512, 5798.391168927202, 5830.067189249699, 5837.241158649434, 5837.50381217597, 5848.093545500412, 6090.451859332681, 6108.259146143328, 6115.848865095124, 6156.0, 6178.073289006685, 6131.9269127903335, 6136.631802091889, 6141.263048434127, 6147.739713358751, 6148.831721972811, 6187.721552530147, 6225.28409232935, 6226.782062627786, 6233.407826256498, 6243.032329988859, 5871.882562348815, 5919.792807251725, 6088.726007329681, 6150.446459818549, 6187.721552530147, 6150.523874270143, 6181.134641150243, 6187.907181392283, 6198.690065496708, 6214.4407003369915, 4906.067847109038, 4907.55831975124, 4907.803687852517, 4909.7790599423115, 4927.728974906415, 4370.825166993348, 4377.387165796791, 4403.515613282536, 4427.2252355838245, 4444.644428445717, 4751.263554149113, 5658.066419040894, 5662.68855212959, 5676.4323567642905, 5687.914681279784, 6199.3490683477585, 6221.657808820468, 6254.781082662176, 6255.74977000924, 6267.185265915402, 5551.2228288039705, 5590.776368945278, 5603.915941260884, 5640.210370548127, 5643.209950606918, 5313.787448502017, 5352.812651557177, 5354.812581559627, 5361.51746342831, 5395.946040539623, 5740.880528296942, 6188.690565471798, 6194.008958566607, 6210.285817130997, 6214.0, 6110.205673262625, 6139.324674285915, 6159.106929495288, 6180.412860778219, 6201.782937597162, 6172.907406388818, 6185.969070154621, 6192.721327540181, 6196.256449225942, 6196.752129914753, 6063.333033366414, 6098.26820781509, 6099.573029888043, 6133.84665383369, 6136.110264011782, 5990.760369585342, 6044.637321760709, 6116.579253789288, 6189.1954045975535, 6200.3179650239335, 5989.131575921554, 6034.818955431344, 6049.455549000611, 6053.0, 6111.84720381994, 5285.577153827761, 5889.263842019747, 6030.005049166838, 6059.848503787441, 6068.51451883857, 5804.245448091721, 5874.148248504029, 5888.587798854039, 5898.528117750656, 5911.261092363455, 6104.532665215114, 6126.426068710427, 6130.1878025749575, 6130.52043589939, 6138.996717675081, 5805.332386775575, 5819.79630712923, 5824.417558244142, 5980.641161530322, 6031.939680603138], "value": 5645.060358481552, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.04204196711487, 485.5581420906975, 490.9926351104732, 502.63056653359797, 509.31242822190035, 31456.52815207774, 42173.78913105434, 43323.78338108305, 43356.78321608395, 43376.132477350475, 44661.7766911165, 45272.77363613177, 45278.773606132, 45358.86602834931, 45474.0, 8659.04750477447, 9427.340086193952, 9666.371685840433, 9673.177779888718, 9710.36882602632, 11430.42847857608, 11741.4716337765, 11753.0, 11972.401379931012, 11973.880261197364, 11490.482928268206, 11533.365664888428, 11533.884661153365, 11588.420578971018, 11608.30350178992, 8602.795608614799, 11504.76990460186, 11633.185677002613, 11691.883081169164, 11758.470868810924, 11702.590409335633, 11798.997085247678, 11819.704507387298, 11820.349880756556, 11872.228305160223, 40831.0, 41401.75794726163, 41502.79248603768, 41772.58227417717, 41793.582064179274, 3122.5784519089857, 3181.109289398972, 3200.9039728808175, 3219.17910932713, 3440.0367896988882, 28408.573871391945, 29974.85012574923, 29986.55020174699, 30088.398232035455, 30425.847870760503, 2661.5342315094977, 2748.848813315267, 2765.5436852919242, 2797.510435673749, 2833.7874659400627, 372467.0, 374428.2557174421, 517339.41330293484, 517543.0, 518443.0, 34392.0, 35395.64604353924, 60438.0, 62097.72169365564, 62357.0, 32538.34923301568, 35204.823975879954, 36260.0, 36416.27167456689, 36447.088822779115, 14605.78091328631, 14864.554063378117, 15102.697946041018, 15132.773008404884, 15166.31751571182, 11437.885621143849, 11675.007624351856, 12249.265044097383, 12406.13157079014, 12535.686607834878, 7777.844443111106, 7974.4417890748255, 7980.720674776471, 8036.4374493786045, 8046.436749427484, 2839.5030869597736, 2900.9564856527168, 3248.6101667800017, 3280.8851690190727, 3281.6718328166994, 859.3984211052164, 983.0808194338316, 988.5057471264367, 995.1392045880406, 1044.9968030690623, 29287.414251715272, 29353.412931741674, 29681.0, 30082.247943801143, 30215.199785890534, 1025.5897640943683, 1041.6979076068008, 1062.2351906627216, 1102.691246450995, 1120.93834839083, 13318.667033324244, 15049.871259655569, 15192.696146077016, 15292.847071529364, 15568.922155389377, 691031.0, 695141.0, 699361.5031924808, 699875.0, 703718.0, 60390.09414858781, 60946.0858087129, 61525.69237153875, 61686.0, 61712.38287617156, 1285.3637449462576, 1353.749556332062, 1409.9013069085065, 1454.7817827325987, 1455.046944251517, 28375.0, 29957.70042299549, 30184.698153019068, 30380.0505934236, 31249.687503125588, 51826.0, 51880.0, 52491.0, 52518.0, 52563.737181313845, 563698.0, 564293.0, 564497.0, 565345.0, 565605.0, 22907.31278061661, 25259.368515787253, 25948.0, 26259.737402626495, 27045.45909081768, 44632.77683611561, 45832.541674582826, 46079.76960115178, 46238.306425402974, 47185.52814471949, 31355.686443135277, 32120.839395802872, 36211.0, 37273.0, 37574.87275381743, 31786.36427271488, 35258.647413525534, 36931.26137477289, 37699.05752356213, 37853.81073094617, 36281.81859090688, 36880.07799805026, 37085.62914370822, 37626.05934851651, 38434.42348364721, 32454.513182302755, 33211.16972075621, 33860.830695846365, 34221.48667769936, 34748.0, 24118.03527858866, 24628.630570541816, 25125.0, 25263.621045684697, 25342.239732808044], "value": 18769.17561163253, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.65854389593267, 383.9328117579423, 391.23513531046825, 404.15934855500564, 411.50824764406525, 19160.616787664258, 20506.69239961398, 20802.375928722144, 20902.790972090275, 21491.92540372979, 20244.0, 20474.590508189827, 20488.078036488358, 20839.47901302468, 21483.355499335004, 5170.53465188133, 5747.166660834187, 5789.768409263625, 5946.078357854518, 5962.642241465526, 5743.4543718346695, 5833.3874943130995, 5889.734961926702, 5942.346341902387, 5943.851403714899, 5671.7731290748325, 5802.477777000069, 5830.3586605473365, 5833.037548804464, 5851.8537036574, 5722.971385143048, 5787.04513755232, 5824.504917082053, 5833.737481813286, 5839.737211825478, 4947.406311242638, 5070.974645126787, 5111.744412779346, 5111.897762044776, 5122.462141475166, 24684.25947221586, 25111.748882511125, 25529.361765956, 25694.614580781305, 25930.962761489518, 1891.4798430431604, 2116.65075262583, 2188.5841690078832, 2230.0, 2657.906973255946, 9686.854697179548, 9849.359791613522, 10003.549840257134, 10060.39637621745, 10274.178065754722, 1693.254967814158, 1716.1333526569003, 1730.3424698614572, 1737.1401156427523, 1778.6531626332871, 89708.20583588292, 115659.0, 116484.41757791156, 122890.38554807169, 132850.3357483216, 9805.607775689035, 10950.809838032266, 11355.94322028401, 11480.483378247998, 11571.247868888495, 14373.928130359282, 14495.20276384809, 14571.271436428136, 14574.635634109232, 14590.56228313131, 7455.701771929171, 7920.485168464032, 7920.5247685139075, 8204.794880128045, 8482.703105391405, 8502.234798868132, 8525.360597955054, 8588.355873309401, 8615.22462978335, 8724.69463568772, 4447.066116115588, 4525.330055267342, 4563.06457176278, 4567.771611419415, 4595.770211489412, 1591.5304985029445, 1757.7714897063556, 1819.2813838533705, 1944.656688690125, 1969.8621096523393, 879.784452809053, 928.865314529403, 934.6541779541477, 939.056248470283, 1011.8482227665909, 19233.8076619232, 20288.695669564968, 20403.795962040487, 20619.07214175366, 20645.89677051605, 943.990560094404, 956.8947415784259, 989.4162444157981, 1020.3316827478013, 1036.124474818777, 9693.757656058513, 10257.74355641115, 11019.669409917715, 11022.283551569122, 11023.72440688973, 413278.0, 559570.0, 603522.0, 606803.0, 608395.9580202071, 111012.4449377748, 128349.35825320813, 147560.26219868832, 148344.0, 150387.0, 940.8353538130937, 994.9701508954743, 1057.1014637558014, 1071.9464026798628, 1072.871255449351, 13973.650658733206, 17101.57246068858, 18610.255589776258, 19306.227750889808, 19354.838709677286, 52855.735721322686, 54251.0, 54609.0, 55102.72448637731, 55766.0, 59159.704201478715, 81288.78066828883, 81842.59078704569, 82116.58941705493, 83227.5838620803, 7744.380449563909, 8024.358051355995, 8097.554634495159, 8140.83718325642, 8394.328453723809, 9208.12522810332, 11744.88255117472, 12544.184627999151, 12742.554010609585, 12755.362231888554, 10594.47027648599, 10622.415767132881, 10839.945800270949, 11066.446677666245, 11246.718832028937, 8848.247898928406, 10760.085392741705, 10871.347719136877, 10889.0, 10943.945280273547, 9132.680356187502, 11080.279781814157, 11137.164712646745, 11293.322400655987, 11328.773424531628, 8000.5599692017495, 8089.8382032360205, 8184.2224988625985, 8193.672253110048, 8540.14598540141, 10862.837057444303, 10930.01629853317, 10947.507362168562, 11183.217174797686, 11297.604583839526], "value": 9434.13008416721, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1457.031074335567, 1615.9192040397984, 1644.6875093732192, 1917.731517587538, 2042.8161465468115, 7762.844743105142, 8675.566221688921, 8922.598483068261, 8932.821343573132, 9048.321375896801, 8610.52642104684, 8717.520536370497, 8758.562071896396, 8847.646094156225, 8852.981907080672, 2083.312506872736, 2222.8107962240206, 2292.2321022457495, 2338.8830558472096, 2378.0963233971065, 2235.854669446489, 2384.95230095398, 2387.510560335127, 2387.9761202388013, 2390.796782273509, 2308.3075077476774, 2350.9882450587725, 2351.071326825903, 2352.8353015288935, 2355.3993731598416, 2045.2125931516398, 2253.5605556916407, 2297.8046866016407, 2326.174208156114, 2333.9649905251435, 2327.301809457156, 2351.7413084560685, 2353.188150088234, 2353.694019777435, 2355.4582446037334, 8661.30709543235, 8697.652093916236, 8745.912540874573, 8766.824663506757, 8806.515641639708, 5148.459411761749, 5218.269442278046, 5323.600729945269, 5323.813666521692, 5341.412444631087, 3281.786683865541, 3337.516060171286, 3502.7548071634997, 3512.1570823002553, 3536.4341705327283, 1003.7540802503361, 1004.9045340692695, 1005.0552480668149, 1010.6664800615779, 1021.8927012663712, 11873.881261187364, 11898.881011189864, 11900.464479098462, 11943.283402995849, 11948.044156467462, 4586.656000799953, 4741.478437371887, 4746.169420351424, 4791.808327666924, 4816.34979277793, 9745.512724363753, 9925.702228933143, 9994.900050999542, 9998.100170984646, 10018.0, 4700.918788678555, 5296.417394086648, 5344.946550534522, 5352.2506849041165, 5375.381831089397, 4350.825966961286, 4525.841595544206, 4749.667523273407, 4890.755462226874, 4901.681390709592, 3883.786391748481, 3943.6056394360908, 3983.7410568312966, 4025.134596061861, 4064.695147863922, 1190.9047276217882, 1273.0961017677553, 1286.9742605147844, 1296.604535616646, 1305.8367704036996, 792.2513225002433, 898.5552151684958, 920.6041402196986, 938.9154976052051, 953.0, 5505.752241149158, 5698.6580805151, 5809.0, 5894.705264736746, 5914.964881145782, 428.97855107244516, 436.0907507846176, 442.3055802390281, 443.7071532788344, 456.0946521155504, 3835.175437280998, 3837.194189220295, 3842.3275926712713, 3855.7300988930506, 3928.273269445162, 10459.267851250492, 10757.623483178051, 10764.0, 10877.292975956538, 10902.454877256265, 9424.246060315158, 10451.268411211295, 10565.9471702641, 10570.101541368917, 10820.945895270474, 7884.64519096642, 8078.798030049178, 8258.298044666151, 8290.46112002718, 8295.626696798538, 5579.776808927598, 5683.8010669626365, 5709.7494120996425, 5746.9712651436475, 5805.564582656233, 9853.310268281151, 9902.207823373968, 9908.653197138066, 10150.949245254022, 10172.898271017195, 9768.413895166314, 10083.798324033332, 10190.235732319958, 10255.128314093387, 10294.742631434274, 6833.214180369321, 7067.964660176666, 7294.562326260441, 7504.324610784837, 7603.695852165852, 7486.812829679302, 8303.750887473385, 8460.450070745383, 8505.574721264036, 8523.105073967145, 6266.498680105672, 7476.289752473506, 7500.887486687813, 7575.015248017943, 7643.273888980538, 6984.301569842975, 7177.7487787927175, 7182.748603798842, 7283.490155689263, 7435.0, 6787.253402125861, 6824.829379265359, 6912.827179320558, 6913.0667359905765, 6914.308569143045, 4661.417322834645, 4732.597729193058, 4791.95208047929, 4853.0779151961015, 4958.900821983468, 4762.571368576845, 4763.54746299101, 4796.0, 4821.831235906727, 4894.95105048945], "value": 4452.833140167747, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.1146617478438, 303.97872148949574, 317.4365501235307, 321.25148404218174, 322.922498600336, 4614.469336026358, 4818.27725841124, 5288.603354748395, 5366.651167674098, 5366.865828354293, 5026.8384594624295, 5214.0, 5223.555997740187, 5223.876866473707, 5235.502627250415, 3986.1230529283657, 4004.899877503057, 4011.638952494275, 4020.6582440492593, 4037.656799172074, 3403.319336132771, 3781.5819067849557, 3876.3991581304945, 4041.595250825754, 4041.6766658667234, 3800.9547374471963, 4007.338789099795, 4022.939655905164, 4030.576789437111, 4073.63337299643, 3265.918352041206, 3882.8446862125766, 3978.8607398740905, 4022.4915656628764, 4034.3141665916864, 3211.2614098757185, 3374.358871814372, 3484.61669216386, 3498.6326435724136, 3506.2987402519598, 4756.025014871978, 5075.796968121271, 5078.974605126988, 5139.537441630234, 5219.5041471060185, 3943.881683549498, 4032.5765794591734, 4341.413909122291, 4486.343629941587, 4523.5024147343765, 4724.283536966258, 5513.476219759116, 5551.83545379134, 5570.0, 5577.6852606168395, 3614.6023937366867, 3775.9811200944096, 3778.4521244419407, 3780.716446266541, 3800.467934489146, 5282.286891269668, 5631.746571404296, 5684.777772778831, 5737.368889422202, 5742.425757424266, 3350.447176215946, 3830.75100118497, 3838.7504812187117, 3841.9231615367535, 3846.1730727893078, 3424.417848965632, 3466.5493485846678, 3693.90765230866, 3849.749766265184, 3889.474920885644, 3861.38217885137, 4446.599806017474, 4464.441944756905, 4477.007731559424, 4505.88735281614, 4874.5856602189215, 5084.542391184811, 5533.806316778893, 5553.861153471195, 5559.305086864142, 1167.96496105117, 1185.3717529709268, 1244.595506460404, 1264.5647190438735, 1341.9865801341862, 787.5117427195175, 803.3252068262688, 817.0, 822.3010441125116, 822.5722624235484, 4277.465316835395, 4365.80353884076, 4424.80088396023, 4435.844745433894, 4472.306792447176, 4056.8782936511952, 4584.4498660161, 4821.614270858323, 4886.705023168863, 5132.999065182254, 4114.362273847559, 4313.741175529478, 4322.416473776063, 4328.632066274339, 4396.406120388986, 2187.8687278763327, 2409.7590240976115, 2439.9756002440104, 2440.7437219092094, 2565.3586603349163, 4231.830726770956, 4504.707194032378, 4915.729634870045, 4945.975270123627, 4978.352814134212, 4292.260416204254, 4529.071540334224, 4578.946842226306, 4880.536349046835, 4941.777620007108, 4211.0, 4890.33980412654, 4921.966387058757, 4942.9752851236235, 4949.208126699711, 4072.450219220367, 4287.0847823480335, 4325.983393902493, 4708.0461608432115, 4727.57451829337, 4223.676977662212, 4313.799962019455, 4390.560943905712, 4729.753367150576, 4762.0713610987605, 4293.638498825571, 4350.238708225985, 4521.91991683816, 4695.929561056655, 4698.707855339809, 2859.5281778506314, 3056.930074473916, 3164.2089477630593, 3302.7522935779434, 3323.9833800830843, 4429.911401772011, 4435.379046933367, 4694.6009589185305, 4746.78639461232, 4824.638152138674, 4087.325591277406, 4148.315527937977, 4601.884952876205, 4613.031263434582, 4714.878304762939, 4306.397104405323, 4420.801063952065, 4436.844710435119, 4600.217962946374, 4612.285095810154, 3779.263043706451, 4196.985446985525, 4557.769402261437, 4566.0, 5305.585958389001, 5501.367342755634, 5530.096699232935, 5545.390007099297, 5558.3607885092115, 5578.246936663661, 4573.382593349856, 4837.879053023703, 5375.838724838261, 5447.53695935834, 5447.9025575153055], "value": 3730.371354781185, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.7858674517523, 688.3604505632042, 717.1072015340901, 748.0686545251164, 759.813845607826, 14196.219207943572, 14199.006069575122, 14223.644408889757, 14251.643708907282, 14825.703485930275, 14031.64920876976, 14057.648558786035, 14253.572392828206, 14357.138571685706, 14405.711885762279, 708.0865683268578, 727.952683075601, 768.7501561992361, 921.9539023048854, 923.852183650616, 890.688259109313, 911.1025639744846, 913.9771505712378, 920.4293338130335, 921.7004473546091, 925.4956048953337, 935.223764275653, 954.3462728031286, 963.6049219819876, 964.4502633498909, 735.1325435985535, 930.5765876526165, 955.6655170690273, 956.2971216156154, 959.0888655777005, 722.2055738687454, 724.2576359231776, 724.4385601159144, 726.8255618651539, 727.0148948175199, 13055.281959492224, 14389.352479138464, 14948.028378155384, 14986.0, 15011.774823377658, 12084.274943503418, 12165.33090680021, 12278.324692141929, 12282.38588070593, 12415.75168496625, 10735.946320268427, 10843.349399036084, 10936.0, 11275.548978040868, 11283.266587671775, 3951.6245956634075, 3990.1345022816704, 4011.2980228460196, 4038.414429907676, 4174.3947127666315, 78603.60698196529, 79921.0, 80500.59749701273, 80653.14448271904, 80713.59643201747, 9284.396514226553, 9356.298277629205, 9392.483413412328, 9395.436273823592, 9595.712128636153, 19730.30943916956, 20485.0, 21570.892145539172, 21627.459313517287, 21671.349859503924, 7420.072490938633, 7442.367398771057, 7502.0, 7562.924370756332, 7580.658870350848, 3979.303621866162, 4029.6977726670616, 4065.573114822901, 4110.856120035784, 4330.935035974463, 3261.853216605211, 3403.0981789825223, 3495.632958539317, 3497.4753786932165, 3500.7724497907557, 635.8346829824279, 642.2678146912524, 643.2024289880515, 656.7931101702949, 662.9569078009914, 533.8451848963834, 558.4387690371151, 598.2970010237952, 601.2634522709733, 612.4487960835195, 7682.7311044113185, 7975.7607271781935, 8069.471346555073, 8182.22268884455, 8284.10619116409, 449.934759459883, 565.7652074389082, 569.5699746691222, 604.7369394313488, 606.5178183344163, 3747.8500859965297, 3841.7118716096406, 3846.653801157909, 3868.7872167031082, 3915.197384536163, 83988.58005709933, 87099.0, 87282.0, 91572.0, 92491.0, 55882.44117558772, 65490.0, 74437.0, 79666.60166699246, 82150.99772063832, 67501.0, 68453.0, 69153.0, 69801.41406708262, 71994.28005719981, 7913.9604301978125, 8067.193280671886, 8074.636641351036, 8134.83730325402, 8213.95893020531, 30954.53568196524, 41021.794891025354, 41439.37840932328, 41888.58111418847, 42095.1580968385, 56430.7178464105, 57277.0, 59213.0, 59244.70377648084, 59792.70103649454, 11417.771644567229, 11557.942210288895, 11578.652640420802, 11842.0, 12091.516339346328, 9745.610175592898, 11748.765024699629, 11967.520299812617, 11999.220050696676, 12014.0, 11234.887651123385, 11357.37534435614, 11359.886401136213, 11426.94286528562, 11676.591319303783, 11157.051650609465, 11317.038051765692, 11331.433428328715, 11371.260868043551, 11376.203665743318, 10049.397036177561, 10080.243981701255, 10116.19070474375, 10274.640387586398, 10369.896301036893, 9402.15380615748, 9487.66793162236, 9552.188064014637, 9564.808703825745, 9645.71062868115, 7672.5012874163, 9372.109649583279, 9373.625054997723, 9418.4819834907, 9559.761005974628], "value": 6753.771734738264, "unit": "score"}], "name": "dromaeo_css", "value": 6944.376708426642, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 107.0, 113.0, 112.0, 114.0, 112.0, 112.0, 112.0, 112.0, 111.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 119.0, 162.0, 244.0, 120.0, 139.0, 137.0, 116.0, 165.0, 116.0], "value": 146.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 186.0, 181.0, 183.0, 185.0, 179.0, 183.0, 186.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 57.0, 125.0, 57.0, 63.0, 56.0, 60.0, 110.0, 66.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 99.0, 122.0, 80.0, 82.0, 76.0, 100.0, 78.0, 79.0, 101.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 88.0, 85.0, 87.0, 84.0, 89.0, 85.0, 88.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 107.0, 148.0, 94.0, 94.0, 93.0, 94.0, 92.0], "value": 100.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 83.0, 84.0, 83.0, 85.0, 85.0, 84.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 58.0, 56.0, 57.0, 58.0, 121.0, 59.0, 56.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 79.0, 78.0, 74.0, 72.0, 67.0, 68.0, 68.0, 70.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 85.0, 86.0, 80.0, 85.0, 82.0, 85.0, 85.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 138.0, 138.0, 139.0, 124.0, 155.0, 142.0, 236.0, 153.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 169.0, 166.0, 168.0, 165.0, 165.0, 164.0, 175.0, 165.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 59.0, 58.0, 68.0, 80.0, 83.0, 83.0, 81.0, 68.0, 66.0], "value": 71.3, "unit": "score"}], "name": "kraken", "value": 1487.8, "alertThreshold": 2.0}]} 19:44:19 INFO - Return code: 0 19:44:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:44:19 ERROR - # TBPL SUCCESS # 19:44:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:44:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:44:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:44:19 INFO - 2016-05-19 19:44:19.716 screenresolution[1947:13179] starting screenresolution argv=screenresolution get 19:44:19 INFO - 2016-05-19 19:44:19.719 screenresolution[1947:13179] Display 0: 1600x1200x32@60 19:44:19 INFO - 2016-05-19 19:44:19.725 screenresolution[1948:13182] starting screenresolution argv=screenresolution list 19:44:19 INFO - Available Modes on Display 0 19:44:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:44:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:44:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:44:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:44:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:44:19 INFO - Intel Iris: 19:44:19 INFO - Chipset Model: Intel Iris 19:44:19 INFO - Type: GPU 19:44:19 INFO - Bus: Built-In 19:44:19 INFO - VRAM (Dynamic, Max): 1536 MB 19:44:19 INFO - Vendor: Intel (0x8086) 19:44:19 INFO - Device ID: 0x0a2e 19:44:19 INFO - Revision ID: 0x0009 19:44:19 INFO - Displays: 19:44:19 INFO - Display: 19:44:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:44:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:44:19 INFO - Display Serial Number: 1600x1200 60 19:44:19 INFO - Main Display: Yes 19:44:19 INFO - Mirror: Off 19:44:19 INFO - Online: Yes 19:44:19 INFO - Rotation: Supported 19:44:19 INFO - Return code: 0 19:44:19 INFO - Running post-action listener: _resource_record_post_action 19:44:19 INFO - ##### 19:44:19 INFO - ##### Finished run-tests step (success) 19:44:19 INFO - ##### 19:44:19 INFO - Running post-run listener: _resource_record_post_run 19:44:20 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1753088; Write bytes: 694331392; Read time: 20; Write time: 1901 19:44:20 INFO - TinderboxPrint: CPU usage
54.0% 19:44:20 INFO - TinderboxPrint: I/O read bytes / time
1,753,088 / 20 19:44:20 INFO - TinderboxPrint: I/O write bytes / time
694,331,392 / 1,901 19:44:20 INFO - TinderboxPrint: CPU idle
2,230.1 (46.0%) 19:44:20 INFO - TinderboxPrint: CPU system
967.9 (20.0%) 19:44:20 INFO - TinderboxPrint: CPU user
1,651.8 (34.1%) 19:44:20 INFO - TinderboxPrint: Swap in / out
398,196,736 / 0 19:44:20 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 379451904; Write bytes: 373704704; Read time: 28318; Write time: 755 19:44:20 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1753088; Write bytes: 295608320; Read time: 20; Write time: 1082 19:44:20 INFO - Running post-run listener: _upload_blobber_files 19:44:20 INFO - Blob upload gear active. 19:44:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:44:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:44:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:44:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:44:20 INFO - (blobuploader) - INFO - Open directory for files ... 19:44:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:44:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:44:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:44:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:44:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:44:21 INFO - (blobuploader) - INFO - Done attempting. 19:44:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:44:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:44:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:44:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:44:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:44:22 INFO - (blobuploader) - INFO - Done attempting. 19:44:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:44:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:44:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:44:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:44:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:44:24 INFO - (blobuploader) - INFO - Done attempting. 19:44:24 INFO - (blobuploader) - INFO - Iteration through files over. 19:44:24 INFO - Return code: 0 19:44:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:44:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:44:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2dd1bc7091b8d22f25382d77027fb0b3293927c48a62c4d606991652a8e3fd4c23fa14ecef6854cc2c73d1e819bf3da301e0c271db8b28fdf78462e536b07cab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7a6a4f74dc2e9f4c0dfc721d9e163abb11fd1e47b9b12cfaca161962d78332ad85d24738f4bd60252ee901af686343c98b1e4efa898a9445866488bb8028b64c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4a0b4606b5af7146bcb6b82d4cbd8e8d5cbcbb70b769dd85f706f8be90362233f99b46f6308e5c4d428cd4aa148d95e55ae0723b2c516e0f457884a2d9aa9ce"} 19:44:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:44:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:44:24 INFO - Contents: 19:44:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2dd1bc7091b8d22f25382d77027fb0b3293927c48a62c4d606991652a8e3fd4c23fa14ecef6854cc2c73d1e819bf3da301e0c271db8b28fdf78462e536b07cab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7a6a4f74dc2e9f4c0dfc721d9e163abb11fd1e47b9b12cfaca161962d78332ad85d24738f4bd60252ee901af686343c98b1e4efa898a9445866488bb8028b64c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4a0b4606b5af7146bcb6b82d4cbd8e8d5cbcbb70b769dd85f706f8be90362233f99b46f6308e5c4d428cd4aa148d95e55ae0723b2c516e0f457884a2d9aa9ce"} 19:44:24 INFO - Running post-run listener: copy_logs_to_upload_dir 19:44:24 INFO - Copying logs to upload dir... 19:44:24 INFO - mkdir: /builds/slave/test/build/upload/logs 19:44:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.107308 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-19 19:44:24.876818) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 19:44:24.880165) ========= 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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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/2dd1bc7091b8d22f25382d77027fb0b3293927c48a62c4d606991652a8e3fd4c23fa14ecef6854cc2c73d1e819bf3da301e0c271db8b28fdf78462e536b07cab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7a6a4f74dc2e9f4c0dfc721d9e163abb11fd1e47b9b12cfaca161962d78332ad85d24738f4bd60252ee901af686343c98b1e4efa898a9445866488bb8028b64c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4a0b4606b5af7146bcb6b82d4cbd8e8d5cbcbb70b769dd85f706f8be90362233f99b46f6308e5c4d428cd4aa148d95e55ae0723b2c516e0f457884a2d9aa9ce"} build_url:https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011062 build_url: 'https://queue.taskcluster.net/v1/task/SJSfHfPUTmO3tJvN3onC8Q/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/2dd1bc7091b8d22f25382d77027fb0b3293927c48a62c4d606991652a8e3fd4c23fa14ecef6854cc2c73d1e819bf3da301e0c271db8b28fdf78462e536b07cab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7a6a4f74dc2e9f4c0dfc721d9e163abb11fd1e47b9b12cfaca161962d78332ad85d24738f4bd60252ee901af686343c98b1e4efa898a9445866488bb8028b64c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4a0b4606b5af7146bcb6b82d4cbd8e8d5cbcbb70b769dd85f706f8be90362233f99b46f6308e5c4d428cd4aa148d95e55ae0723b2c516e0f457884a2d9aa9ce"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 19:44:24.908795) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 19:44:24.909136) ========= 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.iw2ufHf1Nu/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.QaT2SlaQra/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.005123 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 19:44:24.957896) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 19:44:24.958197) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 19:44:24.958542) ========= ========= Total master_lag: 0.54 =========