builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0113 starttime: 1463805301.42 results: success (0) buildid: 20160520181836 builduid: dc7f2d7053d74aa08369953fe333fdb8 revision: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:01.418523) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:01.418956) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:01.419248) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:01.480215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:01.480470) ========= 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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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-20 21:35:01-- 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% 170M=0s 2016-05-20 21:35:02 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.529409 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:02.049206) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:02.049487) ========= 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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.021238 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:02.114684) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 21:35:02.115025) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 --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 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OpLLqkNZH0/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.mIPwHMjlyy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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-20 21:35:02,211 truncating revision to first 12 chars 2016-05-20 21:35:02,211 Setting DEBUG logging. 2016-05-20 21:35:02,211 attempt 1/10 2016-05-20 21:35:02,211 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/06d9362a57a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 21:35:02,520 unpacking tar archive at: fx-team-06d9362a57a1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.600133 ========= master_lag: 0.80 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 21:35:03.515406) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:03.515702) ========= script_repo_revision: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:03.516079) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:03.516356) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 21:35:03.616887) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-20 21:35:03.617194) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OpLLqkNZH0/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.mIPwHMjlyy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 21:35:03 INFO - MultiFileLogger online at 20160520 21:35:03 in /builds/slave/test 21:35:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:35:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:35:03 INFO - {'append_to_log': False, 21:35:03 INFO - 'base_work_dir': '/builds/slave/test', 21:35:03 INFO - 'blob_upload_branch': 'Fx-Team', 21:35:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:35:03 INFO - 'branch': 'Fx-Team', 21:35:03 INFO - 'buildbot_json_path': 'buildprops.json', 21:35:03 INFO - 'config_files': ('talos/mac_config.py',), 21:35:03 INFO - 'default_actions': ('clobber', 21:35:03 INFO - 'read-buildbot-config', 21:35:03 INFO - 'download-and-extract', 21:35:03 INFO - 'populate-webroot', 21:35:03 INFO - 'create-virtualenv', 21:35:03 INFO - 'install', 21:35:03 INFO - 'run-tests'), 21:35:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:35:03 INFO - 'download_minidump_stackwalk': True, 21:35:03 INFO - 'download_symbols': 'ondemand', 21:35:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:35:03 INFO - 'tooltool.py': '/tools/tooltool.py', 21:35:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:35:03 INFO - '/tools/misc-python/virtualenv.py')}, 21:35:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:35:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:35:03 INFO - 'installer_path': 'installer.exe', 21:35:03 INFO - 'log_level': 'info', 21:35:03 INFO - 'log_name': 'talos', 21:35:03 INFO - 'log_to_console': True, 21:35:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:35:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:35:03 INFO - 'opt_config_files': (), 21:35:03 INFO - 'pip_index': False, 21:35:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:35:03 INFO - 'cmd': ('bash', 21:35:03 INFO - '-c', 21:35:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:35:03 INFO - 'enabled': True, 21:35:03 INFO - 'halt_on_failure': False, 21:35:03 INFO - 'name': 'check_screen_resolution'},), 21:35:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:35:03 INFO - 'cmd': ('bash', 21:35:03 INFO - '-c', 21:35:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:35:03 INFO - 'enabled': True, 21:35:03 INFO - 'halt_on_failure': False, 21:35:03 INFO - 'name': 'check_screen_resolution'},), 21:35:03 INFO - 'run_cmd_checks_enabled': True, 21:35:03 INFO - 'sps_profile': False, 21:35:03 INFO - 'sps_profile_interval': 0, 21:35:03 INFO - 'suite': 'dromaeojs-e10s', 21:35:03 INFO - 'system_bits': '32', 21:35:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:35:03 INFO - 'title': 't-yosemite-r7-0113', 21:35:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:35:03 INFO - 'use_talos_json': True, 21:35:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:35:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:35:03 INFO - 'work_dir': 'build'} 21:35:03 INFO - ##### 21:35:03 INFO - ##### Running clobber step. 21:35:03 INFO - ##### 21:35:03 INFO - Running pre-action listener: _resource_record_pre_action 21:35:03 INFO - Running main action method: clobber 21:35:03 INFO - rmtree: /builds/slave/test/build 21:35:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:35:04 INFO - Running post-action listener: _resource_record_post_action 21:35:04 INFO - ##### 21:35:04 INFO - ##### Finished clobber step (success) 21:35:04 INFO - ##### 21:35:04 INFO - ##### 21:35:04 INFO - ##### Running read-buildbot-config step. 21:35:04 INFO - ##### 21:35:04 INFO - Running pre-action listener: _resource_record_pre_action 21:35:04 INFO - Running main action method: read_buildbot_config 21:35:04 INFO - Using buildbot properties: 21:35:04 INFO - { 21:35:04 INFO - "project": "", 21:35:04 INFO - "product": "firefox", 21:35:04 INFO - "script_repo_revision": "production", 21:35:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 21:35:04 INFO - "repository": "", 21:35:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 21:35:04 INFO - "buildid": "20160520181836", 21:35:04 INFO - "pgo_build": "False", 21:35:04 INFO - "basedir": "/builds/slave/test", 21:35:04 INFO - "buildnumber": 11, 21:35:04 INFO - "slavename": "t-yosemite-r7-0113", 21:35:04 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:35:04 INFO - "platform": "yosemite_r7", 21:35:04 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 21:35:04 INFO - "branch": "fx-team", 21:35:04 INFO - "repo_path": "integration/fx-team", 21:35:04 INFO - "revision": "06d9362a57a10396f3f7c7989c21f1a416f4c7e9", 21:35:04 INFO - "stage_platform": "macosx64", 21:35:04 INFO - "builduid": "dc7f2d7053d74aa08369953fe333fdb8", 21:35:04 INFO - "slavebuilddir": "test" 21:35:04 INFO - } 21:35:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 21:35:04 INFO - Running post-action listener: _resource_record_post_action 21:35:04 INFO - ##### 21:35:04 INFO - ##### Finished read-buildbot-config step (success) 21:35:04 INFO - ##### 21:35:04 INFO - ##### 21:35:04 INFO - ##### Running download-and-extract step. 21:35:04 INFO - ##### 21:35:04 INFO - Running pre-action listener: _resource_record_pre_action 21:35:04 INFO - Running main action method: download_and_extract 21:35:04 INFO - mkdir: /builds/slave/test/build/tests 21:35:04 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')]} 21:35:04 INFO - https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:35:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:35:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:35:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/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 21:35:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/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 21:35:05 INFO - Downloaded 1199 bytes. 21:35:05 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 21:35:05 INFO - Using the following test package requirements: 21:35:05 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:35:05 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:35:05 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 21:35:05 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:35:05 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 21:35:05 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:35:05 INFO - u'jsshell-mac.zip'], 21:35:05 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:35:05 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 21:35:05 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:35:05 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:35:05 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 21:35:05 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:35:05 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 21:35:05 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:35:05 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 21:35:05 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:35:05 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 21:35:05 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:05 INFO - https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:35:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:35:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:35:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/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 21:35:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/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 21:35:07 INFO - Downloaded 18630571 bytes. 21:35:07 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'] 21:35:07 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 21:35:10 INFO - Return code: 0 21:35:10 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 21:35:10 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')]} 21:35:10 INFO - https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:35:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:35:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:35:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/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 21:35:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/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 21:35:11 INFO - Downloaded 18630571 bytes. 21:35:11 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'] 21:35:11 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 21:35:15 INFO - Return code: 0 21:35:15 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')]} 21:35:15 INFO - https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:35:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:35:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:35:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/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 21:35:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/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 21:35:16 INFO - Downloaded 10840359 bytes. 21:35:16 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'] 21:35:16 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 21:35:16 INFO - Return code: 0 21:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:16 INFO - https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 21:35:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:35:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:35:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 21:35:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmWatUBRnmVa6bVpkeuVQ/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 21:35:20 INFO - Downloaded 110163801 bytes. 21:35:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:35:20 INFO - mkdir: /builds/slave/test/properties 21:35:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:35:20 INFO - Writing to file /builds/slave/test/properties/build_url 21:35:20 INFO - Contents: 21:35:20 INFO - build_url:https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:35:21 INFO - Running post-action listener: _resource_record_post_action 21:35:21 INFO - Running post-action listener: set_extra_try_arguments 21:35:21 INFO - ##### 21:35:21 INFO - ##### Finished download-and-extract step (success) 21:35:21 INFO - ##### 21:35:21 INFO - ##### 21:35:21 INFO - ##### Running populate-webroot step. 21:35:21 INFO - ##### 21:35:21 INFO - Running pre-action listener: _resource_record_pre_action 21:35:21 INFO - Running main action method: populate_webroot 21:35:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:35:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:35:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:35:21 INFO - '--tppagecycles', 21:35:21 INFO - '7'], 21:35:21 INFO - 'tests': ['tsvgm']}}, 21:35:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:35:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:35:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:35:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:35:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:35:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:35:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:21 INFO - 'talos_options': ['--disable-e10s'], 21:35:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:35:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:35:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:21 INFO - 'talos_options': ['--disable-e10s'], 21:35:21 INFO - 'tests': ['damp', 'tps']}, 21:35:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:21 INFO - 'tests': ['damp', 'tps']}, 21:35:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:35:21 INFO - 'tests': ['dromaeo_dom']}, 21:35:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:35:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:35:21 INFO - 'tests': ['a11yr', 21:35:21 INFO - 'ts_paint', 21:35:21 INFO - 'tpaint', 21:35:21 INFO - 'sessionrestore', 21:35:21 INFO - 'sessionrestore_no_auto_restore', 21:35:21 INFO - 'tabpaint']}, 21:35:21 INFO - 'other-e10s': {'tests': ['a11yr', 21:35:21 INFO - 'ts_paint', 21:35:21 INFO - 'tpaint', 21:35:21 INFO - 'sessionrestore', 21:35:21 INFO - 'sessionrestore_no_auto_restore', 21:35:21 INFO - 'tabpaint']}, 21:35:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:35:21 INFO - 'ts_paint', 21:35:21 INFO - 'tpaint', 21:35:21 INFO - 'sessionrestore', 21:35:21 INFO - 'sessionrestore_no_auto_restore', 21:35:21 INFO - 'tabpaint']}, 21:35:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:35:21 INFO - 'ts_paint', 21:35:21 INFO - 'tpaint', 21:35:21 INFO - 'sessionrestore', 21:35:21 INFO - 'sessionrestore_no_auto_restore', 21:35:21 INFO - 'tabpaint']}, 21:35:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:35:21 INFO - 'tests': ['a11yr', 21:35:21 INFO - 'ts_paint', 21:35:21 INFO - 'tpaint', 21:35:21 INFO - 'sessionrestore', 21:35:21 INFO - 'sessionrestore_no_auto_restore', 21:35:21 INFO - 'tabpaint']}, 21:35:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:35:21 INFO - 'tests': ['a11yr', 21:35:21 INFO - 'ts_paint', 21:35:21 INFO - 'tpaint', 21:35:21 INFO - 'sessionrestore', 21:35:21 INFO - 'sessionrestore_no_auto_restore', 21:35:21 INFO - 'tabpaint']}, 21:35:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:35:21 INFO - 'tests': ['tsvgx', 21:35:21 INFO - 'tsvgr_opacity', 21:35:21 INFO - 'tart', 21:35:21 INFO - 'tscrollx', 21:35:21 INFO - 'cart']}, 21:35:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:35:21 INFO - 'tsvgr_opacity', 21:35:21 INFO - 'tart', 21:35:21 INFO - 'tscrollx', 21:35:21 INFO - 'cart']}, 21:35:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:21 INFO - 'talos_options': ['--disable-e10s'], 21:35:21 INFO - 'tests': ['tp5o']}, 21:35:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:21 INFO - 'tests': ['tp5o']}, 21:35:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:21 INFO - 'talos_options': ['--disable-e10s', 21:35:21 INFO - '--xperf_path', 21:35:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:35:21 INFO - 'tests': ['tp5n']}, 21:35:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:35:21 INFO - 'talos_options': ['--xperf_path', 21:35:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:35:21 INFO - 'tests': ['tp5n']}}, 21:35:21 INFO - 'talos.zip': {'path': '', 21:35:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:35:21 INFO - Running post-action listener: _resource_record_post_action 21:35:21 INFO - ##### 21:35:21 INFO - ##### Finished populate-webroot step (success) 21:35:21 INFO - ##### 21:35:21 INFO - ##### 21:35:21 INFO - ##### Running create-virtualenv step. 21:35:21 INFO - ##### 21:35:21 INFO - Running pre-action listener: _resource_record_pre_action 21:35:21 INFO - Running main action method: create_virtualenv 21:35:21 INFO - Creating virtualenv /builds/slave/test/build/venv 21:35:21 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 21:35:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:35:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:35:21 INFO - Using real prefix '/tools/python27' 21:35:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:35:22 INFO - Installing distribute.............................................................................................................................................................................................done. 21:35:25 INFO - Installing pip.................done. 21:35:25 INFO - Return code: 0 21:35:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:35:25 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')]} 21:35:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:25 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')]} 21:35:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:25 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 0x107e62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcca5572a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d06d30>, '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 0x107b2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cfdb20>, '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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 21:35:25 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 21:35:25 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 21:35:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OpLLqkNZH0/Render', 21:35:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:25 INFO - 'HOME': '/Users/cltbld', 21:35:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:25 INFO - 'LOGNAME': 'cltbld', 21:35:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:25 INFO - 'MOZ_NO_REMOTE': '1', 21:35:25 INFO - 'NO_EM_RESTART': '1', 21:35:25 INFO - 'PAGER': '/bin/cat', 21:35:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:25 INFO - 'PWD': '/builds/slave/test', 21:35:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:25 INFO - 'SHELL': '/bin/bash', 21:35:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mIPwHMjlyy/Listeners', 21:35:25 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 21:35:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:25 INFO - 'USER': 'cltbld', 21:35:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:25 INFO - 'XPC_FLAGS': '0x0', 21:35:25 INFO - 'XPC_SERVICE_NAME': '0', 21:35:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:25 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 21:35:25 INFO - Cleaning up... 21:35:25 INFO - Return code: 0 21:35:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:35:25 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')]} 21:35:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:25 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')]} 21:35:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:25 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 0x107e62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcca5572a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d06d30>, '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 0x107b2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cfdb20>, '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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 21:35:25 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 21:35:25 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 21:35:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OpLLqkNZH0/Render', 21:35:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:25 INFO - 'HOME': '/Users/cltbld', 21:35:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:25 INFO - 'LOGNAME': 'cltbld', 21:35:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:25 INFO - 'MOZ_NO_REMOTE': '1', 21:35:25 INFO - 'NO_EM_RESTART': '1', 21:35:25 INFO - 'PAGER': '/bin/cat', 21:35:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:25 INFO - 'PWD': '/builds/slave/test', 21:35:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:25 INFO - 'SHELL': '/bin/bash', 21:35:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mIPwHMjlyy/Listeners', 21:35:25 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 21:35:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:25 INFO - 'USER': 'cltbld', 21:35:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:25 INFO - 'XPC_FLAGS': '0x0', 21:35:25 INFO - 'XPC_SERVICE_NAME': '0', 21:35:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:25 INFO - Downloading/unpacking psutil>=3.1.1 21:35:25 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 21:35:25 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 21:35:25 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 21:35:25 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 21:35:25 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 21:35:25 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 21:35:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:35:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:35:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:35:29 INFO - Installing collected packages: psutil 21:35:29 INFO - Running setup.py install for psutil 21:35:29 INFO - building 'psutil._psutil_osx' extension 21:35:29 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 21:35:29 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 21:35:29 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 21:35:29 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 21:35:29 INFO - building 'psutil._psutil_posix' extension 21:35:29 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 21:35:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:35:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:35:29 INFO - ^ 21:35:29 INFO - 1 warning generated. 21:35:29 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 21:35:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:35:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:35:30 INFO - Successfully installed psutil 21:35:30 INFO - Cleaning up... 21:35:30 INFO - Return code: 0 21:35:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:35:30 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')]} 21:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:30 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')]} 21:35:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:30 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 0x107e62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcca5572a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d06d30>, '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 0x107b2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cfdb20>, '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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 21:35:30 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 21:35:30 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 21:35:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OpLLqkNZH0/Render', 21:35:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:30 INFO - 'HOME': '/Users/cltbld', 21:35:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:30 INFO - 'LOGNAME': 'cltbld', 21:35:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:30 INFO - 'MOZ_NO_REMOTE': '1', 21:35:30 INFO - 'NO_EM_RESTART': '1', 21:35:30 INFO - 'PAGER': '/bin/cat', 21:35:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:30 INFO - 'PWD': '/builds/slave/test', 21:35:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:30 INFO - 'SHELL': '/bin/bash', 21:35:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mIPwHMjlyy/Listeners', 21:35:30 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 21:35:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:30 INFO - 'USER': 'cltbld', 21:35:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:30 INFO - 'XPC_FLAGS': '0x0', 21:35:30 INFO - 'XPC_SERVICE_NAME': '0', 21:35:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:35:30 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 21:35:30 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 21:35:30 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 21:35:30 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 21:35:30 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 21:35:30 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 21:35:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:35:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:35:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:35:32 INFO - Installing collected packages: mozsystemmonitor 21:35:32 INFO - Running setup.py install for mozsystemmonitor 21:35:33 INFO - Successfully installed mozsystemmonitor 21:35:33 INFO - Cleaning up... 21:35:33 INFO - Return code: 0 21:35:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:35:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:33 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 0x107e62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcca5572a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d06d30>, '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 0x107b2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cfdb20>, '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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 21:35:33 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 21:35:33 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 21:35:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OpLLqkNZH0/Render', 21:35:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:33 INFO - 'HOME': '/Users/cltbld', 21:35:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:33 INFO - 'LOGNAME': 'cltbld', 21:35:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:33 INFO - 'MOZ_NO_REMOTE': '1', 21:35:33 INFO - 'NO_EM_RESTART': '1', 21:35:33 INFO - 'PAGER': '/bin/cat', 21:35:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:33 INFO - 'PWD': '/builds/slave/test', 21:35:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:33 INFO - 'SHELL': '/bin/bash', 21:35:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mIPwHMjlyy/Listeners', 21:35:33 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 21:35:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:33 INFO - 'USER': 'cltbld', 21:35:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:33 INFO - 'XPC_FLAGS': '0x0', 21:35:33 INFO - 'XPC_SERVICE_NAME': '0', 21:35:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:33 INFO - Downloading/unpacking blobuploader==1.2.4 21:35:33 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 21:35:33 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 21:35:33 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 21:35:33 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 21:35:33 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 21:35:33 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 21:35:35 INFO - Downloading blobuploader-1.2.4.tar.gz 21:35:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:35:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:35:35 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 21:35:35 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 21:35:35 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 21:35:35 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 21:35:35 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 21:35:35 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 21:35:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:35:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:35:36 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 21:35:36 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 21:35:36 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 21:35:36 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 21:35:36 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 21:35:36 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 21:35:36 INFO - Downloading docopt-0.6.1.tar.gz 21:35:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:35:37 INFO - Installing collected packages: blobuploader, requests, docopt 21:35:37 INFO - Running setup.py install for blobuploader 21:35:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:35:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:35:37 INFO - Running setup.py install for requests 21:35:37 INFO - Running setup.py install for docopt 21:35:37 INFO - Successfully installed blobuploader requests docopt 21:35:37 INFO - Cleaning up... 21:35:37 INFO - Return code: 0 21:35:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:35: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')]} 21:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35: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')]} 21:35:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:37 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 0x107e62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcca5572a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d06d30>, '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 0x107b2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cfdb20>, '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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 21:35:37 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 21:35:37 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 21:35:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OpLLqkNZH0/Render', 21:35:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:37 INFO - 'HOME': '/Users/cltbld', 21:35:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:37 INFO - 'LOGNAME': 'cltbld', 21:35:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:37 INFO - 'MOZ_NO_REMOTE': '1', 21:35:37 INFO - 'NO_EM_RESTART': '1', 21:35:37 INFO - 'PAGER': '/bin/cat', 21:35:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:37 INFO - 'PWD': '/builds/slave/test', 21:35:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:37 INFO - 'SHELL': '/bin/bash', 21:35:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mIPwHMjlyy/Listeners', 21:35:37 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 21:35:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:37 INFO - 'USER': 'cltbld', 21:35:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:37 INFO - 'XPC_FLAGS': '0x0', 21:35:37 INFO - 'XPC_SERVICE_NAME': '0', 21:35:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:35:38 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-YKKsSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:35:38 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-UHmeNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:35:38 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-eho1V1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:35:38 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-jlWXPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:35:38 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-VMweq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:35:38 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-RAgalR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:35:38 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-zKvBNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:35:38 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-iJs4P9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:35:39 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-bq7jRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:35:39 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-gw3K_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:35:39 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ng8e6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:35:39 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-zrN4WE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:35:39 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-OLb6yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:35:39 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-7fiq1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:35:39 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-sZi00l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:35:39 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-v1fsSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:35:39 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-NA_k4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:35:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:35:40 INFO - Running setup.py install for manifestparser 21:35:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:35:40 INFO - Running setup.py install for mozcrash 21:35:40 INFO - Running setup.py install for mozdebug 21:35:40 INFO - Running setup.py install for mozdevice 21:35:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:35:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:35:40 INFO - Running setup.py install for mozfile 21:35:40 INFO - Running setup.py install for mozhttpd 21:35:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:35:40 INFO - Running setup.py install for mozinfo 21:35:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:35:41 INFO - Running setup.py install for mozInstall 21:35:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:35:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:35:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:35:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:35:41 INFO - Running setup.py install for mozleak 21:35:41 INFO - Running setup.py install for mozlog 21:35:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:35:41 INFO - Running setup.py install for moznetwork 21:35:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:35:41 INFO - Running setup.py install for mozprocess 21:35:41 INFO - Running setup.py install for mozprofile 21:35:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:35:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:35:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:35:41 INFO - Running setup.py install for mozrunner 21:35:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:35:42 INFO - Running setup.py install for mozscreenshot 21:35:42 INFO - Running setup.py install for moztest 21:35:42 INFO - Running setup.py install for mozversion 21:35:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:35:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:35:42 INFO - Cleaning up... 21:35:42 INFO - Return code: 0 21:35:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:35:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:42 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 0x107e62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcca5572a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d06d30>, '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 0x107b2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cfdb20>, '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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 21:35:42 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 21:35:42 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 21:35:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OpLLqkNZH0/Render', 21:35:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:42 INFO - 'HOME': '/Users/cltbld', 21:35:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:42 INFO - 'LOGNAME': 'cltbld', 21:35:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:42 INFO - 'MOZ_NO_REMOTE': '1', 21:35:42 INFO - 'NO_EM_RESTART': '1', 21:35:42 INFO - 'PAGER': '/bin/cat', 21:35:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:42 INFO - 'PWD': '/builds/slave/test', 21:35:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:42 INFO - 'SHELL': '/bin/bash', 21:35:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mIPwHMjlyy/Listeners', 21:35:42 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 21:35:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:42 INFO - 'USER': 'cltbld', 21:35:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:42 INFO - 'XPC_FLAGS': '0x0', 21:35:42 INFO - 'XPC_SERVICE_NAME': '0', 21:35:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:35:42 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-aEfK4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:35:42 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)) 21:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:35:42 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-1r48CA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:35:43 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)) 21:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:35:43 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-SAPRgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:35:43 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)) 21:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:35:43 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-1bLgnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:35:43 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)) 21:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:35:43 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-KTvdxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:35:43 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)) 21:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:35:43 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-avmYKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:35:43 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)) 21:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:35:43 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-gmPmvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:35:43 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)) 21:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:35:43 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Vqj9Bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:35:43 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)) 21:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:35:43 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-0s85KG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:35:43 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)) 21:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:35:43 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-UqMfNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:35:43 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)) 21:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:35:43 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-umMaOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:35:44 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)) 21:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:35:44 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-bwnzXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:35:44 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)) 21:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:35:44 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-EMGndh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:35:44 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)) 21:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:35:44 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-rVELxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:35:44 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)) 21:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:35:44 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-zTVfEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:35:44 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)) 21:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:35:44 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ovwyCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:35:44 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)) 21:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:35:44 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-i0Olme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:35:44 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)) 21:35:44 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)) 21:35:44 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)) 21:35:44 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)) 21:35:44 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)) 21:35:44 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)) 21:35:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:35: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 21:35: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 21:35: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 21:35: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 21:35: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 21:35: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 21:35:47 INFO - Downloading blessings-1.6.tar.gz 21:35:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:35:47 INFO - Installing collected packages: blessings 21:35:47 INFO - Running setup.py install for blessings 21:35:47 INFO - Successfully installed blessings 21:35:47 INFO - Cleaning up... 21:35:47 INFO - Return code: 0 21:35:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:35:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:35:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:35:47 INFO - Reading from file tmpfile_stdout 21:35:47 INFO - Current package versions: 21:35:47 INFO - blessings == 1.6 21:35:47 INFO - blobuploader == 1.2.4 21:35:47 INFO - docopt == 0.6.1 21:35:47 INFO - manifestparser == 1.1 21:35:47 INFO - mozInstall == 1.12 21:35:47 INFO - mozcrash == 0.17 21:35:47 INFO - mozdebug == 0.1 21:35:47 INFO - mozdevice == 0.48 21:35:47 INFO - mozfile == 1.2 21:35:47 INFO - mozhttpd == 0.7 21:35:47 INFO - mozinfo == 0.9 21:35:47 INFO - mozleak == 0.1 21:35:47 INFO - mozlog == 3.2 21:35:47 INFO - moznetwork == 0.27 21:35:47 INFO - mozprocess == 0.22 21:35:47 INFO - mozprofile == 0.28 21:35:47 INFO - mozrunner == 6.11 21:35:47 INFO - mozscreenshot == 0.1 21:35:47 INFO - mozsystemmonitor == 0.3 21:35:47 INFO - moztest == 0.7 21:35:47 INFO - mozversion == 1.4 21:35:47 INFO - psutil == 3.1.1 21:35:47 INFO - requests == 1.2.3 21:35:47 INFO - wsgiref == 0.1.2 21:35:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:35:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:47 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 0x107e62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcca5572a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d06d30>, '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 0x107b2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cfdb20>, '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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 21:35:47 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 21:35:47 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 21:35:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OpLLqkNZH0/Render', 21:35:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:47 INFO - 'HOME': '/Users/cltbld', 21:35:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:47 INFO - 'LOGNAME': 'cltbld', 21:35:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:47 INFO - 'MOZ_NO_REMOTE': '1', 21:35:47 INFO - 'NO_EM_RESTART': '1', 21:35:47 INFO - 'PAGER': '/bin/cat', 21:35:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:47 INFO - 'PWD': '/builds/slave/test', 21:35:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:47 INFO - 'SHELL': '/bin/bash', 21:35:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mIPwHMjlyy/Listeners', 21:35:47 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 21:35:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:47 INFO - 'USER': 'cltbld', 21:35:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:47 INFO - 'XPC_FLAGS': '0x0', 21:35:47 INFO - 'XPC_SERVICE_NAME': '0', 21:35:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:48 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)) 21:35:48 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)) 21:35:48 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)) 21:35:48 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)) 21:35:48 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)) 21:35:48 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)) 21:35:48 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)) 21:35:48 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)) 21:35:48 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)) 21:35:48 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)) 21:35:48 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)) 21:35:48 INFO - Cleaning up... 21:35:48 INFO - Return code: 0 21:35:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:35:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:48 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 0x107e62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcca5572a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d06d30>, '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 0x107b2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cfdb20>, '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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 21:35:48 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 21:35:48 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 21:35:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OpLLqkNZH0/Render', 21:35:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:48 INFO - 'HOME': '/Users/cltbld', 21:35:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:48 INFO - 'LOGNAME': 'cltbld', 21:35:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:48 INFO - 'MOZ_NO_REMOTE': '1', 21:35:48 INFO - 'NO_EM_RESTART': '1', 21:35:48 INFO - 'PAGER': '/bin/cat', 21:35:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:48 INFO - 'PWD': '/builds/slave/test', 21:35:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:48 INFO - 'SHELL': '/bin/bash', 21:35:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mIPwHMjlyy/Listeners', 21:35:48 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 21:35:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:48 INFO - 'USER': 'cltbld', 21:35:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:48 INFO - 'XPC_FLAGS': '0x0', 21:35:48 INFO - 'XPC_SERVICE_NAME': '0', 21:35:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:48 INFO - Downloading/unpacking jsonschema 21:35:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:35:50 INFO - Downloading/unpacking functools32 (from jsonschema) 21:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:51 INFO - Downloading functools32-3.2.3-2.tar.gz 21:35:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:35:51 INFO - warning: no files found matching '*.txt' 21:35:51 INFO - no previously-included directories found matching 'build' 21:35:51 INFO - no previously-included directories found matching 'dist' 21:35:51 INFO - no previously-included directories found matching '.git*' 21:35:51 INFO - Installing collected packages: jsonschema, functools32 21:35:51 INFO - Running setup.py install for functools32 21:35:51 INFO - warning: no files found matching '*.txt' 21:35:51 INFO - no previously-included directories found matching 'build' 21:35:51 INFO - no previously-included directories found matching 'dist' 21:35:51 INFO - no previously-included directories found matching '.git*' 21:35:51 INFO - Successfully installed jsonschema functools32 21:35:51 INFO - Cleaning up... 21:35:51 INFO - Return code: 0 21:35:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:35:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:35:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:35:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcca5572a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d06d30>, '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 0x107b2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cfdb20>, '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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 21:35:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 21:35:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 21:35:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OpLLqkNZH0/Render', 21:35:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:51 INFO - 'HOME': '/Users/cltbld', 21:35:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:51 INFO - 'LOGNAME': 'cltbld', 21:35:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:51 INFO - 'MOZ_NO_REMOTE': '1', 21:35:51 INFO - 'NO_EM_RESTART': '1', 21:35:51 INFO - 'PAGER': '/bin/cat', 21:35:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:51 INFO - 'PWD': '/builds/slave/test', 21:35:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:51 INFO - 'SHELL': '/bin/bash', 21:35:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mIPwHMjlyy/Listeners', 21:35:51 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 21:35:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:51 INFO - 'USER': 'cltbld', 21:35:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:51 INFO - 'XPC_FLAGS': '0x0', 21:35:51 INFO - 'XPC_SERVICE_NAME': '0', 21:35:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:51 INFO - Downloading/unpacking flake8 21:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:35:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:35:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:35:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:35:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:35:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:35:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:35:55 INFO - no previously-included directories found matching 'docs/_build' 21:35:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:35:55 INFO - Running setup.py install for pep8 21:35:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:35:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:35:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:35:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:35:55 INFO - no previously-included directories found matching 'docs/_build' 21:35:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:35:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:35:55 INFO - Cleaning up... 21:35:55 INFO - Return code: 0 21:35:55 INFO - Running post-action listener: _resource_record_post_action 21:35:55 INFO - Running post-action listener: _start_resource_monitoring 21:35:55 INFO - Starting resource monitoring. 21:35:55 INFO - ##### 21:35:55 INFO - ##### Finished create-virtualenv step (success) 21:35:55 INFO - ##### 21:35:55 INFO - ##### 21:35:55 INFO - ##### Running install step. 21:35:55 INFO - ##### 21:35:55 INFO - Running pre-action listener: _resource_record_pre_action 21:35:55 INFO - Running main action method: install 21:35:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:35:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:35:56 INFO - Reading from file tmpfile_stdout 21:35:56 INFO - Detecting whether we're running mozinstall >=1.0... 21:35:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:35:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:35:56 INFO - Reading from file tmpfile_stdout 21:35:56 INFO - Output received: 21:35:56 INFO - Usage: mozinstall [options] installer 21:35:56 INFO - Options: 21:35:56 INFO - -h, --help show this help message and exit 21:35:56 INFO - -d DEST, --destination=DEST 21:35:56 INFO - Directory to install application into. [default: 21:35:56 INFO - "/builds/slave/test"] 21:35:56 INFO - --app=APP Application being installed. [default: firefox] 21:35:56 INFO - mkdir: /builds/slave/test/build/application 21:35:56 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'] 21:35:56 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 21:36:29 INFO - Reading from file tmpfile_stdout 21:36:29 INFO - Output received: 21:36:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:36:29 INFO - Running post-action listener: _resource_record_post_action 21:36:29 INFO - ##### 21:36:29 INFO - ##### Finished install step (success) 21:36:29 INFO - ##### 21:36:29 INFO - ##### 21:36:29 INFO - ##### Running run-tests step. 21:36:29 INFO - ##### 21:36:29 INFO - Running pre-action listener: _resource_record_pre_action 21:36:29 INFO - Running main action method: run_tests 21:36:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:36:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:36:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:36:29 INFO - 2016-05-20 21:36:29.674 screenresolution[1948:13878] starting screenresolution argv=screenresolution get 21:36:29 INFO - 2016-05-20 21:36:29.677 screenresolution[1948:13878] Display 0: 1600x1200x32@60 21:36:29 INFO - 2016-05-20 21:36:29.684 screenresolution[1949:13880] starting screenresolution argv=screenresolution list 21:36:29 INFO - Available Modes on Display 0 21:36:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:36:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:36:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:36:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:36:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:36:29 INFO - Intel Iris: 21:36:29 INFO - Chipset Model: Intel Iris 21:36:29 INFO - Type: GPU 21:36:29 INFO - Bus: Built-In 21:36:29 INFO - VRAM (Dynamic, Max): 1536 MB 21:36:29 INFO - Vendor: Intel (0x8086) 21:36:29 INFO - Device ID: 0x0a2e 21:36:29 INFO - Revision ID: 0x0009 21:36:29 INFO - Displays: 21:36:29 INFO - Display: 21:36:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:36:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:36:29 INFO - Display Serial Number: 1600x1200 60 21:36:29 INFO - Main Display: Yes 21:36:29 INFO - Mirror: Off 21:36:29 INFO - Online: Yes 21:36:29 INFO - Rotation: Supported 21:36:29 INFO - Return code: 0 21:36:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:36:29 INFO - Python 2.7.3 21:36:29 INFO - Return code: 0 21:36:29 INFO - grabbing minidump binary from tooltool 21:36:29 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')]} 21:36:29 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 0x107d06d30>, '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 0x107b2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cfdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:36:29 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 21:36:29 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 21:36:29 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 21:36:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:36:30 INFO - Return code: 0 21:36:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:36:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:36:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:36:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:36:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:36:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:36:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:36:31 INFO - Return code: 0 21:36:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0113 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:36:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OpLLqkNZH0/Render', 21:36:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:36:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:36:31 INFO - 'HOME': '/Users/cltbld', 21:36:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:36:31 INFO - 'LOGNAME': 'cltbld', 21:36:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:36:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:31 INFO - 'MOZ_NO_REMOTE': '1', 21:36:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:36:31 INFO - 'NO_EM_RESTART': '1', 21:36:31 INFO - 'PAGER': '/bin/cat', 21:36:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:31 INFO - 'PWD': '/builds/slave/test', 21:36:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:36:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:36:31 INFO - 'SHELL': '/bin/bash', 21:36:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mIPwHMjlyy/Listeners', 21:36:31 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 21:36:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:36:31 INFO - 'USER': 'cltbld', 21:36:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:36:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:31 INFO - 'XPC_FLAGS': '0x0', 21:36:31 INFO - 'XPC_SERVICE_NAME': '0', 21:36:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:36:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:36:31 INFO - mozversion application_buildid: 20160520181836 21:36:31 INFO - mozversion application_changeset: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 21:36:31 INFO - mozversion application_display_name: Nightly 21:36:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:36:31 INFO - mozversion application_name: Firefox 21:36:31 INFO - mozversion application_remotingname: firefox 21:36:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 21:36:31 INFO - mozversion application_vendor: Mozilla 21:36:31 INFO - mozversion application_version: 49.0a1 21:36:31 INFO - mozversion platform_buildid: 20160520181836 21:36:31 INFO - mozversion platform_changeset: 06d9362a57a10396f3f7c7989c21f1a416f4c7e9 21:36:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 21:36:31 INFO - mozversion platform_version: 49.0a1 21:36:31 INFO - using testdate: 1463805391 21:36:31 INFO - actual date: 1463805391 21:36:31 INFO - starting webserver on 'localhost:49663' 21:36:31 INFO - SUITE-START | Running 2 tests 21:36:31 INFO - TEST-START | dromaeo_css 21:36:31 INFO - operating with platform_type : mac_ 21:36:31 INFO - Initialising browser for dromaeo_css test... 21:36:31 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpTSiqYM/profile http://localhost:49663/getInfo.html) 21:36:34 INFO - PROCESS | 1967 | __metrics Screen width/height:1600/1200 21:36:34 INFO - PROCESS | 1967 | colorDepth:24 21:36:34 INFO - PROCESS | 1967 | Browser inner width/height: 1024/689 21:36:34 INFO - PROCESS | 1967 | __metrics 21:36:34 ERROR - PROCESS | 1967 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 21:36:35 INFO - TEST-INFO | 1967: exit 0 21:36:35 INFO - Browser initialized. 21:36:35 INFO - Running cycle 1/1 for dromaeo_css test... 21:36:35 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpTSiqYM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:36:47 INFO - PROCESS | 1970 | RSS: Main: 249700352 21:36:47 INFO - PROCESS | 1970 | 21:37:36 INFO - PROCESS | 1970 | 1463805456654 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] 21:39:47 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:49663/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49663/tests/dromaeo/cssquery-ext.html) 21:39:49 INFO - PROCESS | 1970 | RSS: Main: 260943872 21:39:49 INFO - PROCESS | 1970 | 21:42:51 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:49663/tests/dromaeo/cssquery-ext.html (next: http://localhost:49663/tests/dromaeo/cssquery-jquery.html) 21:42:51 INFO - PROCESS | 1970 | RSS: Main: 273031168 21:42:51 INFO - PROCESS | 1970 | 21:45:53 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:49663/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49663/tests/dromaeo/cssquery-mootools.html) 21:45:54 INFO - PROCESS | 1970 | RSS: Main: 275267584 21:45:54 INFO - PROCESS | 1970 | 21:48:56 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:49663/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49663/tests/dromaeo/cssquery-prototype.html) 21:48:56 INFO - PROCESS | 1970 | RSS: Main: 276758528 21:48:56 INFO - PROCESS | 1970 | 21:51:59 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:49663/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49663/tests/dromaeo/cssquery-yui.html) 21:52:00 INFO - PROCESS | 1970 | RSS: Main: 280006656 21:52:00 INFO - PROCESS | 1970 | 21:55:02 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:49663/tests/dromaeo/cssquery-yui.html (next: http://localhost:49663/tests/dromaeo/cssquery-dojo.html) 21:55:02 INFO - PROCESS | 1970 | RSS: Main: 280948736 21:55:02 INFO - PROCESS | 1970 | 21:55:02 INFO - PROCESS | 1970 | __start_tp_report 21:55:02 INFO - PROCESS | 1970 | _x_x_mozilla_page_load 21:55:02 INFO - PROCESS | 1970 | _x_x_mozilla_page_load_details 21:55:02 INFO - PROCESS | 1970 | |i|pagename|runs| 21:55:02 INFO - PROCESS | 1970 | |0;dojo.html;2770.6952235254125;3515.964840351597;3535.505029295898;3553.9160556030433;3579.785212887227;5898.380670029649;5914.940850591487;5960.970195149018;5978.8206353809455;6010.699465026753;5738.885222295552;5740.569457290699;5800.53595712343;5827.475527202551;5926.051831706928;5301.496357845998;5323.866903327419;5358.705271210082;5483.862903427426;5544.22380866679;4730.739809310487;5408.296921400212;5508.4491550845;5546.972265138668;5608.383077861432;5353.7590808413715;5388.892222155535;5429.375621803464;5436.184572314145;5440.972795136019;4479.014616784302;5421.810236641699;5443.129099344125;5445.754941027663;5466.1527463243265;5379.81170659029;5406.91889621656;5445.37378201504;5478.753456094445;5480.287562616874;4351.912961740747;5728.22668939692;5791.73937172824;5871.1193321001765;5918.260217472816;6229.127922090911;6327.24073111225;6360.650164240965;6368.14030105939;6368.554201205918;6269.655168965705;6359.904601430982;6364.172657554488;6366.013267943476;6370.362963703593;5948.940510594882;6012.458878700894;6057.394260573908;6059.0608455689435;6059.939400605982;6358.415959624099;6425.518086143465;6431.292557818636;6461.386168314003;6462.935370646233;6206.782762603378;6235.376462353728;6334.429901308904;6341.23905131378;6383.71273292703;6250.781222657185;6284.591501552384;6357.141785858942;6358.618482891041;6359.713812878432;6236.844078898064;6237.376262373817;6300.873982520324;6318.968405157945;6370.362963703593;5746.741396637161;6233.812985610439;6279.780207692709;6354.586951848115;6376.075469056961;5040.765012571944;5068.746562671851;5074.035933172685;5084.745762711849;5125.846224613268;4328.242557552416;4563.54364563541;4602.309653551994;4612.377329060601;4612.838550650711;5855.824325270249;5973.522118230531;6039.909401358983;6074.088886667036;6124.693765311806;4706.8117275308605;6276.435120839146;6290.748370065237;6294.8111556653375;6300.180976473029;5858.2677165354335;5887.764489420375;6070.150178974947;6076.453119219292;6087.29996050451;5472.315960504937;5534.944650553523;5541.445855414427;5542.501174894279;5583.134614134816;6484.351564843572;6527.379898909596;6536.477081833537;6553.901691474632;6563.278039415756;6322.146510221061;6322.715477803602;6326.145970293951;6333.20834895638;6353.809385718436;5862.618929769551;6082.72627731762;6294.968525157345;6470.38531339522;6542.803715888531;6245.594036387621;6270.216222972129;6341.175647165839;6350.269718982377;6352.491800656029;6054.789042191667;6319.210098737658;6331.620102793847;6331.746730130743;6339.366063393623;5162.457941916045;6192.318844927145;6214.347493513116;6243.406876346739;6245.937540624535;4817.518248175154;6299.685015749103;6304.054391841077;6322.778702745566;6345.587536810092;6057.606255593372;6152.723127459365;6183.505319574513;6190.41191086846;6219.036049412348;5076.36545431821;6122.093225952662;6211.440970312694;6262.5929314594405;6288.308286088435;6200.782972595937;6215.285242197025;6222.968885155546;6233.127362169209;6237.5321850860455 21:55:02 INFO - PROCESS | 1970 | |1;ext.html;499.32591002147103;512.1396054628224;530.8115618955271;537.8225185688723;564.6781334639257;39741;42008.57991420085;42626.14747705044;42691.719248422596;42819;42982.35526467105;43611.34582981258;43700.78149609255;43770.781146094225;43864;9570.66502672405;9715;9774.609015639402;9783.168430683401;9790.412575245473;9579.377340472882;9628.518574071304;9674.177694895941;9677.70966870995;9681.854772178423;9617.182539484118;9664.4201347919;9708.41749495029;9715.514224288792;9719.854202186973;9625.037496250354;9686.225101991824;9693.030696930286;9711.980242074622;9716.028397160262;9036.186743193079;9115.22520585751;9557.139857412867;9690.37012594179;9706.174975127122;40935.59064409348;40974.1805163898;41193.794031029654;41423.7928810354;41501.79249103765;3128.5307203919365;3136.5452009458727;3332.6667333266723;3472.2708231271463;3504.859805607798;28356.00753973601;29328.853355733085;29531.114066578037;30050.54924176139;30701.385972280652;2760.2823265950797;2770.4043630619312;2842.1757690269797;2850.50116229659;2908.476474234637;524267;536155;537863;538325.3083734595;538790;35026.9491915243;35828.74599389061;36084;66237.33762662408;66729.99810005707;36483.635163648556;36785.08037299089;37157.628423715956;37172.256554869295;37381.43927841084;15222.46721364747;15373.154476503676;15380.30788614515;15387.999780014263;15438.845611543966;12702.872971270353;12865.614031579067;12897.613071607866;12906.161099528501;12928.224306541637;6646.335366463315;7294.270572942663;7772.261635144652;7916.406269529808;8070.587647161723;3288.408086544397;3322.9501557476656;3351.916202094967;3355.647656996029;3374.9493757593164;938.7653086728318;993.3543196922059;997.002997002997;1024.8565200871883;1105.8894110589038;29066.854665726536;29368.559471607943;29568.260793479905;29763.40473190524;30018;824.8391563645032;989.4854675568807;1067.4555976451911;1086.5165001574223;1146.7591805720724;12845.61463156088;14595.708085838436;14937.70124597502;14970.850291497163;15191.544253672408;688834;699008;707408;710691;715007;62548.06177907335;63000;63014;63191.36808631855;63430.68284658547;1456.3446449097949;1461.0649184521915;1477.2761346940138;1479.400842658725;1494.8056752622308;28129;28207.57688634631;28899.988500402385;28982;29360.11919642414;52359.95280094453;53867.19199211936;54102;54308.45691543192;54592.181117282475;612928;613913;619068;619361;619450;26114;26189.869050654626;26232.73767262303;26295.737042629327;27284.727152729014;37143.814280928425;47673;48522.5147748518;48769.26846097382;49104.508954909994;34091.82954085313;38319.23361532809;38832;39504.60495395009;40049.59950400459;36241.63758362383;38285.2342953145;38875.6112438872;39255.60744392634;39384.6061539381;33438.33123337471;35804.64195358013;37556.21519125544;37914;38166;33100.83449582833;33988;34551.65448345484;34582.654173457944;34595.4810677835;18511.629767404847;24495.77521123901;24597.262082137564;24833.62749558794;25489.872550637127 21:55:02 INFO - PROCESS | 1970 | |2;jquery.html;375.72196574534837;382.2069206396726;383.78316251318006;397.25514660012476;404.29854202957864;19178.42464726059;21234.575308493822;21337.35987920363;21561;22061.558768824634;19868.801311986877;20894.955252237367;20945.847978361184;20991;21569.46076348092;5100.234964755288;5360;5442.482964118422;5445.591580631449;5453.23654688344;3881.5342158940966;5335.839924802262;5683.943160568383;6017.548683848729;6019.608725432855;6031.668258245794;6066.09008648704;6084.939150608504;6087.147799308101;6097.8170654880205;5467.371252305996;5754.510866576346;5835.912461313083;5837.066069428893;5921.911171332434;5600.271964644611;5712.1431785232135;5722.713864306768;5886.6173698709445;5898.616589921641;12779.169353991962;13065.804012939814;13252.933735331262;13281.86718132816;13327.800082998763;1900.8574356923148;2139.379579921821;2143.121320258702;2212.1372664660794;2309.5265470578493;9719.076687714656;9736.707898763048;9817.214622830228;9962.003799620052;10179.03299186576;1756.6223261998732;1758.3581992572747;1766.7084930986373;1804.7112462006148;1839.7792264928294;111091;115400.42299788447;115448;115725;118407.4079629605;9320.440773553608;11027.22809403334;11481.540738370371;11650.88349116498;11670.183087183816;14524;14622.780658289921;14685.559433216808;14697.632559186106;14700.485483008044;8429.620667069996;8574.914250857537;8630.741077767678;8693.913060869438;8708.346873984476;7758.379329653614;7893.684252629945;8518.318534517224;8529.189726975927;8656.78358041054;3432.8626854925524;4533.54664533544;4592.494825569184;4610.2854057621125;4639.350490931272;1769.4780039888265;1819.954501137482;1927.2291083566686;1927.4506765572025;1957.8237958583795;890.9955450222708;928.2852203803034;966.1642679082543;1010.6412223660494;1036.6009086501751;15307;17742.29030838778;18744.625107498046;19776.505587360145;19872.205111795367;975.2198241406988;993.7267251505893;1024.4826362686795;1050.975299083403;1077.8275475923815;10400.843987340197;10548.736281592868;10609.363438193734;10625.628103016512;10662.89337106619;627595;634882;635663;640450;649217;129969;131151;131271.34364328117;131898.34050829685;137070;883.8143989762093;1006.9798604027878;1026.6817286641165;1043.3218408034838;1050.7950949564915;15680.353562875734;16932.576685582466;17648.55878603045;17923.910380448015;18084.819151808315;51803;52357.21464178117;52379;52511.73744131408;52678.73660631672;72430.63784681042;74545.6272718633;75948.62025689836;76615.61692191503;77199;7692.884456774432;7806.843863122819;7987;8044.1955804419085;8259.545724985184;10445.895541044492;11683.415829208336;12334.938325308674;12615.36923153857;12682.365881706062;10703.892961070289;10786.460676966277;11155.27490713101;11222.831657525307;11349.978501934866;10651.946740266249;10692.14462842955;10722.571097156339;10835.9164083591;11000.009999099799;5490.917636235539;9732.637430759842;10985.340879547253;11281.605143819927;11432.256903301259;7808.882866757117;8075.313598344206;8080.383923215498;8207.917920820715;8238.052623948082;8124.959375203322;10585.417802020655;10809.729756756144;11118.61084862026;11198.944005279922 21:55:02 INFO - PROCESS | 1970 | |3;mootools.html;1555.9999999999995;1603.3987254779458;1802.6304607555444;1878.8027257137992;1910.5892233169866;7990.280874721274;8527.530985795778;8532.189442003015;8980.461172329664;9020.962589302233;8608.913910860889;8675.08911564286;8793.648254069829;8841.513716745592;8855;2371.5494056129364;2376.3346263046287;2410.8553486790847;2418.0811291709215;2422.2854257993845;2383.6782034425305;2386.8090552755825;2398.124684490166;2423.5758742220128;2431.8297719159664;2377.679013333195;2379.2029670060465;2388.605880029793;2410.3371572817528;2415.070197973784;2384.3443053160345;2389.3668177932773;2394.1381102802975;2401.5677178107844;2404.0744313439277;2340.7659234076455;2362.598358279097;2363.2555744940287;2368.4434157972864;2370.751071137523;8770.956145219297;8786.824263514758;8812.295016398737;8819.338549608805;8831;5244.685318880879;5320.787168513293;5323.281357016792;5351.839444816662;5421.9457805421835;3381.7801842880135;3493.406120959443;3578.3380074686265;3579.391503444421;3585.7848529088337;1021.6577446555413;1033.178622994721;1035.2753072848961;1037.771690228156;1062.8352605346183;11460.71348216293;11483.885161148366;11560.595379161774;11633.941830290794;11639.185257032012;3824.5028146340796;4528.366028755977;4540.88647783808;4579.587837094611;4657.231556793159;9138.406003609743;9147.679831205876;9157.313201509913;9161.312901532412;9177.128172823572;5164.948350516522;5232;5232.9738351308;5244.475552444725;5244.606654500852;4388.956110438919;4720.221163508051;4839.927401088987;4875.341828853059;4880.316755654211;3320.900372988814;3480.286541259036;3793.279276937373;3835.328817456934;3909.1204478992;945.432740355781;1165.44641295384;1168.351564881499;1178.9174757766873;1185.4529838560761;826.8878358607664;865.5153114256037;885.964561417549;889.4040992535017;956.9808154315771;4482.887927801766;5688.971555142198;5803.680797556175;5842.094475356326;6020.337762846083;411.9814608342632;431.1872121051777;431.97621636721175;440.46483522520293;454.2958414457644;3367.343368043257;3840.462335273064;3898.9805050975133;3964.5440774311323;3984.1035766952205;10415.375077495222;10786.46067696612;10805.891941080647;10807.243492955418;10849;10424.843627345595;10444.112250458644;10452;10689.198310126616;10755.892441075646;8039.839203215903;8163.632636531371;8250.339972802161;8261.1325810789;8282.213189746964;4984.155862330961;5691.77232910679;5698.487136157766;5803.216565763737;5805.883882322245;9818.018198180125;9925.255605829447;10094.394336339843;10095;10098.040686134806;10070.647527336509;10200.132988696045;10201.693949181536;10304.330218535772;10342.32774869631;6478.838029049311;6740.12378390806;6990.440764738707;6996.230414654486;7003.5797852129035;8298.917010829815;8374.371922105745;8375.036870759697;8458.154184581492;8459.915400845914;7310.305520975498;7401.222871598405;7434.182239953492;7532.811679708051;7577.280158384945;5678.829635110953;6878.209005964378;7223.2415596361625;7316.304951029643;7398.556086634819;6912.619805910723;7025.859482810418;7058.682359293947;7059.541129826544;7060.929390706233;4713.9057218856115;4743.9525604744895;4767.952320476889;4848.539388758062;4960.330355401975;4247.745135291892;4662.160811054043;4829.106615276185;4887.853364399074;4961.578265847444 21:55:02 INFO - PROCESS | 1970 | |4;prototype.html;304.4064075053646;312.60299419736936;321.64297629631113;324.05376301200494;325.17081442322075;4318.07161460286;5456.263404440399;5583.352910891286;5621.718914054296;5684.09582789766;4624.8612541623725;4829.975850120744;5537.723113844312;5544.722763861811;5551.694656793875;3527.7706949048356;3824.617538246167;4225.535191128974;4232.830686772525;4263.744175349489;3496.3356962177104;3847.634089898088;4047.8583249586272;4233.1956928183545;4239.830406783741;3630.6006339302658;3854.9807250963845;3888.786116763577;4174.7893110997775;4221.176870510251;3514;4217.587108318687;4230.873073807791;4234.534201237861;4267.594578515036;2913.4318807832483;2962.6000489934013;3221.468457704476;3660.3045421369857;3705.166337574024;4994.475580064077;5025.924611130836;5255.527002569749;5278.2317923495675;5296.92054619181;3670.7981061041633;4206.768627725474;4648.83729069481;4827.879303017417;4855.465898751135;5245.31810864589;5341.305630268041;5784.855378615526;5845.346658338538;5862.3585396089165;3264.60824701035;3834.753705045843;3943.881683549498;3959.841606335743;3960.5445373782095;5020.665390593355;6094.847628809315;6128.63228206309;6132.325444201134;6134.846628834315;3526.929461410809;3957.3470377387407;3979.6617287530307;3990.6009399060445;4033.7781422021485;3807.58116607173;3907.1404291055924;4025.91948161041;4032.737872038308;4087.1621317629815;3771.26460340231;4105.7331273467125;4717.9292310615365;4729.456112547033;4759.76201189946;5091.26896855071;5157.432682404932;5159.535641792257;5422.91865622016;5900.4099590041515;1183.9763204735857;1202.759448110364;1217.4704003758393;1246.902725601483;1386.4869998100567;784.0865391818581;808.002117385029;816.318374157585;821.765796747924;848.9702860399856;4138.751674899516;4439.334099884978;4500.324951257272;4521;4543.840965566189;4570.605965180652;4894.461609222983;4988;5005.374328208974;5068.873278168076;3847.3267178243696;3862.3156905950095;4557.635389168859;4589.242774942097;4619.699719518288;2311.6994790677104;2496.301035709968;2610.451805120908;2664.8534330612;2679.3120334189725;4279.443672322641;4686.531346865286;4903.558679718771;5120.924605832743;5290.259363689087;4395.626371758372;4949.727764972961;5191.506806853343;5205.375354957354;5244;5703.828885133453;6111.96944015277;6381.457576106083;6401.463648724272;6419.903701444482;5915.526757859275;6108.4807791338235;6115.816525504242;6116.755329786759;6153.261608606996;4884.5603895649565;5825.300963884361;6072.848178795565;6074.873794172181;6096.78661246872;5231.843044708665;6349.904751428639;6466.676666166767;6487.994360874072;6506.470979319762;3935.547412047651;3999.8000099995465;4015.2170326785995;4020.979895100623;4058.776767277828;5336.252924590638;5662.518685911744;5703.543716502587;6040.942835003769;6057.575969682256;4609.907801844011;5689.601727879174;5827.659638283303;5843.2695913010875;5862.208601838697;5131.820386286462;5189.662671926313;5409.945060713123;5578.2748242728185;5830.271216097988;5344.839654810362;5747.712614369349;5751;5780.855478613068;5797.449242321973;5038.659021437288;5370.892582148414;5472.835814925558;5942.584019118794;5944.1975333331175;5144;5444.468322230182;5696.344920334049;5754.56840736938;5759.625624334405 21:55:02 INFO - PROCESS | 1970 | |5;yui.html;664.4916638771339;702.3117345001895;743.1416713695684;749.0973377080618;751.1211882097944;14879.553613391601;15118.168500732456;15220.923895380522;15424.46014389498;15455;13881.16712997221;14197.503087391944;14209.147451152912;14218.502352417667;14228;732.8094695379186;856.6273670953124;906.918377346042;937.8358787212245;944.2068662323646;933.3932943586655;937.1331518345537;938.4134915677702;939.2720641502822;942.4439580647417;955.073578628729;986.9753256168617;987.856760769688;992.3797626483448;992.9702108936743;880.9867851982225;919.9126083022136;939.3236869453982;982.9655962041294;989.8267803134459;730.883058710609;733.1165945036219;733.3509843788232;735.4557627355772;736.4108713029555;12309.769023097708;14228.502002429968;14431.422743090263;14436.639084022878;14653;12050.216735912138;12205.816912746315;12338.259704417675;12429.005679545615;12734.61796146117;9824.508774561244;9923.354981926224;9948.95025524875;10147.238957078249;10664.461920818369;3542.822858857073;3624.8912532624063;3631.527901372831;3688.4651725499652;3727.422249551324;73753.6312318435;76028;76930.84603730892;79874.6006269965;80545;9308.627654893728;9346.158845703783;9348;9395.859062114074;9758.804823903482;21733.456663583536;21737.23919662804;21804.127834886745;21856.781432185475;21997.340079797632;7134.072570565793;7173.6413179340825;7323.780286591411;7358.043454351007;7482.363999060032;3960.5247370315747;3961.7028722845903;3965.2267807777785;3969.6824254059607;3984.980075099606;3381.0871064812854;3474.2877710069374;3489.7557170998293;3491.389006923778;3498.755087143926;600.3366280260336;617.746723843231;619.9938000620026;621.1211136242257;622.0886401421939;539.4713181082561;599.3526990849872;604.4284859365614;619.8140557832633;622.6326467384265;7659.119201291811;7920.920790792134;8169.387295952827;8198.38512111594;8280.792980175544;504.73249177935753;532.9067413202674;589.1251491535153;599.3377318063477;620.8168590265882;2753.3529620539157;3844.308024555551;3894.82473288697;3939.2121575684405;4070.043539768152;69345.61308773796;77564.44871102659;77693;77767.22232777713;89752;68377.3162268371;68889;71585.6420717893;71597.28402715907;72665;63548.68225658842;63846;64516;64775.67612161909;65521.689566208406;7471.476996610185;8092.271695547429;8241.081797746174;8284.25441710249;8297.709580164907;29922.551161732157;37887.81056094702;38678.61321386863;39032.609673902894;39378.60621393864;43158.56841431671;54637.72681136569;56653.15020274617;56989.71505142448;57020.574485636535;10283.22875784334;10377;10481.895181048092;10580.41807700584;11037.337759734442;11675.649730508097;11708.824367634323;11772.941135294268;11859.940700296444;11959.76080478403;10956.4092356686;10988.395638239974;10989.450527473755;11479.27810828349;11557.595484158013;10816.539767131337;11042.613508527164;11047.281926674741;11323.037541809039;12119.030477561597;10835.891641083488;10864.239503234698;10922.508487118257;10945.83581246266;11338.829917551078;8962.820743585222;9131.680391186277;9283.675071372469;9296.860547091934;9369.81260374775;9043.63825447001;9285.953570232106;9306.906930930603;9368.156865882103;9409.200217981548 21:55:02 INFO - PROCESS | 1970 | __end_tp_report 21:55:02 INFO - PROCESS | 1970 | __start_cc_report 21:55:02 INFO - PROCESS | 1970 | _x_x_mozilla_cycle_collect,422 21:55:02 INFO - PROCESS | 1970 | __end_cc_report 21:55:02 INFO - PROCESS | 1970 | __startTimestamp1463806502880__endTimestamp 21:55:02 INFO - PROCESS | 1970 | 21:55:02 INFO - PROCESS | 1970 | ------- Summary: start ------- 21:55:02 INFO - PROCESS | 1970 | Number of tests: 6 21:55:02 INFO - PROCESS | 1970 | 21:55:02 INFO - PROCESS | 1970 | [#0] dojo.html Cycles:165 Average:5849.66 Median:6087.30 stddev:676.09 (11.1%) stddev-sans-first:633.56 21:55:02 INFO - PROCESS | 1970 | Values: 2770.7 3516.0 3535.5 3553.9 3579.8 5898.4 5914.9 5961.0 5978.8 6010.7 5738.9 5740.6 5800.5 5827.5 5926.1 5301.5 5323.9 5358.7 5483.9 5544.2 4730.7 5408.3 5508.4 5547.0 5608.4 5353.8 5388.9 5429.4 5436.2 5441.0 4479.0 5421.8 5443.1 5445.8 5466.2 5379.8 5406.9 5445.4 5478.8 5480.3 4351.9 5728.2 5791.7 5871.1 5918.3 6229.1 6327.2 6360.7 6368.1 6368.6 6269.7 6359.9 6364.2 6366.0 6370.4 5948.9 6012.5 6057.4 6059.1 6059.9 6358.4 6425.5 6431.3 6461.4 6462.9 6206.8 6235.4 6334.4 6341.2 6383.7 6250.8 6284.6 6357.1 6358.6 6359.7 6236.8 6237.4 6300.9 6319.0 6370.4 5746.7 6233.8 6279.8 6354.6 6376.1 5040.8 5068.7 5074.0 5084.7 5125.8 4328.2 4563.5 4602.3 4612.4 4612.8 5855.8 5973.5 6039.9 6074.1 6124.7 4706.8 6276.4 6290.7 6294.8 6300.2 5858.3 5887.8 6070.2 6076.5 6087.3 5472.3 5534.9 5541.4 5542.5 5583.1 6484.4 6527.4 6536.5 6553.9 6563.3 6322.1 6322.7 6326.1 6333.2 6353.8 5862.6 6082.7 6295.0 6470.4 6542.8 6245.6 6270.2 6341.2 6350.3 6352.5 6054.8 6319.2 6331.6 6331.7 6339.4 5162.5 6192.3 6214.3 6243.4 6245.9 4817.5 6299.7 6304.1 6322.8 6345.6 6057.6 6152.7 6183.5 6190.4 6219.0 5076.4 6122.1 6211.4 6262.6 6288.3 6200.8 6215.3 6223.0 6233.1 6237.5 21:55:02 INFO - PROCESS | 1970 | 21:55:02 INFO - PROCESS | 1970 | [#1] ext.html Cycles:180 Average:73006.19 Median:39320.11 stddev:167115.34 (425.0%) stddev-sans-first:167495.47 21:55:02 INFO - PROCESS | 1970 | Values: 499.3 512.1 530.8 537.8 564.7 39741.0 42008.6 42626.1 42691.7 42819.0 42982.4 43611.3 43700.8 43770.8 43864.0 9570.7 9715.0 9774.6 9783.2 9790.4 9579.4 9628.5 9674.2 9677.7 9681.9 9617.2 9664.4 9708.4 9715.5 9719.9 9625.0 9686.2 9693.0 9712.0 9716.0 9036.2 9115.2 9557.1 9690.4 9706.2 40935.6 40974.2 41193.8 41423.8 41501.8 3128.5 3136.5 3332.7 3472.3 3504.9 28356.0 29328.9 29531.1 30050.5 30701.4 2760.3 2770.4 2842.2 2850.5 2908.5 524267.0 536155.0 537863.0 538325.3 538790.0 35026.9 35828.7 36084.0 66237.3 66730.0 36483.6 36785.1 37157.6 37172.3 37381.4 15222.5 15373.2 15380.3 15388.0 15438.8 12702.9 12865.6 12897.6 12906.2 12928.2 6646.3 7294.3 7772.3 7916.4 8070.6 3288.4 3323.0 3351.9 3355.6 3374.9 938.8 993.4 997.0 1024.9 1105.9 29066.9 29368.6 29568.3 29763.4 30018.0 824.8 989.5 1067.5 1086.5 1146.8 12845.6 14595.7 14937.7 14970.9 15191.5 688834.0 699008.0 707408.0 710691.0 715007.0 62548.1 63000.0 63014.0 63191.4 63430.7 1456.3 1461.1 1477.3 1479.4 1494.8 28129.0 28207.6 28900.0 28982.0 29360.1 52360.0 53867.2 54102.0 54308.5 54592.2 612928.0 613913.0 619068.0 619361.0 619450.0 26114.0 26189.9 26232.7 26295.7 27284.7 37143.8 47673.0 48522.5 48769.3 49104.5 34091.8 38319.2 38832.0 39504.6 40049.6 36241.6 38285.2 38875.6 39255.6 39384.6 33438.3 35804.6 37556.2 37914.0 38166.0 33100.8 33988.0 34551.7 34582.7 34595.5 18511.6 24495.8 24597.3 24833.6 25489.9 21:55:02 INFO - PROCESS | 1970 | 21:55:02 INFO - PROCESS | 1970 | [#2] jquery.html Cycles:180 Average:35506.38 Median:11852.06 stddev:106183.90 (895.9%) stddev-sans-first:106449.01 21:55:02 INFO - PROCESS | 1970 | Values: 375.7 382.2 383.8 397.3 404.3 19178.4 21234.6 21337.4 21561.0 22061.6 19868.8 20895.0 20945.8 20991.0 21569.5 5100.2 5360.0 5442.5 5445.6 5453.2 3881.5 5335.8 5683.9 6017.5 6019.6 6031.7 6066.1 6084.9 6087.1 6097.8 5467.4 5754.5 5835.9 5837.1 5921.9 5600.3 5712.1 5722.7 5886.6 5898.6 12779.2 13065.8 13252.9 13281.9 13327.8 1900.9 2139.4 2143.1 2212.1 2309.5 9719.1 9736.7 9817.2 9962.0 10179.0 1756.6 1758.4 1766.7 1804.7 1839.8 111091.0 115400.4 115448.0 115725.0 118407.4 9320.4 11027.2 11481.5 11650.9 11670.2 14524.0 14622.8 14685.6 14697.6 14700.5 8429.6 8574.9 8630.7 8693.9 8708.3 7758.4 7893.7 8518.3 8529.2 8656.8 3432.9 4533.5 4592.5 4610.3 4639.4 1769.5 1820.0 1927.2 1927.5 1957.8 891.0 928.3 966.2 1010.6 1036.6 15307.0 17742.3 18744.6 19776.5 19872.2 975.2 993.7 1024.5 1051.0 1077.8 10400.8 10548.7 10609.4 10625.6 10662.9 627595.0 634882.0 635663.0 640450.0 649217.0 129969.0 131151.0 131271.3 131898.3 137070.0 883.8 1007.0 1026.7 1043.3 1050.8 15680.4 16932.6 17648.6 17923.9 18084.8 51803.0 52357.2 52379.0 52511.7 52678.7 72430.6 74545.6 75948.6 76615.6 77199.0 7692.9 7806.8 7987.0 8044.2 8259.5 10445.9 11683.4 12334.9 12615.4 12682.4 10703.9 10786.5 11155.3 11222.8 11350.0 10651.9 10692.1 10722.6 10835.9 11000.0 5490.9 9732.6 10985.3 11281.6 11432.3 7808.9 8075.3 8080.4 8207.9 8238.1 8125.0 10585.4 10809.7 11118.6 11198.9 21:55:02 INFO - PROCESS | 1970 | 21:55:02 INFO - PROCESS | 1970 | [#3] mootools.html Cycles:180 Average:5605.86 Median:4688.03 stddev:3192.04 (68.1%) stddev-sans-first:3186.49 21:55:02 INFO - PROCESS | 1970 | Values: 1556.0 1603.4 1802.6 1878.8 1910.6 7990.3 8527.5 8532.2 8980.5 9021.0 8608.9 8675.1 8793.6 8841.5 8855.0 2371.5 2376.3 2410.9 2418.1 2422.3 2383.7 2386.8 2398.1 2423.6 2431.8 2377.7 2379.2 2388.6 2410.3 2415.1 2384.3 2389.4 2394.1 2401.6 2404.1 2340.8 2362.6 2363.3 2368.4 2370.8 8771.0 8786.8 8812.3 8819.3 8831.0 5244.7 5320.8 5323.3 5351.8 5421.9 3381.8 3493.4 3578.3 3579.4 3585.8 1021.7 1033.2 1035.3 1037.8 1062.8 11460.7 11483.9 11560.6 11633.9 11639.2 3824.5 4528.4 4540.9 4579.6 4657.2 9138.4 9147.7 9157.3 9161.3 9177.1 5164.9 5232.0 5233.0 5244.5 5244.6 4389.0 4720.2 4839.9 4875.3 4880.3 3320.9 3480.3 3793.3 3835.3 3909.1 945.4 1165.4 1168.4 1178.9 1185.5 826.9 865.5 886.0 889.4 957.0 4482.9 5689.0 5803.7 5842.1 6020.3 412.0 431.2 432.0 440.5 454.3 3367.3 3840.5 3899.0 3964.5 3984.1 10415.4 10786.5 10805.9 10807.2 10849.0 10424.8 10444.1 10452.0 10689.2 10755.9 8039.8 8163.6 8250.3 8261.1 8282.2 4984.2 5691.8 5698.5 5803.2 5805.9 9818.0 9925.3 10094.4 10095.0 10098.0 10070.6 10200.1 10201.7 10304.3 10342.3 6478.8 6740.1 6990.4 6996.2 7003.6 8298.9 8374.4 8375.0 8458.2 8459.9 7310.3 7401.2 7434.2 7532.8 7577.3 5678.8 6878.2 7223.2 7316.3 7398.6 6912.6 7025.9 7058.7 7059.5 7060.9 4713.9 4744.0 4768.0 4848.5 4960.3 4247.7 4662.2 4829.1 4887.9 4961.6 21:55:02 INFO - PROCESS | 1970 | 21:55:02 INFO - PROCESS | 1970 | [#4] prototype.html Cycles:180 Average:4519.28 Median:4926.64 stddev:1455.89 (29.6%) stddev-sans-first:1425.19 21:55:02 INFO - PROCESS | 1970 | Values: 304.4 312.6 321.6 324.1 325.2 4318.1 5456.3 5583.4 5621.7 5684.1 4624.9 4830.0 5537.7 5544.7 5551.7 3527.8 3824.6 4225.5 4232.8 4263.7 3496.3 3847.6 4047.9 4233.2 4239.8 3630.6 3855.0 3888.8 4174.8 4221.2 3514.0 4217.6 4230.9 4234.5 4267.6 2913.4 2962.6 3221.5 3660.3 3705.2 4994.5 5025.9 5255.5 5278.2 5296.9 3670.8 4206.8 4648.8 4827.9 4855.5 5245.3 5341.3 5784.9 5845.3 5862.4 3264.6 3834.8 3943.9 3959.8 3960.5 5020.7 6094.8 6128.6 6132.3 6134.8 3526.9 3957.3 3979.7 3990.6 4033.8 3807.6 3907.1 4025.9 4032.7 4087.2 3771.3 4105.7 4717.9 4729.5 4759.8 5091.3 5157.4 5159.5 5422.9 5900.4 1184.0 1202.8 1217.5 1246.9 1386.5 784.1 808.0 816.3 821.8 849.0 4138.8 4439.3 4500.3 4521.0 4543.8 4570.6 4894.5 4988.0 5005.4 5068.9 3847.3 3862.3 4557.6 4589.2 4619.7 2311.7 2496.3 2610.5 2664.9 2679.3 4279.4 4686.5 4903.6 5120.9 5290.3 4395.6 4949.7 5191.5 5205.4 5244.0 5703.8 6112.0 6381.5 6401.5 6419.9 5915.5 6108.5 6115.8 6116.8 6153.3 4884.6 5825.3 6072.8 6074.9 6096.8 5231.8 6349.9 6466.7 6488.0 6506.5 3935.5 3999.8 4015.2 4021.0 4058.8 5336.3 5662.5 5703.5 6040.9 6057.6 4609.9 5689.6 5827.7 5843.3 5862.2 5131.8 5189.7 5409.9 5578.3 5830.3 5344.8 5747.7 5751.0 5780.9 5797.4 5038.7 5370.9 5472.8 5942.6 5944.2 5144.0 5444.5 5696.3 5754.6 5759.6 21:55:02 INFO - PROCESS | 1970 | 21:55:02 INFO - PROCESS | 1970 | [#5] yui.html Cycles:180 Average:16614.87 Median:618.78 stddev:22534.27 (3641.7%) stddev-sans-first:22565.66 21:55:02 INFO - PROCESS | 1970 | Values: 664.5 702.3 743.1 749.1 751.1 14879.6 15118.2 15220.9 15424.5 15455.0 13881.2 14197.5 14209.1 14218.5 14228.0 732.8 856.6 906.9 937.8 944.2 933.4 937.1 938.4 939.3 942.4 955.1 987.0 987.9 992.4 993.0 881.0 919.9 939.3 983.0 989.8 730.9 733.1 733.4 735.5 736.4 12309.8 14228.5 14431.4 14436.6 14653.0 12050.2 12205.8 12338.3 12429.0 12734.6 9824.5 9923.4 9949.0 10147.2 10664.5 3542.8 3624.9 3631.5 3688.5 3727.4 73753.6 76028.0 76930.8 79874.6 80545.0 9308.6 9346.2 9348.0 9395.9 9758.8 21733.5 21737.2 21804.1 21856.8 21997.3 7134.1 7173.6 7323.8 7358.0 7482.4 3960.5 3961.7 3965.2 3969.7 3985.0 3381.1 3474.3 3489.8 3491.4 3498.8 600.3 617.7 620.0 621.1 622.1 539.5 599.4 604.4 619.8 622.6 7659.1 7920.9 8169.4 8198.4 8280.8 504.7 532.9 589.1 599.3 620.8 2753.4 3844.3 3894.8 3939.2 4070.0 69345.6 77564.4 77693.0 77767.2 89752.0 68377.3 68889.0 71585.6 71597.3 72665.0 63548.7 63846.0 64516.0 64775.7 65521.7 7471.5 8092.3 8241.1 8284.3 8297.7 29922.6 37887.8 38678.6 39032.6 39378.6 43158.6 54637.7 56653.2 56989.7 57020.6 10283.2 10377.0 10481.9 10580.4 11037.3 11675.6 11708.8 11772.9 11859.9 11959.8 10956.4 10988.4 10989.5 11479.3 11557.6 10816.5 11042.6 11047.3 11323.0 12119.0 10835.9 10864.2 10922.5 10945.8 11338.8 8962.8 9131.7 9283.7 9296.9 9369.8 9043.6 9286.0 9306.9 9368.2 9409.2 21:55:02 INFO - PROCESS | 1970 | -------- Summary: end -------- 21:55:02 INFO - PROCESS | 1970 | 21:55:03 INFO - TEST-INFO | 1970: exit 0 21:55:03 INFO - TEST-OK | dromaeo_css | took 1112064ms 21:55:03 INFO - TEST-START | kraken 21:55:03 INFO - operating with platform_type : mac_ 21:55:03 INFO - Initialising browser for kraken test... 21:55:03 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpx9JAw9/profile http://localhost:49663/getInfo.html) 21:55:06 INFO - PROCESS | 1974 | __metrics Screen width/height:1600/1200 21:55:06 INFO - PROCESS | 1974 | colorDepth:24 21:55:06 INFO - PROCESS | 1974 | Browser inner width/height: 1024/689 21:55:06 INFO - PROCESS | 1974 | __metrics 21:55:06 ERROR - PROCESS | 1974 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 21:55:07 INFO - TEST-INFO | 1974: exit 0 21:55:07 INFO - Browser initialized. 21:55:07 INFO - Running cycle 1/1 for kraken test... 21:55:07 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpx9JAw9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:55:19 INFO - PROCESS | 1977 | RSS: Main: 250036224 21:55:19 INFO - PROCESS | 1977 | 21:55:22 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49663/tests/kraken/driver.html?testName=audio-beat-detection) 21:55:23 INFO - PROCESS | 1977 | RSS: Main: 261701632 21:55:23 INFO - PROCESS | 1977 | 21:55:25 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49663/tests/kraken/driver.html?testName=audio-dft) 21:55:26 INFO - PROCESS | 1977 | RSS: Main: 266485760 21:55:26 INFO - PROCESS | 1977 | 21:55:29 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49663/tests/kraken/driver.html?testName=audio-fft) 21:55:30 INFO - PROCESS | 1977 | RSS: Main: 267358208 21:55:30 INFO - PROCESS | 1977 | 21:55:32 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49663/tests/kraken/driver.html?testName=audio-oscillator) 21:55:33 INFO - PROCESS | 1977 | RSS: Main: 267976704 21:55:33 INFO - PROCESS | 1977 | 21:55:35 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49663/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:55:35 INFO - PROCESS | 1977 | RSS: Main: 268374016 21:55:35 INFO - PROCESS | 1977 | 21:55:39 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49663/tests/kraken/driver.html?testName=imaging-darkroom) 21:55:39 INFO - PROCESS | 1977 | RSS: Main: 269324288 21:55:39 INFO - PROCESS | 1977 | 21:55:43 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49663/tests/kraken/driver.html?testName=imaging-desaturate) 21:55:43 INFO - PROCESS | 1977 | RSS: Main: 269107200 21:55:43 INFO - PROCESS | 1977 | 21:55:47 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49663/tests/kraken/driver.html?testName=json-parse-financial) 21:55:47 INFO - PROCESS | 1977 | RSS: Main: 269398016 21:55:47 INFO - PROCESS | 1977 | 21:55:49 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49663/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:55:50 INFO - PROCESS | 1977 | RSS: Main: 268398592 21:55:50 INFO - PROCESS | 1977 | 21:55:52 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49663/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:55:52 INFO - PROCESS | 1977 | RSS: Main: 268910592 21:55:52 INFO - PROCESS | 1977 | 21:55:54 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49663/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:55:55 INFO - PROCESS | 1977 | RSS: Main: 268484608 21:55:55 INFO - PROCESS | 1977 | 21:55:58 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49663/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:55:59 INFO - PROCESS | 1977 | RSS: Main: 268664832 21:55:59 INFO - PROCESS | 1977 | 21:56:02 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49663/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:56:02 INFO - PROCESS | 1977 | RSS: Main: 268599296 21:56:02 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49663/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49663/tests/kraken/driver.html?testName=ai-astar) 21:56:04 INFO - PROCESS | 1977 | RSS: Main: 271372288 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | __start_tp_report 21:56:04 INFO - PROCESS | 1977 | _x_x_mozilla_page_load 21:56:04 INFO - PROCESS | 1977 | _x_x_mozilla_page_load_details 21:56:04 INFO - PROCESS | 1977 | |i|pagename|runs| 21:56:04 INFO - PROCESS | 1977 | |0;ai-astar;115;110;120;116;117;137;115;115;115;115 21:56:04 INFO - PROCESS | 1977 | |1;audio-beat-detection;162;138;145;200;121;147;142;115;214;147 21:56:04 INFO - PROCESS | 1977 | |2;audio-dft;198;244;207;197;199;235;283;194;198;197 21:56:04 INFO - PROCESS | 1977 | |3;audio-fft;65;81;75;174;55;59;62;70;128;58 21:56:04 INFO - PROCESS | 1977 | |4;audio-oscillator;81;83;139;81;82;79;122;81;81;126 21:56:04 INFO - PROCESS | 1977 | |5;imaging-gaussian-blur;90;90;122;90;132;87;83;132;77;76 21:56:04 INFO - PROCESS | 1977 | |6;imaging-darkroom;96;94;124;97;144;110;146;150;113;136 21:56:04 INFO - PROCESS | 1977 | |7;imaging-desaturate;123;149;132;94;102;103;102;101;103;101 21:56:04 INFO - PROCESS | 1977 | |8;json-parse-financial;58;64;81;49;57;57;49;119;85;87 21:56:04 INFO - PROCESS | 1977 | |9;json-stringify-tinderbox;67;69;79;82;83;76;84;83;71;71 21:56:04 INFO - PROCESS | 1977 | |10;stanford-crypto-aes;85;88;90;87;88;87;87;80;98;112 21:56:04 INFO - PROCESS | 1977 | |11;stanford-crypto-ccm;131;166;167;156;150;160;156;151;211;143 21:56:04 INFO - PROCESS | 1977 | |12;stanford-crypto-pbkdf2;166;168;168;162;169;194;202;165;167;167 21:56:04 INFO - PROCESS | 1977 | |13;stanford-crypto-sha256-iterative;67;65;66;69;68;67;68;87;80;61 21:56:04 INFO - PROCESS | 1977 | __end_tp_report 21:56:04 INFO - PROCESS | 1977 | __start_cc_report 21:56:04 INFO - PROCESS | 1977 | _x_x_mozilla_cycle_collect,1087 21:56:04 INFO - PROCESS | 1977 | __end_cc_report 21:56:04 INFO - PROCESS | 1977 | __startTimestamp1463806564973__endTimestamp 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | ------- Summary: start ------- 21:56:04 INFO - PROCESS | 1977 | Number of tests: 14 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#0] ai-astar Cycles:10 Average:117.50 Median:115.50 stddev:7.28 (6.3%) stddev-sans-first:7.66 21:56:04 INFO - PROCESS | 1977 | Values: 115.0 110.0 120.0 116.0 117.0 137.0 115.0 115.0 115.0 115.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#1] audio-beat-detection Cycles:10 Average:153.10 Median:147.00 stddev:31.55 (21.5%) stddev-sans-first:33.30 21:56:04 INFO - PROCESS | 1977 | Values: 162.0 138.0 145.0 200.0 121.0 147.0 142.0 115.0 214.0 147.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#2] audio-dft Cycles:10 Average:215.20 Median:203.00 stddev:29.54 (14.5%) stddev-sans-first:30.67 21:56:04 INFO - PROCESS | 1977 | Values: 198.0 244.0 207.0 197.0 199.0 235.0 283.0 194.0 198.0 197.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#3] audio-fft Cycles:10 Average:82.70 Median:63.50 stddev:38.43 (60.5%) stddev-sans-first:40.22 21:56:04 INFO - PROCESS | 1977 | Values: 65.0 81.0 75.0 174.0 55.0 59.0 62.0 70.0 128.0 58.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:81.00 stddev:23.51 (29.0%) stddev-sans-first:24.35 21:56:04 INFO - PROCESS | 1977 | Values: 81.0 83.0 139.0 81.0 82.0 79.0 122.0 81.0 81.0 126.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#5] imaging-gaussian-blur Cycles:10 Average:97.90 Median:85.00 stddev:21.99 (25.9%) stddev-sans-first:23.13 21:56:04 INFO - PROCESS | 1977 | Values: 90.0 90.0 122.0 90.0 132.0 87.0 83.0 132.0 77.0 76.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#6] imaging-darkroom Cycles:10 Average:121.00 Median:148.00 stddev:21.97 (14.8%) stddev-sans-first:21.36 21:56:04 INFO - PROCESS | 1977 | Values: 96.0 94.0 124.0 97.0 144.0 110.0 146.0 150.0 113.0 136.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#7] imaging-desaturate Cycles:10 Average:111.00 Median:113.00 stddev:17.66 (15.6%) stddev-sans-first:18.19 21:56:04 INFO - PROCESS | 1977 | Values: 123.0 149.0 132.0 94.0 102.0 103.0 102.0 101.0 103.0 101.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#8] json-parse-financial Cycles:10 Average:70.60 Median:61.00 stddev:22.19 (36.4%) stddev-sans-first:23.07 21:56:04 INFO - PROCESS | 1977 | Values: 58.0 64.0 81.0 49.0 57.0 57.0 49.0 119.0 85.0 87.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#9] json-stringify-tinderbox Cycles:10 Average:76.50 Median:80.50 stddev:6.54 (8.1%) stddev-sans-first:5.96 21:56:04 INFO - PROCESS | 1977 | Values: 67.0 69.0 79.0 82.0 83.0 76.0 84.0 83.0 71.0 71.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:87.50 stddev:8.87 (10.1%) stddev-sans-first:9.20 21:56:04 INFO - PROCESS | 1977 | Values: 85.0 88.0 90.0 87.0 88.0 87.0 87.0 80.0 98.0 112.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#11] stanford-crypto-ccm Cycles:10 Average:159.10 Median:158.00 stddev:21.14 (13.4%) stddev-sans-first:19.82 21:56:04 INFO - PROCESS | 1977 | Values: 131.0 166.0 167.0 156.0 150.0 160.0 156.0 151.0 211.0 143.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:168.00 stddev:13.55 (8.1%) stddev-sans-first:14.15 21:56:04 INFO - PROCESS | 1977 | Values: 166.0 168.0 168.0 162.0 169.0 194.0 202.0 165.0 167.0 167.0 21:56:04 INFO - PROCESS | 1977 | 21:56:04 INFO - PROCESS | 1977 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:68.00 stddev:7.73 (11.4%) stddev-sans-first:8.13 21:56:04 INFO - PROCESS | 1977 | Values: 67.0 65.0 66.0 69.0 68.0 67.0 68.0 87.0 80.0 61.0 21:56:04 INFO - PROCESS | 1977 | -------- Summary: end -------- 21:56:04 INFO - PROCESS | 1977 | 21:56:05 INFO - TEST-INFO | 1977: exit 0 21:56:05 INFO - TEST-OK | kraken | took 62327ms 21:56:05 INFO - SUITE-END | took 1174s 21:56:06 INFO - Completed test suite (00:19:34) 21:56:06 INFO - javascript benchmark 21:56:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7023.696683124933, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2770.6952235254125, 3515.964840351597, 3535.505029295898, 3553.9160556030433, 3579.785212887227, 5898.380670029649, 5914.940850591487, 5960.970195149018, 5978.8206353809455, 6010.699465026753, 5738.885222295552, 5740.569457290699, 5800.53595712343, 5827.475527202551, 5926.051831706928, 5301.496357845998, 5323.866903327419, 5358.705271210082, 5483.862903427426, 5544.22380866679, 4730.739809310487, 5408.296921400212, 5508.4491550845, 5546.972265138668, 5608.383077861432, 5353.7590808413715, 5388.892222155535, 5429.375621803464, 5436.184572314145, 5440.972795136019, 4479.014616784302, 5421.810236641699, 5443.129099344125, 5445.754941027663, 5466.1527463243265, 5379.81170659029, 5406.91889621656, 5445.37378201504, 5478.753456094445, 5480.287562616874, 4351.912961740747, 5728.22668939692, 5791.73937172824, 5871.1193321001765, 5918.260217472816, 6229.127922090911, 6327.24073111225, 6360.650164240965, 6368.14030105939, 6368.554201205918, 6269.655168965705, 6359.904601430982, 6364.172657554488, 6366.013267943476, 6370.362963703593, 5948.940510594882, 6012.458878700894, 6057.394260573908, 6059.0608455689435, 6059.939400605982, 6358.415959624099, 6425.518086143465, 6431.292557818636, 6461.386168314003, 6462.935370646233, 6206.782762603378, 6235.376462353728, 6334.429901308904, 6341.23905131378, 6383.71273292703, 6250.781222657185, 6284.591501552384, 6357.141785858942, 6358.618482891041, 6359.713812878432, 6236.844078898064, 6237.376262373817, 6300.873982520324, 6318.968405157945, 6370.362963703593, 5746.741396637161, 6233.812985610439, 6279.780207692709, 6354.586951848115, 6376.075469056961, 5040.765012571944, 5068.746562671851, 5074.035933172685, 5084.745762711849, 5125.846224613268, 4328.242557552416, 4563.54364563541, 4602.309653551994, 4612.377329060601, 4612.838550650711, 5855.824325270249, 5973.522118230531, 6039.909401358983, 6074.088886667036, 6124.693765311806, 4706.8117275308605, 6276.435120839146, 6290.748370065237, 6294.8111556653375, 6300.180976473029, 5858.2677165354335, 5887.764489420375, 6070.150178974947, 6076.453119219292, 6087.29996050451, 5472.315960504937, 5534.944650553523, 5541.445855414427, 5542.501174894279, 5583.134614134816, 6484.351564843572, 6527.379898909596, 6536.477081833537, 6553.901691474632, 6563.278039415756, 6322.146510221061, 6322.715477803602, 6326.145970293951, 6333.20834895638, 6353.809385718436, 5862.618929769551, 6082.72627731762, 6294.968525157345, 6470.38531339522, 6542.803715888531, 6245.594036387621, 6270.216222972129, 6341.175647165839, 6350.269718982377, 6352.491800656029, 6054.789042191667, 6319.210098737658, 6331.620102793847, 6331.746730130743, 6339.366063393623, 5162.457941916045, 6192.318844927145, 6214.347493513116, 6243.406876346739, 6245.937540624535, 4817.518248175154, 6299.685015749103, 6304.054391841077, 6322.778702745566, 6345.587536810092, 6057.606255593372, 6152.723127459365, 6183.505319574513, 6190.41191086846, 6219.036049412348, 5076.36545431821, 6122.093225952662, 6211.440970312694, 6262.5929314594405, 6288.308286088435, 6200.782972595937, 6215.285242197025, 6222.968885155546, 6233.127362169209, 6237.5321850860455], "value": 5809.108800272453, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.32591002147103, 512.1396054628224, 530.8115618955271, 537.8225185688723, 564.6781334639257, 39741.0, 42008.57991420085, 42626.14747705044, 42691.719248422596, 42819.0, 42982.35526467105, 43611.34582981258, 43700.78149609255, 43770.781146094225, 43864.0, 9570.66502672405, 9715.0, 9774.609015639402, 9783.168430683401, 9790.412575245473, 9579.377340472882, 9628.518574071304, 9674.177694895941, 9677.70966870995, 9681.854772178423, 9617.182539484118, 9664.4201347919, 9708.41749495029, 9715.514224288792, 9719.854202186973, 9625.037496250354, 9686.225101991824, 9693.030696930286, 9711.980242074622, 9716.028397160262, 9036.186743193079, 9115.22520585751, 9557.139857412867, 9690.37012594179, 9706.174975127122, 40935.59064409348, 40974.1805163898, 41193.794031029654, 41423.7928810354, 41501.79249103765, 3128.5307203919365, 3136.5452009458727, 3332.6667333266723, 3472.2708231271463, 3504.859805607798, 28356.00753973601, 29328.853355733085, 29531.114066578037, 30050.54924176139, 30701.385972280652, 2760.2823265950797, 2770.4043630619312, 2842.1757690269797, 2850.50116229659, 2908.476474234637, 524267.0, 536155.0, 537863.0, 538325.3083734595, 538790.0, 35026.9491915243, 35828.74599389061, 36084.0, 66237.33762662408, 66729.99810005707, 36483.635163648556, 36785.08037299089, 37157.628423715956, 37172.256554869295, 37381.43927841084, 15222.46721364747, 15373.154476503676, 15380.30788614515, 15387.999780014263, 15438.845611543966, 12702.872971270353, 12865.614031579067, 12897.613071607866, 12906.161099528501, 12928.224306541637, 6646.335366463315, 7294.270572942663, 7772.261635144652, 7916.406269529808, 8070.587647161723, 3288.408086544397, 3322.9501557476656, 3351.916202094967, 3355.647656996029, 3374.9493757593164, 938.7653086728318, 993.3543196922059, 997.002997002997, 1024.8565200871883, 1105.8894110589038, 29066.854665726536, 29368.559471607943, 29568.260793479905, 29763.40473190524, 30018.0, 824.8391563645032, 989.4854675568807, 1067.4555976451911, 1086.5165001574223, 1146.7591805720724, 12845.61463156088, 14595.708085838436, 14937.70124597502, 14970.850291497163, 15191.544253672408, 688834.0, 699008.0, 707408.0, 710691.0, 715007.0, 62548.06177907335, 63000.0, 63014.0, 63191.36808631855, 63430.68284658547, 1456.3446449097949, 1461.0649184521915, 1477.2761346940138, 1479.400842658725, 1494.8056752622308, 28129.0, 28207.57688634631, 28899.988500402385, 28982.0, 29360.11919642414, 52359.95280094453, 53867.19199211936, 54102.0, 54308.45691543192, 54592.181117282475, 612928.0, 613913.0, 619068.0, 619361.0, 619450.0, 26114.0, 26189.869050654626, 26232.73767262303, 26295.737042629327, 27284.727152729014, 37143.814280928425, 47673.0, 48522.5147748518, 48769.26846097382, 49104.508954909994, 34091.82954085313, 38319.23361532809, 38832.0, 39504.60495395009, 40049.59950400459, 36241.63758362383, 38285.2342953145, 38875.6112438872, 39255.60744392634, 39384.6061539381, 33438.33123337471, 35804.64195358013, 37556.21519125544, 37914.0, 38166.0, 33100.83449582833, 33988.0, 34551.65448345484, 34582.654173457944, 34595.4810677835, 18511.629767404847, 24495.77521123901, 24597.262082137564, 24833.62749558794, 25489.872550637127], "value": 18720.996431106574, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.72196574534837, 382.2069206396726, 383.78316251318006, 397.25514660012476, 404.29854202957864, 19178.42464726059, 21234.575308493822, 21337.35987920363, 21561.0, 22061.558768824634, 19868.801311986877, 20894.955252237367, 20945.847978361184, 20991.0, 21569.46076348092, 5100.234964755288, 5360.0, 5442.482964118422, 5445.591580631449, 5453.23654688344, 3881.5342158940966, 5335.839924802262, 5683.943160568383, 6017.548683848729, 6019.608725432855, 6031.668258245794, 6066.09008648704, 6084.939150608504, 6087.147799308101, 6097.8170654880205, 5467.371252305996, 5754.510866576346, 5835.912461313083, 5837.066069428893, 5921.911171332434, 5600.271964644611, 5712.1431785232135, 5722.713864306768, 5886.6173698709445, 5898.616589921641, 12779.169353991962, 13065.804012939814, 13252.933735331262, 13281.86718132816, 13327.800082998763, 1900.8574356923148, 2139.379579921821, 2143.121320258702, 2212.1372664660794, 2309.5265470578493, 9719.076687714656, 9736.707898763048, 9817.214622830228, 9962.003799620052, 10179.03299186576, 1756.6223261998732, 1758.3581992572747, 1766.7084930986373, 1804.7112462006148, 1839.7792264928294, 111091.0, 115400.42299788447, 115448.0, 115725.0, 118407.4079629605, 9320.440773553608, 11027.22809403334, 11481.540738370371, 11650.88349116498, 11670.183087183816, 14524.0, 14622.780658289921, 14685.559433216808, 14697.632559186106, 14700.485483008044, 8429.620667069996, 8574.914250857537, 8630.741077767678, 8693.913060869438, 8708.346873984476, 7758.379329653614, 7893.684252629945, 8518.318534517224, 8529.189726975927, 8656.78358041054, 3432.8626854925524, 4533.54664533544, 4592.494825569184, 4610.2854057621125, 4639.350490931272, 1769.4780039888265, 1819.954501137482, 1927.2291083566686, 1927.4506765572025, 1957.8237958583795, 890.9955450222708, 928.2852203803034, 966.1642679082543, 1010.6412223660494, 1036.6009086501751, 15307.0, 17742.29030838778, 18744.625107498046, 19776.505587360145, 19872.205111795367, 975.2198241406988, 993.7267251505893, 1024.4826362686795, 1050.975299083403, 1077.8275475923815, 10400.843987340197, 10548.736281592868, 10609.363438193734, 10625.628103016512, 10662.89337106619, 627595.0, 634882.0, 635663.0, 640450.0, 649217.0, 129969.0, 131151.0, 131271.34364328117, 131898.34050829685, 137070.0, 883.8143989762093, 1006.9798604027878, 1026.6817286641165, 1043.3218408034838, 1050.7950949564915, 15680.353562875734, 16932.576685582466, 17648.55878603045, 17923.910380448015, 18084.819151808315, 51803.0, 52357.21464178117, 52379.0, 52511.73744131408, 52678.73660631672, 72430.63784681042, 74545.6272718633, 75948.62025689836, 76615.61692191503, 77199.0, 7692.884456774432, 7806.843863122819, 7987.0, 8044.1955804419085, 8259.545724985184, 10445.895541044492, 11683.415829208336, 12334.938325308674, 12615.36923153857, 12682.365881706062, 10703.892961070289, 10786.460676966277, 11155.27490713101, 11222.831657525307, 11349.978501934866, 10651.946740266249, 10692.14462842955, 10722.571097156339, 10835.9164083591, 11000.009999099799, 5490.917636235539, 9732.637430759842, 10985.340879547253, 11281.605143819927, 11432.256903301259, 7808.882866757117, 8075.313598344206, 8080.383923215498, 8207.917920820715, 8238.052623948082, 8124.959375203322, 10585.417802020655, 10809.729756756144, 11118.61084862026, 11198.944005279922], "value": 9226.94706338367, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1555.9999999999995, 1603.3987254779458, 1802.6304607555444, 1878.8027257137992, 1910.5892233169866, 7990.280874721274, 8527.530985795778, 8532.189442003015, 8980.461172329664, 9020.962589302233, 8608.913910860889, 8675.08911564286, 8793.648254069829, 8841.513716745592, 8855.0, 2371.5494056129364, 2376.3346263046287, 2410.8553486790847, 2418.0811291709215, 2422.2854257993845, 2383.6782034425305, 2386.8090552755825, 2398.124684490166, 2423.5758742220128, 2431.8297719159664, 2377.679013333195, 2379.2029670060465, 2388.605880029793, 2410.3371572817528, 2415.070197973784, 2384.3443053160345, 2389.3668177932773, 2394.1381102802975, 2401.5677178107844, 2404.0744313439277, 2340.7659234076455, 2362.598358279097, 2363.2555744940287, 2368.4434157972864, 2370.751071137523, 8770.956145219297, 8786.824263514758, 8812.295016398737, 8819.338549608805, 8831.0, 5244.685318880879, 5320.787168513293, 5323.281357016792, 5351.839444816662, 5421.9457805421835, 3381.7801842880135, 3493.406120959443, 3578.3380074686265, 3579.391503444421, 3585.7848529088337, 1021.6577446555413, 1033.178622994721, 1035.2753072848961, 1037.771690228156, 1062.8352605346183, 11460.71348216293, 11483.885161148366, 11560.595379161774, 11633.941830290794, 11639.185257032012, 3824.5028146340796, 4528.366028755977, 4540.88647783808, 4579.587837094611, 4657.231556793159, 9138.406003609743, 9147.679831205876, 9157.313201509913, 9161.312901532412, 9177.128172823572, 5164.948350516522, 5232.0, 5232.9738351308, 5244.475552444725, 5244.606654500852, 4388.956110438919, 4720.221163508051, 4839.927401088987, 4875.341828853059, 4880.316755654211, 3320.900372988814, 3480.286541259036, 3793.279276937373, 3835.328817456934, 3909.1204478992, 945.432740355781, 1165.44641295384, 1168.351564881499, 1178.9174757766873, 1185.4529838560761, 826.8878358607664, 865.5153114256037, 885.964561417549, 889.4040992535017, 956.9808154315771, 4482.887927801766, 5688.971555142198, 5803.680797556175, 5842.094475356326, 6020.337762846083, 411.9814608342632, 431.1872121051777, 431.97621636721175, 440.46483522520293, 454.2958414457644, 3367.343368043257, 3840.462335273064, 3898.9805050975133, 3964.5440774311323, 3984.1035766952205, 10415.375077495222, 10786.46067696612, 10805.891941080647, 10807.243492955418, 10849.0, 10424.843627345595, 10444.112250458644, 10452.0, 10689.198310126616, 10755.892441075646, 8039.839203215903, 8163.632636531371, 8250.339972802161, 8261.1325810789, 8282.213189746964, 4984.155862330961, 5691.77232910679, 5698.487136157766, 5803.216565763737, 5805.883882322245, 9818.018198180125, 9925.255605829447, 10094.394336339843, 10095.0, 10098.040686134806, 10070.647527336509, 10200.132988696045, 10201.693949181536, 10304.330218535772, 10342.32774869631, 6478.838029049311, 6740.12378390806, 6990.440764738707, 6996.230414654486, 7003.5797852129035, 8298.917010829815, 8374.371922105745, 8375.036870759697, 8458.154184581492, 8459.915400845914, 7310.305520975498, 7401.222871598405, 7434.182239953492, 7532.811679708051, 7577.280158384945, 5678.829635110953, 6878.209005964378, 7223.2415596361625, 7316.304951029643, 7398.556086634819, 6912.619805910723, 7025.859482810418, 7058.682359293947, 7059.541129826544, 7060.929390706233, 4713.9057218856115, 4743.9525604744895, 4767.952320476889, 4848.539388758062, 4960.330355401975, 4247.745135291892, 4662.160811054043, 4829.106615276185, 4887.853364399074, 4961.578265847444], "value": 4421.415855310511, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.4064075053646, 312.60299419736936, 321.64297629631113, 324.05376301200494, 325.17081442322075, 4318.07161460286, 5456.263404440399, 5583.352910891286, 5621.718914054296, 5684.09582789766, 4624.8612541623725, 4829.975850120744, 5537.723113844312, 5544.722763861811, 5551.694656793875, 3527.7706949048356, 3824.617538246167, 4225.535191128974, 4232.830686772525, 4263.744175349489, 3496.3356962177104, 3847.634089898088, 4047.8583249586272, 4233.1956928183545, 4239.830406783741, 3630.6006339302658, 3854.9807250963845, 3888.786116763577, 4174.7893110997775, 4221.176870510251, 3514.0, 4217.587108318687, 4230.873073807791, 4234.534201237861, 4267.594578515036, 2913.4318807832483, 2962.6000489934013, 3221.468457704476, 3660.3045421369857, 3705.166337574024, 4994.475580064077, 5025.924611130836, 5255.527002569749, 5278.2317923495675, 5296.92054619181, 3670.7981061041633, 4206.768627725474, 4648.83729069481, 4827.879303017417, 4855.465898751135, 5245.31810864589, 5341.305630268041, 5784.855378615526, 5845.346658338538, 5862.3585396089165, 3264.60824701035, 3834.753705045843, 3943.881683549498, 3959.841606335743, 3960.5445373782095, 5020.665390593355, 6094.847628809315, 6128.63228206309, 6132.325444201134, 6134.846628834315, 3526.929461410809, 3957.3470377387407, 3979.6617287530307, 3990.6009399060445, 4033.7781422021485, 3807.58116607173, 3907.1404291055924, 4025.91948161041, 4032.737872038308, 4087.1621317629815, 3771.26460340231, 4105.7331273467125, 4717.9292310615365, 4729.456112547033, 4759.76201189946, 5091.26896855071, 5157.432682404932, 5159.535641792257, 5422.91865622016, 5900.4099590041515, 1183.9763204735857, 1202.759448110364, 1217.4704003758393, 1246.902725601483, 1386.4869998100567, 784.0865391818581, 808.002117385029, 816.318374157585, 821.765796747924, 848.9702860399856, 4138.751674899516, 4439.334099884978, 4500.324951257272, 4521.0, 4543.840965566189, 4570.605965180652, 4894.461609222983, 4988.0, 5005.374328208974, 5068.873278168076, 3847.3267178243696, 3862.3156905950095, 4557.635389168859, 4589.242774942097, 4619.699719518288, 2311.6994790677104, 2496.301035709968, 2610.451805120908, 2664.8534330612, 2679.3120334189725, 4279.443672322641, 4686.531346865286, 4903.558679718771, 5120.924605832743, 5290.259363689087, 4395.626371758372, 4949.727764972961, 5191.506806853343, 5205.375354957354, 5244.0, 5703.828885133453, 6111.96944015277, 6381.457576106083, 6401.463648724272, 6419.903701444482, 5915.526757859275, 6108.4807791338235, 6115.816525504242, 6116.755329786759, 6153.261608606996, 4884.5603895649565, 5825.300963884361, 6072.848178795565, 6074.873794172181, 6096.78661246872, 5231.843044708665, 6349.904751428639, 6466.676666166767, 6487.994360874072, 6506.470979319762, 3935.547412047651, 3999.8000099995465, 4015.2170326785995, 4020.979895100623, 4058.776767277828, 5336.252924590638, 5662.518685911744, 5703.543716502587, 6040.942835003769, 6057.575969682256, 4609.907801844011, 5689.601727879174, 5827.659638283303, 5843.2695913010875, 5862.208601838697, 5131.820386286462, 5189.662671926313, 5409.945060713123, 5578.2748242728185, 5830.271216097988, 5344.839654810362, 5747.712614369349, 5751.0, 5780.855478613068, 5797.449242321973, 5038.659021437288, 5370.892582148414, 5472.835814925558, 5942.584019118794, 5944.1975333331175, 5144.0, 5444.468322230182, 5696.344920334049, 5754.56840736938, 5759.625624334405], "value": 4055.6893990075296, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.4916638771339, 702.3117345001895, 743.1416713695684, 749.0973377080618, 751.1211882097944, 14879.553613391601, 15118.168500732456, 15220.923895380522, 15424.46014389498, 15455.0, 13881.16712997221, 14197.503087391944, 14209.147451152912, 14218.502352417667, 14228.0, 732.8094695379186, 856.6273670953124, 906.918377346042, 937.8358787212245, 944.2068662323646, 933.3932943586655, 937.1331518345537, 938.4134915677702, 939.2720641502822, 942.4439580647417, 955.073578628729, 986.9753256168617, 987.856760769688, 992.3797626483448, 992.9702108936743, 880.9867851982225, 919.9126083022136, 939.3236869453982, 982.9655962041294, 989.8267803134459, 730.883058710609, 733.1165945036219, 733.3509843788232, 735.4557627355772, 736.4108713029555, 12309.769023097708, 14228.502002429968, 14431.422743090263, 14436.639084022878, 14653.0, 12050.216735912138, 12205.816912746315, 12338.259704417675, 12429.005679545615, 12734.61796146117, 9824.508774561244, 9923.354981926224, 9948.95025524875, 10147.238957078249, 10664.461920818369, 3542.822858857073, 3624.8912532624063, 3631.527901372831, 3688.4651725499652, 3727.422249551324, 73753.6312318435, 76028.0, 76930.84603730892, 79874.6006269965, 80545.0, 9308.627654893728, 9346.158845703783, 9348.0, 9395.859062114074, 9758.804823903482, 21733.456663583536, 21737.23919662804, 21804.127834886745, 21856.781432185475, 21997.340079797632, 7134.072570565793, 7173.6413179340825, 7323.780286591411, 7358.043454351007, 7482.363999060032, 3960.5247370315747, 3961.7028722845903, 3965.2267807777785, 3969.6824254059607, 3984.980075099606, 3381.0871064812854, 3474.2877710069374, 3489.7557170998293, 3491.389006923778, 3498.755087143926, 600.3366280260336, 617.746723843231, 619.9938000620026, 621.1211136242257, 622.0886401421939, 539.4713181082561, 599.3526990849872, 604.4284859365614, 619.8140557832633, 622.6326467384265, 7659.119201291811, 7920.920790792134, 8169.387295952827, 8198.38512111594, 8280.792980175544, 504.73249177935753, 532.9067413202674, 589.1251491535153, 599.3377318063477, 620.8168590265882, 2753.3529620539157, 3844.308024555551, 3894.82473288697, 3939.2121575684405, 4070.043539768152, 69345.61308773796, 77564.44871102659, 77693.0, 77767.22232777713, 89752.0, 68377.3162268371, 68889.0, 71585.6420717893, 71597.28402715907, 72665.0, 63548.68225658842, 63846.0, 64516.0, 64775.67612161909, 65521.689566208406, 7471.476996610185, 8092.271695547429, 8241.081797746174, 8284.25441710249, 8297.709580164907, 29922.551161732157, 37887.81056094702, 38678.61321386863, 39032.609673902894, 39378.60621393864, 43158.56841431671, 54637.72681136569, 56653.15020274617, 56989.71505142448, 57020.574485636535, 10283.22875784334, 10377.0, 10481.895181048092, 10580.41807700584, 11037.337759734442, 11675.649730508097, 11708.824367634323, 11772.941135294268, 11859.940700296444, 11959.76080478403, 10956.4092356686, 10988.395638239974, 10989.450527473755, 11479.27810828349, 11557.595484158013, 10816.539767131337, 11042.613508527164, 11047.281926674741, 11323.037541809039, 12119.030477561597, 10835.891641083488, 10864.239503234698, 10922.508487118257, 10945.83581246266, 11338.829917551078, 8962.820743585222, 9131.680391186277, 9283.675071372469, 9296.860547091934, 9369.81260374775, 9043.63825447001, 9285.953570232106, 9306.906930930603, 9368.156865882103, 9409.200217981548], "value": 6671.561835669555, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1632.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 110.0, 120.0, 116.0, 117.0, 137.0, 115.0, 115.0, 115.0, 115.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 138.0, 145.0, 200.0, 121.0, 147.0, 142.0, 115.0, 214.0, 147.0], "value": 153.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 244.0, 207.0, 197.0, 199.0, 235.0, 283.0, 194.0, 198.0, 197.0], "value": 215.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 81.0, 75.0, 174.0, 55.0, 59.0, 62.0, 70.0, 128.0, 58.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 139.0, 81.0, 82.0, 79.0, 122.0, 81.0, 81.0, 126.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 122.0, 90.0, 132.0, 87.0, 83.0, 132.0, 77.0, 76.0], "value": 97.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 124.0, 97.0, 144.0, 110.0, 146.0, 150.0, 113.0, 136.0], "value": 121.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 149.0, 132.0, 94.0, 102.0, 103.0, 102.0, 101.0, 103.0, 101.0], "value": 111.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 81.0, 49.0, 57.0, 57.0, 49.0, 119.0, 85.0, 87.0], "value": 70.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 79.0, 82.0, 83.0, 76.0, 84.0, 83.0, 71.0, 71.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 90.0, 87.0, 88.0, 87.0, 87.0, 80.0, 98.0, 112.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 166.0, 167.0, 156.0, 150.0, 160.0, 156.0, 151.0, 211.0, 143.0], "value": 159.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 168.0, 162.0, 169.0, 194.0, 202.0, 165.0, 167.0, 167.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 69.0, 68.0, 67.0, 68.0, 87.0, 80.0, 61.0], "value": 69.8, "unit": "score"}]}]} 21:56:06 INFO - Return code: 0 21:56:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:56:06 ERROR - # TBPL SUCCESS # 21:56:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:56:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:56:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:56:06 INFO - 2016-05-20 21:56:06.284 screenresolution[1982:16354] starting screenresolution argv=screenresolution get 21:56:06 INFO - 2016-05-20 21:56:06.287 screenresolution[1982:16354] Display 0: 1600x1200x32@60 21:56:06 INFO - 2016-05-20 21:56:06.293 screenresolution[1983:16357] starting screenresolution argv=screenresolution list 21:56:06 INFO - Available Modes on Display 0 21:56:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:56:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:56:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:56:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:56:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:56:06 INFO - Intel Iris: 21:56:06 INFO - Chipset Model: Intel Iris 21:56:06 INFO - Type: GPU 21:56:06 INFO - Bus: Built-In 21:56:06 INFO - VRAM (Dynamic, Max): 1536 MB 21:56:06 INFO - Vendor: Intel (0x8086) 21:56:06 INFO - Device ID: 0x0a2e 21:56:06 INFO - Revision ID: 0x0009 21:56:06 INFO - Displays: 21:56:06 INFO - Display: 21:56:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:56:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:56:06 INFO - Display Serial Number: 1600x1200 60 21:56:06 INFO - Main Display: Yes 21:56:06 INFO - Mirror: Off 21:56:06 INFO - Online: Yes 21:56:06 INFO - Rotation: Supported 21:56:06 INFO - Return code: 0 21:56:06 INFO - Running post-action listener: _resource_record_post_action 21:56:06 INFO - ##### 21:56:06 INFO - ##### Finished run-tests step (success) 21:56:06 INFO - ##### 21:56:06 INFO - Running post-run listener: _resource_record_post_run 21:56:06 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 12288; Write bytes: 698146304; Read time: 2; Write time: 3095 21:56:06 INFO - TinderboxPrint: CPU usage
54.4% 21:56:06 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 2 21:56:06 INFO - TinderboxPrint: I/O write bytes / time
698,146,304 / 3,095 21:56:06 INFO - TinderboxPrint: CPU idle
2,206.3 (45.6%) 21:56:06 INFO - TinderboxPrint: CPU system
981.7 (20.3%) 21:56:06 INFO - TinderboxPrint: CPU user
1,653.7 (34.2%) 21:56:06 INFO - TinderboxPrint: Swap in / out
395,685,888 / 0 21:56:06 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 379480576; Write bytes: 373990912; Read time: 28155; Write time: 679 21:56:06 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 0; Write bytes: 299194368; Read time: 0; Write time: 2367 21:56:06 INFO - Running post-run listener: _upload_blobber_files 21:56:06 INFO - Blob upload gear active. 21:56:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:56:06 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 21:56:06 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'] 21:56:06 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 21:56:07 INFO - (blobuploader) - INFO - Open directory for files ... 21:56:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:56:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:56:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:08 INFO - (blobuploader) - INFO - Done attempting. 21:56:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:56:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:56:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:09 INFO - (blobuploader) - INFO - Done attempting. 21:56:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:56:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:56:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:11 INFO - (blobuploader) - INFO - Done attempting. 21:56:11 INFO - (blobuploader) - INFO - Iteration through files over. 21:56:11 INFO - Return code: 0 21:56:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:56:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:56:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b634dcdb1d460b9875d15aa6e7aeabf4d1bf7cabc8798b271e2ff28f5ff9592e642036531e4768219d33cdb247fc1de855abe1eaab8a6d64e24d66344b7a371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8d7b13866d681fc1e5d21e8c6d731bec08a557bbe10608a6fbed010cdfce653124210f6b203429a6366f17b9fa08adcc297e749d98c92da4cfec3cc2c27f2acc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e1d54a844f1312f529718ec5b28383c9cf4d23579355ee57d2b7b3d095186d287966fa2b565b000c6726b712e451dc41358f7ed130eb8ec68daee9ad00aaa0e9"} 21:56:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:56:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:56:11 INFO - Contents: 21:56:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b634dcdb1d460b9875d15aa6e7aeabf4d1bf7cabc8798b271e2ff28f5ff9592e642036531e4768219d33cdb247fc1de855abe1eaab8a6d64e24d66344b7a371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8d7b13866d681fc1e5d21e8c6d731bec08a557bbe10608a6fbed010cdfce653124210f6b203429a6366f17b9fa08adcc297e749d98c92da4cfec3cc2c27f2acc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e1d54a844f1312f529718ec5b28383c9cf4d23579355ee57d2b7b3d095186d287966fa2b565b000c6726b712e451dc41358f7ed130eb8ec68daee9ad00aaa0e9"} 21:56:11 INFO - Running post-run listener: copy_logs_to_upload_dir 21:56:11 INFO - Copying logs to upload dir... 21:56:11 INFO - mkdir: /builds/slave/test/build/upload/logs 21:56:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.939804 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-20 21:56:11.614645) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 21:56:11.618668) ========= 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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b634dcdb1d460b9875d15aa6e7aeabf4d1bf7cabc8798b271e2ff28f5ff9592e642036531e4768219d33cdb247fc1de855abe1eaab8a6d64e24d66344b7a371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8d7b13866d681fc1e5d21e8c6d731bec08a557bbe10608a6fbed010cdfce653124210f6b203429a6366f17b9fa08adcc297e749d98c92da4cfec3cc2c27f2acc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e1d54a844f1312f529718ec5b28383c9cf4d23579355ee57d2b7b3d095186d287966fa2b565b000c6726b712e451dc41358f7ed130eb8ec68daee9ad00aaa0e9"} build_url:https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010784 build_url: 'https://queue.taskcluster.net/v1/task/OhmWatUBRnmVa6bVpkeuVQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b634dcdb1d460b9875d15aa6e7aeabf4d1bf7cabc8798b271e2ff28f5ff9592e642036531e4768219d33cdb247fc1de855abe1eaab8a6d64e24d66344b7a371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8d7b13866d681fc1e5d21e8c6d731bec08a557bbe10608a6fbed010cdfce653124210f6b203429a6366f17b9fa08adcc297e749d98c92da4cfec3cc2c27f2acc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e1d54a844f1312f529718ec5b28383c9cf4d23579355ee57d2b7b3d095186d287966fa2b565b000c6726b712e451dc41358f7ed130eb8ec68daee9ad00aaa0e9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 21:56:11.647098) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:56:11.647444) ========= 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.OpLLqkNZH0/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.mIPwHMjlyy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.005166 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:56:11.696295) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 21:56:11.696636) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 21:56:11.697015) ========= ========= Total master_lag: 1.00 =========