builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0079 starttime: 1463625337.9 results: success (0) buildid: 20160518172746 builduid: 6061971e4f2143fb821a97bb10053a7f revision: e0fa395e4982a5225e73908a4a0816932043440d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:37.902960) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:37.903391) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:37.903662) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:37.921366) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:37.921718) ========= 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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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-18 19:35:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 190M=0s 2016-05-18 19:35:38 (190 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097520 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:38.037157) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:38.037472) ========= 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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.026741 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:38.085736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 19:35:38.086115) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e0fa395e4982a5225e73908a4a0816932043440d --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 e0fa395e4982a5225e73908a4a0816932043440d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gvEJU7jmmh/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.DB1oXgO5a0/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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-18 19:35:38,160 truncating revision to first 12 chars 2016-05-18 19:35:38,160 Setting DEBUG logging. 2016-05-18 19:35:38,160 attempt 1/10 2016-05-18 19:35:38,160 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e0fa395e4982?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 19:35:39,191 unpacking tar archive at: fx-team-e0fa395e4982/testing/mozharness/ program finished with exit code 0 elapsedTime=1.281038 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 19:35:39.384168) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:39.384482) ========= script_repo_revision: e0fa395e4982a5225e73908a4a0816932043440d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:39.384903) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:39.385211) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 19:35:39.398841) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-18 19:35:39.399142) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gvEJU7jmmh/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.DB1oXgO5a0/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 19:35:39 INFO - MultiFileLogger online at 20160518 19:35:39 in /builds/slave/test 19:35:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 19:35:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:35:39 INFO - {'append_to_log': False, 19:35:39 INFO - 'base_work_dir': '/builds/slave/test', 19:35:39 INFO - 'blob_upload_branch': 'Fx-Team', 19:35:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:35:39 INFO - 'branch': 'Fx-Team', 19:35:39 INFO - 'buildbot_json_path': 'buildprops.json', 19:35:39 INFO - 'config_files': ('talos/mac_config.py',), 19:35:39 INFO - 'default_actions': ('clobber', 19:35:39 INFO - 'read-buildbot-config', 19:35:39 INFO - 'download-and-extract', 19:35:39 INFO - 'populate-webroot', 19:35:39 INFO - 'create-virtualenv', 19:35:39 INFO - 'install', 19:35:39 INFO - 'run-tests'), 19:35:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:35:39 INFO - 'download_minidump_stackwalk': True, 19:35:39 INFO - 'download_symbols': 'ondemand', 19:35:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:35:39 INFO - 'tooltool.py': '/tools/tooltool.py', 19:35:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:35:39 INFO - '/tools/misc-python/virtualenv.py')}, 19:35:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:35:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:35:39 INFO - 'installer_path': 'installer.exe', 19:35:39 INFO - 'log_level': 'info', 19:35:39 INFO - 'log_name': 'talos', 19:35:39 INFO - 'log_to_console': True, 19:35:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:35:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:35:39 INFO - 'opt_config_files': (), 19:35:39 INFO - 'pip_index': False, 19:35:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:35:39 INFO - 'cmd': ('bash', 19:35:39 INFO - '-c', 19:35:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:35:39 INFO - 'enabled': True, 19:35:39 INFO - 'halt_on_failure': False, 19:35:39 INFO - 'name': 'check_screen_resolution'},), 19:35:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:35:39 INFO - 'cmd': ('bash', 19:35:39 INFO - '-c', 19:35:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:35:39 INFO - 'enabled': True, 19:35:39 INFO - 'halt_on_failure': False, 19:35:39 INFO - 'name': 'check_screen_resolution'},), 19:35:39 INFO - 'run_cmd_checks_enabled': True, 19:35:39 INFO - 'sps_profile': False, 19:35:39 INFO - 'sps_profile_interval': 0, 19:35:39 INFO - 'suite': 'dromaeojs', 19:35:39 INFO - 'system_bits': '32', 19:35:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:35:39 INFO - 'title': 't-yosemite-r7-0079', 19:35:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:35:39 INFO - 'use_talos_json': True, 19:35:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:35:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:35:39 INFO - 'work_dir': 'build'} 19:35:39 INFO - ##### 19:35:39 INFO - ##### Running clobber step. 19:35:39 INFO - ##### 19:35:39 INFO - Running pre-action listener: _resource_record_pre_action 19:35:39 INFO - Running main action method: clobber 19:35:39 INFO - rmtree: /builds/slave/test/build 19:35:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:35:41 INFO - Running post-action listener: _resource_record_post_action 19:35:41 INFO - ##### 19:35:41 INFO - ##### Finished clobber step (success) 19:35:41 INFO - ##### 19:35:41 INFO - ##### 19:35:41 INFO - ##### Running read-buildbot-config step. 19:35:41 INFO - ##### 19:35:41 INFO - Running pre-action listener: _resource_record_pre_action 19:35:41 INFO - Running main action method: read_buildbot_config 19:35:41 INFO - Using buildbot properties: 19:35:41 INFO - { 19:35:41 INFO - "project": "", 19:35:41 INFO - "product": "firefox", 19:35:41 INFO - "script_repo_revision": "production", 19:35:41 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 19:35:41 INFO - "repository": "", 19:35:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 19:35:41 INFO - "buildid": "20160518172746", 19:35:41 INFO - "pgo_build": "False", 19:35:41 INFO - "basedir": "/builds/slave/test", 19:35:41 INFO - "buildnumber": 31, 19:35:41 INFO - "slavename": "t-yosemite-r7-0079", 19:35:41 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 19:35:41 INFO - "platform": "yosemite_r7", 19:35:41 INFO - "scheduler": "tests-fx-team-macosx64-talos", 19:35:41 INFO - "branch": "fx-team", 19:35:41 INFO - "repo_path": "integration/fx-team", 19:35:41 INFO - "revision": "e0fa395e4982a5225e73908a4a0816932043440d", 19:35:41 INFO - "stage_platform": "macosx64", 19:35:41 INFO - "builduid": "6061971e4f2143fb821a97bb10053a7f", 19:35:41 INFO - "slavebuilddir": "test" 19:35:41 INFO - } 19:35:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:35:41 INFO - Running post-action listener: _resource_record_post_action 19:35:41 INFO - ##### 19:35:41 INFO - ##### Finished read-buildbot-config step (success) 19:35:41 INFO - ##### 19:35:41 INFO - ##### 19:35:41 INFO - ##### Running download-and-extract step. 19:35:41 INFO - ##### 19:35:41 INFO - Running pre-action listener: _resource_record_pre_action 19:35:41 INFO - Running main action method: download_and_extract 19:35:41 INFO - mkdir: /builds/slave/test/build/tests 19:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:41 INFO - https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:35:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:35:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:35:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:35:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 19:35:43 INFO - Downloaded 1199 bytes. 19:35:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:35:43 INFO - Using the following test package requirements: 19:35:43 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:35:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:35:43 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:35:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:35:43 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:35:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:35:43 INFO - u'jsshell-mac.zip'], 19:35:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:35:43 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:35:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:35:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:35:43 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:35:43 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:35:43 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:35:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:35:43 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:35:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:35:43 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:35:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:43 INFO - https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:35:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:35:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:35:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:35:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:35:44 INFO - Downloaded 18629048 bytes. 19:35:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:35:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:35:47 INFO - Return code: 0 19:35:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19: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')]} 19:35:47 INFO - https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:35:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:35:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:35:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:35:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:35:48 INFO - Downloaded 18629048 bytes. 19:35:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:35:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:35:52 INFO - Return code: 0 19:35:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:52 INFO - https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:35:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:35:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:35:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:35:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:35:53 INFO - Downloaded 10840194 bytes. 19:35:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:35:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:35:53 INFO - Return code: 0 19:35:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:53 INFO - https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:35:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:35:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:35:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:35:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 19:35:56 INFO - Downloaded 110317337 bytes. 19:35:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:35:56 INFO - mkdir: /builds/slave/test/properties 19:35:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:35:56 INFO - Writing to file /builds/slave/test/properties/build_url 19:35:56 INFO - Contents: 19:35:56 INFO - build_url:https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:35:58 INFO - Running post-action listener: _resource_record_post_action 19:35:58 INFO - Running post-action listener: set_extra_try_arguments 19:35:58 INFO - ##### 19:35:58 INFO - ##### Finished download-and-extract step (success) 19:35:58 INFO - ##### 19:35:58 INFO - ##### 19:35:58 INFO - ##### Running populate-webroot step. 19:35:58 INFO - ##### 19:35:58 INFO - Running pre-action listener: _resource_record_pre_action 19:35:58 INFO - Running main action method: populate_webroot 19:35:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:35:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:35:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:35:58 INFO - '--tppagecycles', 19:35:58 INFO - '7'], 19:35:58 INFO - 'tests': ['tsvgm']}}, 19:35:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:35:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:35:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:35:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:35:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:35:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:35:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:58 INFO - 'talos_options': ['--disable-e10s'], 19:35:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:35:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:35:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:58 INFO - 'talos_options': ['--disable-e10s'], 19:35:58 INFO - 'tests': ['damp', 'tps']}, 19:35:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:58 INFO - 'tests': ['damp', 'tps']}, 19:35:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:35:58 INFO - 'tests': ['dromaeo_dom']}, 19:35:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:35:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:35:58 INFO - 'tests': ['a11yr', 19:35:58 INFO - 'ts_paint', 19:35:58 INFO - 'tpaint', 19:35:58 INFO - 'sessionrestore', 19:35:58 INFO - 'sessionrestore_no_auto_restore', 19:35:58 INFO - 'tabpaint']}, 19:35:58 INFO - 'other-e10s': {'tests': ['a11yr', 19:35:58 INFO - 'ts_paint', 19:35:58 INFO - 'tpaint', 19:35:58 INFO - 'sessionrestore', 19:35:58 INFO - 'sessionrestore_no_auto_restore', 19:35:58 INFO - 'tabpaint']}, 19:35:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:35:58 INFO - 'ts_paint', 19:35:58 INFO - 'tpaint', 19:35:58 INFO - 'sessionrestore', 19:35:58 INFO - 'sessionrestore_no_auto_restore', 19:35:58 INFO - 'tabpaint']}, 19:35:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:35:58 INFO - 'ts_paint', 19:35:58 INFO - 'tpaint', 19:35:58 INFO - 'sessionrestore', 19:35:58 INFO - 'sessionrestore_no_auto_restore', 19:35:58 INFO - 'tabpaint']}, 19:35:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:35:58 INFO - 'tests': ['a11yr', 19:35:58 INFO - 'ts_paint', 19:35:58 INFO - 'tpaint', 19:35:58 INFO - 'sessionrestore', 19:35:58 INFO - 'sessionrestore_no_auto_restore', 19:35:58 INFO - 'tabpaint']}, 19:35:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:35:58 INFO - 'tests': ['a11yr', 19:35:58 INFO - 'ts_paint', 19:35:58 INFO - 'tpaint', 19:35:58 INFO - 'sessionrestore', 19:35:58 INFO - 'sessionrestore_no_auto_restore', 19:35:58 INFO - 'tabpaint']}, 19:35:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:35:58 INFO - 'tests': ['tsvgx', 19:35:58 INFO - 'tsvgr_opacity', 19:35:58 INFO - 'tart', 19:35:58 INFO - 'tscrollx', 19:35:58 INFO - 'cart']}, 19:35:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:35:58 INFO - 'tsvgr_opacity', 19:35:58 INFO - 'tart', 19:35:58 INFO - 'tscrollx', 19:35:58 INFO - 'cart']}, 19:35:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:58 INFO - 'talos_options': ['--disable-e10s'], 19:35:58 INFO - 'tests': ['tp5o']}, 19:35:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:58 INFO - 'tests': ['tp5o']}, 19:35:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:58 INFO - 'talos_options': ['--disable-e10s', 19:35:58 INFO - '--xperf_path', 19:35:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:35:58 INFO - 'tests': ['tp5n']}, 19:35:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:58 INFO - 'talos_options': ['--xperf_path', 19:35:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:35:58 INFO - 'tests': ['tp5n']}}, 19:35:58 INFO - 'talos.zip': {'path': '', 19:35:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:35:58 INFO - Running post-action listener: _resource_record_post_action 19:35:58 INFO - ##### 19:35:58 INFO - ##### Finished populate-webroot step (success) 19:35:58 INFO - ##### 19:35:58 INFO - ##### 19:35:58 INFO - ##### Running create-virtualenv step. 19:35:58 INFO - ##### 19:35:58 INFO - Running pre-action listener: _resource_record_pre_action 19:35:58 INFO - Running main action method: create_virtualenv 19:35:58 INFO - Creating virtualenv /builds/slave/test/build/venv 19:35:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:35:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:35:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:35:58 INFO - Using real prefix '/tools/python27' 19:35:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:35:58 INFO - Installing distribute.............................................................................................................................................................................................done. 19:36:01 INFO - Installing pip.................done. 19:36:01 INFO - Return code: 0 19:36:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:36:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:01 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 0x100728a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10072e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe094b46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ccd30>, '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 0x1003f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005c3b20>, '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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:36:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:36:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:36:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gvEJU7jmmh/Render', 19:36:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:01 INFO - 'HOME': '/Users/cltbld', 19:36:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:01 INFO - 'LOGNAME': 'cltbld', 19:36:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:01 INFO - 'MOZ_NO_REMOTE': '1', 19:36:01 INFO - 'NO_EM_RESTART': '1', 19:36:01 INFO - 'PAGER': '/bin/cat', 19:36:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:01 INFO - 'PWD': '/builds/slave/test', 19:36:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:01 INFO - 'SHELL': '/bin/bash', 19:36:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DB1oXgO5a0/Listeners', 19:36:01 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 19:36:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:01 INFO - 'USER': 'cltbld', 19:36:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:01 INFO - 'XPC_FLAGS': '0x0', 19:36:01 INFO - 'XPC_SERVICE_NAME': '0', 19:36:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:36:02 INFO - Cleaning up... 19:36:02 INFO - Return code: 0 19:36:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100728a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10072e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe094b46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ccd30>, '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 0x1003f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005c3b20>, '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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:36:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:36:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:36:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gvEJU7jmmh/Render', 19:36:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:02 INFO - 'HOME': '/Users/cltbld', 19:36:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:02 INFO - 'LOGNAME': 'cltbld', 19:36:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:02 INFO - 'MOZ_NO_REMOTE': '1', 19:36:02 INFO - 'NO_EM_RESTART': '1', 19:36:02 INFO - 'PAGER': '/bin/cat', 19:36:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:02 INFO - 'PWD': '/builds/slave/test', 19:36:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:02 INFO - 'SHELL': '/bin/bash', 19:36:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DB1oXgO5a0/Listeners', 19:36:02 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 19:36:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:02 INFO - 'USER': 'cltbld', 19:36:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:02 INFO - 'XPC_FLAGS': '0x0', 19:36:02 INFO - 'XPC_SERVICE_NAME': '0', 19:36:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:02 INFO - Downloading/unpacking psutil>=3.1.1 19:36:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:36:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:36:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:36:05 INFO - Installing collected packages: psutil 19:36:05 INFO - Running setup.py install for psutil 19:36:05 INFO - building 'psutil._psutil_osx' extension 19:36:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:36:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:36:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:36:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:36:06 INFO - building 'psutil._psutil_posix' extension 19:36:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:36:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:36:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:36:06 INFO - ^ 19:36:06 INFO - 1 warning generated. 19:36:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:36:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:36:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:36:06 INFO - Successfully installed psutil 19:36:06 INFO - Cleaning up... 19:36:06 INFO - Return code: 0 19:36:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:06 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 0x100728a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10072e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe094b46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ccd30>, '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 0x1003f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005c3b20>, '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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:36:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:36:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:36:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gvEJU7jmmh/Render', 19:36:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:06 INFO - 'HOME': '/Users/cltbld', 19:36:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:06 INFO - 'LOGNAME': 'cltbld', 19:36:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:06 INFO - 'MOZ_NO_REMOTE': '1', 19:36:06 INFO - 'NO_EM_RESTART': '1', 19:36:06 INFO - 'PAGER': '/bin/cat', 19:36:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:06 INFO - 'PWD': '/builds/slave/test', 19:36:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:06 INFO - 'SHELL': '/bin/bash', 19:36:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DB1oXgO5a0/Listeners', 19:36:06 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 19:36:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:06 INFO - 'USER': 'cltbld', 19:36:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:06 INFO - 'XPC_FLAGS': '0x0', 19:36:06 INFO - 'XPC_SERVICE_NAME': '0', 19:36:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:36:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:36:09 INFO - Installing collected packages: mozsystemmonitor 19:36:09 INFO - Running setup.py install for mozsystemmonitor 19:36:09 INFO - Successfully installed mozsystemmonitor 19:36:09 INFO - Cleaning up... 19:36:09 INFO - Return code: 0 19:36:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:36:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100728a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10072e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe094b46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ccd30>, '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 0x1003f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005c3b20>, '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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:36:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:36:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gvEJU7jmmh/Render', 19:36:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:09 INFO - 'HOME': '/Users/cltbld', 19:36:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:09 INFO - 'LOGNAME': 'cltbld', 19:36:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:09 INFO - 'MOZ_NO_REMOTE': '1', 19:36:09 INFO - 'NO_EM_RESTART': '1', 19:36:09 INFO - 'PAGER': '/bin/cat', 19:36:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:09 INFO - 'PWD': '/builds/slave/test', 19:36:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:09 INFO - 'SHELL': '/bin/bash', 19:36:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DB1oXgO5a0/Listeners', 19:36:09 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 19:36:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:09 INFO - 'USER': 'cltbld', 19:36:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:09 INFO - 'XPC_FLAGS': '0x0', 19:36:09 INFO - 'XPC_SERVICE_NAME': '0', 19:36:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:09 INFO - Downloading/unpacking blobuploader==1.2.4 19:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:12 INFO - Downloading blobuploader-1.2.4.tar.gz 19:36:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:36:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:36:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:13 INFO - Downloading docopt-0.6.1.tar.gz 19:36:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:36:13 INFO - Installing collected packages: blobuploader, requests, docopt 19:36:13 INFO - Running setup.py install for blobuploader 19:36:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:36:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:36:13 INFO - Running setup.py install for requests 19:36:14 INFO - Running setup.py install for docopt 19:36:14 INFO - Successfully installed blobuploader requests docopt 19:36:14 INFO - Cleaning up... 19:36:14 INFO - Return code: 0 19:36:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:36:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:14 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 0x100728a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10072e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe094b46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ccd30>, '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 0x1003f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005c3b20>, '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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:36:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:36:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:36:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gvEJU7jmmh/Render', 19:36:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:14 INFO - 'HOME': '/Users/cltbld', 19:36:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:14 INFO - 'LOGNAME': 'cltbld', 19:36:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:14 INFO - 'MOZ_NO_REMOTE': '1', 19:36:14 INFO - 'NO_EM_RESTART': '1', 19:36:14 INFO - 'PAGER': '/bin/cat', 19:36:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:14 INFO - 'PWD': '/builds/slave/test', 19:36:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:14 INFO - 'SHELL': '/bin/bash', 19:36:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DB1oXgO5a0/Listeners', 19:36:14 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 19:36:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:14 INFO - 'USER': 'cltbld', 19:36:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:14 INFO - 'XPC_FLAGS': '0x0', 19:36:14 INFO - 'XPC_SERVICE_NAME': '0', 19:36:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:36:14 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-9kt1bM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:36:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:36:14 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-cnMsIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:36:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:36:14 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-x8pp3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:36:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:36:14 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-2HE6VL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:36:15 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-vVOte3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:36:15 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Ok3VOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:36:15 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-2IvGcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:36:15 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-q2HPHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:36:15 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Gus61R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:36:15 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-kA9CwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:36:15 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-9JFLuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:36:15 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-vQ5OMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:36:15 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-raEBCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:36:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:36:15 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Fxec3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:36:16 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Gt3UXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:36:16 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-EqNBMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:36:16 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-HWPePQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:36:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:36:16 INFO - Running setup.py install for manifestparser 19:36:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:36:16 INFO - Running setup.py install for mozcrash 19:36:16 INFO - Running setup.py install for mozdebug 19:36:16 INFO - Running setup.py install for mozdevice 19:36:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:36:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:36:17 INFO - Running setup.py install for mozfile 19:36:17 INFO - Running setup.py install for mozhttpd 19:36:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:36:17 INFO - Running setup.py install for mozinfo 19:36:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:36:17 INFO - Running setup.py install for mozInstall 19:36:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:36:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:36:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:36:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:36:17 INFO - Running setup.py install for mozleak 19:36:17 INFO - Running setup.py install for mozlog 19:36:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:36:17 INFO - Running setup.py install for moznetwork 19:36:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:36:18 INFO - Running setup.py install for mozprocess 19:36:18 INFO - Running setup.py install for mozprofile 19:36:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:36:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:36:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:36:18 INFO - Running setup.py install for mozrunner 19:36:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:36:18 INFO - Running setup.py install for mozscreenshot 19:36:18 INFO - Running setup.py install for moztest 19:36:18 INFO - Running setup.py install for mozversion 19:36:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:36:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:36:18 INFO - Cleaning up... 19:36:18 INFO - Return code: 0 19:36:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:36:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:18 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 0x100728a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10072e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe094b46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ccd30>, '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 0x1003f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005c3b20>, '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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:36:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:36:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:36:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gvEJU7jmmh/Render', 19:36:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:18 INFO - 'HOME': '/Users/cltbld', 19:36:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:18 INFO - 'LOGNAME': 'cltbld', 19:36:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:18 INFO - 'MOZ_NO_REMOTE': '1', 19:36:18 INFO - 'NO_EM_RESTART': '1', 19:36:18 INFO - 'PAGER': '/bin/cat', 19:36:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:18 INFO - 'PWD': '/builds/slave/test', 19:36:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:18 INFO - 'SHELL': '/bin/bash', 19:36:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DB1oXgO5a0/Listeners', 19:36:18 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 19:36:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:18 INFO - 'USER': 'cltbld', 19:36:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:18 INFO - 'XPC_FLAGS': '0x0', 19:36:18 INFO - 'XPC_SERVICE_NAME': '0', 19:36:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:36:19 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-LBKApP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:36:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:36:19 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-5H_nOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:36:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:36:19 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-GfOg_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:36:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:36:19 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-cwXFJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:36:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:36:19 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-nmMxq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:36:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:36:19 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-OJAHKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:36:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:36:19 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-USUi1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:36:20 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-telewp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:36:20 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-9nl7j0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:36:20 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-tBhV04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:36:20 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-jZ1Hgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:36:20 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-oODBGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:36:20 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-xfayDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:36:20 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-PCdORd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:36:20 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-LMCoeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:36:20 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Rl7uQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:36:20 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-XYhebi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:36:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:36:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:36:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:23 INFO - Downloading blessings-1.6.tar.gz 19:36:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:36:23 INFO - Installing collected packages: blessings 19:36:23 INFO - Running setup.py install for blessings 19:36:23 INFO - Successfully installed blessings 19:36:23 INFO - Cleaning up... 19:36:23 INFO - Return code: 0 19:36:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:36:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:36:24 INFO - Reading from file tmpfile_stdout 19:36:24 INFO - Current package versions: 19:36:24 INFO - blessings == 1.6 19:36:24 INFO - blobuploader == 1.2.4 19:36:24 INFO - docopt == 0.6.1 19:36:24 INFO - manifestparser == 1.1 19:36:24 INFO - mozInstall == 1.12 19:36:24 INFO - mozcrash == 0.17 19:36:24 INFO - mozdebug == 0.1 19:36:24 INFO - mozdevice == 0.48 19:36:24 INFO - mozfile == 1.2 19:36:24 INFO - mozhttpd == 0.7 19:36:24 INFO - mozinfo == 0.9 19:36:24 INFO - mozleak == 0.1 19:36:24 INFO - mozlog == 3.2 19:36:24 INFO - moznetwork == 0.27 19:36:24 INFO - mozprocess == 0.22 19:36:24 INFO - mozprofile == 0.28 19:36:24 INFO - mozrunner == 6.11 19:36:24 INFO - mozscreenshot == 0.1 19:36:24 INFO - mozsystemmonitor == 0.3 19:36:24 INFO - moztest == 0.7 19:36:24 INFO - mozversion == 1.4 19:36:24 INFO - psutil == 3.1.1 19:36:24 INFO - requests == 1.2.3 19:36:24 INFO - wsgiref == 0.1.2 19:36:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:24 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 0x100728a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10072e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe094b46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ccd30>, '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 0x1003f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005c3b20>, '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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:36:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:36:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:36:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gvEJU7jmmh/Render', 19:36:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:24 INFO - 'HOME': '/Users/cltbld', 19:36:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:24 INFO - 'LOGNAME': 'cltbld', 19:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:24 INFO - 'MOZ_NO_REMOTE': '1', 19:36:24 INFO - 'NO_EM_RESTART': '1', 19:36:24 INFO - 'PAGER': '/bin/cat', 19:36:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:24 INFO - 'PWD': '/builds/slave/test', 19:36:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:24 INFO - 'SHELL': '/bin/bash', 19:36:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DB1oXgO5a0/Listeners', 19:36:24 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 19:36:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:24 INFO - 'USER': 'cltbld', 19:36:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:24 INFO - 'XPC_FLAGS': '0x0', 19:36:24 INFO - 'XPC_SERVICE_NAME': '0', 19:36:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:36:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:36:24 INFO - Cleaning up... 19:36:24 INFO - Return code: 0 19:36:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:24 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 0x100728a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10072e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe094b46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ccd30>, '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 0x1003f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005c3b20>, '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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:36:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:36:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:36:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gvEJU7jmmh/Render', 19:36:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:24 INFO - 'HOME': '/Users/cltbld', 19:36:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:24 INFO - 'LOGNAME': 'cltbld', 19:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:24 INFO - 'MOZ_NO_REMOTE': '1', 19:36:24 INFO - 'NO_EM_RESTART': '1', 19:36:24 INFO - 'PAGER': '/bin/cat', 19:36:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:24 INFO - 'PWD': '/builds/slave/test', 19:36:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:24 INFO - 'SHELL': '/bin/bash', 19:36:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DB1oXgO5a0/Listeners', 19:36:24 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 19:36:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:24 INFO - 'USER': 'cltbld', 19:36:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:24 INFO - 'XPC_FLAGS': '0x0', 19:36:24 INFO - 'XPC_SERVICE_NAME': '0', 19:36:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:24 INFO - Downloading/unpacking jsonschema 19:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:36:27 INFO - Downloading/unpacking functools32 (from jsonschema) 19:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:27 INFO - Downloading functools32-3.2.3-2.tar.gz 19:36:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:36:27 INFO - warning: no files found matching '*.txt' 19:36:27 INFO - no previously-included directories found matching 'build' 19:36:27 INFO - no previously-included directories found matching 'dist' 19:36:27 INFO - no previously-included directories found matching '.git*' 19:36:27 INFO - Installing collected packages: jsonschema, functools32 19:36:27 INFO - Running setup.py install for functools32 19:36:27 INFO - warning: no files found matching '*.txt' 19:36:27 INFO - no previously-included directories found matching 'build' 19:36:27 INFO - no previously-included directories found matching 'dist' 19:36:27 INFO - no previously-included directories found matching '.git*' 19:36:27 INFO - Successfully installed jsonschema functools32 19:36:27 INFO - Cleaning up... 19:36:27 INFO - Return code: 0 19:36:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:36:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:27 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 0x100728a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10072e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe094b46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005ccd30>, '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 0x1003f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005c3b20>, '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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:36:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 19:36:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 19:36:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gvEJU7jmmh/Render', 19:36:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:27 INFO - 'HOME': '/Users/cltbld', 19:36:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:27 INFO - 'LOGNAME': 'cltbld', 19:36:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:27 INFO - 'MOZ_NO_REMOTE': '1', 19:36:27 INFO - 'NO_EM_RESTART': '1', 19:36:27 INFO - 'PAGER': '/bin/cat', 19:36:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:27 INFO - 'PWD': '/builds/slave/test', 19:36:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:27 INFO - 'SHELL': '/bin/bash', 19:36:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DB1oXgO5a0/Listeners', 19:36:27 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 19:36:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:27 INFO - 'USER': 'cltbld', 19:36:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:27 INFO - 'XPC_FLAGS': '0x0', 19:36:27 INFO - 'XPC_SERVICE_NAME': '0', 19:36:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:28 INFO - Downloading/unpacking flake8 19:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:36:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:36: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 19:36: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 19:36: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 19:36: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 19:36: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 19:36: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 19:36:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:36:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:36: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 19:36: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 19:36: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 19:36: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 19:36: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 19:36: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 19:36:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:36:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:36:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:36:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:36:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:36:31 INFO - no previously-included directories found matching 'docs/_build' 19:36:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:36:31 INFO - Running setup.py install for pep8 19:36:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:36:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:36:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:36:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:36:31 INFO - no previously-included directories found matching 'docs/_build' 19:36:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:36:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:36:31 INFO - Cleaning up... 19:36:32 INFO - Return code: 0 19:36:32 INFO - Running post-action listener: _resource_record_post_action 19:36:32 INFO - Running post-action listener: _start_resource_monitoring 19:36:32 INFO - Starting resource monitoring. 19:36:32 INFO - ##### 19:36:32 INFO - ##### Finished create-virtualenv step (success) 19:36:32 INFO - ##### 19:36:32 INFO - ##### 19:36:32 INFO - ##### Running install step. 19:36:32 INFO - ##### 19:36:32 INFO - Running pre-action listener: _resource_record_pre_action 19:36:32 INFO - Running main action method: install 19:36:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:36:32 INFO - Reading from file tmpfile_stdout 19:36:32 INFO - Detecting whether we're running mozinstall >=1.0... 19:36:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:36:32 INFO - Reading from file tmpfile_stdout 19:36:32 INFO - Output received: 19:36:32 INFO - Usage: mozinstall [options] installer 19:36:32 INFO - Options: 19:36:32 INFO - -h, --help show this help message and exit 19:36:32 INFO - -d DEST, --destination=DEST 19:36:32 INFO - Directory to install application into. [default: 19:36:32 INFO - "/builds/slave/test"] 19:36:32 INFO - --app=APP Application being installed. [default: firefox] 19:36:32 INFO - mkdir: /builds/slave/test/build/application 19:36:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:37:05 INFO - Reading from file tmpfile_stdout 19:37:05 INFO - Output received: 19:37:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:37:05 INFO - Running post-action listener: _resource_record_post_action 19:37:05 INFO - ##### 19:37:05 INFO - ##### Finished install step (success) 19:37:05 INFO - ##### 19:37:05 INFO - ##### 19:37:05 INFO - ##### Running run-tests step. 19:37:05 INFO - ##### 19:37:05 INFO - Running pre-action listener: _resource_record_pre_action 19:37:05 INFO - Running main action method: run_tests 19:37:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:37:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:37:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:37:05 INFO - 2016-05-18 19:37:05.318 screenresolution[1643:7527] starting screenresolution argv=screenresolution get 19:37:05 INFO - 2016-05-18 19:37:05.321 screenresolution[1643:7527] Display 0: 1600x1200x32@60 19:37:05 INFO - 2016-05-18 19:37:05.327 screenresolution[1644:7529] starting screenresolution argv=screenresolution list 19:37:05 INFO - Available Modes on Display 0 19:37:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:37:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:37:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:37:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:37:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:37:05 INFO - Intel Iris: 19:37:05 INFO - Chipset Model: Intel Iris 19:37:05 INFO - Type: GPU 19:37:05 INFO - Bus: Built-In 19:37:05 INFO - VRAM (Dynamic, Max): 1536 MB 19:37:05 INFO - Vendor: Intel (0x8086) 19:37:05 INFO - Device ID: 0x0a2e 19:37:05 INFO - Revision ID: 0x0009 19:37:05 INFO - Displays: 19:37:05 INFO - Display: 19:37:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:37:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:37:05 INFO - Display Serial Number: 1600x1200 60 19:37:05 INFO - Main Display: Yes 19:37:05 INFO - Mirror: Off 19:37:05 INFO - Online: Yes 19:37:05 INFO - Rotation: Supported 19:37:05 INFO - Return code: 0 19:37:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:37:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:37:05 INFO - Python 2.7.3 19:37:05 INFO - Return code: 0 19:37:05 INFO - grabbing minidump binary from tooltool 19:37: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')]} 19:37:05 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 0x1005ccd30>, '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 0x1003f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005c3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:37:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:37:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:37:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:37:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:37:05 INFO - Return code: 0 19:37:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:37:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:37:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:37:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:37:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:37:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:37:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:37:06 INFO - Return code: 0 19:37:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:37:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0079 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:37:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gvEJU7jmmh/Render', 19:37:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:37:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:37:06 INFO - 'HOME': '/Users/cltbld', 19:37:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:37:06 INFO - 'LOGNAME': 'cltbld', 19:37:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:37:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:37:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:06 INFO - 'MOZ_NO_REMOTE': '1', 19:37:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:37:06 INFO - 'NO_EM_RESTART': '1', 19:37:06 INFO - 'PAGER': '/bin/cat', 19:37:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:37:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:06 INFO - 'PWD': '/builds/slave/test', 19:37:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:37:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:37:06 INFO - 'SHELL': '/bin/bash', 19:37:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DB1oXgO5a0/Listeners', 19:37:06 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 19:37:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:37:06 INFO - 'USER': 'cltbld', 19:37:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:37:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:37:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:06 INFO - 'XPC_FLAGS': '0x0', 19:37:06 INFO - 'XPC_SERVICE_NAME': '0', 19:37:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:37:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:37:07 INFO - mozversion application_buildid: 20160518172746 19:37:07 INFO - mozversion application_changeset: e0fa395e4982a5225e73908a4a0816932043440d 19:37:07 INFO - mozversion application_display_name: Nightly 19:37:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:37:07 INFO - mozversion application_name: Firefox 19:37:07 INFO - mozversion application_remotingname: firefox 19:37:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:37:07 INFO - mozversion application_vendor: Mozilla 19:37:07 INFO - mozversion application_version: 49.0a1 19:37:07 INFO - mozversion platform_buildid: 20160518172746 19:37:07 INFO - mozversion platform_changeset: e0fa395e4982a5225e73908a4a0816932043440d 19:37:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:37:07 INFO - mozversion platform_version: 49.0a1 19:37:07 INFO - using testdate: 1463625427 19:37:07 INFO - actual date: 1463625427 19:37:07 INFO - starting webserver on 'localhost:49276' 19:37:07 INFO - SUITE-START | Running 2 tests 19:37:07 INFO - TEST-START | dromaeo_css 19:37:07 INFO - operating with platform_type : mac_ 19:37:07 INFO - Initialising browser for dromaeo_css test... 19:37:07 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmp6qazs_/profile http://localhost:49276/getInfo.html) 19:37:09 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 19:37:09 INFO - PROCESS | 1662 | colorDepth:24 19:37:09 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 19:37:09 INFO - PROCESS | 1662 | __metrics 19:37:09 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:37:09 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:37:10 INFO - TEST-INFO | 1662: exit 0 19:37:10 INFO - Browser initialized. 19:37:10 INFO - Running cycle 1/1 for dromaeo_css test... 19:37:10 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmp6qazs_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:37:22 INFO - PROCESS | 1664 | RSS: Main: 238653440 19:37:22 INFO - PROCESS | 1664 | 19:38:13 INFO - PROCESS | 1664 | 1463625493929 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:40:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 19:40:25 INFO - PROCESS | 1664 | RSS: Main: 345120768 19:40:25 INFO - PROCESS | 1664 | 19:43:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 19:43:29 INFO - PROCESS | 1664 | RSS: Main: 373571584 19:43:29 INFO - PROCESS | 1664 | 19:46:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 19:46:32 INFO - PROCESS | 1664 | RSS: Main: 364883968 19:46:32 INFO - PROCESS | 1664 | 19:49:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 19:49:35 INFO - PROCESS | 1664 | RSS: Main: 370802688 19:49:35 INFO - PROCESS | 1664 | 19:52:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 19:52:38 INFO - PROCESS | 1664 | RSS: Main: 334983168 19:52:38 INFO - PROCESS | 1664 | 19:55:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 19:55:41 INFO - PROCESS | 1664 | RSS: Main: 380354560 19:55:41 INFO - PROCESS | 1664 | 19:55:41 INFO - PROCESS | 1664 | __start_tp_report 19:55:41 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 19:55:41 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 19:55:41 INFO - PROCESS | 1664 | |i|pagename|runs| 19:55:41 INFO - PROCESS | 1664 | |0;dojo.html;3257.7230935370494;3412.164019815144;3430.056734398042;3456.6370531094244;3488.4069708149627;5096.082705113087;5145.588352931766;5561.33264008319;5632.408597097302;5642.322921249451;5557.444255574441;5620.803271885485;5629.746661400237;5653.1237658162945;5654.6041777075625;5061.367329083864;5094.2103973884105;5102.872428189288;5191.4548972357925;5201.063808514466;5116;5194.376674799029;5203.349581302337;5205.557527610158;5224.634275600691;5071.619628527875;5091.261767043777;5103.4896510349035;5112.948870511284;5207.843764687065;5168.664036837631;5200.167973124319;5206.349206349207;5212.739363031833;5294.49702278283;4921.876953076166;4923.704577725348;4998.8250411235795;5145.25393817898;5159.845204643867;5453.072977593819;5460.399356070829;5460.918086228709;5466.125419932831;5532.806351777668;6095.928722681712;6107.908381374283;6157.78447754331;6163.7842675506145;6189.81430557084;5468.234447177399;6099.664518451484;6236.251649802008;6249.937500624981;6250.312465628777;5690.40250773667;5742.942570574241;5748.798792042342;5751.993401154781;5755.798547050833;6248.250209974832;6274.121622972787;6286.748530058838;6295.150154729053;6296.811095667137;5911.9358515466765;5986.431289027535;6083.11794789753;6097.384193188808;6116.113163591254;6128.075379514901;6149.77004599082;6153.538484613672;6261.467775239064;6262.405071518198;6130.877382452415;6146.938530614637;6151.815445536641;6162.784302549389;6168.321484636686;6027.457528822427;6074.9392506075255;6093.908591371133;6174.938250617526;6179;4892.241702536113;4895.755212239374;4907.190313598217;4910.950890491121;4913.680610760289;4354.042110735633;4378.693491455631;4397.648188144941;4424.778761061998;4449.3326001100095;5525.806596769094;5556.249906262684;5567.137093750394;5567.220589117527;5570.470805273492;6104.877902441926;6119.204503414528;6138.386161383915;6147.84630384244;6181.938180618226;5194.27280180775;5517.062099443105;5523.917141242885;5560.7219639018695;5582;5265.104932161542;5267.130923397596;5276.445973172838;5304.734763261821;5320.920186197211;6205.224346956721;6245.156903817927;6262.874742505124;6275.403836635513;6309.589876658002;6039.849003774941;6118.357572454828;6134.11055396956;6162.7534898605345;6168.1056246844855;6145.877082458415;6240.438360547572;6244.125822384961;6273.811785646438;6279.277883043508;6078.45293923549;6113.205283313141;6135.049067394561;6144.047672610753;6145.754169833157;5101.285819985131;5361.651492652965;6128.4790792783115;6229.190205273286;6230.968845155745;5967.6121052131475;5992.221011268682;5999.430054144849;6048.425399587032;6076.848078797889;5982.551308651781;6003.0695242237525;6019.307779605401;6023.367546407564;6075.574709770451;5748.741306641127;5861.472467477947;5922.496587789914;5933.792317269048;5947.197128387612;6059.848503787441;6064.757409703562;6104.685823297947;6127.601705889103;6131.509479241561;5965.164876917322;5969.91045134332;5978.491828194478;5984.982552965919;5988.071848863259 19:55:41 INFO - PROCESS | 1664 | |1;ext.html;500.18469894072655;519.810269251723;524.1325606121868;533.0777754484744;575.0109811124865;31142.68857311426;32914.01257962259;44321.11357772843;44689.329660055126;44729.65811025666;45342.546574534244;45837.0832583349;45884;45912.3113153303;46051.84870378242;9721.347370946956;9964.103230709236;9964.651237206699;9974.152197063258;9979;8697.086805885387;8946.776330591721;11629.302241865473;11896.167268291223;11902.642920712393;11835.94082029593;11927.045836333116;11941.984931280851;11946.462409191563;12015.699607509794;8692.608832602547;8972.282217422591;11825.822612660773;11954.282743035446;11969.16215864881;7615.5430674159725;8889.155530224618;11429.428528573539;11769.705757356049;11782.823257651142;41960.370594441105;42544.149117017485;42699;42738;42799.35800962988;3050.740687041604;3438.449848024304;3444.50054742061;3485.7385696072897;3824.694024478035;29480.115596532138;29487.673054712373;29576;30206.84896575525;30867.845660771778;2777.8333300002064;2782.5408807546733;2809.1712944681367;2845.445138198052;2907.4330505551425;369569;370569.1471542652;373001.99999999726;373440;374353;64160;64386;64483;64648;64864.35135648583;68336;68473.97289040669;68509.65745171242;68631;68672;16724.24740886663;17153.399631012853;17163.399281025104;17218.397356092475;17242.913785431243;11978.281303121841;12178.330191839534;12190.81713774294;13170.670733231746;13259;15894.284757185724;16054.036757794569;17199;17689.292428302724;17726.29094836218;6183.443490085914;6232.345603711636;6288.748450061947;6295.559310848196;6303.527235457359;956.2111258211864;961.3606951377255;1028.2339656955467;1063.6170978447753;1090.2695194219891;29113.56329655057;30175.698243017727;30190.24524386864;30917.227069323446;31021.534676979863;1092.530212008828;1129.6046383765615;1148.0815347721789;1158.4497363752319;1188.928664280146;13392.531261405999;15317.923410382877;15320.770188447183;15761.290741916411;15819.446319378996;660444;662887;666052;666291;672677;61234.69382653058;62622.06066909;62737.05894411587;62751.68624156941;62967.37032629615;1307.5946456598485;1355.600097971101;1432.3053319140417;1457.5845883923243;1482.7034593081428;29663.001450573836;30857.69142308548;31097.68902310948;31436;31722.841385792923;52328.69178270574;52702.73648631732;53226.73386633042;53256.467435325154;53373;563007.184964089;564699;565310;567729;569354.3064569301;34200.48699269563;35174.88950662968;40440.3933940997;41899;42377.15245695007;47191.528084720085;48065.31771387985;48331.75834120807;48844.511554885416;49254;28840.278993025342;36988.33912383639;38628.420573690855;39717.60282397255;39819.40270895881;37309.99585400102;37620;37944.24111517809;38427;38609.13908609116;37496.6250337504;38103.618963810004;38127.42808857814;38309.4253586202;38908.41637375385;32140.517892232103;32258.516122258654;33594.664053359156;33903.66096339005;33934.151646209044;24273.51452970966;24911.750882490942;24918.501629966937;25110.37224069413;25588 19:55:41 INFO - PROCESS | 1664 | |2;jquery.html;397.8627373556123;402.49486893948125;404.96355328020474;410.3762281332373;427.2480434435393;14697.632559185999;19378.321758738446;20608.866512341814;20616.69074963875;20877.582448351062;15367.308471118798;17338.306467741277;19484.318048868292;20139.697904531444;20924.476888077807;5584.9162262566;6104.236970378703;6113.266408031042;6123.7244324005305;6132.325444201134;5829.271341082364;5946;5951.6726580038085;6003.069524223731;6185.845353866144;3938.054866831954;4160.500739911215;4169.041120542277;4169.666426685873;4178.770167640779;4162.250794856925;5893.233879595647;6160.969195154041;6178.320384757673;6213.937860621381;5849.7660093596205;6002.54980876436;6033.215681961361;6037.335893051761;6037.728302226366;11419.48612312448;14048.648783780487;21094.894525527427;22077.558448831092;25777;2044.8159665630017;2092.163134746114;2094.3088780702483;2100.705901173837;2132.5201829588373;9782.413055216639;9989;10003.099721025143;10238.180945524415;10445.321054131457;1674.4641714651316;1787.4905651889148;1794.2733193056833;1812.8730988830787;1832.193834712722;106686.46656766743;108293;109447.90552094457;109518;110638;11536.365499897585;11731.589394371156;11736.178467507363;11738.178327517162;11936.582219622445;15411.15238661862;15465.768013479807;15495.767563486557;15506.534803955674;15521.301541430663;8745.825083498294;8837.60230789616;8850.026497085315;8922.420042697204;8950.462972221558;8625.396222264506;8630.28370737537;8637.524936128448;8722.781930451789;8761.737147885573;4587.885302867455;4708.246680531099;4734.881627959328;4748.121597504403;4821.4696383397795;1710.8545773609383;1954.189011560215;2027.276814707487;2041.2957529652247;2049.2110537442895;674.8515326628134;843.9831203376021;864.5245115186622;951.5242378810594;987.3779518903045;15424.305906234247;19831.801681983285;20136.899315503328;21286;21536.784632153478;930.3812964378612;945.3429866242896;953.6995846308529;956.5121787888088;1069.096613361708;9332.626694932127;10762.892371076345;11125.833112503318;11131.276467029616;11168.385738784445;240899;621254;631559.8422007861;655580;656516;84210.57894710488;116383;133549.37932549685;133601.33199334136;141489.29255353656;1012.1497941728836;1028.100412139378;1048.260976011925;1049.2026060194307;1054.3094273251033;15277.236138193268;18079.63840723152;18130;18718.532036698645;18742.570598048194;53669.19496207482;53720.731396342766;54357.91284174373;54618.18072728833;55310.44689553053;71108.64445677739;79525;81010.59494702489;81776.18223817686;84055.15944840474;5768.336641286306;7641.885371719318;7689.615519223905;8074.757757267292;8643.524606146722;12452.688682783002;12690.746185076432;12861;12998.870011300145;13147.408366623333;11035.282706624044;11296.717582060186;11341.716457088638;11364.374959377314;11478.827817582576;9432.669856554987;10651.733706657396;10860.837087443853;11145.888541114486;11445.484953177294;9296.489888219854;11440.370779607201;11586;11707.239029463057;11764.176507644383;7688.8462230756195;11747.47136378848;12414.379281035732;12446.128770985944;12715.87284127184;10895.836562451412;11122.332660040423;11467.197296189186;11756.94121529387;12126.333051682243 19:55:41 INFO - PROCESS | 1664 | |3;mootools.html;1405.3394904394931;1607.2204980584415;1614.0396464103858;1874.7281644161596;1990.8108729670676;7281.453890958172;8598.957005214965;8623.137686231374;8884.33367497437;9148.679796207134;8998.325125615573;9011.864822027675;9116.08839116088;9143.085691430853;9162.312826538004;2496.6379874918125;2519.508695804319;2524.406764410362;2527.5703130467773;2529.2285852814884;2459.9139030133947;2485.5028994201143;2487.1046423287603;2487.6019836826113;2491.451880586277;2454.0919859651954;2464.9753502465014;2518.899244030237;2522.293757747824;2522.5711629023017;2453.1291391556097;2473.9257822265363;2486.3784053986506;2490.651308816767;2495.663085483473;2419.4677171022345;2423.7818596326415;2487.6019836826204;2494.538510375568;2497.7377375375504;8941.50821704806;9028.006919238878;9030.54847257641;9032.954835225848;9048.049954754722;5469.480399362054;5481.12302031677;5645.983722929871;5660.830175094754;5667.489925906647;3676.4301533262387;3679.8344074516713;3701.1857391374124;3752.924941501182;3768.8492460301827;1055.5566662001902;1073.2272763610188;1073.3345325897915;1081.118888106191;1081.9518889408168;12062.457189426497;12176.81734773979;12183.964363029154;12190.939045304805;12195.878041219652;4793.791727912976;4803.279508073817;4840.201366774443;4870.951290487121;4877.951220487821;8220.917790822135;9547.61809527625;9557.761055973517;9567.899691535424;9570.186534144537;5482.451754824566;5490.423505531861;5491.670499769947;5500.944990550043;5514.476124768221;4316.805743741539;4571.9085618287445;4996.925046124311;5063.5695965843315;5098.490150984871;3939.3697008478507;3947.50656167979;3995.700322475826;4035.051762835731;4039.5758445363404;1226.141700809437;1227.742174143422;1227.895628871538;1235.3390935849277;1243.1484433163132;669.5279827721445;849.2484151525821;896.789254525186;946.3091942881597;974.1423961114068;6094.695265236809;6187.319394866561;6240.7503699851495;6267.122602835607;6290.402411770874;509.2514004413506;510.75483767791667;515.5282916131697;523.0611053159588;672.0893189727946;3958.426028225891;4075.612816782401;4131.153113611703;4153.91692166155;4192.11965487245;10872.891271087188;11250.268732532359;11281.266717663326;11291.153163512769;11312.321260724382;11071.225014249087;11152.553897843994;11260.887391125983;11264;11321.71695707614;8407.747767566983;8541.316694664412;8544.18830211129;8567.914320856837;8573.957130214309;5864.794732184353;6028.969855150696;6191.25704915413;6215.9378406217165;6357.809265722036;9941.502924853583;9964.800703985735;10296.742581435525;10452.529636166239;10487.790244195226;10221.9488902555;10261.538230779484;10331.896681033093;10658.360498370123;10675.412852293199;8460.915390846012;8575;8683.305335573265;8685.782855428411;8694.695685650971;8493.830123397622;8562.443441176303;8572.699955501528;8615.310775137848;8622.094680058504;7725.884111738441;7797.298243157916;7853.803654908673;7857.410694197843;7905.920940790748;7603.543787372775;7634.8091297718;7653.617319133909;7670.0795904491815;7724.575148366894;7155.570665760071;7321.816954576178;7325.597092159769;7331.963340183265;7343.082114735658;4859.892963093742;4999.350084488993;5060.443351231288;5115.616328775282;5333.013392522395;5093.821716239914;5093.974530127326;5126.179811230185;5198;5443.047466693392 19:55:41 INFO - PROCESS | 1664 | |4;prototype.html;319.26409625812505;320.0765790693848;337.036076820294;338.1326896510452;341.54403870832436;5477.302036368724;5511.704749383896;5525.806596769114;5532.031894418481;5583.4974852263285;4257.723247988879;4977.900441991158;5221.12039665721;5335.439778823226;5420.214068960008;3364.444866597015;4093.2836753568154;4152.626263636272;4167.791610419482;4189.727667701605;3396.9129878438907;4110.28070087735;4165.375193720951;4171.561985991472;4192.790360481979;3314.442212160293;3818.3508803503332;4102.528209255944;4149.834006639746;4182.247195504808;3512.4380099184136;4048.4332193492933;4093;4094.1402305515876;4095.9180816383655;3040.6807285235172;3145.946108053805;3171.1596426946876;3571.2678900825526;3574.428091505347;4999.675151054741;5192.636515443921;5216.713080780555;5273.152497651264;5279.815206467755;4455.353973673831;4535.7732113394195;4690.624750019991;4735.887066539395;4794.712317260975;5590.496855283003;5641.266635337421;5680.091185410314;5691.886162276773;5730.942690573124;3422.8973130805866;3831.1762970961377;3993.7004724645476;3999.3601023836336;4017.979910100431;5912.9113063304085;5923;5940.910886336708;5945.16767652529;5952.821415357546;3745.232227393378;4035.1929614077303;4039.8384064637085;4057.214825476781;4071.206114807584;3031.9236670981927;3479.6172421033666;4055.2756039213987;4106.137711080647;4110.650594699424;4247.088810035496;4683.555062269079;4690.718556886598;4723.126221649006;4757.14371413149;4981.676191047571;5263.631455821437;5273.2617433559335;5642.181883626851;5888.676122813286;1134.4497918509517;1199.4722322178213;1358.3156885461956;1406.746785578585;1407.8944079193896;774.6630215856121;804.5454318310136;831.8170002599419;940.5891163255118;967.4243824924159;4518.367428560005;4666.4633567139535;4985.9501404985485;5218.825764203099;5296.364436267672;4862.975685121552;5696.743646535916;5744.454276843693;5795.275590551181;5818.679972601463;5063.240513922868;5303.655262407931;5325.733713314319;5437.537809286176;5550.640093177198;3013.5630333601944;3067.7852550321263;3097.9225519361744;3113.3032497288577;3123.1255248530447;5508.9724551377785;5848.415158484203;6015.969920150371;6191.19514463117;6197.690115494207;4868.2210846264425;5777.45114214149;6044.425779550936;6072.969635151796;6083.39166083397;5713.6857472838565;6097.109895932275;6132.969335153296;6159.3224745277985;6224.906626400608;5155.381354237516;5550.167474878801;5562.415946325739;5880.441358070976;5917.28992520883;5204.713740744295;5475.698836564027;5710.2862142232225;5784.512659556432;5807.157962095557;5822.787340810465;6126.95841706902;6188.4739797117745;6215.285242197205;6246.187995560548;3651.776654820665;3713.4801127841583;3841.5786159120184;3871.7483363581277;3873.818485361879;5522.846090024974;5703.800366987136;5873.4126587340925;5951.404859514014;5971.880562388815;4691.741954192552;5267.157254839208;5515.420880807457;5565.499105080562;5808.593398462067;5326.34883186198;5422.749558933157;5685.77256909719;5687.488126068673;5707.600467967202;5346.919796202982;5429.886873190907;5560.638558493685;5620.185073164286;5641.464060914206;4915.803367865388;5159.277701121921;5726.112452569859;5785.239594263762;5786.479216870502;4914.434839993446;5266.420693723764;5626.409227031103;5662.462066103713;5714.314282286152 19:55:41 INFO - PROCESS | 1664 | |5;yui.html;655.2562841175267;716.8028792082176;736.4403053679202;765.8238605120824;804.428855512586;14899.925500372496;15714.528564143066;15718.449854255103;15750.921245393756;15785.921070394646;12175.391230438465;14300.141991480492;14363.56909292723;14398.208098554602;14427.495037673683;933.0529512544772;958.2238386906594;959.8320293948566;963.2486660404895;964.0455948610895;945.2107490245653;956.8325543029977;961.2358175250693;964.6382606522554;965.4882912056586;1015.8120747661669;1016.4917541229386;1017.1862509992014;1017.2828156149897;1017.5217647705566;1004.437514991607;1005.0000249751471;1006.3458751811381;1006.7583779892847;1007.5868893753562;754.6792613139394;755.7921571567808;756.916739158692;757.4054367321681;760.3840888880025;13819.447222111205;13859.792103118461;13932.651683707887;13935.860641393558;14005.549028919899;11315.207935444521;11345.886541134565;11362.431878406047;11437.942810285978;11708.180427370087;9294.488803115826;9383.436993780395;9389.812203755886;9415.811683766286;9482.431054136774;3310.9006729798143;3390.915227119317;3396.3207358528393;3410.5719771965487;3646.74472786905;76752;78513.60743196304;78976;79169;79719.6014019932;8229.95885020583;8318.542480163649;8321.08468068512;8326.66693332272;8334.874976875351;18986.62026759457;19079.52301192481;19123.71314430285;19984;20871.269505567234;6810.489213309022;7375.524895021017;7633.313001829862;7813.843723125506;7843.176466471053;3674.06311390599;3724.366857634209;3975;4180.1848639515;4212.936805947913;3621.5473065866768;3718.6839118674675;3721.8511259549855;3725.906852328659;3725.962740372616;566.1903478026379;585.4789237578558;586.2408181405006;587.8089620873234;597.7190720361423;502.379561241866;555.7193617223277;580.7067430947344;614.4746241963146;629.1066685306876;7973.282404583615;8002.9599852000365;8039.919600804034;8079.555624440713;8197.180281971756;601.2394321183706;602.1449541650866;602.2772672792667;604.4861867412664;608.5739982012609;3754.2303827715255;3824.2160357127277;3844.75009124406;3867.497225360685;3878.398848178537;90911.09088909159;91757;94003.0599694008;95114.52442737742;98387;80009.79985300225;80805;84644.57677711573;86445.567772162;87162;45189.09621807611;71639;71670.64164679247;71854;73143;7487.5507469552;7665.808354791175;7674.8081297968;7705.691772329044;7740;36940.81529592335;47079.52920470751;48164.27753583629;48182;48811.0237795235;62741.68629156825;66295.00557491546;67036.32963670301;68153;68551.65724171347;10493.790124197625;10739.516721747385;10753.301035432673;10793.73015674583;10958.506867191156;11119.83320250213;11169.776604467703;11191.72020699489;12092.638873677133;12113.394330283274;11170.162237832357;11403.771924561297;11611.013063889326;12135.271883687006;12247;11211.383373914186;11529.483507530284;11611.5355385787;11653.067754579783;11825.940870295593;11109.944450277697;11193.776124477303;11230.719232019264;11272.210945234083;11756.70608234801;9510.762230944005;9564.13922746956;9608.327417081016;9694.660686876208;10059.547320370748;9098.772530686785;9560.23518118535;9676.854847177157;9776.169025632616;9813.172484497556 19:55:41 INFO - PROCESS | 1664 | __end_tp_report 19:55:41 INFO - PROCESS | 1664 | __start_cc_report 19:55:41 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1061 19:55:41 INFO - PROCESS | 1664 | __end_cc_report 19:55:41 INFO - PROCESS | 1664 | __startTimestamp1463626541849__endTimestamp 19:55:41 INFO - PROCESS | 1664 | 19:55:41 INFO - PROCESS | 1664 | ------- Summary: start ------- 19:55:41 INFO - PROCESS | 1664 | Number of tests: 6 19:55:41 INFO - PROCESS | 1664 | 19:55:41 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5655.54 Median:5933.79 stddev:629.19 (10.6%) stddev-sans-first:602.35 19:55:41 INFO - PROCESS | 1664 | Values: 3257.7 3412.2 3430.1 3456.6 3488.4 5096.1 5145.6 5561.3 5632.4 5642.3 5557.4 5620.8 5629.7 5653.1 5654.6 5061.4 5094.2 5102.9 5191.5 5201.1 5116.0 5194.4 5203.3 5205.6 5224.6 5071.6 5091.3 5103.5 5112.9 5207.8 5168.7 5200.2 5206.3 5212.7 5294.5 4921.9 4923.7 4998.8 5145.3 5159.8 5453.1 5460.4 5460.9 5466.1 5532.8 6095.9 6107.9 6157.8 6163.8 6189.8 5468.2 6099.7 6236.3 6249.9 6250.3 5690.4 5742.9 5748.8 5752.0 5755.8 6248.3 6274.1 6286.7 6295.2 6296.8 5911.9 5986.4 6083.1 6097.4 6116.1 6128.1 6149.8 6153.5 6261.5 6262.4 6130.9 6146.9 6151.8 6162.8 6168.3 6027.5 6074.9 6093.9 6174.9 6179.0 4892.2 4895.8 4907.2 4911.0 4913.7 4354.0 4378.7 4397.6 4424.8 4449.3 5525.8 5556.2 5567.1 5567.2 5570.5 6104.9 6119.2 6138.4 6147.8 6181.9 5194.3 5517.1 5523.9 5560.7 5582.0 5265.1 5267.1 5276.4 5304.7 5320.9 6205.2 6245.2 6262.9 6275.4 6309.6 6039.8 6118.4 6134.1 6162.8 6168.1 6145.9 6240.4 6244.1 6273.8 6279.3 6078.5 6113.2 6135.0 6144.0 6145.8 5101.3 5361.7 6128.5 6229.2 6231.0 5967.6 5992.2 5999.4 6048.4 6076.8 5982.6 6003.1 6019.3 6023.4 6075.6 5748.7 5861.5 5922.5 5933.8 5947.2 6059.8 6064.8 6104.7 6127.6 6131.5 5965.2 5969.9 5978.5 5985.0 5988.1 19:55:41 INFO - PROCESS | 1664 | 19:55:41 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:68373.68 Median:205375.00 stddev:146567.61 (71.4%) stddev-sans-first:146890.18 19:55:41 INFO - PROCESS | 1664 | Values: 500.2 519.8 524.1 533.1 575.0 31142.7 32914.0 44321.1 44689.3 44729.7 45342.5 45837.1 45884.0 45912.3 46051.8 9721.3 9964.1 9964.7 9974.2 9979.0 8697.1 8946.8 11629.3 11896.2 11902.6 11835.9 11927.0 11942.0 11946.5 12015.7 8692.6 8972.3 11825.8 11954.3 11969.2 7615.5 8889.2 11429.4 11769.7 11782.8 41960.4 42544.1 42699.0 42738.0 42799.4 3050.7 3438.4 3444.5 3485.7 3824.7 29480.1 29487.7 29576.0 30206.8 30867.8 2777.8 2782.5 2809.2 2845.4 2907.4 369569.0 370569.1 373002.0 373440.0 374353.0 64160.0 64386.0 64483.0 64648.0 64864.4 68336.0 68474.0 68509.7 68631.0 68672.0 16724.2 17153.4 17163.4 17218.4 17242.9 11978.3 12178.3 12190.8 13170.7 13259.0 15894.3 16054.0 17199.0 17689.3 17726.3 6183.4 6232.3 6288.7 6295.6 6303.5 956.2 961.4 1028.2 1063.6 1090.3 29113.6 30175.7 30190.2 30917.2 31021.5 1092.5 1129.6 1148.1 1158.4 1188.9 13392.5 15317.9 15320.8 15761.3 15819.4 660444.0 662887.0 666052.0 666291.0 672677.0 61234.7 62622.1 62737.1 62751.7 62967.4 1307.6 1355.6 1432.3 1457.6 1482.7 29663.0 30857.7 31097.7 31436.0 31722.8 52328.7 52702.7 53226.7 53256.5 53373.0 563007.2 564699.0 565310.0 567729.0 569354.3 34200.5 35174.9 40440.4 41899.0 42377.2 47191.5 48065.3 48331.8 48844.5 49254.0 28840.3 36988.3 38628.4 39717.6 39819.4 37310.0 37620.0 37944.2 38427.0 38609.1 37496.6 38103.6 38127.4 38309.4 38908.4 32140.5 32258.5 33594.7 33903.7 33934.2 24273.5 24911.8 24918.5 25110.4 25588.0 19:55:41 INFO - PROCESS | 1664 | 19:55:41 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:33433.63 Median:11509.39 stddev:97530.30 (847.4%) stddev-sans-first:97772.35 19:55:41 INFO - PROCESS | 1664 | Values: 397.9 402.5 405.0 410.4 427.2 14697.6 19378.3 20608.9 20616.7 20877.6 15367.3 17338.3 19484.3 20139.7 20924.5 5584.9 6104.2 6113.3 6123.7 6132.3 5829.3 5946.0 5951.7 6003.1 6185.8 3938.1 4160.5 4169.0 4169.7 4178.8 4162.3 5893.2 6161.0 6178.3 6213.9 5849.8 6002.5 6033.2 6037.3 6037.7 11419.5 14048.6 21094.9 22077.6 25777.0 2044.8 2092.2 2094.3 2100.7 2132.5 9782.4 9989.0 10003.1 10238.2 10445.3 1674.5 1787.5 1794.3 1812.9 1832.2 106686.5 108293.0 109447.9 109518.0 110638.0 11536.4 11731.6 11736.2 11738.2 11936.6 15411.2 15465.8 15495.8 15506.5 15521.3 8745.8 8837.6 8850.0 8922.4 8950.5 8625.4 8630.3 8637.5 8722.8 8761.7 4587.9 4708.2 4734.9 4748.1 4821.5 1710.9 1954.2 2027.3 2041.3 2049.2 674.9 844.0 864.5 951.5 987.4 15424.3 19831.8 20136.9 21286.0 21536.8 930.4 945.3 953.7 956.5 1069.1 9332.6 10762.9 11125.8 11131.3 11168.4 240899.0 621254.0 631559.8 655580.0 656516.0 84210.6 116383.0 133549.4 133601.3 141489.3 1012.1 1028.1 1048.3 1049.2 1054.3 15277.2 18079.6 18130.0 18718.5 18742.6 53669.2 53720.7 54357.9 54618.2 55310.4 71108.6 79525.0 81010.6 81776.2 84055.2 5768.3 7641.9 7689.6 8074.8 8643.5 12452.7 12690.7 12861.0 12998.9 13147.4 11035.3 11296.7 11341.7 11364.4 11478.8 9432.7 10651.7 10860.8 11145.9 11445.5 9296.5 11440.4 11586.0 11707.2 11764.2 7688.8 11747.5 12414.4 12446.1 12715.9 10895.8 11122.3 11467.2 11756.9 12126.3 19:55:41 INFO - PROCESS | 1664 | 19:55:41 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5906.94 Median:2801.54 stddev:3320.81 (118.5%) stddev-sans-first:3312.89 19:55:41 INFO - PROCESS | 1664 | Values: 1405.3 1607.2 1614.0 1874.7 1990.8 7281.5 8599.0 8623.1 8884.3 9148.7 8998.3 9011.9 9116.1 9143.1 9162.3 2496.6 2519.5 2524.4 2527.6 2529.2 2459.9 2485.5 2487.1 2487.6 2491.5 2454.1 2465.0 2518.9 2522.3 2522.6 2453.1 2473.9 2486.4 2490.7 2495.7 2419.5 2423.8 2487.6 2494.5 2497.7 8941.5 9028.0 9030.5 9033.0 9048.0 5469.5 5481.1 5646.0 5660.8 5667.5 3676.4 3679.8 3701.2 3752.9 3768.8 1055.6 1073.2 1073.3 1081.1 1082.0 12062.5 12176.8 12184.0 12190.9 12195.9 4793.8 4803.3 4840.2 4871.0 4878.0 8220.9 9547.6 9557.8 9567.9 9570.2 5482.5 5490.4 5491.7 5500.9 5514.5 4316.8 4571.9 4996.9 5063.6 5098.5 3939.4 3947.5 3995.7 4035.1 4039.6 1226.1 1227.7 1227.9 1235.3 1243.1 669.5 849.2 896.8 946.3 974.1 6094.7 6187.3 6240.8 6267.1 6290.4 509.3 510.8 515.5 523.1 672.1 3958.4 4075.6 4131.2 4153.9 4192.1 10872.9 11250.3 11281.3 11291.2 11312.3 11071.2 11152.6 11260.9 11264.0 11321.7 8407.7 8541.3 8544.2 8567.9 8574.0 5864.8 6029.0 6191.3 6215.9 6357.8 9941.5 9964.8 10296.7 10452.5 10487.8 10221.9 10261.5 10331.9 10658.4 10675.4 8460.9 8575.0 8683.3 8685.8 8694.7 8493.8 8562.4 8572.7 8615.3 8622.1 7725.9 7797.3 7853.8 7857.4 7905.9 7603.5 7634.8 7653.6 7670.1 7724.6 7155.6 7321.8 7325.6 7332.0 7343.1 4859.9 4999.4 5060.4 5115.6 5333.0 5093.8 5094.0 5126.2 5198.0 5443.0 19:55:41 INFO - PROCESS | 1664 | 19:55:41 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4620.40 Median:5265.03 stddev:1444.25 (27.4%) stddev-sans-first:1411.76 19:55:41 INFO - PROCESS | 1664 | Values: 319.3 320.1 337.0 338.1 341.5 5477.3 5511.7 5525.8 5532.0 5583.5 4257.7 4977.9 5221.1 5335.4 5420.2 3364.4 4093.3 4152.6 4167.8 4189.7 3396.9 4110.3 4165.4 4171.6 4192.8 3314.4 3818.4 4102.5 4149.8 4182.2 3512.4 4048.4 4093.0 4094.1 4095.9 3040.7 3145.9 3171.2 3571.3 3574.4 4999.7 5192.6 5216.7 5273.2 5279.8 4455.4 4535.8 4690.6 4735.9 4794.7 5590.5 5641.3 5680.1 5691.9 5730.9 3422.9 3831.2 3993.7 3999.4 4018.0 5912.9 5923.0 5940.9 5945.2 5952.8 3745.2 4035.2 4039.8 4057.2 4071.2 3031.9 3479.6 4055.3 4106.1 4110.7 4247.1 4683.6 4690.7 4723.1 4757.1 4981.7 5263.6 5273.3 5642.2 5888.7 1134.4 1199.5 1358.3 1406.7 1407.9 774.7 804.5 831.8 940.6 967.4 4518.4 4666.5 4986.0 5218.8 5296.4 4863.0 5696.7 5744.5 5795.3 5818.7 5063.2 5303.7 5325.7 5437.5 5550.6 3013.6 3067.8 3097.9 3113.3 3123.1 5509.0 5848.4 6016.0 6191.2 6197.7 4868.2 5777.5 6044.4 6073.0 6083.4 5713.7 6097.1 6133.0 6159.3 6224.9 5155.4 5550.2 5562.4 5880.4 5917.3 5204.7 5475.7 5710.3 5784.5 5807.2 5822.8 6127.0 6188.5 6215.3 6246.2 3651.8 3713.5 3841.6 3871.7 3873.8 5522.8 5703.8 5873.4 5951.4 5971.9 4691.7 5267.2 5515.4 5565.5 5808.6 5326.3 5422.7 5685.8 5687.5 5707.6 5346.9 5429.9 5560.6 5620.2 5641.5 4915.8 5159.3 5726.1 5785.2 5786.5 4914.4 5266.4 5626.4 5662.5 5714.3 19:55:41 INFO - PROCESS | 1664 | 19:55:41 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18033.30 Median:583.09 stddev:25815.20 (4427.3%) stddev-sans-first:25854.64 19:55:41 INFO - PROCESS | 1664 | Values: 655.3 716.8 736.4 765.8 804.4 14899.9 15714.5 15718.4 15750.9 15785.9 12175.4 14300.1 14363.6 14398.2 14427.5 933.1 958.2 959.8 963.2 964.0 945.2 956.8 961.2 964.6 965.5 1015.8 1016.5 1017.2 1017.3 1017.5 1004.4 1005.0 1006.3 1006.8 1007.6 754.7 755.8 756.9 757.4 760.4 13819.4 13859.8 13932.7 13935.9 14005.5 11315.2 11345.9 11362.4 11437.9 11708.2 9294.5 9383.4 9389.8 9415.8 9482.4 3310.9 3390.9 3396.3 3410.6 3646.7 76752.0 78513.6 78976.0 79169.0 79719.6 8230.0 8318.5 8321.1 8326.7 8334.9 18986.6 19079.5 19123.7 19984.0 20871.3 6810.5 7375.5 7633.3 7813.8 7843.2 3674.1 3724.4 3975.0 4180.2 4212.9 3621.5 3718.7 3721.9 3725.9 3726.0 566.2 585.5 586.2 587.8 597.7 502.4 555.7 580.7 614.5 629.1 7973.3 8003.0 8039.9 8079.6 8197.2 601.2 602.1 602.3 604.5 608.6 3754.2 3824.2 3844.8 3867.5 3878.4 90911.1 91757.0 94003.1 95114.5 98387.0 80009.8 80805.0 84644.6 86445.6 87162.0 45189.1 71639.0 71670.6 71854.0 73143.0 7487.6 7665.8 7674.8 7705.7 7740.0 36940.8 47079.5 48164.3 48182.0 48811.0 62741.7 66295.0 67036.3 68153.0 68551.7 10493.8 10739.5 10753.3 10793.7 10958.5 11119.8 11169.8 11191.7 12092.6 12113.4 11170.2 11403.8 11611.0 12135.3 12247.0 11211.4 11529.5 11611.5 11653.1 11825.9 11109.9 11193.8 11230.7 11272.2 11756.7 9510.8 9564.1 9608.3 9694.7 10059.5 9098.8 9560.2 9676.9 9776.2 9813.2 19:55:41 INFO - PROCESS | 1664 | -------- Summary: end -------- 19:55:41 INFO - PROCESS | 1664 | 19:55:42 INFO - TEST-INFO | 1664: exit 0 19:55:42 INFO - TEST-OK | dromaeo_css | took 1115420ms 19:55:42 INFO - TEST-START | kraken 19:55:42 INFO - operating with platform_type : mac_ 19:55:42 INFO - Initialising browser for kraken test... 19:55:42 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpBstVl4/profile http://localhost:49276/getInfo.html) 19:55:44 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 19:55:44 INFO - PROCESS | 1673 | colorDepth:24 19:55:44 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 19:55:44 INFO - PROCESS | 1673 | __metrics 19:55:44 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:55:44 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:55:45 INFO - TEST-INFO | 1673: exit 0 19:55:45 INFO - Browser initialized. 19:55:45 INFO - Running cycle 1/1 for kraken test... 19:55:45 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpBstVl4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:55:57 INFO - PROCESS | 1675 | RSS: Main: 238792704 19:55:57 INFO - PROCESS | 1675 | 19:56:00 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 19:56:01 INFO - PROCESS | 1675 | RSS: Main: 286810112 19:56:01 INFO - PROCESS | 1675 | 19:56:03 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 19:56:04 INFO - PROCESS | 1675 | RSS: Main: 296013824 19:56:04 INFO - PROCESS | 1675 | 19:56:08 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 19:56:08 INFO - PROCESS | 1675 | RSS: Main: 386428928 19:56:08 INFO - PROCESS | 1675 | 19:56:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 19:56:11 INFO - PROCESS | 1675 | RSS: Main: 286158848 19:56:11 INFO - PROCESS | 1675 | 19:56:14 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:56:14 INFO - PROCESS | 1675 | RSS: Main: 289697792 19:56:14 INFO - PROCESS | 1675 | 19:56:17 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 19:56:18 INFO - PROCESS | 1675 | RSS: Main: 325697536 19:56:18 INFO - PROCESS | 1675 | 19:56:21 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 19:56:21 INFO - PROCESS | 1675 | RSS: Main: 329752576 19:56:21 INFO - PROCESS | 1675 | 19:56:24 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 19:56:25 INFO - PROCESS | 1675 | RSS: Main: 327593984 19:56:25 INFO - PROCESS | 1675 | 19:56:27 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:56:27 INFO - PROCESS | 1675 | RSS: Main: 316424192 19:56:27 INFO - PROCESS | 1675 | 19:56:29 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:56:30 INFO - PROCESS | 1675 | RSS: Main: 324263936 19:56:30 INFO - PROCESS | 1675 | 19:56:32 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:56:33 INFO - PROCESS | 1675 | RSS: Main: 324993024 19:56:33 INFO - PROCESS | 1675 | 19:56:36 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:56:37 INFO - PROCESS | 1675 | RSS: Main: 329748480 19:56:37 INFO - PROCESS | 1675 | 19:56:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:56:40 INFO - PROCESS | 1675 | RSS: Main: 327380992 19:56:40 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 19:56:42 INFO - PROCESS | 1675 | RSS: Main: 374398976 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | __start_tp_report 19:56:42 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 19:56:42 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 19:56:42 INFO - PROCESS | 1675 | |i|pagename|runs| 19:56:42 INFO - PROCESS | 1675 | |0;ai-astar;112;106;112;107;107;110;112;111;111;111 19:56:42 INFO - PROCESS | 1675 | |1;audio-beat-detection;154;121;140;202;139;136;134;113;165;115 19:56:42 INFO - PROCESS | 1675 | |2;audio-dft;187;186;187;185;187;184;261;235;185;186 19:56:42 INFO - PROCESS | 1675 | |3;audio-fft;77;71;54;125;55;62;69;82;137;80 19:56:42 INFO - PROCESS | 1675 | |4;audio-oscillator;95;96;131;78;104;60;101;113;113;138 19:56:42 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;85;82;85;83;85;83;86;82;85;83 19:56:42 INFO - PROCESS | 1675 | |6;imaging-darkroom;92;91;92;91;91;91;91;92;94;91 19:56:42 INFO - PROCESS | 1675 | |7;imaging-desaturate;125;135;86;84;83;84;85;83;84;84 19:56:42 INFO - PROCESS | 1675 | |8;json-parse-financial;54;57;55;53;54;54;53;120;54;53 19:56:42 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;62;64;66;76;70;69;69;66;67;67 19:56:42 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;85;84;86;83;84;94;99;84;85;84 19:56:42 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;144;141;129;139;141;128;147;141;246;147 19:56:42 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;151;163;162;163;157;159;159;161;164;158 19:56:42 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;67;66;65;63;65;64;66;64;65;64 19:56:42 INFO - PROCESS | 1675 | __end_tp_report 19:56:42 INFO - PROCESS | 1675 | __start_cc_report 19:56:42 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2228 19:56:42 INFO - PROCESS | 1675 | __end_cc_report 19:56:42 INFO - PROCESS | 1675 | __startTimestamp1463626602585__endTimestamp 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | ------- Summary: start ------- 19:56:42 INFO - PROCESS | 1675 | Number of tests: 14 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:2.33 (2.1%) stddev-sans-first:2.35 19:56:42 INFO - PROCESS | 1675 | Values: 112.0 106.0 112.0 107.0 107.0 110.0 112.0 111.0 111.0 111.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:141.90 Median:139.50 stddev:26.66 (19.1%) stddev-sans-first:27.92 19:56:42 INFO - PROCESS | 1675 | Values: 154.0 121.0 140.0 202.0 139.0 136.0 134.0 113.0 165.0 115.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:198.30 Median:187.00 stddev:26.92 (14.4%) stddev-sans-first:28.24 19:56:42 INFO - PROCESS | 1675 | Values: 187.0 186.0 187.0 185.0 187.0 184.0 261.0 235.0 185.0 186.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:81.20 Median:70.00 stddev:28.09 (40.1%) stddev-sans-first:29.75 19:56:42 INFO - PROCESS | 1675 | Values: 77.0 71.0 54.0 125.0 55.0 62.0 69.0 82.0 137.0 80.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:102.90 Median:99.00 stddev:23.10 (23.3%) stddev-sans-first:24.32 19:56:42 INFO - PROCESS | 1675 | Values: 95.0 96.0 131.0 78.0 104.0 60.0 101.0 113.0 113.0 138.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:83.90 Median:85.00 stddev:1.45 (1.7%) stddev-sans-first:1.48 19:56:42 INFO - PROCESS | 1675 | Values: 85.0 82.0 85.0 83.0 85.0 83.0 86.0 82.0 85.0 83.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:91.60 Median:91.50 stddev:0.97 (1.1%) stddev-sans-first:1.01 19:56:42 INFO - PROCESS | 1675 | Values: 92.0 91.0 92.0 91.0 91.0 91.0 91.0 92.0 94.0 91.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:93.30 Median:84.00 stddev:19.51 (23.2%) stddev-sans-first:16.98 19:56:42 INFO - PROCESS | 1675 | Values: 125.0 135.0 86.0 84.0 83.0 84.0 85.0 83.0 84.0 84.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:54.00 stddev:20.87 (38.6%) stddev-sans-first:21.99 19:56:42 INFO - PROCESS | 1675 | Values: 54.0 57.0 55.0 53.0 54.0 54.0 53.0 120.0 54.0 53.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:68.00 stddev:3.81 (5.6%) stddev-sans-first:3.46 19:56:42 INFO - PROCESS | 1675 | Values: 62.0 64.0 66.0 76.0 70.0 69.0 69.0 66.0 67.0 67.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:85.00 stddev:5.31 (6.2%) stddev-sans-first:5.59 19:56:42 INFO - PROCESS | 1675 | Values: 85.0 84.0 86.0 83.0 84.0 94.0 99.0 84.0 85.0 84.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:142.50 stddev:34.25 (24.0%) stddev-sans-first:36.25 19:56:42 INFO - PROCESS | 1675 | Values: 144.0 141.0 129.0 139.0 141.0 128.0 147.0 141.0 246.0 147.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.70 Median:161.50 stddev:3.86 (2.4%) stddev-sans-first:2.50 19:56:42 INFO - PROCESS | 1675 | Values: 151.0 163.0 162.0 163.0 157.0 159.0 159.0 161.0 164.0 158.0 19:56:42 INFO - PROCESS | 1675 | 19:56:42 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:1.20 (1.8%) stddev-sans-first:1.00 19:56:42 INFO - PROCESS | 1675 | Values: 67.0 66.0 65.0 63.0 65.0 64.0 66.0 64.0 65.0 64.0 19:56:42 INFO - PROCESS | 1675 | -------- Summary: end -------- 19:56:42 INFO - PROCESS | 1675 | 19:56:43 INFO - TEST-INFO | 1675: exit 0 19:56:43 INFO - TEST-OK | kraken | took 61042ms 19:56:43 INFO - SUITE-END | took 1176s 19:56:43 INFO - Completed test suite (00:19:36) 19:56:43 INFO - javascript benchmark 19:56:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3257.7230935370494, 3412.164019815144, 3430.056734398042, 3456.6370531094244, 3488.4069708149627, 5096.082705113087, 5145.588352931766, 5561.33264008319, 5632.408597097302, 5642.322921249451, 5557.444255574441, 5620.803271885485, 5629.746661400237, 5653.1237658162945, 5654.6041777075625, 5061.367329083864, 5094.2103973884105, 5102.872428189288, 5191.4548972357925, 5201.063808514466, 5116.0, 5194.376674799029, 5203.349581302337, 5205.557527610158, 5224.634275600691, 5071.619628527875, 5091.261767043777, 5103.4896510349035, 5112.948870511284, 5207.843764687065, 5168.664036837631, 5200.167973124319, 5206.349206349207, 5212.739363031833, 5294.49702278283, 4921.876953076166, 4923.704577725348, 4998.8250411235795, 5145.25393817898, 5159.845204643867, 5453.072977593819, 5460.399356070829, 5460.918086228709, 5466.125419932831, 5532.806351777668, 6095.928722681712, 6107.908381374283, 6157.78447754331, 6163.7842675506145, 6189.81430557084, 5468.234447177399, 6099.664518451484, 6236.251649802008, 6249.937500624981, 6250.312465628777, 5690.40250773667, 5742.942570574241, 5748.798792042342, 5751.993401154781, 5755.798547050833, 6248.250209974832, 6274.121622972787, 6286.748530058838, 6295.150154729053, 6296.811095667137, 5911.9358515466765, 5986.431289027535, 6083.11794789753, 6097.384193188808, 6116.113163591254, 6128.075379514901, 6149.77004599082, 6153.538484613672, 6261.467775239064, 6262.405071518198, 6130.877382452415, 6146.938530614637, 6151.815445536641, 6162.784302549389, 6168.321484636686, 6027.457528822427, 6074.9392506075255, 6093.908591371133, 6174.938250617526, 6179.0, 4892.241702536113, 4895.755212239374, 4907.190313598217, 4910.950890491121, 4913.680610760289, 4354.042110735633, 4378.693491455631, 4397.648188144941, 4424.778761061998, 4449.3326001100095, 5525.806596769094, 5556.249906262684, 5567.137093750394, 5567.220589117527, 5570.470805273492, 6104.877902441926, 6119.204503414528, 6138.386161383915, 6147.84630384244, 6181.938180618226, 5194.27280180775, 5517.062099443105, 5523.917141242885, 5560.7219639018695, 5582.0, 5265.104932161542, 5267.130923397596, 5276.445973172838, 5304.734763261821, 5320.920186197211, 6205.224346956721, 6245.156903817927, 6262.874742505124, 6275.403836635513, 6309.589876658002, 6039.849003774941, 6118.357572454828, 6134.11055396956, 6162.7534898605345, 6168.1056246844855, 6145.877082458415, 6240.438360547572, 6244.125822384961, 6273.811785646438, 6279.277883043508, 6078.45293923549, 6113.205283313141, 6135.049067394561, 6144.047672610753, 6145.754169833157, 5101.285819985131, 5361.651492652965, 6128.4790792783115, 6229.190205273286, 6230.968845155745, 5967.6121052131475, 5992.221011268682, 5999.430054144849, 6048.425399587032, 6076.848078797889, 5982.551308651781, 6003.0695242237525, 6019.307779605401, 6023.367546407564, 6075.574709770451, 5748.741306641127, 5861.472467477947, 5922.496587789914, 5933.792317269048, 5947.197128387612, 6059.848503787441, 6064.757409703562, 6104.685823297947, 6127.601705889103, 6131.509479241561, 5965.164876917322, 5969.91045134332, 5978.491828194478, 5984.982552965919, 5988.071848863259], "value": 5616.341072011007, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.18469894072655, 519.810269251723, 524.1325606121868, 533.0777754484744, 575.0109811124865, 31142.68857311426, 32914.01257962259, 44321.11357772843, 44689.329660055126, 44729.65811025666, 45342.546574534244, 45837.0832583349, 45884.0, 45912.3113153303, 46051.84870378242, 9721.347370946956, 9964.103230709236, 9964.651237206699, 9974.152197063258, 9979.0, 8697.086805885387, 8946.776330591721, 11629.302241865473, 11896.167268291223, 11902.642920712393, 11835.94082029593, 11927.045836333116, 11941.984931280851, 11946.462409191563, 12015.699607509794, 8692.608832602547, 8972.282217422591, 11825.822612660773, 11954.282743035446, 11969.16215864881, 7615.5430674159725, 8889.155530224618, 11429.428528573539, 11769.705757356049, 11782.823257651142, 41960.370594441105, 42544.149117017485, 42699.0, 42738.0, 42799.35800962988, 3050.740687041604, 3438.449848024304, 3444.50054742061, 3485.7385696072897, 3824.694024478035, 29480.115596532138, 29487.673054712373, 29576.0, 30206.84896575525, 30867.845660771778, 2777.8333300002064, 2782.5408807546733, 2809.1712944681367, 2845.445138198052, 2907.4330505551425, 369569.0, 370569.1471542652, 373001.99999999726, 373440.0, 374353.0, 64160.0, 64386.0, 64483.0, 64648.0, 64864.35135648583, 68336.0, 68473.97289040669, 68509.65745171242, 68631.0, 68672.0, 16724.24740886663, 17153.399631012853, 17163.399281025104, 17218.397356092475, 17242.913785431243, 11978.281303121841, 12178.330191839534, 12190.81713774294, 13170.670733231746, 13259.0, 15894.284757185724, 16054.036757794569, 17199.0, 17689.292428302724, 17726.29094836218, 6183.443490085914, 6232.345603711636, 6288.748450061947, 6295.559310848196, 6303.527235457359, 956.2111258211864, 961.3606951377255, 1028.2339656955467, 1063.6170978447753, 1090.2695194219891, 29113.56329655057, 30175.698243017727, 30190.24524386864, 30917.227069323446, 31021.534676979863, 1092.530212008828, 1129.6046383765615, 1148.0815347721789, 1158.4497363752319, 1188.928664280146, 13392.531261405999, 15317.923410382877, 15320.770188447183, 15761.290741916411, 15819.446319378996, 660444.0, 662887.0, 666052.0, 666291.0, 672677.0, 61234.69382653058, 62622.06066909, 62737.05894411587, 62751.68624156941, 62967.37032629615, 1307.5946456598485, 1355.600097971101, 1432.3053319140417, 1457.5845883923243, 1482.7034593081428, 29663.001450573836, 30857.69142308548, 31097.68902310948, 31436.0, 31722.841385792923, 52328.69178270574, 52702.73648631732, 53226.73386633042, 53256.467435325154, 53373.0, 563007.184964089, 564699.0, 565310.0, 567729.0, 569354.3064569301, 34200.48699269563, 35174.88950662968, 40440.3933940997, 41899.0, 42377.15245695007, 47191.528084720085, 48065.31771387985, 48331.75834120807, 48844.511554885416, 49254.0, 28840.278993025342, 36988.33912383639, 38628.420573690855, 39717.60282397255, 39819.40270895881, 37309.99585400102, 37620.0, 37944.24111517809, 38427.0, 38609.13908609116, 37496.6250337504, 38103.618963810004, 38127.42808857814, 38309.4253586202, 38908.41637375385, 32140.517892232103, 32258.516122258654, 33594.664053359156, 33903.66096339005, 33934.151646209044, 24273.51452970966, 24911.750882490942, 24918.501629966937, 25110.37224069413, 25588.0], "value": 20434.355951048266, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.8627373556123, 402.49486893948125, 404.96355328020474, 410.3762281332373, 427.2480434435393, 14697.632559185999, 19378.321758738446, 20608.866512341814, 20616.69074963875, 20877.582448351062, 15367.308471118798, 17338.306467741277, 19484.318048868292, 20139.697904531444, 20924.476888077807, 5584.9162262566, 6104.236970378703, 6113.266408031042, 6123.7244324005305, 6132.325444201134, 5829.271341082364, 5946.0, 5951.6726580038085, 6003.069524223731, 6185.845353866144, 3938.054866831954, 4160.500739911215, 4169.041120542277, 4169.666426685873, 4178.770167640779, 4162.250794856925, 5893.233879595647, 6160.969195154041, 6178.320384757673, 6213.937860621381, 5849.7660093596205, 6002.54980876436, 6033.215681961361, 6037.335893051761, 6037.728302226366, 11419.48612312448, 14048.648783780487, 21094.894525527427, 22077.558448831092, 25777.0, 2044.8159665630017, 2092.163134746114, 2094.3088780702483, 2100.705901173837, 2132.5201829588373, 9782.413055216639, 9989.0, 10003.099721025143, 10238.180945524415, 10445.321054131457, 1674.4641714651316, 1787.4905651889148, 1794.2733193056833, 1812.8730988830787, 1832.193834712722, 106686.46656766743, 108293.0, 109447.90552094457, 109518.0, 110638.0, 11536.365499897585, 11731.589394371156, 11736.178467507363, 11738.178327517162, 11936.582219622445, 15411.15238661862, 15465.768013479807, 15495.767563486557, 15506.534803955674, 15521.301541430663, 8745.825083498294, 8837.60230789616, 8850.026497085315, 8922.420042697204, 8950.462972221558, 8625.396222264506, 8630.28370737537, 8637.524936128448, 8722.781930451789, 8761.737147885573, 4587.885302867455, 4708.246680531099, 4734.881627959328, 4748.121597504403, 4821.4696383397795, 1710.8545773609383, 1954.189011560215, 2027.276814707487, 2041.2957529652247, 2049.2110537442895, 674.8515326628134, 843.9831203376021, 864.5245115186622, 951.5242378810594, 987.3779518903045, 15424.305906234247, 19831.801681983285, 20136.899315503328, 21286.0, 21536.784632153478, 930.3812964378612, 945.3429866242896, 953.6995846308529, 956.5121787888088, 1069.096613361708, 9332.626694932127, 10762.892371076345, 11125.833112503318, 11131.276467029616, 11168.385738784445, 240899.0, 621254.0, 631559.8422007861, 655580.0, 656516.0, 84210.57894710488, 116383.0, 133549.37932549685, 133601.33199334136, 141489.29255353656, 1012.1497941728836, 1028.100412139378, 1048.260976011925, 1049.2026060194307, 1054.3094273251033, 15277.236138193268, 18079.63840723152, 18130.0, 18718.532036698645, 18742.570598048194, 53669.19496207482, 53720.731396342766, 54357.91284174373, 54618.18072728833, 55310.44689553053, 71108.64445677739, 79525.0, 81010.59494702489, 81776.18223817686, 84055.15944840474, 5768.336641286306, 7641.885371719318, 7689.615519223905, 8074.757757267292, 8643.524606146722, 12452.688682783002, 12690.746185076432, 12861.0, 12998.870011300145, 13147.408366623333, 11035.282706624044, 11296.717582060186, 11341.716457088638, 11364.374959377314, 11478.827817582576, 9432.669856554987, 10651.733706657396, 10860.837087443853, 11145.888541114486, 11445.484953177294, 9296.489888219854, 11440.370779607201, 11586.0, 11707.239029463057, 11764.176507644383, 7688.8462230756195, 11747.47136378848, 12414.379281035732, 12446.128770985944, 12715.87284127184, 10895.836562451412, 11122.332660040423, 11467.197296189186, 11756.94121529387, 12126.333051682243], "value": 9435.556833435237, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.3394904394931, 1607.2204980584415, 1614.0396464103858, 1874.7281644161596, 1990.8108729670676, 7281.453890958172, 8598.957005214965, 8623.137686231374, 8884.33367497437, 9148.679796207134, 8998.325125615573, 9011.864822027675, 9116.08839116088, 9143.085691430853, 9162.312826538004, 2496.6379874918125, 2519.508695804319, 2524.406764410362, 2527.5703130467773, 2529.2285852814884, 2459.9139030133947, 2485.5028994201143, 2487.1046423287603, 2487.6019836826113, 2491.451880586277, 2454.0919859651954, 2464.9753502465014, 2518.899244030237, 2522.293757747824, 2522.5711629023017, 2453.1291391556097, 2473.9257822265363, 2486.3784053986506, 2490.651308816767, 2495.663085483473, 2419.4677171022345, 2423.7818596326415, 2487.6019836826204, 2494.538510375568, 2497.7377375375504, 8941.50821704806, 9028.006919238878, 9030.54847257641, 9032.954835225848, 9048.049954754722, 5469.480399362054, 5481.12302031677, 5645.983722929871, 5660.830175094754, 5667.489925906647, 3676.4301533262387, 3679.8344074516713, 3701.1857391374124, 3752.924941501182, 3768.8492460301827, 1055.5566662001902, 1073.2272763610188, 1073.3345325897915, 1081.118888106191, 1081.9518889408168, 12062.457189426497, 12176.81734773979, 12183.964363029154, 12190.939045304805, 12195.878041219652, 4793.791727912976, 4803.279508073817, 4840.201366774443, 4870.951290487121, 4877.951220487821, 8220.917790822135, 9547.61809527625, 9557.761055973517, 9567.899691535424, 9570.186534144537, 5482.451754824566, 5490.423505531861, 5491.670499769947, 5500.944990550043, 5514.476124768221, 4316.805743741539, 4571.9085618287445, 4996.925046124311, 5063.5695965843315, 5098.490150984871, 3939.3697008478507, 3947.50656167979, 3995.700322475826, 4035.051762835731, 4039.5758445363404, 1226.141700809437, 1227.742174143422, 1227.895628871538, 1235.3390935849277, 1243.1484433163132, 669.5279827721445, 849.2484151525821, 896.789254525186, 946.3091942881597, 974.1423961114068, 6094.695265236809, 6187.319394866561, 6240.7503699851495, 6267.122602835607, 6290.402411770874, 509.2514004413506, 510.75483767791667, 515.5282916131697, 523.0611053159588, 672.0893189727946, 3958.426028225891, 4075.612816782401, 4131.153113611703, 4153.91692166155, 4192.11965487245, 10872.891271087188, 11250.268732532359, 11281.266717663326, 11291.153163512769, 11312.321260724382, 11071.225014249087, 11152.553897843994, 11260.887391125983, 11264.0, 11321.71695707614, 8407.747767566983, 8541.316694664412, 8544.18830211129, 8567.914320856837, 8573.957130214309, 5864.794732184353, 6028.969855150696, 6191.25704915413, 6215.9378406217165, 6357.809265722036, 9941.502924853583, 9964.800703985735, 10296.742581435525, 10452.529636166239, 10487.790244195226, 10221.9488902555, 10261.538230779484, 10331.896681033093, 10658.360498370123, 10675.412852293199, 8460.915390846012, 8575.0, 8683.305335573265, 8685.782855428411, 8694.695685650971, 8493.830123397622, 8562.443441176303, 8572.699955501528, 8615.310775137848, 8622.094680058504, 7725.884111738441, 7797.298243157916, 7853.803654908673, 7857.410694197843, 7905.920940790748, 7603.543787372775, 7634.8091297718, 7653.617319133909, 7670.0795904491815, 7724.575148366894, 7155.570665760071, 7321.816954576178, 7325.597092159769, 7331.963340183265, 7343.082114735658, 4859.892963093742, 4999.350084488993, 5060.443351231288, 5115.616328775282, 5333.013392522395, 5093.821716239914, 5093.974530127326, 5126.179811230185, 5198.0, 5443.047466693392], "value": 4674.760815869227, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.26409625812505, 320.0765790693848, 337.036076820294, 338.1326896510452, 341.54403870832436, 5477.302036368724, 5511.704749383896, 5525.806596769114, 5532.031894418481, 5583.4974852263285, 4257.723247988879, 4977.900441991158, 5221.12039665721, 5335.439778823226, 5420.214068960008, 3364.444866597015, 4093.2836753568154, 4152.626263636272, 4167.791610419482, 4189.727667701605, 3396.9129878438907, 4110.28070087735, 4165.375193720951, 4171.561985991472, 4192.790360481979, 3314.442212160293, 3818.3508803503332, 4102.528209255944, 4149.834006639746, 4182.247195504808, 3512.4380099184136, 4048.4332193492933, 4093.0, 4094.1402305515876, 4095.9180816383655, 3040.6807285235172, 3145.946108053805, 3171.1596426946876, 3571.2678900825526, 3574.428091505347, 4999.675151054741, 5192.636515443921, 5216.713080780555, 5273.152497651264, 5279.815206467755, 4455.353973673831, 4535.7732113394195, 4690.624750019991, 4735.887066539395, 4794.712317260975, 5590.496855283003, 5641.266635337421, 5680.091185410314, 5691.886162276773, 5730.942690573124, 3422.8973130805866, 3831.1762970961377, 3993.7004724645476, 3999.3601023836336, 4017.979910100431, 5912.9113063304085, 5923.0, 5940.910886336708, 5945.16767652529, 5952.821415357546, 3745.232227393378, 4035.1929614077303, 4039.8384064637085, 4057.214825476781, 4071.206114807584, 3031.9236670981927, 3479.6172421033666, 4055.2756039213987, 4106.137711080647, 4110.650594699424, 4247.088810035496, 4683.555062269079, 4690.718556886598, 4723.126221649006, 4757.14371413149, 4981.676191047571, 5263.631455821437, 5273.2617433559335, 5642.181883626851, 5888.676122813286, 1134.4497918509517, 1199.4722322178213, 1358.3156885461956, 1406.746785578585, 1407.8944079193896, 774.6630215856121, 804.5454318310136, 831.8170002599419, 940.5891163255118, 967.4243824924159, 4518.367428560005, 4666.4633567139535, 4985.9501404985485, 5218.825764203099, 5296.364436267672, 4862.975685121552, 5696.743646535916, 5744.454276843693, 5795.275590551181, 5818.679972601463, 5063.240513922868, 5303.655262407931, 5325.733713314319, 5437.537809286176, 5550.640093177198, 3013.5630333601944, 3067.7852550321263, 3097.9225519361744, 3113.3032497288577, 3123.1255248530447, 5508.9724551377785, 5848.415158484203, 6015.969920150371, 6191.19514463117, 6197.690115494207, 4868.2210846264425, 5777.45114214149, 6044.425779550936, 6072.969635151796, 6083.39166083397, 5713.6857472838565, 6097.109895932275, 6132.969335153296, 6159.3224745277985, 6224.906626400608, 5155.381354237516, 5550.167474878801, 5562.415946325739, 5880.441358070976, 5917.28992520883, 5204.713740744295, 5475.698836564027, 5710.2862142232225, 5784.512659556432, 5807.157962095557, 5822.787340810465, 6126.95841706902, 6188.4739797117745, 6215.285242197205, 6246.187995560548, 3651.776654820665, 3713.4801127841583, 3841.5786159120184, 3871.7483363581277, 3873.818485361879, 5522.846090024974, 5703.800366987136, 5873.4126587340925, 5951.404859514014, 5971.880562388815, 4691.741954192552, 5267.157254839208, 5515.420880807457, 5565.499105080562, 5808.593398462067, 5326.34883186198, 5422.749558933157, 5685.77256909719, 5687.488126068673, 5707.600467967202, 5346.919796202982, 5429.886873190907, 5560.638558493685, 5620.185073164286, 5641.464060914206, 4915.803367865388, 5159.277701121921, 5726.112452569859, 5785.239594263762, 5786.479216870502, 4914.434839993446, 5266.420693723764, 5626.409227031103, 5662.462066103713, 5714.314282286152], "value": 4161.360984331928, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.2562841175267, 716.8028792082176, 736.4403053679202, 765.8238605120824, 804.428855512586, 14899.925500372496, 15714.528564143066, 15718.449854255103, 15750.921245393756, 15785.921070394646, 12175.391230438465, 14300.141991480492, 14363.56909292723, 14398.208098554602, 14427.495037673683, 933.0529512544772, 958.2238386906594, 959.8320293948566, 963.2486660404895, 964.0455948610895, 945.2107490245653, 956.8325543029977, 961.2358175250693, 964.6382606522554, 965.4882912056586, 1015.8120747661669, 1016.4917541229386, 1017.1862509992014, 1017.2828156149897, 1017.5217647705566, 1004.437514991607, 1005.0000249751471, 1006.3458751811381, 1006.7583779892847, 1007.5868893753562, 754.6792613139394, 755.7921571567808, 756.916739158692, 757.4054367321681, 760.3840888880025, 13819.447222111205, 13859.792103118461, 13932.651683707887, 13935.860641393558, 14005.549028919899, 11315.207935444521, 11345.886541134565, 11362.431878406047, 11437.942810285978, 11708.180427370087, 9294.488803115826, 9383.436993780395, 9389.812203755886, 9415.811683766286, 9482.431054136774, 3310.9006729798143, 3390.915227119317, 3396.3207358528393, 3410.5719771965487, 3646.74472786905, 76752.0, 78513.60743196304, 78976.0, 79169.0, 79719.6014019932, 8229.95885020583, 8318.542480163649, 8321.08468068512, 8326.66693332272, 8334.874976875351, 18986.62026759457, 19079.52301192481, 19123.71314430285, 19984.0, 20871.269505567234, 6810.489213309022, 7375.524895021017, 7633.313001829862, 7813.843723125506, 7843.176466471053, 3674.06311390599, 3724.366857634209, 3975.0, 4180.1848639515, 4212.936805947913, 3621.5473065866768, 3718.6839118674675, 3721.8511259549855, 3725.906852328659, 3725.962740372616, 566.1903478026379, 585.4789237578558, 586.2408181405006, 587.8089620873234, 597.7190720361423, 502.379561241866, 555.7193617223277, 580.7067430947344, 614.4746241963146, 629.1066685306876, 7973.282404583615, 8002.9599852000365, 8039.919600804034, 8079.555624440713, 8197.180281971756, 601.2394321183706, 602.1449541650866, 602.2772672792667, 604.4861867412664, 608.5739982012609, 3754.2303827715255, 3824.2160357127277, 3844.75009124406, 3867.497225360685, 3878.398848178537, 90911.09088909159, 91757.0, 94003.0599694008, 95114.52442737742, 98387.0, 80009.79985300225, 80805.0, 84644.57677711573, 86445.567772162, 87162.0, 45189.09621807611, 71639.0, 71670.64164679247, 71854.0, 73143.0, 7487.5507469552, 7665.808354791175, 7674.8081297968, 7705.691772329044, 7740.0, 36940.81529592335, 47079.52920470751, 48164.27753583629, 48182.0, 48811.0237795235, 62741.68629156825, 66295.00557491546, 67036.32963670301, 68153.0, 68551.65724171347, 10493.790124197625, 10739.516721747385, 10753.301035432673, 10793.73015674583, 10958.506867191156, 11119.83320250213, 11169.776604467703, 11191.72020699489, 12092.638873677133, 12113.394330283274, 11170.162237832357, 11403.771924561297, 11611.013063889326, 12135.271883687006, 12247.0, 11211.383373914186, 11529.483507530284, 11611.5355385787, 11653.067754579783, 11825.940870295593, 11109.944450277697, 11193.776124477303, 11230.719232019264, 11272.210945234083, 11756.70608234801, 9510.762230944005, 9564.13922746956, 9608.327417081016, 9694.660686876208, 10059.547320370748, 9098.772530686785, 9560.23518118535, 9676.854847177157, 9776.169025632616, 9813.172484497556], "value": 6834.683603360211, "unit": "score"}], "name": "dromaeo_css", "value": 7239.747846658081, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 112.0, 107.0, 107.0, 110.0, 112.0, 111.0, 111.0, 111.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 121.0, 140.0, 202.0, 139.0, 136.0, 134.0, 113.0, 165.0, 115.0], "value": 141.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 187.0, 185.0, 187.0, 184.0, 261.0, 235.0, 185.0, 186.0], "value": 198.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 71.0, 54.0, 125.0, 55.0, 62.0, 69.0, 82.0, 137.0, 80.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 131.0, 78.0, 104.0, 60.0, 101.0, 113.0, 113.0, 138.0], "value": 102.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 85.0, 83.0, 85.0, 83.0, 86.0, 82.0, 85.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 91.0, 91.0, 91.0, 91.0, 92.0, 94.0, 91.0], "value": 91.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 135.0, 86.0, 84.0, 83.0, 84.0, 85.0, 83.0, 84.0, 84.0], "value": 93.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 55.0, 53.0, 54.0, 54.0, 53.0, 120.0, 54.0, 53.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 66.0, 76.0, 70.0, 69.0, 69.0, 66.0, 67.0, 67.0], "value": 67.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 83.0, 84.0, 94.0, 99.0, 84.0, 85.0, 84.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 141.0, 129.0, 139.0, 141.0, 128.0, 147.0, 141.0, 246.0, 147.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 163.0, 162.0, 163.0, 157.0, 159.0, 159.0, 161.0, 164.0, 158.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 63.0, 65.0, 64.0, 66.0, 64.0, 65.0, 64.0], "value": 64.9, "unit": "score"}], "name": "kraken", "value": 1493.0, "alertThreshold": 2.0}]} 19:56:43 INFO - Return code: 0 19:56:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:56:43 ERROR - # TBPL SUCCESS # 19:56:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:56:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:56:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:56:43 INFO - 2016-05-18 19:56:43.894 screenresolution[1679:9939] starting screenresolution argv=screenresolution get 19:56:43 INFO - 2016-05-18 19:56:43.897 screenresolution[1679:9939] Display 0: 1600x1200x32@60 19:56:43 INFO - 2016-05-18 19:56:43.903 screenresolution[1680:9942] starting screenresolution argv=screenresolution list 19:56:43 INFO - Available Modes on Display 0 19:56:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:56:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:56:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:56:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:56:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:56:44 INFO - Intel Iris: 19:56:44 INFO - Chipset Model: Intel Iris 19:56:44 INFO - Type: GPU 19:56:44 INFO - Bus: Built-In 19:56:44 INFO - VRAM (Dynamic, Max): 1536 MB 19:56:44 INFO - Vendor: Intel (0x8086) 19:56:44 INFO - Device ID: 0x0a2e 19:56:44 INFO - Revision ID: 0x0009 19:56:44 INFO - Displays: 19:56:44 INFO - Display: 19:56:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:56:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:56:44 INFO - Display Serial Number: 1600x1200 60 19:56:44 INFO - Main Display: Yes 19:56:44 INFO - Mirror: Off 19:56:44 INFO - Online: Yes 19:56:44 INFO - Rotation: Supported 19:56:44 INFO - Return code: 0 19:56:44 INFO - Running post-action listener: _resource_record_post_action 19:56:44 INFO - ##### 19:56:44 INFO - ##### Finished run-tests step (success) 19:56:44 INFO - ##### 19:56:44 INFO - Running post-run listener: _resource_record_post_run 19:56:44 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37942784; Write bytes: 695515648; Read time: 387; Write time: 1911 19:56:44 INFO - TinderboxPrint: CPU usage
54.1% 19:56:44 INFO - TinderboxPrint: I/O read bytes / time
37,942,784 / 387 19:56:44 INFO - TinderboxPrint: I/O write bytes / time
695,515,648 / 1,911 19:56:44 INFO - TinderboxPrint: CPU idle
2,222.5 (45.9%) 19:56:44 INFO - TinderboxPrint: CPU system
975.4 (20.1%) 19:56:44 INFO - TinderboxPrint: CPU user
1,647.8 (34.0%) 19:56:44 INFO - TinderboxPrint: Swap in / out
433,516,544 / 0 19:56:44 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 393730560; Write bytes: 387843584; Read time: 28843; Write time: 780 19:56:44 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36120064; Write bytes: 296539136; Read time: 365; Write time: 1098 19:56:44 INFO - Running post-run listener: _upload_blobber_files 19:56:44 INFO - Blob upload gear active. 19:56:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:56:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:56:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:56:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:56:45 INFO - (blobuploader) - INFO - Open directory for files ... 19:56:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:56:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:56:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:46 INFO - (blobuploader) - INFO - Done attempting. 19:56:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:56:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:56:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:47 INFO - (blobuploader) - INFO - Done attempting. 19:56:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:56:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:56:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:49 INFO - (blobuploader) - INFO - Done attempting. 19:56:49 INFO - (blobuploader) - INFO - Iteration through files over. 19:56:49 INFO - Return code: 0 19:56:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:56:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:56:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/51cc2e738a8bac983be56beabdd784cb502205c9e97d81443e5460ccba5c3b7c2676aab3c01b95fd5a1e7072bb1c58c25969ccc9daf8ff5b3dbb493e757f259f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4119b471358b8a6b6aff293d1df469b3dc424c6e150eb92d233de7c8efcf1e8b8dc97b1e96a50b93df65e3ab463bfa1b43996be28e182c2f6b6df7a1968bf229", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b9ddc8fae4812f1510c2fdb6eb7900865fd6035c6e3810dd4871cfd4678a7aab2f2126196d3f350161fe437729c2d87e17fd61eae020fa9a248916fda3b4adc"} 19:56:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:56:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:56:49 INFO - Contents: 19:56:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/51cc2e738a8bac983be56beabdd784cb502205c9e97d81443e5460ccba5c3b7c2676aab3c01b95fd5a1e7072bb1c58c25969ccc9daf8ff5b3dbb493e757f259f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4119b471358b8a6b6aff293d1df469b3dc424c6e150eb92d233de7c8efcf1e8b8dc97b1e96a50b93df65e3ab463bfa1b43996be28e182c2f6b6df7a1968bf229", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b9ddc8fae4812f1510c2fdb6eb7900865fd6035c6e3810dd4871cfd4678a7aab2f2126196d3f350161fe437729c2d87e17fd61eae020fa9a248916fda3b4adc"} 19:56:49 INFO - Running post-run listener: copy_logs_to_upload_dir 19:56:49 INFO - Copying logs to upload dir... 19:56:49 INFO - mkdir: /builds/slave/test/build/upload/logs 19:56:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.314412 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-18 19:56:49.745170) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 19:56:49.748412) ========= 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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/51cc2e738a8bac983be56beabdd784cb502205c9e97d81443e5460ccba5c3b7c2676aab3c01b95fd5a1e7072bb1c58c25969ccc9daf8ff5b3dbb493e757f259f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4119b471358b8a6b6aff293d1df469b3dc424c6e150eb92d233de7c8efcf1e8b8dc97b1e96a50b93df65e3ab463bfa1b43996be28e182c2f6b6df7a1968bf229", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b9ddc8fae4812f1510c2fdb6eb7900865fd6035c6e3810dd4871cfd4678a7aab2f2126196d3f350161fe437729c2d87e17fd61eae020fa9a248916fda3b4adc"} build_url:https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010989 build_url: 'https://queue.taskcluster.net/v1/task/BEsdONOvQoGpnrPL64Hm1w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/51cc2e738a8bac983be56beabdd784cb502205c9e97d81443e5460ccba5c3b7c2676aab3c01b95fd5a1e7072bb1c58c25969ccc9daf8ff5b3dbb493e757f259f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4119b471358b8a6b6aff293d1df469b3dc424c6e150eb92d233de7c8efcf1e8b8dc97b1e96a50b93df65e3ab463bfa1b43996be28e182c2f6b6df7a1968bf229", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b9ddc8fae4812f1510c2fdb6eb7900865fd6035c6e3810dd4871cfd4678a7aab2f2126196d3f350161fe437729c2d87e17fd61eae020fa9a248916fda3b4adc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 19:56:49.775728) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:56:49.776033) ========= 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.gvEJU7jmmh/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.DB1oXgO5a0/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.004948 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:56:49.823573) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 19:56:49.823854) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 19:56:49.824161) ========= ========= Total master_lag: 0.15 =========