builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0296 starttime: 1463055153.52 results: success (0) buildid: 20160512030253 builduid: b5913a5383694d02beae43ab6792dc0d revision: c3f5e6079284a7b7053c41f05d0fe06ff031db03 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:33.521686) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:33.522053) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:33.522324) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:33.767207) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:33.767466) ========= 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.qK2wxqDUmU/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.SIkTc97nAB/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-12 05:12:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.7M=0.001s 2016-05-12 05:12:33 (19.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.188176 ========= master_lag: 0.60 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:34.555268) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:34.555553) ========= 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.qK2wxqDUmU/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.SIkTc97nAB/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.024596 ========= master_lag: 0.18 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:34.756383) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:34.756743) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c3f5e6079284a7b7053c41f05d0fe06ff031db03 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c3f5e6079284a7b7053c41f05d0fe06ff031db03 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qK2wxqDUmU/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.SIkTc97nAB/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-12 05:12:34,808 truncating revision to first 12 chars 2016-05-12 05:12:34,808 Setting DEBUG logging. 2016-05-12 05:12:34,808 attempt 1/10 2016-05-12 05:12:34,808 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c3f5e6079284?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 05:12:35,093 unpacking tar archive at: mozilla-central-c3f5e6079284/testing/mozharness/ program finished with exit code 0 elapsedTime=0.526562 ========= master_lag: 0.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:35.556542) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:35.556829) ========= script_repo_revision: c3f5e6079284a7b7053c41f05d0fe06ff031db03 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:35.557201) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:35.561522) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 05:12:35.623361) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-05-12 05:12:35.623664) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qK2wxqDUmU/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.SIkTc97nAB/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:12:35 INFO - MultiFileLogger online at 20160512 05:12:35 in /builds/slave/test 05:12:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:12:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:12:35 INFO - {'append_to_log': False, 05:12:35 INFO - 'base_work_dir': '/builds/slave/test', 05:12:35 INFO - 'blob_upload_branch': 'Firefox', 05:12:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:12:35 INFO - 'branch': 'Firefox', 05:12:35 INFO - 'buildbot_json_path': 'buildprops.json', 05:12:35 INFO - 'config_files': ('talos/mac_config.py',), 05:12:35 INFO - 'default_actions': ('clobber', 05:12:35 INFO - 'read-buildbot-config', 05:12:35 INFO - 'download-and-extract', 05:12:35 INFO - 'populate-webroot', 05:12:35 INFO - 'create-virtualenv', 05:12:35 INFO - 'install', 05:12:35 INFO - 'run-tests'), 05:12:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:12:35 INFO - 'download_minidump_stackwalk': True, 05:12:35 INFO - 'download_symbols': 'ondemand', 05:12:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:12:35 INFO - 'tooltool.py': '/tools/tooltool.py', 05:12:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:12:35 INFO - '/tools/misc-python/virtualenv.py')}, 05:12:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:12:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:12:35 INFO - 'installer_path': 'installer.exe', 05:12:35 INFO - 'log_level': 'info', 05:12:35 INFO - 'log_name': 'talos', 05:12:35 INFO - 'log_to_console': True, 05:12:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:12:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:12:35 INFO - 'opt_config_files': (), 05:12:35 INFO - 'pip_index': False, 05:12:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:12:35 INFO - 'cmd': ('bash', 05:12:35 INFO - '-c', 05:12:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:12:35 INFO - 'enabled': True, 05:12:35 INFO - 'halt_on_failure': False, 05:12:35 INFO - 'name': 'check_screen_resolution'},), 05:12:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:12:35 INFO - 'cmd': ('bash', 05:12:35 INFO - '-c', 05:12:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:12:35 INFO - 'enabled': True, 05:12:35 INFO - 'halt_on_failure': False, 05:12:35 INFO - 'name': 'check_screen_resolution'},), 05:12:35 INFO - 'run_cmd_checks_enabled': True, 05:12:35 INFO - 'sps_profile': False, 05:12:35 INFO - 'sps_profile_interval': 0, 05:12:35 INFO - 'suite': 'dromaeojs-e10s', 05:12:35 INFO - 'system_bits': '32', 05:12:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:12:35 INFO - 'title': 't-yosemite-r7-0296', 05:12:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:12:35 INFO - 'use_talos_json': True, 05:12:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:12:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:12:35 INFO - 'work_dir': 'build'} 05:12:35 INFO - ##### 05:12:35 INFO - ##### Running clobber step. 05:12:35 INFO - ##### 05:12:35 INFO - Running pre-action listener: _resource_record_pre_action 05:12:35 INFO - Running main action method: clobber 05:12:35 INFO - rmtree: /builds/slave/test/build 05:12:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:12:37 INFO - Running post-action listener: _resource_record_post_action 05:12:37 INFO - ##### 05:12:37 INFO - ##### Finished clobber step (success) 05:12:37 INFO - ##### 05:12:37 INFO - ##### 05:12:37 INFO - ##### Running read-buildbot-config step. 05:12:37 INFO - ##### 05:12:37 INFO - Running pre-action listener: _resource_record_pre_action 05:12:37 INFO - Running main action method: read_buildbot_config 05:12:37 INFO - Using buildbot properties: 05:12:37 INFO - { 05:12:37 INFO - "project": "", 05:12:37 INFO - "product": "firefox", 05:12:37 INFO - "script_repo_revision": "production", 05:12:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:12:37 INFO - "repository": "", 05:12:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:12:37 INFO - "buildid": "20160512030253", 05:12:37 INFO - "stage_platform": "macosx64", 05:12:37 INFO - "basedir": "/builds/slave/test", 05:12:37 INFO - "nightly_build": "True", 05:12:37 INFO - "buildnumber": 273, 05:12:37 INFO - "slavename": "t-yosemite-r7-0296", 05:12:37 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:12:37 INFO - "platform": "yosemite_r7", 05:12:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:12:37 INFO - "branch": "mozilla-central", 05:12:37 INFO - "repo_path": "mozilla-central", 05:12:37 INFO - "revision": "c3f5e6079284a7b7053c41f05d0fe06ff031db03", 05:12:37 INFO - "pgo_build": "False", 05:12:37 INFO - "builduid": "b5913a5383694d02beae43ab6792dc0d", 05:12:37 INFO - "slavebuilddir": "test" 05:12:37 INFO - } 05:12:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:12:37 INFO - Running post-action listener: _resource_record_post_action 05:12:37 INFO - ##### 05:12:37 INFO - ##### Finished read-buildbot-config step (success) 05:12:37 INFO - ##### 05:12:37 INFO - ##### 05:12:37 INFO - ##### Running download-and-extract step. 05:12:37 INFO - ##### 05:12:37 INFO - Running pre-action listener: _resource_record_pre_action 05:12:37 INFO - Running main action method: download_and_extract 05:12:37 INFO - mkdir: /builds/slave/test/build/tests 05:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:37 INFO - https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:12:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:12:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:12:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:12:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 05:13:07 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json: timed out 05:13:07 INFO - retry: attempt #1 caught exception: timed out 05:13:07 INFO - retry: Failed, sleeping 30 seconds before retrying 05:13:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/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 #2 05:13:38 INFO - Downloaded 1199 bytes. 05:13:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:13:38 INFO - Using the following test package requirements: 05:13:38 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:13:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:38 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:13:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:38 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:13:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:38 INFO - u'jsshell-mac.zip'], 05:13:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:38 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:13:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:13:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:38 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:13:38 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:38 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:13:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:38 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:13:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:38 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:13:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:13:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:38 INFO - https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:13:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:13:39 INFO - Downloaded 18151282 bytes. 05:13:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:13:42 INFO - Return code: 0 05:13:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:42 INFO - https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:13:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:13:49 INFO - Downloaded 18151282 bytes. 05:13:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:13:53 INFO - Return code: 0 05:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:53 INFO - https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:13:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:13:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:13:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:13:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:13:54 INFO - Downloaded 10840239 bytes. 05:13:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:13:54 INFO - Return code: 0 05:13:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:54 INFO - https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:13:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:13:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 05:13:58 INFO - Downloaded 109957691 bytes. 05:13:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:58 INFO - mkdir: /builds/slave/test/properties 05:13:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:13:58 INFO - Writing to file /builds/slave/test/properties/build_url 05:13:58 INFO - Contents: 05:13:58 INFO - build_url:https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:59 INFO - Running post-action listener: _resource_record_post_action 05:13:59 INFO - Running post-action listener: set_extra_try_arguments 05:13:59 INFO - ##### 05:13:59 INFO - ##### Finished download-and-extract step (success) 05:13:59 INFO - ##### 05:13:59 INFO - ##### 05:13:59 INFO - ##### Running populate-webroot step. 05:13:59 INFO - ##### 05:13:59 INFO - Running pre-action listener: _resource_record_pre_action 05:13:59 INFO - Running main action method: populate_webroot 05:13:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:13:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:13:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:13:59 INFO - '--tppagecycles', 05:13:59 INFO - '7'], 05:13:59 INFO - 'tests': ['tsvgm']}}, 05:13:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:13:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:13:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:13:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:13:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:13:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:13:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:59 INFO - 'talos_options': ['--disable-e10s'], 05:13:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:59 INFO - 'talos_options': ['--disable-e10s'], 05:13:59 INFO - 'tests': ['damp', 'tps']}, 05:13:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:59 INFO - 'tests': ['damp', 'tps']}, 05:13:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:13:59 INFO - 'tests': ['dromaeo_dom']}, 05:13:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:13:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:13:59 INFO - 'tests': ['a11yr', 05:13:59 INFO - 'ts_paint', 05:13:59 INFO - 'tpaint', 05:13:59 INFO - 'sessionrestore', 05:13:59 INFO - 'sessionrestore_no_auto_restore', 05:13:59 INFO - 'tabpaint']}, 05:13:59 INFO - 'other-e10s': {'tests': ['a11yr', 05:13:59 INFO - 'ts_paint', 05:13:59 INFO - 'tpaint', 05:13:59 INFO - 'sessionrestore', 05:13:59 INFO - 'sessionrestore_no_auto_restore', 05:13:59 INFO - 'tabpaint']}, 05:13:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:13:59 INFO - 'ts_paint', 05:13:59 INFO - 'tpaint', 05:13:59 INFO - 'sessionrestore', 05:13:59 INFO - 'sessionrestore_no_auto_restore', 05:13:59 INFO - 'tabpaint']}, 05:13:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:13:59 INFO - 'ts_paint', 05:13:59 INFO - 'tpaint', 05:13:59 INFO - 'sessionrestore', 05:13:59 INFO - 'sessionrestore_no_auto_restore', 05:13:59 INFO - 'tabpaint']}, 05:13:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:13:59 INFO - 'tests': ['a11yr', 05:13:59 INFO - 'ts_paint', 05:13:59 INFO - 'tpaint', 05:13:59 INFO - 'sessionrestore', 05:13:59 INFO - 'sessionrestore_no_auto_restore', 05:13:59 INFO - 'tabpaint']}, 05:13:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:13:59 INFO - 'tests': ['a11yr', 05:13:59 INFO - 'ts_paint', 05:13:59 INFO - 'tpaint', 05:13:59 INFO - 'sessionrestore', 05:13:59 INFO - 'sessionrestore_no_auto_restore', 05:13:59 INFO - 'tabpaint']}, 05:13:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:13:59 INFO - 'tests': ['tsvgx', 05:13:59 INFO - 'tsvgr_opacity', 05:13:59 INFO - 'tart', 05:13:59 INFO - 'tscrollx', 05:13:59 INFO - 'cart']}, 05:13:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:13:59 INFO - 'tsvgr_opacity', 05:13:59 INFO - 'tart', 05:13:59 INFO - 'tscrollx', 05:13:59 INFO - 'cart']}, 05:13:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:59 INFO - 'talos_options': ['--disable-e10s'], 05:13:59 INFO - 'tests': ['tp5o']}, 05:13:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:59 INFO - 'tests': ['tp5o']}, 05:13:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:59 INFO - 'talos_options': ['--disable-e10s', 05:13:59 INFO - '--xperf_path', 05:13:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:59 INFO - 'tests': ['tp5n']}, 05:13:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:59 INFO - 'talos_options': ['--xperf_path', 05:13:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:59 INFO - 'tests': ['tp5n']}}, 05:13:59 INFO - 'talos.zip': {'path': '', 05:13:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:13:59 INFO - Running post-action listener: _resource_record_post_action 05:13:59 INFO - ##### 05:13:59 INFO - ##### Finished populate-webroot step (success) 05:13:59 INFO - ##### 05:13:59 INFO - ##### 05:13:59 INFO - ##### Running create-virtualenv step. 05:13:59 INFO - ##### 05:13:59 INFO - Running pre-action listener: _resource_record_pre_action 05:13:59 INFO - Running main action method: create_virtualenv 05:13:59 INFO - Creating virtualenv /builds/slave/test/build/venv 05:13:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:13:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:13:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:13:59 INFO - Using real prefix '/tools/python27' 05:13:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:14:00 INFO - Installing distribute.............................................................................................................................................................................................done. 05:14:03 INFO - Installing pip.................done. 05:14:03 INFO - Return code: 0 05:14:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:03 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 0x10a32ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a334570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb1d62ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1d2d30>, '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 0x109ff6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c9b20>, '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.qK2wxqDUmU/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.SIkTc97nAB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:14:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:14:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qK2wxqDUmU/Render', 05:14:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:03 INFO - 'HOME': '/Users/cltbld', 05:14:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:03 INFO - 'LOGNAME': 'cltbld', 05:14:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:03 INFO - 'MOZ_NO_REMOTE': '1', 05:14:03 INFO - 'NO_EM_RESTART': '1', 05:14:03 INFO - 'PAGER': '/bin/cat', 05:14:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:03 INFO - 'PWD': '/builds/slave/test', 05:14:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:03 INFO - 'SHELL': '/bin/bash', 05:14:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SIkTc97nAB/Listeners', 05:14:03 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:14:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:03 INFO - 'USER': 'cltbld', 05:14:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:03 INFO - 'XPC_FLAGS': '0x0', 05:14:03 INFO - 'XPC_SERVICE_NAME': '0', 05:14:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:14:03 INFO - Cleaning up... 05:14:03 INFO - Return code: 0 05:14:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:03 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a32ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a334570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb1d62ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1d2d30>, '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 0x109ff6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c9b20>, '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.qK2wxqDUmU/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.SIkTc97nAB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:03 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>=0.7.1'] in /builds/slave/test/build 05:14:03 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>=0.7.1 05:14:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qK2wxqDUmU/Render', 05:14:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:03 INFO - 'HOME': '/Users/cltbld', 05:14:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:03 INFO - 'LOGNAME': 'cltbld', 05:14:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:03 INFO - 'MOZ_NO_REMOTE': '1', 05:14:03 INFO - 'NO_EM_RESTART': '1', 05:14:03 INFO - 'PAGER': '/bin/cat', 05:14:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:03 INFO - 'PWD': '/builds/slave/test', 05:14:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:03 INFO - 'SHELL': '/bin/bash', 05:14:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SIkTc97nAB/Listeners', 05:14:03 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:14:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:03 INFO - 'USER': 'cltbld', 05:14:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:03 INFO - 'XPC_FLAGS': '0x0', 05:14:03 INFO - 'XPC_SERVICE_NAME': '0', 05:14:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:03 INFO - Downloading/unpacking psutil>=0.7.1 05:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:14:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:07 INFO - Installing collected packages: psutil 05:14:07 INFO - Running setup.py install for psutil 05:14:07 INFO - building 'psutil._psutil_osx' extension 05:14:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:14:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:14:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:14:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:14:08 INFO - building 'psutil._psutil_posix' extension 05:14:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:14:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:14:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:14:08 INFO - ^ 05:14:08 INFO - 1 warning generated. 05:14:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:14:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:08 INFO - Successfully installed psutil 05:14:08 INFO - Cleaning up... 05:14:08 INFO - Return code: 0 05:14:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a32ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a334570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb1d62ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1d2d30>, '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 0x109ff6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c9b20>, '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.qK2wxqDUmU/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.SIkTc97nAB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:14:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:14:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qK2wxqDUmU/Render', 05:14:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:08 INFO - 'HOME': '/Users/cltbld', 05:14:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:08 INFO - 'LOGNAME': 'cltbld', 05:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:08 INFO - 'MOZ_NO_REMOTE': '1', 05:14:08 INFO - 'NO_EM_RESTART': '1', 05:14:08 INFO - 'PAGER': '/bin/cat', 05:14:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:08 INFO - 'PWD': '/builds/slave/test', 05:14:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:08 INFO - 'SHELL': '/bin/bash', 05:14:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SIkTc97nAB/Listeners', 05:14:08 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:14:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:08 INFO - 'USER': 'cltbld', 05:14:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:08 INFO - 'XPC_FLAGS': '0x0', 05:14:08 INFO - 'XPC_SERVICE_NAME': '0', 05:14:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:14:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:14:11 INFO - Installing collected packages: mozsystemmonitor 05:14:11 INFO - Running setup.py install for mozsystemmonitor 05:14:11 INFO - Successfully installed mozsystemmonitor 05:14:11 INFO - Cleaning up... 05:14:11 INFO - Return code: 0 05:14:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a32ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a334570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb1d62ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1d2d30>, '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 0x109ff6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c9b20>, '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.qK2wxqDUmU/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.SIkTc97nAB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:14:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qK2wxqDUmU/Render', 05:14:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:11 INFO - 'HOME': '/Users/cltbld', 05:14:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:11 INFO - 'LOGNAME': 'cltbld', 05:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:11 INFO - 'MOZ_NO_REMOTE': '1', 05:14:11 INFO - 'NO_EM_RESTART': '1', 05:14:11 INFO - 'PAGER': '/bin/cat', 05:14:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:11 INFO - 'PWD': '/builds/slave/test', 05:14:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:11 INFO - 'SHELL': '/bin/bash', 05:14:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SIkTc97nAB/Listeners', 05:14:11 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:14:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:11 INFO - 'USER': 'cltbld', 05:14:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:11 INFO - 'XPC_FLAGS': '0x0', 05:14:11 INFO - 'XPC_SERVICE_NAME': '0', 05:14:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:11 INFO - Downloading/unpacking blobuploader==1.2.4 05:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:14 INFO - Downloading blobuploader-1.2.4.tar.gz 05:14:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:14:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:14:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:15 INFO - Downloading docopt-0.6.1.tar.gz 05:14:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:14:15 INFO - Installing collected packages: blobuploader, requests, docopt 05:14:15 INFO - Running setup.py install for blobuploader 05:14:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:14:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:14:15 INFO - Running setup.py install for requests 05:14:16 INFO - Running setup.py install for docopt 05:14:16 INFO - Successfully installed blobuploader requests docopt 05:14:16 INFO - Cleaning up... 05:14:16 INFO - Return code: 0 05:14:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:16 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 0x10a32ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a334570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb1d62ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1d2d30>, '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 0x109ff6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c9b20>, '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.qK2wxqDUmU/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.SIkTc97nAB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qK2wxqDUmU/Render', 05:14:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:16 INFO - 'HOME': '/Users/cltbld', 05:14:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:16 INFO - 'LOGNAME': 'cltbld', 05:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:16 INFO - 'MOZ_NO_REMOTE': '1', 05:14:16 INFO - 'NO_EM_RESTART': '1', 05:14:16 INFO - 'PAGER': '/bin/cat', 05:14:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:16 INFO - 'PWD': '/builds/slave/test', 05:14:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:16 INFO - 'SHELL': '/bin/bash', 05:14:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SIkTc97nAB/Listeners', 05:14:16 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:14:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:16 INFO - 'USER': 'cltbld', 05:14:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:16 INFO - 'XPC_FLAGS': '0x0', 05:14:16 INFO - 'XPC_SERVICE_NAME': '0', 05:14:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-4M6wBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-u6F8Gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-u9p3VP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-U59tEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:16 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-_o2_99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-SlGnVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-cIqgf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-CSXqdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ZF8mf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-8EPn0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-_HRhuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-g5fkWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-FHffN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-LxwLKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:17 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-h3S1RC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:18 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-4EomJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:18 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-UTWw_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:14:18 INFO - Running setup.py install for manifestparser 05:14:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:14:18 INFO - Running setup.py install for mozcrash 05:14:18 INFO - Running setup.py install for mozdebug 05:14:18 INFO - Running setup.py install for mozdevice 05:14:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:14:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:14:18 INFO - Running setup.py install for mozfile 05:14:19 INFO - Running setup.py install for mozhttpd 05:14:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:14:19 INFO - Running setup.py install for mozinfo 05:14:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:14:19 INFO - Running setup.py install for mozInstall 05:14:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:14:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:14:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:14:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:14:19 INFO - Running setup.py install for mozleak 05:14:19 INFO - Running setup.py install for mozlog 05:14:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:14:19 INFO - Running setup.py install for moznetwork 05:14:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:14:19 INFO - Running setup.py install for mozprocess 05:14:20 INFO - Running setup.py install for mozprofile 05:14:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:14:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:14:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:14:20 INFO - Running setup.py install for mozrunner 05:14:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:14:20 INFO - Running setup.py install for mozscreenshot 05:14:20 INFO - Running setup.py install for moztest 05:14:20 INFO - Running setup.py install for mozversion 05:14:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:14:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:14:20 INFO - Cleaning up... 05:14:20 INFO - Return code: 0 05:14:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:20 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 0x10a32ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a334570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb1d62ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1d2d30>, '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 0x109ff6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c9b20>, '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.qK2wxqDUmU/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.SIkTc97nAB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:14:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qK2wxqDUmU/Render', 05:14:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:20 INFO - 'HOME': '/Users/cltbld', 05:14:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:20 INFO - 'LOGNAME': 'cltbld', 05:14:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:20 INFO - 'MOZ_NO_REMOTE': '1', 05:14:20 INFO - 'NO_EM_RESTART': '1', 05:14:20 INFO - 'PAGER': '/bin/cat', 05:14:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:20 INFO - 'PWD': '/builds/slave/test', 05:14:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:20 INFO - 'SHELL': '/bin/bash', 05:14:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SIkTc97nAB/Listeners', 05:14:20 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:14:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:20 INFO - 'USER': 'cltbld', 05:14:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:20 INFO - 'XPC_FLAGS': '0x0', 05:14:20 INFO - 'XPC_SERVICE_NAME': '0', 05:14:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:21 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-A_8HQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:14:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:21 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-92JLXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:21 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-DTUXSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:14:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:21 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-sJ8GJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:21 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-me6Kvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:14:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:21 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nReq3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:14:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:21 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-GhGDnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:14:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:21 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-JT7Y0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:14:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:21 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-tTeqw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:14:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:22 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-LZLlWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:22 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-2kvN8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:14:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:22 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-x0K27M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:14:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:22 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-dj9JX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:14:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:22 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-igFKR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:14:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:22 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-bPydim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:14:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:22 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Twf1rZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:14:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:22 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ad14i7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:25 INFO - Downloading blessings-1.6.tar.gz 05:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:14:25 INFO - Installing collected packages: blessings 05:14:25 INFO - Running setup.py install for blessings 05:14:25 INFO - Successfully installed blessings 05:14:25 INFO - Cleaning up... 05:14:25 INFO - Return code: 0 05:14:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:14:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:25 INFO - Reading from file tmpfile_stdout 05:14:25 INFO - Current package versions: 05:14:25 INFO - blessings == 1.6 05:14:25 INFO - blobuploader == 1.2.4 05:14:25 INFO - docopt == 0.6.1 05:14:25 INFO - manifestparser == 1.1 05:14:25 INFO - mozInstall == 1.12 05:14:25 INFO - mozcrash == 0.17 05:14:25 INFO - mozdebug == 0.1 05:14:25 INFO - mozdevice == 0.48 05:14:25 INFO - mozfile == 1.2 05:14:25 INFO - mozhttpd == 0.7 05:14:25 INFO - mozinfo == 0.9 05:14:25 INFO - mozleak == 0.1 05:14:25 INFO - mozlog == 3.2 05:14:25 INFO - moznetwork == 0.27 05:14:25 INFO - mozprocess == 0.22 05:14:25 INFO - mozprofile == 0.28 05:14:25 INFO - mozrunner == 6.11 05:14:25 INFO - mozscreenshot == 0.1 05:14:25 INFO - mozsystemmonitor == 0.0 05:14:25 INFO - moztest == 0.7 05:14:25 INFO - mozversion == 1.4 05:14:25 INFO - psutil == 3.1.1 05:14:25 INFO - requests == 1.2.3 05:14:25 INFO - wsgiref == 0.1.2 05:14:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:25 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 0x10a32ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a334570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb1d62ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1d2d30>, '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 0x109ff6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c9b20>, '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.qK2wxqDUmU/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.SIkTc97nAB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:14:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qK2wxqDUmU/Render', 05:14:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:25 INFO - 'HOME': '/Users/cltbld', 05:14:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:25 INFO - 'LOGNAME': 'cltbld', 05:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:25 INFO - 'MOZ_NO_REMOTE': '1', 05:14:25 INFO - 'NO_EM_RESTART': '1', 05:14:25 INFO - 'PAGER': '/bin/cat', 05:14:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:25 INFO - 'PWD': '/builds/slave/test', 05:14:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:25 INFO - 'SHELL': '/bin/bash', 05:14:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SIkTc97nAB/Listeners', 05:14:25 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:14:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:25 INFO - 'USER': 'cltbld', 05:14:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:25 INFO - 'XPC_FLAGS': '0x0', 05:14:25 INFO - 'XPC_SERVICE_NAME': '0', 05:14:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:14:26 INFO - Cleaning up... 05:14:26 INFO - Return code: 0 05:14:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:26 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 0x10a32ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a334570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb1d62ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1d2d30>, '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 0x109ff6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c9b20>, '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.qK2wxqDUmU/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.SIkTc97nAB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:14:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:14:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qK2wxqDUmU/Render', 05:14:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:26 INFO - 'HOME': '/Users/cltbld', 05:14:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:26 INFO - 'LOGNAME': 'cltbld', 05:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:26 INFO - 'MOZ_NO_REMOTE': '1', 05:14:26 INFO - 'NO_EM_RESTART': '1', 05:14:26 INFO - 'PAGER': '/bin/cat', 05:14:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:26 INFO - 'PWD': '/builds/slave/test', 05:14:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:26 INFO - 'SHELL': '/bin/bash', 05:14:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SIkTc97nAB/Listeners', 05:14:26 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:14:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:26 INFO - 'USER': 'cltbld', 05:14:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:26 INFO - 'XPC_FLAGS': '0x0', 05:14:26 INFO - 'XPC_SERVICE_NAME': '0', 05:14:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:26 INFO - Downloading/unpacking jsonschema 05:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:14:28 INFO - Downloading/unpacking functools32 (from jsonschema) 05:14: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 05:14: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 05:14: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 05:14: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 05:14: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 05:14: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 05:14:29 INFO - Downloading functools32-3.2.3-2.tar.gz 05:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:14:29 INFO - warning: no files found matching '*.txt' 05:14:29 INFO - no previously-included directories found matching 'build' 05:14:29 INFO - no previously-included directories found matching 'dist' 05:14:29 INFO - no previously-included directories found matching '.git*' 05:14:29 INFO - Installing collected packages: jsonschema, functools32 05:14:29 INFO - Running setup.py install for functools32 05:14:29 INFO - warning: no files found matching '*.txt' 05:14:29 INFO - no previously-included directories found matching 'build' 05:14:29 INFO - no previously-included directories found matching 'dist' 05:14:29 INFO - no previously-included directories found matching '.git*' 05:14:29 INFO - Successfully installed jsonschema functools32 05:14:29 INFO - Cleaning up... 05:14:29 INFO - Return code: 0 05:14:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:29 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 0x10a32ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a334570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdb1d62ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1d2d30>, '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 0x109ff6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c9b20>, '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.qK2wxqDUmU/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.SIkTc97nAB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:14:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:14:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qK2wxqDUmU/Render', 05:14:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:29 INFO - 'HOME': '/Users/cltbld', 05:14:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:29 INFO - 'LOGNAME': 'cltbld', 05:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:29 INFO - 'MOZ_NO_REMOTE': '1', 05:14:29 INFO - 'NO_EM_RESTART': '1', 05:14:29 INFO - 'PAGER': '/bin/cat', 05:14:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:29 INFO - 'PWD': '/builds/slave/test', 05:14:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:29 INFO - 'SHELL': '/bin/bash', 05:14:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SIkTc97nAB/Listeners', 05:14:29 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:14:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:29 INFO - 'USER': 'cltbld', 05:14:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:29 INFO - 'XPC_FLAGS': '0x0', 05:14:29 INFO - 'XPC_SERVICE_NAME': '0', 05:14:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:30 INFO - Downloading/unpacking flake8 05:14: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 05:14: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 05:14: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 05:14: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 05:14: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 05:14: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 05:14:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:14:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:14:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:14:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:14:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:14:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:14:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:14:33 INFO - no previously-included directories found matching 'docs/_build' 05:14:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:14:33 INFO - Running setup.py install for pep8 05:14:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:14:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:14:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:14:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:14:33 INFO - no previously-included directories found matching 'docs/_build' 05:14:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:14:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:14:33 INFO - Cleaning up... 05:14:34 INFO - Return code: 0 05:14:34 INFO - Running post-action listener: _resource_record_post_action 05:14:34 INFO - Running post-action listener: _start_resource_monitoring 05:14:34 INFO - Starting resource monitoring. 05:14:34 INFO - ##### 05:14:34 INFO - ##### Finished create-virtualenv step (success) 05:14:34 INFO - ##### 05:14:34 INFO - ##### 05:14:34 INFO - ##### Running install step. 05:14:34 INFO - ##### 05:14:34 INFO - Running pre-action listener: _resource_record_pre_action 05:14:34 INFO - Running main action method: install 05:14:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:34 INFO - Reading from file tmpfile_stdout 05:14:34 INFO - Detecting whether we're running mozinstall >=1.0... 05:14:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:14:34 INFO - Reading from file tmpfile_stdout 05:14:34 INFO - Output received: 05:14:34 INFO - Usage: mozinstall [options] installer 05:14:34 INFO - Options: 05:14:34 INFO - -h, --help show this help message and exit 05:14:34 INFO - -d DEST, --destination=DEST 05:14:34 INFO - Directory to install application into. [default: 05:14:34 INFO - "/builds/slave/test"] 05:14:34 INFO - --app=APP Application being installed. [default: firefox] 05:14:34 INFO - mkdir: /builds/slave/test/build/application 05:14:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:15:07 INFO - Reading from file tmpfile_stdout 05:15:07 INFO - Output received: 05:15:07 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:15:07 INFO - Running post-action listener: _resource_record_post_action 05:15:07 INFO - ##### 05:15:07 INFO - ##### Finished install step (success) 05:15:07 INFO - ##### 05:15:07 INFO - ##### 05:15:07 INFO - ##### Running run-tests step. 05:15:07 INFO - ##### 05:15:07 INFO - Running pre-action listener: _resource_record_pre_action 05:15:07 INFO - Running main action method: run_tests 05:15:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:15:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:15:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:15:07 INFO - 2016-05-12 05:15:07.651 screenresolution[1647:8144] starting screenresolution argv=screenresolution get 05:15:07 INFO - 2016-05-12 05:15:07.653 screenresolution[1647:8144] Display 0: 1024x768x32@60 05:15:07 INFO - 2016-05-12 05:15:07.659 screenresolution[1648:8146] starting screenresolution argv=screenresolution list 05:15:07 INFO - Available Modes on Display 0 05:15:07 INFO - 1024x768x32@60 800x600x32@75 800x600x32@60 05:15:07 INFO - 640x480x32@75 640x480x32@60 Graphics/Displays: 05:15:07 INFO - Intel Iris: 05:15:07 INFO - Chipset Model: Intel Iris 05:15:07 INFO - Type: GPU 05:15:07 INFO - Bus: Built-In 05:15:07 INFO - VRAM (Dynamic, Max): 1536 MB 05:15:07 INFO - Vendor: Intel (0x8086) 05:15:07 INFO - Device ID: 0x0a2e 05:15:07 INFO - Revision ID: 0x0009 05:15:07 INFO - Displays: 05:15:07 INFO - Display: 05:15:07 INFO - Resolution: 1024 x 768 @ 60 Hz 05:15:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:15:07 INFO - Display Serial Number: 1024x768 60 05:15:07 INFO - Main Display: Yes 05:15:07 INFO - Mirror: Off 05:15:07 INFO - Online: Yes 05:15:07 INFO - Rotation: Supported 05:15:07 INFO - Return code: 0 05:15:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:15:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:15:07 INFO - Python 2.7.3 05:15:07 INFO - Return code: 0 05:15:07 INFO - grabbing minidump binary from tooltool 05:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:07 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 0x10a1d2d30>, '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 0x109ff6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1c9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:15:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:15:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:15:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:15:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:15:08 INFO - Return code: 0 05:15:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:15:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:15:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:15:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:15:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:15:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:15:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:15:09 INFO - Return code: 0 05:15:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0296 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:15:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qK2wxqDUmU/Render', 05:15:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:09 INFO - 'HOME': '/Users/cltbld', 05:15:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:09 INFO - 'LOGNAME': 'cltbld', 05:15:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:15:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:09 INFO - 'MOZ_NO_REMOTE': '1', 05:15:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:15:09 INFO - 'NO_EM_RESTART': '1', 05:15:09 INFO - 'PAGER': '/bin/cat', 05:15:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:09 INFO - 'PWD': '/builds/slave/test', 05:15:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:15:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:09 INFO - 'SHELL': '/bin/bash', 05:15:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SIkTc97nAB/Listeners', 05:15:09 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 05:15:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:09 INFO - 'USER': 'cltbld', 05:15:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:09 INFO - 'XPC_FLAGS': '0x0', 05:15:09 INFO - 'XPC_SERVICE_NAME': '0', 05:15:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:15:09 INFO - mozversion application_buildid: 20160512030253 05:15:09 INFO - mozversion application_changeset: c3f5e6079284a7b7053c41f05d0fe06ff031db03 05:15:09 INFO - mozversion application_display_name: Nightly 05:15:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:15:09 INFO - mozversion application_name: Firefox 05:15:09 INFO - mozversion application_remotingname: firefox 05:15:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:15:09 INFO - mozversion application_vendor: Mozilla 05:15:09 INFO - mozversion application_version: 49.0a1 05:15:09 INFO - mozversion platform_buildid: 20160512030253 05:15:09 INFO - mozversion platform_changeset: c3f5e6079284a7b7053c41f05d0fe06ff031db03 05:15:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:15:09 INFO - mozversion platform_version: 49.0a1 05:15:09 INFO - using testdate: 1463055309 05:15:09 INFO - actual date: 1463055309 05:15:09 INFO - starting webserver on 'localhost:49279' 05:15:09 INFO - SUITE-START | Running 2 tests 05:15:09 INFO - TEST-START | dromaeo_css 05:15:09 INFO - operating with platform_type : mac_ 05:15:09 INFO - Initialising browser for dromaeo_css test... 05:15:09 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmp07nmBf/profile http://localhost:49279/getInfo.html) 05:15:12 INFO - PROCESS | 1666 | __metrics Screen width/height:1024/768 05:15:12 INFO - PROCESS | 1666 | colorDepth:24 05:15:12 INFO - PROCESS | 1666 | Browser inner width/height: 1024/605 05:15:12 INFO - PROCESS | 1666 | __metrics 05:15:12 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:15:13 INFO - TEST-INFO | 1666: exit 0 05:15:13 INFO - Browser initialized. 05:15:13 INFO - Running cycle 1/1 for dromaeo_css test... 05:15:13 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmp07nmBf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:25 INFO - PROCESS | 1671 | RSS: Main: 248893440 05:15:25 INFO - PROCESS | 1671 | 05:16:14 INFO - PROCESS | 1671 | 1463055374800 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:18:26 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 05:18:27 INFO - PROCESS | 1671 | RSS: Main: 259293184 05:18:27 INFO - PROCESS | 1671 | 05:21:30 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 05:21:30 INFO - PROCESS | 1671 | RSS: Main: 269975552 05:21:30 INFO - PROCESS | 1671 | 05:24:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 05:24:33 INFO - PROCESS | 1671 | RSS: Main: 272023552 05:24:33 INFO - PROCESS | 1671 | 05:27:34 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 05:27:35 INFO - PROCESS | 1671 | RSS: Main: 273866752 05:27:35 INFO - PROCESS | 1671 | 05:30:38 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 05:30:39 INFO - PROCESS | 1671 | RSS: Main: 276717568 05:30:39 INFO - PROCESS | 1671 | 05:33:41 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 05:33:42 INFO - PROCESS | 1671 | RSS: Main: 277651456 05:33:42 INFO - PROCESS | 1671 | 05:33:42 INFO - PROCESS | 1671 | __start_tp_report 05:33:42 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 05:33:42 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 05:33:42 INFO - PROCESS | 1671 | |i|pagename|runs| 05:33:42 INFO - PROCESS | 1671 | |0;dojo.html;3478.982605086974;3488.5813702355704;3521.929561408772;3526.4005119129747;3527.6364835560203;5280.6534333744885;5395.325584301962;5861.384554621767;5945.643261404319;5985.132155837401;5794.768209271635;5879.85300367491;5990.221271234734;5991;6063.120847477113;5295.867603309919;5297.178937264711;5334.986352593014;5430.945690543103;5455.972720136394;5337.813176538822;5381.327334083239;5388.461153884619;5426.2674538937135;5458.0721277382745;5260.184671375904;5261.394939581959;5323.414424413311;5344.251804747339;5364.249005139283;4234.872953811359;5200;5272.630915835893;5294.311739473844;5319.20211968204;5276.023935571931;5316.867078323035;5359.651622644516;5370.221317908881;5375.919361209585;5685.459881311269;5725.6278341908055;5732.713364331767;5767.567432442542;5888.057910734305;6205.689715514206;6320.841978950517;6333.24001119864;6340.524460665469;6355.586886852339;6200.7908457850735;6330.65181415022;6330.746770129189;6335.303116657164;6358.33237510064;5986.940130598726;6034.24571928509;6060.939390606081;6083.391660833881;6100.75596976116;6383.521235907326;6451.3548645135115;6482.1573195485225;6484.610923344614;6487.318831522716;4875.463698993108;5252.5535329497425;6132.01899668756;6312.5265605078885;6358.268799088071;6249.812505624838;6346.2384513857705;6353.745850166034;6366.363363663597;6371.553991220663;6153.969230153821;6183.134361189437;6315.936840631627;6335.936640633535;6341.270753863272;5508.118701007819;6247.481456054217;6324.462420694201;6338.30278669346;6348.206474190726;4358.694891357638;4821.734804585782;5026.698398096126;5043.823466178739;5049.26785616091;4490.640748740093;4491.595756381941;4491.977540112279;4517.728936263835;4528.207563676409;5159.76165720232;5940.61386009908;6075.908861367083;6093.573449858556;6385.553011289165;6273.592216506004;6282.308946015934;6293.339199383999;6388.968055159788;6424.678766061678;6081.57428979976;6107.908381374283;6124;6125.509959203253;6128.17269668598;5425.674459532362;5489.560835133259;5572.3870374258795;5574.442555744474;5625.634333768292;6425.58233714807;6575.079488871562;6581.572197807127;6592.505562082767;6593.637349945799;5537.667739935456;5967.91048134287;6328.114064030948;6351.237851457855;6356.11014457967;6392.20097487814;6488.578242414228;6519.217693876765;6520.641364724986;6527.172391730323;6310.842228944313;6318.778842740482;6329.90505142414;6330.873382532415;6362.491000719839;6215.906761398673;6352.745890164341;6382.712777924911;6395.968020159869;6400.135981642418;6143.266246520256;6230.190075290183;6239.656818875005;6289.811305660837;6339.524535659936;6187.071939209155;6247.5939063960695;6272.121902933502;6307.842303942438;6316.873662526816;6016.187814645142;6099.8780024398375;6202.782902598387;6219.875602488016;6254.124422580751;6160.876782464236;6219.564630475823;6264.373562643699;6330.177076979963;6331.366863313632;6182.845428864314;6223.533235007302;6242.406971337716;6248.625082494883;6306.558540902093 05:33:42 INFO - PROCESS | 1671 | |1;ext.html;478.98084076636934;536.4099490560384;543.1282791120252;558.4722437296756;577.0882006429841;39007;41438.79280603596;41450.37824432628;41468.792656036756;41536.16927661453;41359.17281654372;41742.791286043524;42119.578804211946;42785;42882;8643.611037503311;9667.90332096677;9687.757806054835;9715.271354648394;9806.068423499755;9744.415335079917;9821.705348839547;9843.310968232227;9844.114029737322;9866.703998880044;9700.563474643623;9820.312578119534;9843.163331116864;9858.471936849799;9875.654352097677;9708.805823883482;9795.118439340493;9806.705798826046;9835.754106147331;9871.40771553709;9695.70912872615;9814.950925245328;9816.901830981671;9817.90182098177;9833.508324583814;38254;38365.61634383648;38591.8422447327;38683.41974870379;38698.80650596729;2781.1517487166407;3234.110619579611;3253.4957081652146;3256.3897326777346;3375.780574262665;26735;28987.42025159506;29092.563611545844;29193.13137955256;29631.407371852656;2640.9867950660123;2731.6039174319812;2771.8336899786077;2788.6653601567737;3014.7338117990275;437597;509597;522722.3863880694;529119.3544032255;533909;35017.824910875795;63941.68029159917;65245;65248.67375663092;65635.34364656388;29427.852860735988;34601.82699086489;35864.924052278475;37277.25445491075;37458.62541374551;15218.467353642569;15313.923430383;15450.690986180214;15495.457658982106;15525.14611696345;10891.564337426415;12990.480380784662;12992.740145197044;13020.804687929689;13052.086353955132;7922.36621070313;8267.214614611603;8297;8343.8331233375;8344.874826877602;3047.3752880659413;3442.7934323940644;3472.3923313420555;3487.6049580168137;3500.369933411959;1047.7427087494884;1050.4905121016302;1065.9520321585546;1075.177574012042;1172.3962159487871;25300.620490692654;29769;30104.548431773543;30715.8464207682;30890.227744306572;1078.6494389323502;1088.0425225801243;1151.7696460707891;1187.554666999875;1191.0769153905792;15456.149911754737;15460.5361839145;15599.922000390152;15903;15974.840251597569;660693;661568;668674;671590;677519;61170.08244876331;61175.38824611786;61263.38736612666;61816;61967;1269.9873001269868;1445.5013020508118;1448.478547722819;1490.5602847160114;1496.9176695281647;29125.41749165047;29194;29306.560401593568;29680.703192967794;29920.55119173258;52438.95122097613;52754;52800.47199528109;52855.207171891685;52867.735661322986;563308;564742;585045;586579;586654;26771.464570708085;27142.050028249705;27149;27218.72781272162;27220;45589.77205113953;46512.302315465975;46778.29832552582;46945.53054469411;47199.52800471951;38073.238535228586;38562.2287554253;38600.42099368568;39043.609563905135;39389.80305098456;32487;37805.86582402532;38068.80965595154;38515;38873;38106.809465952494;38910.6108938907;39046.023849403995;39497;40754.14720969504;29815;31526;34186;34222;34323.828380857936;24908.377290567158;25066;25077.122300719388;25246.747532525176;25920.8703956479 05:33:42 INFO - PROCESS | 1671 | |2;jquery.html;308.73603069375685;365.9451082337649;393.8326211360173;411.1037937296692;420.2918083030095;16632.83367166328;20816.791832081693;20999.95000249989;21911.67132493014;21955.890220548896;9840.606375744961;12104.515819367236;12433.129680922339;12443.253404795718;16617.83382166178;5266.3153790007345;5431.592630552725;5463.699496527689;5463.972680136594;5484.91772623411;3843.7693738375647;5297.337832770903;5859.209006784094;6102.626908945496;6236.532260080489;5604.243427137325;6177.166082578862;6224.937750622504;6226.657533835637;6229.563930524865;5127.180849470195;5978.8206353809455;6033.879322413571;6046.213992181032;6052.394760523956;5818.679972601505;5996.830618029486;5999.220101386835;6020.217371741645;6042.033274676075;13521.594352169352;13898;14139.717205655828;14218.644533886632;14265.28673566328;2110.873347599149;2191.4630915425664;2291.6104262275453;2365.29041287613;2372.5729368713623;10280.794384112276;10348.016938390841;10577;13264.204147751167;14254.14475131485;1740.069063051274;1745.1710437542065;1759.129914188777;1761.4803632928317;1838.3290099113726;125938.96244150223;132926.00610990843;134231;134594;136114.31942840322;11549.191556591126;11765.470553825098;11867;11909.821352679717;11963.820542691867;15321.157336346609;15389.461368852037;15391.230438478031;15434.073955562702;15455.381784728484;8698.173673501135;8766.430182038275;8855.867161992575;8874.334424918028;8918.464892106495;8660.26387757035;8726.171013753683;8742.25690816275;8802.339824513187;8840.823183536293;4679.836205732783;4696.060787842377;4696.412948381452;4726.574608285271;4840.2255639097575;1822.2893071702047;1885.6134492429019;2010.286348346316;2021.5754691514946;2026.3384025099572;749.7375918428615;930.4882314727008;1015.5683834370363;1032.6747074671455;1049.2393015064017;20349.796502035086;20606.484837879172;21118;21257;21297.36107916765;815.4373482297315;910.6402970826487;952.623713633111;960.7502049467189;982.2436723722693;8388.748337549883;9515.952420237854;10744.656917885264;11058.281211721212;11282.830757538644;553827;561320;561581;602353;619740.9012954907;127018.36490817672;131071.68928310786;133247.33376333056;136781.63218367688;140886.2955685215;1006.9949650251701;1013.2350075692805;1028.9073983341534;1031.5873650539845;1034.8240799064176;18302.450926472226;18322.541936451697;18407.07964601791;18439.90780046091;18514.44456666302;43237.78381108074;46679.06641867212;50802.74598626983;53593.92812143813;54688.632784180714;79256.60371698104;79345;79401;79668.60165699135;82024.58987705024;6830.7609233676585;7851.725189618336;8092.87860682102;8135.349172066102;8465.111163328009;8862.512561808904;11533.538658453568;12195.695107622025;12582;12846.614601561969;10673.46632668348;10780.460976951277;10841.186910981865;10898.455077246264;10981.121510279318;9326.974032856517;10562.735931601772;10590.735231619272;10790.190735694698;10931.781364372828;10648.361098334124;10923.890761092605;11070.944645276722;11092.168087393316;11279.492422840827;5727.713614319351;8014.679412823421;8178;8194.262516373556;8406;9261.907380926104;11210.775784484427;11238.719032024264;11287.871212878646;11396.94301528487 05:33:42 INFO - PROCESS | 1671 | |3;mootools.html;1398.8461269260379;1595.6170519075426;1822.6628073806346;1930.8744931579456;1980.861339706221;8238;8833.558322083894;8857.734267971971;9351.251899848014;9368.437893726381;8928.19646231839;8998.73003809885;9163.816723665523;9170.266378689705;9188.127127922837;2452.9574930654444;2477.814163937712;2478.8388754730977;2488.4525404411092;2490.0288887333954;2419.346776370379;2481.627755836621;2490.1284550407395;2490.2778194323623;2504.749525047499;2430.1858877276136;2444.340028192387;2465.285067330472;2481.6525686403916;2492.0779311654715;2404.9519009619885;2439.243834411338;2446.302803700946;2452.9329741562387;2475.096589744748;2441.926742197737;2452.865092419916;2463.1132792194794;2466.3094333586623;2472.554940110779;8935.776605584848;9038.231750301233;9191.494467804268;9231.676891308773;9259.30555208362;5423.2949716537;5448.727563621803;5459.781608735646;5577.191307260425;5649.943500564983;3609.7473176877365;3614.5120408744747;3664.13892735207;3670.632936706335;3712.9071773205615;867.2758246863876;1065.0627447845845;1072.5924148823476;1074.5272080284728;1075.714935542082;10440.425776582286;11329.150313726503;12401.317927513983;12411.379431028412;12490.81263781044;4853.878653033632;4882.194437917704;5028.497150285016;5036;5074.847754567369;10251.692449226535;10371.740706482398;10416.958304169522;10426.322289051339;10430.791384172275;5478.863028424321;5491.697956612425;5544.16837474374;5593.105103183471;5627.521660658808;4558.111168322212;4934.235193545006;5085.466026067284;5091.058154241403;5108.565771909355;4149.585041495826;4171.645410140111;4189.706720529594;4201.264778663722;4206.0326124991425;1335.9599212023654;1360.673438374783;1368.548379034916;1371.8971077169251;1372.670559065817;775.5463054113294;812.8455593437346;877.2674816528142;948.3645957208549;982.823091843475;5828.154917536934;6045.304789949212;6097.939020609737;6129.632222066691;6147.600405973687;451.5371743962465;453.36755226458905;463.9675222734444;472.87468820762524;474.00222531571234;3367.9326413471595;4050.736702114353;4096.73371230869;4099.139180772071;4105.363668631367;9219.953900230455;10592.258541902147;10993.395363254938;11263.943680281545;11285;10894.891051089546;11077.169212309109;11204.775904481865;11236.94381528087;11277.436128193558;7960.56216908064;8403.957980210182;8478.74563763088;8510.234078932926;8593.398462107718;5346.599005074713;6061.909071364022;6072.666003369681;6114.908276375769;6249.281332646804;10214.978502149726;10542.26204165701;10572.84140737905;10607.628732994308;10638.946805265925;10406.843897341394;10531.473168993594;10739.14086873064;10744.19418543628;10860.294080884718;7098.929010709827;7650.273224043707;7778.533288002738;7943.483673561431;8229.753107406787;7671.808204794925;8582.957085214533;8642.049374568667;8680.913190868263;8721.912780872364;7592.240775922363;7712.961435193012;7822.256885595859;7824.295813376823;7830.177831327628;6428.646424446513;7454.813629659302;7486.401087912845;7534.321911028033;7706.306432421109;7210.387117094896;7259.2377800330205;7319.8536029280185;7334.486585939147;7343.265673432614;4878.146324393288;5023.8492845214705;5056.469070747518;5084.51697088776;5155.381354237516;4974.253861920596;5132.897342053064;5182.24857395683;5196.922046169235;5402.9459705404015 05:33:42 INFO - PROCESS | 1671 | |4;prototype.html;313.65654608204017;322.9660885607011;325.5052320472881;328.6089553431418;332.0834496788862;4659.044895796362;5029.572486338666;5491.752871120799;5572.9968605650965;5595.608307418483;4977.900441991158;5529.778808847651;5553.8062465326275;5556.638818476797;5573.804916827912;3523.3481805865867;3992.7803970781597;4152.695254133954;4202.453681021479;4217.536071032199;3681.7790932544;3985.827723890472;4184;4207.705460617758;4253.234417804794;4211.831526738927;4224.61978421942;4234.640055595278;4236.173946080516;4260.68044896634;4059.9188016239805;4227.471566054243;4228.260054490483;4248.617624413802;4250.234957707611;3639.909002274964;3644;3658.2134841009047;3709.684676802475;3709.7588656737494;4785.947091639834;4905.803767849282;5288.6826790392315;5316.946830531684;5333.973330133324;3983;4178.979105104455;4332.805023773938;4877.170880950247;4908.404768450232;5577.414371491016;5753.856153596152;5843.766249350021;5865.76536938522;5866.941330586682;3779.8471466203073;3831.8467261309515;4101.241270364992;4130.401091841696;4132.566080561558;5631.2960879890015;5656.660600363992;5717.826606233539;5980.880382392328;6208.044465993197;4106.137711080647;4163.2298024865495;4174.645155161785;4185.309423945076;4204.5375008749015;4243.915121697549;4251.234777740036;4259.9787001065415;4265.210935976854;4269.765162916007;3950.9257639739367;4504.797284122223;4655.534446555317;4907.2393778964315;4914.410270767531;5152.768125434364;5809.099589563624;5853.5317174625925;5866.413358664099;5880.323762767336;1409.1404243411512;1432.1550285331496;1435.70069087476;1435.7128574285184;1442.7691569348854;769.2999370572752;865.8484215992714;888.7378223424106;894.8702438146432;899.9685011024583;4685.945662225972;5219.173289348773;5231.8168864089575;5290.973545132327;5331.360236771612;4925.581325587294;5652.689102099342;5842.26095299009;5885.911711324334;5890.204822349014;5097.362829646294;5273.578113750891;5283.682979021271;5308.840734777963;5334.706591137525;2568.345072006626;3085.2595377109333;3127.3901589190345;3127.734142597905;3142.6700196479496;5898.171566814215;6131.754729810847;6157.353477884847;6170.346750897068;6214.627122372672;5672.631278966936;5717.914231286534;5726.713664316768;5922.505721624262;6208.2239720034995;6239.098819049073;6273.494361353333;6288.402601752826;6301.779437719658;6302.275238347649;5577.74900129487;6000.849978750566;6013.338532761481;6025.578209525466;6077.361877002851;5307.256984022163;5517.972410137923;5978.312494063239;5985.102234664835;6030.7286172121485;5878.353381127987;5945.524358051259;5972.372900845348;6269;6303.770764700839;3400.7619466637098;3981.7610943343493;4012.498437695288;4015.9397609035304;4022.6781857450746;5598.720063996702;5979.22270104901;6027.21646185993;6048.516118710405;6100.450959413674;5050.898982020412;5808.564357673107;5826.76692932278;5829.854253643693;5844.386339434468;5450.945490545044;5528.861778455571;5792.160136780228;5812.476877081083;5884.08796636522;4529.886752831206;4751.334813126235;5227.529522343008;5392.676439413648;5652.915206271827;5124.9743751281;5615.052097648767;5805.651660900359;5817.680027598522;5823.417658234142;5255.13290307095;5675.687837168995;5681.829545113653;5682.744276507485;5689.776698009948 05:33:42 INFO - PROCESS | 1671 | |5;yui.html;602.7871445197098;733.1019501111138;734.5005396330494;746.9365103966163;763.1833937686678;15202.239888005597;15389.461368852093;15665.373385064599;15759.448419305327;15785.921070394632;14127.081739686913;14372.209528475929;14391.352389142488;14654.560363189095;14679.706405871877;740.6704016712565;910.3218102513637;923.5428462910852;932.645594674023;943.2548286853378;792.2473650032464;877.2587163846539;905.7056456651582;921.3366376209119;921.7741653294952;917.9127982841619;922.3866129024225;954.9474778887159;957.7892863570004;959.904009599038;904.0959040959041;916.4043371808311;920.5719340506696;948.7628092976756;954.8615450759601;607.2439812433504;673.3367632881608;674.689642764329;698.7589281697806;709.2659097833717;12973.416196271097;14460.566183014527;14821.184834834079;14968.47610333633;15002.399904003827;10684.412357320345;12406.75186496265;12522.306227849083;12561.057920655987;12703.61889143318;8574.914250857473;9955.647589986593;10015.74960625983;10164;10643.095336896373;3450.723942084627;3505.0010746937255;3518.40187168182;3542.574891013069;3551.8756843510355;82197.58901205455;82675.75986360149;83049.58475207645;84939.57530212309;84992.57503712503;8094.514329140153;9423.623055077856;9452.05479452063;9547.140757331874;9595.088466595662;21011;21059;21486.677699834516;21525;21583.67624485634;7238.746643867439;7373.483856130127;7458.589777562286;7507.2357995870925;7519.135299440634;3940.763554186758;4015.9799201004394;4024.678025757932;4026.959730402717;4038.757674539537;3498.8075655838666;3600.3159399714496;3606.04439823447;3611.3860643690637;3612.096975756061;541.3422691429931;622.5673157155824;636.8057742388615;642.3608509532951;642.7814543055383;457.2341328275192;555.5916401444935;583.3990989281048;607.3895734786511;617.9196704428396;7732;8085.231902969209;8240.423170378135;8282.917170828214;8383.538905360141;535.253321616343;573.4695406748824;602.9427204415573;616.6782065523308;640.334052585306;3801.4436114213036;3882.7282090253975;3947.5460322062754;3991.980040099781;4080;90365.54817226004;91906.54046729858;92517.53741231251;92789;93477.66305842272;79742.20257797463;83282.58358708168;84297;84474.15525844786;85425.57287213524;57989.710051449474;72994.63502682453;73029.90455143177;73317.26682733212;73531.63234183902;7676.884846727192;7692.730699434755;7821;7933.008373953256;7956.6419511120985;45525.77237113793;45849.54150458453;45985.540144598126;45990.85022874455;46281.305780414;46782.53217467782;63017;63924.68037659782;64069;64477.677611611645;8485.745427637181;8858.512781797062;10222.284440089123;10274.589016439557;10555.366677999345;11230.270032447865;11340.659780206606;11445.167055552864;11595.18833681668;11598.246114002563;8967.327450441113;9032.954835225783;10620.681379558626;10652.307600005975;10968.835467467834;10508.316959397614;10543.103836173705;10604.840927385942;10673.679789606324;10893.346399216072;10546.947265263625;10738.248322617343;10757.677269681923;10916.945415272872;10955.83566246523;9031.774205644648;9036.096390361174;9044.186023257938;9078.022680083233;9287.535623218677;7478.352399110203;8708.737233101037;8768.956155219183;8824.5587720615;9029.909700902906 05:33:42 INFO - PROCESS | 1671 | __end_tp_report 05:33:42 INFO - PROCESS | 1671 | __start_cc_report 05:33:42 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,417 05:33:42 INFO - PROCESS | 1671 | __end_cc_report 05:33:42 INFO - PROCESS | 1671 | __startTimestamp1463056422045__endTimestamp 05:33:42 INFO - PROCESS | 1671 | 05:33:42 INFO - PROCESS | 1671 | ------- Summary: start ------- 05:33:42 INFO - PROCESS | 1671 | Number of tests: 6 05:33:42 INFO - PROCESS | 1671 | 05:33:42 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5858.91 Median:6143.27 stddev:676.95 (11.0%) stddev-sans-first:652.77 05:33:42 INFO - PROCESS | 1671 | Values: 3479.0 3488.6 3521.9 3526.4 3527.6 5280.7 5395.3 5861.4 5945.6 5985.1 5794.8 5879.9 5990.2 5991.0 6063.1 5295.9 5297.2 5335.0 5430.9 5456.0 5337.8 5381.3 5388.5 5426.3 5458.1 5260.2 5261.4 5323.4 5344.3 5364.2 4234.9 5200.0 5272.6 5294.3 5319.2 5276.0 5316.9 5359.7 5370.2 5375.9 5685.5 5725.6 5732.7 5767.6 5888.1 6205.7 6320.8 6333.2 6340.5 6355.6 6200.8 6330.7 6330.7 6335.3 6358.3 5986.9 6034.2 6060.9 6083.4 6100.8 6383.5 6451.4 6482.2 6484.6 6487.3 4875.5 5252.6 6132.0 6312.5 6358.3 6249.8 6346.2 6353.7 6366.4 6371.6 6154.0 6183.1 6315.9 6335.9 6341.3 5508.1 6247.5 6324.5 6338.3 6348.2 4358.7 4821.7 5026.7 5043.8 5049.3 4490.6 4491.6 4492.0 4517.7 4528.2 5159.8 5940.6 6075.9 6093.6 6385.6 6273.6 6282.3 6293.3 6389.0 6424.7 6081.6 6107.9 6124.0 6125.5 6128.2 5425.7 5489.6 5572.4 5574.4 5625.6 6425.6 6575.1 6581.6 6592.5 6593.6 5537.7 5967.9 6328.1 6351.2 6356.1 6392.2 6488.6 6519.2 6520.6 6527.2 6310.8 6318.8 6329.9 6330.9 6362.5 6215.9 6352.7 6382.7 6396.0 6400.1 6143.3 6230.2 6239.7 6289.8 6339.5 6187.1 6247.6 6272.1 6307.8 6316.9 6016.2 6099.9 6202.8 6219.9 6254.1 6160.9 6219.6 6264.4 6330.2 6331.4 6182.8 6223.5 6242.4 6248.6 6306.6 05:33:42 INFO - PROCESS | 1671 | 05:33:42 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:70287.43 Median:38596.13 stddev:157570.51 (408.3%) stddev-sans-first:157925.36 05:33:42 INFO - PROCESS | 1671 | Values: 479.0 536.4 543.1 558.5 577.1 39007.0 41438.8 41450.4 41468.8 41536.2 41359.2 41742.8 42119.6 42785.0 42882.0 8643.6 9667.9 9687.8 9715.3 9806.1 9744.4 9821.7 9843.3 9844.1 9866.7 9700.6 9820.3 9843.2 9858.5 9875.7 9708.8 9795.1 9806.7 9835.8 9871.4 9695.7 9815.0 9816.9 9817.9 9833.5 38254.0 38365.6 38591.8 38683.4 38698.8 2781.2 3234.1 3253.5 3256.4 3375.8 26735.0 28987.4 29092.6 29193.1 29631.4 2641.0 2731.6 2771.8 2788.7 3014.7 437597.0 509597.0 522722.4 529119.4 533909.0 35017.8 63941.7 65245.0 65248.7 65635.3 29427.9 34601.8 35864.9 37277.3 37458.6 15218.5 15313.9 15450.7 15495.5 15525.1 10891.6 12990.5 12992.7 13020.8 13052.1 7922.4 8267.2 8297.0 8343.8 8344.9 3047.4 3442.8 3472.4 3487.6 3500.4 1047.7 1050.5 1066.0 1075.2 1172.4 25300.6 29769.0 30104.5 30715.8 30890.2 1078.6 1088.0 1151.8 1187.6 1191.1 15456.1 15460.5 15599.9 15903.0 15974.8 660693.0 661568.0 668674.0 671590.0 677519.0 61170.1 61175.4 61263.4 61816.0 61967.0 1270.0 1445.5 1448.5 1490.6 1496.9 29125.4 29194.0 29306.6 29680.7 29920.6 52439.0 52754.0 52800.5 52855.2 52867.7 563308.0 564742.0 585045.0 586579.0 586654.0 26771.5 27142.1 27149.0 27218.7 27220.0 45589.8 46512.3 46778.3 46945.5 47199.5 38073.2 38562.2 38600.4 39043.6 39389.8 32487.0 37805.9 38068.8 38515.0 38873.0 38106.8 38910.6 39046.0 39497.0 40754.1 29815.0 31526.0 34186.0 34222.0 34323.8 24908.4 25066.0 25077.1 25246.7 25920.9 05:33:42 INFO - PROCESS | 1671 | 05:33:42 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:34511.78 Median:11744.41 stddev:97691.43 (831.8%) stddev-sans-first:97931.72 05:33:42 INFO - PROCESS | 1671 | Values: 308.7 365.9 393.8 411.1 420.3 16632.8 20816.8 21000.0 21911.7 21955.9 9840.6 12104.5 12433.1 12443.3 16617.8 5266.3 5431.6 5463.7 5464.0 5484.9 3843.8 5297.3 5859.2 6102.6 6236.5 5604.2 6177.2 6224.9 6226.7 6229.6 5127.2 5978.8 6033.9 6046.2 6052.4 5818.7 5996.8 5999.2 6020.2 6042.0 13521.6 13898.0 14139.7 14218.6 14265.3 2110.9 2191.5 2291.6 2365.3 2372.6 10280.8 10348.0 10577.0 13264.2 14254.1 1740.1 1745.2 1759.1 1761.5 1838.3 125939.0 132926.0 134231.0 134594.0 136114.3 11549.2 11765.5 11867.0 11909.8 11963.8 15321.2 15389.5 15391.2 15434.1 15455.4 8698.2 8766.4 8855.9 8874.3 8918.5 8660.3 8726.2 8742.3 8802.3 8840.8 4679.8 4696.1 4696.4 4726.6 4840.2 1822.3 1885.6 2010.3 2021.6 2026.3 749.7 930.5 1015.6 1032.7 1049.2 20349.8 20606.5 21118.0 21257.0 21297.4 815.4 910.6 952.6 960.8 982.2 8388.7 9516.0 10744.7 11058.3 11282.8 553827.0 561320.0 561581.0 602353.0 619740.9 127018.4 131071.7 133247.3 136781.6 140886.3 1007.0 1013.2 1028.9 1031.6 1034.8 18302.5 18322.5 18407.1 18439.9 18514.4 43237.8 46679.1 50802.7 53593.9 54688.6 79256.6 79345.0 79401.0 79668.6 82024.6 6830.8 7851.7 8092.9 8135.3 8465.1 8862.5 11533.5 12195.7 12582.0 12846.6 10673.5 10780.5 10841.2 10898.5 10981.1 9327.0 10562.7 10590.7 10790.2 10931.8 10648.4 10923.9 11070.9 11092.2 11279.5 5727.7 8014.7 8178.0 8194.3 8406.0 9261.9 11210.8 11238.7 11287.9 11396.9 05:33:42 INFO - PROCESS | 1671 | 05:33:42 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5891.58 Median:4880.17 stddev:3323.48 (68.1%) stddev-sans-first:3315.65 05:33:42 INFO - PROCESS | 1671 | Values: 1398.8 1595.6 1822.7 1930.9 1980.9 8238.0 8833.6 8857.7 9351.3 9368.4 8928.2 8998.7 9163.8 9170.3 9188.1 2453.0 2477.8 2478.8 2488.5 2490.0 2419.3 2481.6 2490.1 2490.3 2504.7 2430.2 2444.3 2465.3 2481.7 2492.1 2405.0 2439.2 2446.3 2452.9 2475.1 2441.9 2452.9 2463.1 2466.3 2472.6 8935.8 9038.2 9191.5 9231.7 9259.3 5423.3 5448.7 5459.8 5577.2 5649.9 3609.7 3614.5 3664.1 3670.6 3712.9 867.3 1065.1 1072.6 1074.5 1075.7 10440.4 11329.2 12401.3 12411.4 12490.8 4853.9 4882.2 5028.5 5036.0 5074.8 10251.7 10371.7 10417.0 10426.3 10430.8 5478.9 5491.7 5544.2 5593.1 5627.5 4558.1 4934.2 5085.5 5091.1 5108.6 4149.6 4171.6 4189.7 4201.3 4206.0 1336.0 1360.7 1368.5 1371.9 1372.7 775.5 812.8 877.3 948.4 982.8 5828.2 6045.3 6097.9 6129.6 6147.6 451.5 453.4 464.0 472.9 474.0 3367.9 4050.7 4096.7 4099.1 4105.4 9220.0 10592.3 10993.4 11263.9 11285.0 10894.9 11077.2 11204.8 11236.9 11277.4 7960.6 8404.0 8478.7 8510.2 8593.4 5346.6 6061.9 6072.7 6114.9 6249.3 10215.0 10542.3 10572.8 10607.6 10638.9 10406.8 10531.5 10739.1 10744.2 10860.3 7098.9 7650.3 7778.5 7943.5 8229.8 7671.8 8583.0 8642.0 8680.9 8721.9 7592.2 7713.0 7822.3 7824.3 7830.2 6428.6 7454.8 7486.4 7534.3 7706.3 7210.4 7259.2 7319.9 7334.5 7343.3 4878.1 5023.8 5056.5 5084.5 5155.4 4974.3 5132.9 5182.2 5196.9 5402.9 05:33:42 INFO - PROCESS | 1671 | 05:33:42 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4724.16 Median:5299.12 stddev:1446.31 (27.3%) stddev-sans-first:1411.98 05:33:42 INFO - PROCESS | 1671 | Values: 313.7 323.0 325.5 328.6 332.1 4659.0 5029.6 5491.8 5573.0 5595.6 4977.9 5529.8 5553.8 5556.6 5573.8 3523.3 3992.8 4152.7 4202.5 4217.5 3681.8 3985.8 4184.0 4207.7 4253.2 4211.8 4224.6 4234.6 4236.2 4260.7 4059.9 4227.5 4228.3 4248.6 4250.2 3639.9 3644.0 3658.2 3709.7 3709.8 4785.9 4905.8 5288.7 5316.9 5334.0 3983.0 4179.0 4332.8 4877.2 4908.4 5577.4 5753.9 5843.8 5865.8 5866.9 3779.8 3831.8 4101.2 4130.4 4132.6 5631.3 5656.7 5717.8 5980.9 6208.0 4106.1 4163.2 4174.6 4185.3 4204.5 4243.9 4251.2 4260.0 4265.2 4269.8 3950.9 4504.8 4655.5 4907.2 4914.4 5152.8 5809.1 5853.5 5866.4 5880.3 1409.1 1432.2 1435.7 1435.7 1442.8 769.3 865.8 888.7 894.9 900.0 4685.9 5219.2 5231.8 5291.0 5331.4 4925.6 5652.7 5842.3 5885.9 5890.2 5097.4 5273.6 5283.7 5308.8 5334.7 2568.3 3085.3 3127.4 3127.7 3142.7 5898.2 6131.8 6157.4 6170.3 6214.6 5672.6 5717.9 5726.7 5922.5 6208.2 6239.1 6273.5 6288.4 6301.8 6302.3 5577.7 6000.8 6013.3 6025.6 6077.4 5307.3 5518.0 5978.3 5985.1 6030.7 5878.4 5945.5 5972.4 6269.0 6303.8 3400.8 3981.8 4012.5 4015.9 4022.7 5598.7 5979.2 6027.2 6048.5 6100.5 5050.9 5808.6 5826.8 5829.9 5844.4 5450.9 5528.9 5792.2 5812.5 5884.1 4529.9 4751.3 5227.5 5392.7 5652.9 5125.0 5615.1 5805.7 5817.7 5823.4 5255.1 5675.7 5681.8 5682.7 5689.8 05:33:42 INFO - PROCESS | 1671 | 05:33:42 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:18009.08 Median:32280.74 stddev:25869.27 (80.1%) stddev-sans-first:25908.82 05:33:42 INFO - PROCESS | 1671 | Values: 602.8 733.1 734.5 746.9 763.2 15202.2 15389.5 15665.4 15759.4 15785.9 14127.1 14372.2 14391.4 14654.6 14679.7 740.7 910.3 923.5 932.6 943.3 792.2 877.3 905.7 921.3 921.8 917.9 922.4 954.9 957.8 959.9 904.1 916.4 920.6 948.8 954.9 607.2 673.3 674.7 698.8 709.3 12973.4 14460.6 14821.2 14968.5 15002.4 10684.4 12406.8 12522.3 12561.1 12703.6 8574.9 9955.6 10015.7 10164.0 10643.1 3450.7 3505.0 3518.4 3542.6 3551.9 82197.6 82675.8 83049.6 84939.6 84992.6 8094.5 9423.6 9452.1 9547.1 9595.1 21011.0 21059.0 21486.7 21525.0 21583.7 7238.7 7373.5 7458.6 7507.2 7519.1 3940.8 4016.0 4024.7 4027.0 4038.8 3498.8 3600.3 3606.0 3611.4 3612.1 541.3 622.6 636.8 642.4 642.8 457.2 555.6 583.4 607.4 617.9 7732.0 8085.2 8240.4 8282.9 8383.5 535.3 573.5 602.9 616.7 640.3 3801.4 3882.7 3947.5 3992.0 4080.0 90365.5 91906.5 92517.5 92789.0 93477.7 79742.2 83282.6 84297.0 84474.2 85425.6 57989.7 72994.6 73029.9 73317.3 73531.6 7676.9 7692.7 7821.0 7933.0 7956.6 45525.8 45849.5 45985.5 45990.9 46281.3 46782.5 63017.0 63924.7 64069.0 64477.7 8485.7 8858.5 10222.3 10274.6 10555.4 11230.3 11340.7 11445.2 11595.2 11598.2 8967.3 9033.0 10620.7 10652.3 10968.8 10508.3 10543.1 10604.8 10673.7 10893.3 10546.9 10738.2 10757.7 10916.9 10955.8 9031.8 9036.1 9044.2 9078.0 9287.5 7478.4 8708.7 8769.0 8824.6 9029.9 05:33:42 INFO - PROCESS | 1671 | -------- Summary: end -------- 05:33:42 INFO - PROCESS | 1671 | 05:33:42 INFO - TEST-INFO | 1671: exit 0 05:33:42 INFO - TEST-OK | dromaeo_css | took 1113237ms 05:33:42 INFO - TEST-START | kraken 05:33:42 INFO - operating with platform_type : mac_ 05:33:42 INFO - Initialising browser for kraken test... 05:33:42 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpIipATk/profile http://localhost:49279/getInfo.html) 05:33:45 INFO - PROCESS | 1680 | __metrics Screen width/height:1024/768 05:33:45 INFO - PROCESS | 1680 | colorDepth:24 05:33:45 INFO - PROCESS | 1680 | Browser inner width/height: 1024/605 05:33:45 INFO - PROCESS | 1680 | __metrics 05:33:45 ERROR - PROCESS | 1680 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:33:46 INFO - TEST-INFO | 1680: exit 0 05:33:46 INFO - Browser initialized. 05:33:46 INFO - Running cycle 1/1 for kraken test... 05:33:46 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpIipATk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:58 INFO - PROCESS | 1684 | RSS: Main: 248803328 05:33:58 INFO - PROCESS | 1684 | 05:34:00 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 05:34:01 INFO - PROCESS | 1684 | RSS: Main: 258719744 05:34:01 INFO - PROCESS | 1684 | 05:34:04 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 05:34:05 INFO - PROCESS | 1684 | RSS: Main: 262987776 05:34:05 INFO - PROCESS | 1684 | 05:34:08 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 05:34:09 INFO - PROCESS | 1684 | RSS: Main: 263565312 05:34:09 INFO - PROCESS | 1684 | 05:34:11 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 05:34:11 INFO - PROCESS | 1684 | RSS: Main: 263938048 05:34:11 INFO - PROCESS | 1684 | 05:34:14 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:34:14 INFO - PROCESS | 1684 | RSS: Main: 263782400 05:34:14 INFO - PROCESS | 1684 | 05:34:17 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 05:34:18 INFO - PROCESS | 1684 | RSS: Main: 264376320 05:34:18 INFO - PROCESS | 1684 | 05:34:21 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 05:34:22 INFO - PROCESS | 1684 | RSS: Main: 264798208 05:34:22 INFO - PROCESS | 1684 | 05:34:25 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 05:34:25 INFO - PROCESS | 1684 | RSS: Main: 264368128 05:34:25 INFO - PROCESS | 1684 | 05:34:27 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:34:28 INFO - PROCESS | 1684 | RSS: Main: 263999488 05:34:28 INFO - PROCESS | 1684 | 05:34:29 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:34:30 INFO - PROCESS | 1684 | RSS: Main: 263843840 05:34:30 INFO - PROCESS | 1684 | 05:34:33 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:34:33 INFO - PROCESS | 1684 | RSS: Main: 264216576 05:34:33 INFO - PROCESS | 1684 | 05:34:36 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:34:37 INFO - PROCESS | 1684 | RSS: Main: 264269824 05:34:37 INFO - PROCESS | 1684 | 05:34:40 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:34:40 INFO - PROCESS | 1684 | RSS: Main: 264294400 05:34:40 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 05:34:42 INFO - PROCESS | 1684 | RSS: Main: 265973760 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | __start_tp_report 05:34:42 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 05:34:42 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 05:34:42 INFO - PROCESS | 1684 | |i|pagename|runs| 05:34:42 INFO - PROCESS | 1684 | |0;ai-astar;113;116;112;115;140;175;143;116;115;115 05:34:42 INFO - PROCESS | 1684 | |1;audio-beat-detection;157;120;144;192;110;162;160;109;208;149 05:34:42 INFO - PROCESS | 1684 | |2;audio-dft;194;197;224;250;196;198;197;199;195;192 05:34:42 INFO - PROCESS | 1684 | |3;audio-fft;75;78;74;170;61;57;57;69;129;72 05:34:42 INFO - PROCESS | 1684 | |4;audio-oscillator;80;91;131;90;116;125;107;106;113;161 05:34:42 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;136;130;89;88;89;85;91;87;89;87 05:34:42 INFO - PROCESS | 1684 | |6;imaging-darkroom;143;102;103;95;95;95;96;126;150;132 05:34:42 INFO - PROCESS | 1684 | |7;imaging-desaturate;96;92;91;90;92;89;90;90;98;92 05:34:42 INFO - PROCESS | 1684 | |8;json-parse-financial;62;60;61;60;61;61;61;121;62;60 05:34:42 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;66;65;70;66;73;69;68;64;64;65 05:34:42 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;85;72;107;106;107;107;88;107;109;106 05:34:42 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;134;140;138;138;137;138;144;170;221;170 05:34:42 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;167;170;175;166;171;171;168;171;163;170 05:34:42 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;68;66;73;80;86;89;83;76;68;67 05:34:42 INFO - PROCESS | 1684 | __end_tp_report 05:34:42 INFO - PROCESS | 1684 | __start_cc_report 05:34:42 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,1061 05:34:42 INFO - PROCESS | 1684 | __end_cc_report 05:34:42 INFO - PROCESS | 1684 | __startTimestamp1463056482979__endTimestamp 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | ------- Summary: start ------- 05:34:42 INFO - PROCESS | 1684 | Number of tests: 14 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:126.00 Median:116.00 stddev:20.59 (17.7%) stddev-sans-first:21.29 05:34:42 INFO - PROCESS | 1684 | Values: 113.0 116.0 112.0 115.0 140.0 175.0 143.0 116.0 115.0 115.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:151.10 Median:158.50 stddev:32.71 (20.6%) stddev-sans-first:34.62 05:34:42 INFO - PROCESS | 1684 | Values: 157.0 120.0 144.0 192.0 110.0 162.0 160.0 109.0 208.0 149.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:204.20 Median:197.50 stddev:18.45 (9.3%) stddev-sans-first:19.20 05:34:42 INFO - PROCESS | 1684 | Values: 194.0 197.0 224.0 250.0 196.0 198.0 197.0 199.0 195.0 192.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:84.20 Median:70.50 stddev:36.51 (51.8%) stddev-sans-first:38.57 05:34:42 INFO - PROCESS | 1684 | Values: 75.0 78.0 74.0 170.0 61.0 57.0 57.0 69.0 129.0 72.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:112.00 Median:146.00 stddev:23.47 (16.1%) stddev-sans-first:21.85 05:34:42 INFO - PROCESS | 1684 | Values: 80.0 91.0 131.0 90.0 116.0 125.0 107.0 106.0 113.0 161.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:97.10 Median:88.50 stddev:19.04 (21.5%) stddev-sans-first:14.06 05:34:42 INFO - PROCESS | 1684 | Values: 136.0 130.0 89.0 88.0 89.0 85.0 91.0 87.0 89.0 87.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:113.70 Median:122.50 stddev:21.80 (17.8%) stddev-sans-first:20.38 05:34:42 INFO - PROCESS | 1684 | Values: 143.0 102.0 103.0 95.0 95.0 95.0 96.0 126.0 150.0 132.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:92.00 Median:92.00 stddev:2.87 (3.1%) stddev-sans-first:2.65 05:34:42 INFO - PROCESS | 1684 | Values: 96.0 92.0 91.0 90.0 92.0 89.0 90.0 90.0 98.0 92.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:61.00 stddev:19.02 (31.2%) stddev-sans-first:20.09 05:34:42 INFO - PROCESS | 1684 | Values: 62.0 60.0 61.0 60.0 61.0 61.0 61.0 121.0 62.0 60.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:67.00 stddev:2.94 (4.4%) stddev-sans-first:3.10 05:34:42 INFO - PROCESS | 1684 | Values: 66.0 65.0 70.0 66.0 73.0 69.0 68.0 64.0 64.0 65.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:99.40 Median:108.00 stddev:12.90 (11.9%) stddev-sans-first:12.59 05:34:42 INFO - PROCESS | 1684 | Values: 85.0 72.0 107.0 106.0 107.0 107.0 88.0 107.0 109.0 106.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:142.00 stddev:27.37 (19.3%) stddev-sans-first:28.16 05:34:42 INFO - PROCESS | 1684 | Values: 134.0 140.0 138.0 138.0 137.0 138.0 144.0 170.0 221.0 170.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.20 Median:170.50 stddev:3.33 (2.0%) stddev-sans-first:3.43 05:34:42 INFO - PROCESS | 1684 | Values: 167.0 170.0 175.0 166.0 171.0 171.0 168.0 171.0 163.0 170.0 05:34:42 INFO - PROCESS | 1684 | 05:34:42 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.60 Median:78.00 stddev:8.50 (10.9%) stddev-sans-first:8.56 05:34:42 INFO - PROCESS | 1684 | Values: 68.0 66.0 73.0 80.0 86.0 89.0 83.0 76.0 68.0 67.0 05:34:42 INFO - PROCESS | 1684 | -------- Summary: end -------- 05:34:42 INFO - PROCESS | 1684 | 05:34:43 INFO - TEST-INFO | 1684: exit 0 05:34:43 INFO - TEST-OK | kraken | took 61276ms 05:34:43 INFO - SUITE-END | took 1174s 05:34:44 INFO - Completed test suite (00:19:34) 05:34:44 INFO - javascript benchmark 05:34:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7170.037152396858, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3478.982605086974, 3488.5813702355704, 3521.929561408772, 3526.4005119129747, 3527.6364835560203, 5280.6534333744885, 5395.325584301962, 5861.384554621767, 5945.643261404319, 5985.132155837401, 5794.768209271635, 5879.85300367491, 5990.221271234734, 5991.0, 6063.120847477113, 5295.867603309919, 5297.178937264711, 5334.986352593014, 5430.945690543103, 5455.972720136394, 5337.813176538822, 5381.327334083239, 5388.461153884619, 5426.2674538937135, 5458.0721277382745, 5260.184671375904, 5261.394939581959, 5323.414424413311, 5344.251804747339, 5364.249005139283, 4234.872953811359, 5200.0, 5272.630915835893, 5294.311739473844, 5319.20211968204, 5276.023935571931, 5316.867078323035, 5359.651622644516, 5370.221317908881, 5375.919361209585, 5685.459881311269, 5725.6278341908055, 5732.713364331767, 5767.567432442542, 5888.057910734305, 6205.689715514206, 6320.841978950517, 6333.24001119864, 6340.524460665469, 6355.586886852339, 6200.7908457850735, 6330.65181415022, 6330.746770129189, 6335.303116657164, 6358.33237510064, 5986.940130598726, 6034.24571928509, 6060.939390606081, 6083.391660833881, 6100.75596976116, 6383.521235907326, 6451.3548645135115, 6482.1573195485225, 6484.610923344614, 6487.318831522716, 4875.463698993108, 5252.5535329497425, 6132.01899668756, 6312.5265605078885, 6358.268799088071, 6249.812505624838, 6346.2384513857705, 6353.745850166034, 6366.363363663597, 6371.553991220663, 6153.969230153821, 6183.134361189437, 6315.936840631627, 6335.936640633535, 6341.270753863272, 5508.118701007819, 6247.481456054217, 6324.462420694201, 6338.30278669346, 6348.206474190726, 4358.694891357638, 4821.734804585782, 5026.698398096126, 5043.823466178739, 5049.26785616091, 4490.640748740093, 4491.595756381941, 4491.977540112279, 4517.728936263835, 4528.207563676409, 5159.76165720232, 5940.61386009908, 6075.908861367083, 6093.573449858556, 6385.553011289165, 6273.592216506004, 6282.308946015934, 6293.339199383999, 6388.968055159788, 6424.678766061678, 6081.57428979976, 6107.908381374283, 6124.0, 6125.509959203253, 6128.17269668598, 5425.674459532362, 5489.560835133259, 5572.3870374258795, 5574.442555744474, 5625.634333768292, 6425.58233714807, 6575.079488871562, 6581.572197807127, 6592.505562082767, 6593.637349945799, 5537.667739935456, 5967.91048134287, 6328.114064030948, 6351.237851457855, 6356.11014457967, 6392.20097487814, 6488.578242414228, 6519.217693876765, 6520.641364724986, 6527.172391730323, 6310.842228944313, 6318.778842740482, 6329.90505142414, 6330.873382532415, 6362.491000719839, 6215.906761398673, 6352.745890164341, 6382.712777924911, 6395.968020159869, 6400.135981642418, 6143.266246520256, 6230.190075290183, 6239.656818875005, 6289.811305660837, 6339.524535659936, 6187.071939209155, 6247.5939063960695, 6272.121902933502, 6307.842303942438, 6316.873662526816, 6016.187814645142, 6099.8780024398375, 6202.782902598387, 6219.875602488016, 6254.124422580751, 6160.876782464236, 6219.564630475823, 6264.373562643699, 6330.177076979963, 6331.366863313632, 6182.845428864314, 6223.533235007302, 6242.406971337716, 6248.625082494883, 6306.558540902093], "value": 5816.614680811709, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.98084076636934, 536.4099490560384, 543.1282791120252, 558.4722437296756, 577.0882006429841, 39007.0, 41438.79280603596, 41450.37824432628, 41468.792656036756, 41536.16927661453, 41359.17281654372, 41742.791286043524, 42119.578804211946, 42785.0, 42882.0, 8643.611037503311, 9667.90332096677, 9687.757806054835, 9715.271354648394, 9806.068423499755, 9744.415335079917, 9821.705348839547, 9843.310968232227, 9844.114029737322, 9866.703998880044, 9700.563474643623, 9820.312578119534, 9843.163331116864, 9858.471936849799, 9875.654352097677, 9708.805823883482, 9795.118439340493, 9806.705798826046, 9835.754106147331, 9871.40771553709, 9695.70912872615, 9814.950925245328, 9816.901830981671, 9817.90182098177, 9833.508324583814, 38254.0, 38365.61634383648, 38591.8422447327, 38683.41974870379, 38698.80650596729, 2781.1517487166407, 3234.110619579611, 3253.4957081652146, 3256.3897326777346, 3375.780574262665, 26735.0, 28987.42025159506, 29092.563611545844, 29193.13137955256, 29631.407371852656, 2640.9867950660123, 2731.6039174319812, 2771.8336899786077, 2788.6653601567737, 3014.7338117990275, 437597.0, 509597.0, 522722.3863880694, 529119.3544032255, 533909.0, 35017.824910875795, 63941.68029159917, 65245.0, 65248.67375663092, 65635.34364656388, 29427.852860735988, 34601.82699086489, 35864.924052278475, 37277.25445491075, 37458.62541374551, 15218.467353642569, 15313.923430383, 15450.690986180214, 15495.457658982106, 15525.14611696345, 10891.564337426415, 12990.480380784662, 12992.740145197044, 13020.804687929689, 13052.086353955132, 7922.36621070313, 8267.214614611603, 8297.0, 8343.8331233375, 8344.874826877602, 3047.3752880659413, 3442.7934323940644, 3472.3923313420555, 3487.6049580168137, 3500.369933411959, 1047.7427087494884, 1050.4905121016302, 1065.9520321585546, 1075.177574012042, 1172.3962159487871, 25300.620490692654, 29769.0, 30104.548431773543, 30715.8464207682, 30890.227744306572, 1078.6494389323502, 1088.0425225801243, 1151.7696460707891, 1187.554666999875, 1191.0769153905792, 15456.149911754737, 15460.5361839145, 15599.922000390152, 15903.0, 15974.840251597569, 660693.0, 661568.0, 668674.0, 671590.0, 677519.0, 61170.08244876331, 61175.38824611786, 61263.38736612666, 61816.0, 61967.0, 1269.9873001269868, 1445.5013020508118, 1448.478547722819, 1490.5602847160114, 1496.9176695281647, 29125.41749165047, 29194.0, 29306.560401593568, 29680.703192967794, 29920.55119173258, 52438.95122097613, 52754.0, 52800.47199528109, 52855.207171891685, 52867.735661322986, 563308.0, 564742.0, 585045.0, 586579.0, 586654.0, 26771.464570708085, 27142.050028249705, 27149.0, 27218.72781272162, 27220.0, 45589.77205113953, 46512.302315465975, 46778.29832552582, 46945.53054469411, 47199.52800471951, 38073.238535228586, 38562.2287554253, 38600.42099368568, 39043.609563905135, 39389.80305098456, 32487.0, 37805.86582402532, 38068.80965595154, 38515.0, 38873.0, 38106.809465952494, 38910.6108938907, 39046.023849403995, 39497.0, 40754.14720969504, 29815.0, 31526.0, 34186.0, 34222.0, 34323.828380857936, 24908.377290567158, 25066.0, 25077.122300719388, 25246.747532525176, 25920.8703956479], "value": 18845.249748645572, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.73603069375685, 365.9451082337649, 393.8326211360173, 411.1037937296692, 420.2918083030095, 16632.83367166328, 20816.791832081693, 20999.95000249989, 21911.67132493014, 21955.890220548896, 9840.606375744961, 12104.515819367236, 12433.129680922339, 12443.253404795718, 16617.83382166178, 5266.3153790007345, 5431.592630552725, 5463.699496527689, 5463.972680136594, 5484.91772623411, 3843.7693738375647, 5297.337832770903, 5859.209006784094, 6102.626908945496, 6236.532260080489, 5604.243427137325, 6177.166082578862, 6224.937750622504, 6226.657533835637, 6229.563930524865, 5127.180849470195, 5978.8206353809455, 6033.879322413571, 6046.213992181032, 6052.394760523956, 5818.679972601505, 5996.830618029486, 5999.220101386835, 6020.217371741645, 6042.033274676075, 13521.594352169352, 13898.0, 14139.717205655828, 14218.644533886632, 14265.28673566328, 2110.873347599149, 2191.4630915425664, 2291.6104262275453, 2365.29041287613, 2372.5729368713623, 10280.794384112276, 10348.016938390841, 10577.0, 13264.204147751167, 14254.14475131485, 1740.069063051274, 1745.1710437542065, 1759.129914188777, 1761.4803632928317, 1838.3290099113726, 125938.96244150223, 132926.00610990843, 134231.0, 134594.0, 136114.31942840322, 11549.191556591126, 11765.470553825098, 11867.0, 11909.821352679717, 11963.820542691867, 15321.157336346609, 15389.461368852037, 15391.230438478031, 15434.073955562702, 15455.381784728484, 8698.173673501135, 8766.430182038275, 8855.867161992575, 8874.334424918028, 8918.464892106495, 8660.26387757035, 8726.171013753683, 8742.25690816275, 8802.339824513187, 8840.823183536293, 4679.836205732783, 4696.060787842377, 4696.412948381452, 4726.574608285271, 4840.2255639097575, 1822.2893071702047, 1885.6134492429019, 2010.286348346316, 2021.5754691514946, 2026.3384025099572, 749.7375918428615, 930.4882314727008, 1015.5683834370363, 1032.6747074671455, 1049.2393015064017, 20349.796502035086, 20606.484837879172, 21118.0, 21257.0, 21297.36107916765, 815.4373482297315, 910.6402970826487, 952.623713633111, 960.7502049467189, 982.2436723722693, 8388.748337549883, 9515.952420237854, 10744.656917885264, 11058.281211721212, 11282.830757538644, 553827.0, 561320.0, 561581.0, 602353.0, 619740.9012954907, 127018.36490817672, 131071.68928310786, 133247.33376333056, 136781.63218367688, 140886.2955685215, 1006.9949650251701, 1013.2350075692805, 1028.9073983341534, 1031.5873650539845, 1034.8240799064176, 18302.450926472226, 18322.541936451697, 18407.07964601791, 18439.90780046091, 18514.44456666302, 43237.78381108074, 46679.06641867212, 50802.74598626983, 53593.92812143813, 54688.632784180714, 79256.60371698104, 79345.0, 79401.0, 79668.60165699135, 82024.58987705024, 6830.7609233676585, 7851.725189618336, 8092.87860682102, 8135.349172066102, 8465.111163328009, 8862.512561808904, 11533.538658453568, 12195.695107622025, 12582.0, 12846.614601561969, 10673.46632668348, 10780.460976951277, 10841.186910981865, 10898.455077246264, 10981.121510279318, 9326.974032856517, 10562.735931601772, 10590.735231619272, 10790.190735694698, 10931.781364372828, 10648.361098334124, 10923.890761092605, 11070.944645276722, 11092.168087393316, 11279.492422840827, 5727.713614319351, 8014.679412823421, 8178.0, 8194.262516373556, 8406.0, 9261.907380926104, 11210.775784484427, 11238.719032024264, 11287.871212878646, 11396.94301528487], "value": 9303.426898046591, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.8461269260379, 1595.6170519075426, 1822.6628073806346, 1930.8744931579456, 1980.861339706221, 8238.0, 8833.558322083894, 8857.734267971971, 9351.251899848014, 9368.437893726381, 8928.19646231839, 8998.73003809885, 9163.816723665523, 9170.266378689705, 9188.127127922837, 2452.9574930654444, 2477.814163937712, 2478.8388754730977, 2488.4525404411092, 2490.0288887333954, 2419.346776370379, 2481.627755836621, 2490.1284550407395, 2490.2778194323623, 2504.749525047499, 2430.1858877276136, 2444.340028192387, 2465.285067330472, 2481.6525686403916, 2492.0779311654715, 2404.9519009619885, 2439.243834411338, 2446.302803700946, 2452.9329741562387, 2475.096589744748, 2441.926742197737, 2452.865092419916, 2463.1132792194794, 2466.3094333586623, 2472.554940110779, 8935.776605584848, 9038.231750301233, 9191.494467804268, 9231.676891308773, 9259.30555208362, 5423.2949716537, 5448.727563621803, 5459.781608735646, 5577.191307260425, 5649.943500564983, 3609.7473176877365, 3614.5120408744747, 3664.13892735207, 3670.632936706335, 3712.9071773205615, 867.2758246863876, 1065.0627447845845, 1072.5924148823476, 1074.5272080284728, 1075.714935542082, 10440.425776582286, 11329.150313726503, 12401.317927513983, 12411.379431028412, 12490.81263781044, 4853.878653033632, 4882.194437917704, 5028.497150285016, 5036.0, 5074.847754567369, 10251.692449226535, 10371.740706482398, 10416.958304169522, 10426.322289051339, 10430.791384172275, 5478.863028424321, 5491.697956612425, 5544.16837474374, 5593.105103183471, 5627.521660658808, 4558.111168322212, 4934.235193545006, 5085.466026067284, 5091.058154241403, 5108.565771909355, 4149.585041495826, 4171.645410140111, 4189.706720529594, 4201.264778663722, 4206.0326124991425, 1335.9599212023654, 1360.673438374783, 1368.548379034916, 1371.8971077169251, 1372.670559065817, 775.5463054113294, 812.8455593437346, 877.2674816528142, 948.3645957208549, 982.823091843475, 5828.154917536934, 6045.304789949212, 6097.939020609737, 6129.632222066691, 6147.600405973687, 451.5371743962465, 453.36755226458905, 463.9675222734444, 472.87468820762524, 474.00222531571234, 3367.9326413471595, 4050.736702114353, 4096.73371230869, 4099.139180772071, 4105.363668631367, 9219.953900230455, 10592.258541902147, 10993.395363254938, 11263.943680281545, 11285.0, 10894.891051089546, 11077.169212309109, 11204.775904481865, 11236.94381528087, 11277.436128193558, 7960.56216908064, 8403.957980210182, 8478.74563763088, 8510.234078932926, 8593.398462107718, 5346.599005074713, 6061.909071364022, 6072.666003369681, 6114.908276375769, 6249.281332646804, 10214.978502149726, 10542.26204165701, 10572.84140737905, 10607.628732994308, 10638.946805265925, 10406.843897341394, 10531.473168993594, 10739.14086873064, 10744.19418543628, 10860.294080884718, 7098.929010709827, 7650.273224043707, 7778.533288002738, 7943.483673561431, 8229.753107406787, 7671.808204794925, 8582.957085214533, 8642.049374568667, 8680.913190868263, 8721.912780872364, 7592.240775922363, 7712.961435193012, 7822.256885595859, 7824.295813376823, 7830.177831327628, 6428.646424446513, 7454.813629659302, 7486.401087912845, 7534.321911028033, 7706.306432421109, 7210.387117094896, 7259.2377800330205, 7319.8536029280185, 7334.486585939147, 7343.265673432614, 4878.146324393288, 5023.8492845214705, 5056.469070747518, 5084.51697088776, 5155.381354237516, 4974.253861920596, 5132.897342053064, 5182.24857395683, 5196.922046169235, 5402.9459705404015], "value": 4650.809911109454, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.65654608204017, 322.9660885607011, 325.5052320472881, 328.6089553431418, 332.0834496788862, 4659.044895796362, 5029.572486338666, 5491.752871120799, 5572.9968605650965, 5595.608307418483, 4977.900441991158, 5529.778808847651, 5553.8062465326275, 5556.638818476797, 5573.804916827912, 3523.3481805865867, 3992.7803970781597, 4152.695254133954, 4202.453681021479, 4217.536071032199, 3681.7790932544, 3985.827723890472, 4184.0, 4207.705460617758, 4253.234417804794, 4211.831526738927, 4224.61978421942, 4234.640055595278, 4236.173946080516, 4260.68044896634, 4059.9188016239805, 4227.471566054243, 4228.260054490483, 4248.617624413802, 4250.234957707611, 3639.909002274964, 3644.0, 3658.2134841009047, 3709.684676802475, 3709.7588656737494, 4785.947091639834, 4905.803767849282, 5288.6826790392315, 5316.946830531684, 5333.973330133324, 3983.0, 4178.979105104455, 4332.805023773938, 4877.170880950247, 4908.404768450232, 5577.414371491016, 5753.856153596152, 5843.766249350021, 5865.76536938522, 5866.941330586682, 3779.8471466203073, 3831.8467261309515, 4101.241270364992, 4130.401091841696, 4132.566080561558, 5631.2960879890015, 5656.660600363992, 5717.826606233539, 5980.880382392328, 6208.044465993197, 4106.137711080647, 4163.2298024865495, 4174.645155161785, 4185.309423945076, 4204.5375008749015, 4243.915121697549, 4251.234777740036, 4259.9787001065415, 4265.210935976854, 4269.765162916007, 3950.9257639739367, 4504.797284122223, 4655.534446555317, 4907.2393778964315, 4914.410270767531, 5152.768125434364, 5809.099589563624, 5853.5317174625925, 5866.413358664099, 5880.323762767336, 1409.1404243411512, 1432.1550285331496, 1435.70069087476, 1435.7128574285184, 1442.7691569348854, 769.2999370572752, 865.8484215992714, 888.7378223424106, 894.8702438146432, 899.9685011024583, 4685.945662225972, 5219.173289348773, 5231.8168864089575, 5290.973545132327, 5331.360236771612, 4925.581325587294, 5652.689102099342, 5842.26095299009, 5885.911711324334, 5890.204822349014, 5097.362829646294, 5273.578113750891, 5283.682979021271, 5308.840734777963, 5334.706591137525, 2568.345072006626, 3085.2595377109333, 3127.3901589190345, 3127.734142597905, 3142.6700196479496, 5898.171566814215, 6131.754729810847, 6157.353477884847, 6170.346750897068, 6214.627122372672, 5672.631278966936, 5717.914231286534, 5726.713664316768, 5922.505721624262, 6208.2239720034995, 6239.098819049073, 6273.494361353333, 6288.402601752826, 6301.779437719658, 6302.275238347649, 5577.74900129487, 6000.849978750566, 6013.338532761481, 6025.578209525466, 6077.361877002851, 5307.256984022163, 5517.972410137923, 5978.312494063239, 5985.102234664835, 6030.7286172121485, 5878.353381127987, 5945.524358051259, 5972.372900845348, 6269.0, 6303.770764700839, 3400.7619466637098, 3981.7610943343493, 4012.498437695288, 4015.9397609035304, 4022.6781857450746, 5598.720063996702, 5979.22270104901, 6027.21646185993, 6048.516118710405, 6100.450959413674, 5050.898982020412, 5808.564357673107, 5826.76692932278, 5829.854253643693, 5844.386339434468, 5450.945490545044, 5528.861778455571, 5792.160136780228, 5812.476877081083, 5884.08796636522, 4529.886752831206, 4751.334813126235, 5227.529522343008, 5392.676439413648, 5652.915206271827, 5124.9743751281, 5615.052097648767, 5805.651660900359, 5817.680027598522, 5823.417658234142, 5255.13290307095, 5675.687837168995, 5681.829545113653, 5682.744276507485, 5689.776698009948], "value": 4260.05622551126, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.7871445197098, 733.1019501111138, 734.5005396330494, 746.9365103966163, 763.1833937686678, 15202.239888005597, 15389.461368852093, 15665.373385064599, 15759.448419305327, 15785.921070394632, 14127.081739686913, 14372.209528475929, 14391.352389142488, 14654.560363189095, 14679.706405871877, 740.6704016712565, 910.3218102513637, 923.5428462910852, 932.645594674023, 943.2548286853378, 792.2473650032464, 877.2587163846539, 905.7056456651582, 921.3366376209119, 921.7741653294952, 917.9127982841619, 922.3866129024225, 954.9474778887159, 957.7892863570004, 959.904009599038, 904.0959040959041, 916.4043371808311, 920.5719340506696, 948.7628092976756, 954.8615450759601, 607.2439812433504, 673.3367632881608, 674.689642764329, 698.7589281697806, 709.2659097833717, 12973.416196271097, 14460.566183014527, 14821.184834834079, 14968.47610333633, 15002.399904003827, 10684.412357320345, 12406.75186496265, 12522.306227849083, 12561.057920655987, 12703.61889143318, 8574.914250857473, 9955.647589986593, 10015.74960625983, 10164.0, 10643.095336896373, 3450.723942084627, 3505.0010746937255, 3518.40187168182, 3542.574891013069, 3551.8756843510355, 82197.58901205455, 82675.75986360149, 83049.58475207645, 84939.57530212309, 84992.57503712503, 8094.514329140153, 9423.623055077856, 9452.05479452063, 9547.140757331874, 9595.088466595662, 21011.0, 21059.0, 21486.677699834516, 21525.0, 21583.67624485634, 7238.746643867439, 7373.483856130127, 7458.589777562286, 7507.2357995870925, 7519.135299440634, 3940.763554186758, 4015.9799201004394, 4024.678025757932, 4026.959730402717, 4038.757674539537, 3498.8075655838666, 3600.3159399714496, 3606.04439823447, 3611.3860643690637, 3612.096975756061, 541.3422691429931, 622.5673157155824, 636.8057742388615, 642.3608509532951, 642.7814543055383, 457.2341328275192, 555.5916401444935, 583.3990989281048, 607.3895734786511, 617.9196704428396, 7732.0, 8085.231902969209, 8240.423170378135, 8282.917170828214, 8383.538905360141, 535.253321616343, 573.4695406748824, 602.9427204415573, 616.6782065523308, 640.334052585306, 3801.4436114213036, 3882.7282090253975, 3947.5460322062754, 3991.980040099781, 4080.0, 90365.54817226004, 91906.54046729858, 92517.53741231251, 92789.0, 93477.66305842272, 79742.20257797463, 83282.58358708168, 84297.0, 84474.15525844786, 85425.57287213524, 57989.710051449474, 72994.63502682453, 73029.90455143177, 73317.26682733212, 73531.63234183902, 7676.884846727192, 7692.730699434755, 7821.0, 7933.008373953256, 7956.6419511120985, 45525.77237113793, 45849.54150458453, 45985.540144598126, 45990.85022874455, 46281.305780414, 46782.53217467782, 63017.0, 63924.68037659782, 64069.0, 64477.677611611645, 8485.745427637181, 8858.512781797062, 10222.284440089123, 10274.589016439557, 10555.366677999345, 11230.270032447865, 11340.659780206606, 11445.167055552864, 11595.18833681668, 11598.246114002563, 8967.327450441113, 9032.954835225783, 10620.681379558626, 10652.307600005975, 10968.835467467834, 10508.316959397614, 10543.103836173705, 10604.840927385942, 10673.679789606324, 10893.346399216072, 10546.947265263625, 10738.248322617343, 10757.677269681923, 10916.945415272872, 10955.83566246523, 9031.774205644648, 9036.096390361174, 9044.186023257938, 9078.022680083233, 9287.535623218677, 7478.352399110203, 8708.737233101037, 8768.956155219183, 8824.5587720615, 9029.909700902906], "value": 6724.0123686448405, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1611.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 112.0, 115.0, 140.0, 175.0, 143.0, 116.0, 115.0, 115.0], "value": 126.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 120.0, 144.0, 192.0, 110.0, 162.0, 160.0, 109.0, 208.0, 149.0], "value": 151.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 197.0, 224.0, 250.0, 196.0, 198.0, 197.0, 199.0, 195.0, 192.0], "value": 204.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 74.0, 170.0, 61.0, 57.0, 57.0, 69.0, 129.0, 72.0], "value": 84.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 91.0, 131.0, 90.0, 116.0, 125.0, 107.0, 106.0, 113.0, 161.0], "value": 112.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 130.0, 89.0, 88.0, 89.0, 85.0, 91.0, 87.0, 89.0, 87.0], "value": 97.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 102.0, 103.0, 95.0, 95.0, 95.0, 96.0, 126.0, 150.0, 132.0], "value": 113.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 92.0, 91.0, 90.0, 92.0, 89.0, 90.0, 90.0, 98.0, 92.0], "value": 92.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 61.0, 60.0, 61.0, 61.0, 61.0, 121.0, 62.0, 60.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 70.0, 66.0, 73.0, 69.0, 68.0, 64.0, 64.0, 65.0], "value": 67.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 107.0, 106.0, 107.0, 107.0, 88.0, 107.0, 109.0, 106.0], "value": 99.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 140.0, 138.0, 138.0, 137.0, 138.0, 144.0, 170.0, 221.0, 170.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 175.0, 166.0, 171.0, 171.0, 168.0, 171.0, 163.0, 170.0], "value": 169.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 73.0, 80.0, 86.0, 89.0, 83.0, 76.0, 68.0, 67.0], "value": 75.6, "unit": "score"}]}]} 05:34:44 INFO - Return code: 0 05:34:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:34:44 ERROR - # TBPL SUCCESS # 05:34:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:34:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:34:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:34:44 INFO - 2016-05-12 05:34:44.309 screenresolution[1689:11002] starting screenresolution argv=screenresolution get 05:34:44 INFO - 2016-05-12 05:34:44.313 screenresolution[1689:11002] Display 0: 1024x768x32@60 05:34:44 INFO - 2016-05-12 05:34:44.319 screenresolution[1690:11007] starting screenresolution argv=screenresolution list 05:34:44 INFO - Available Modes on Display 0 05:34:44 INFO - 1024x768x32@60 800x600x32@75 800x600x32@60 05:34:44 INFO - 640x480x32@75 640x480x32@60 Graphics/Displays: 05:34:44 INFO - Intel Iris: 05:34:44 INFO - Chipset Model: Intel Iris 05:34:44 INFO - Type: GPU 05:34:44 INFO - Bus: Built-In 05:34:44 INFO - VRAM (Dynamic, Max): 1536 MB 05:34:44 INFO - Vendor: Intel (0x8086) 05:34:44 INFO - Device ID: 0x0a2e 05:34:44 INFO - Revision ID: 0x0009 05:34:44 INFO - Displays: 05:34:44 INFO - Display: 05:34:44 INFO - Resolution: 1024 x 768 @ 60 Hz 05:34:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:34:44 INFO - Display Serial Number: 1024x768 60 05:34:44 INFO - Main Display: Yes 05:34:44 INFO - Mirror: Off 05:34:44 INFO - Online: Yes 05:34:44 INFO - Rotation: Supported 05:34:44 INFO - Return code: 0 05:34:44 INFO - Running post-action listener: _resource_record_post_action 05:34:44 INFO - ##### 05:34:44 INFO - ##### Finished run-tests step (success) 05:34:44 INFO - ##### 05:34:44 INFO - Running post-run listener: _resource_record_post_run 05:34:45 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 37645824; Write bytes: 699399168; Read time: 218; Write time: 1918 05:34:45 INFO - TinderboxPrint: CPU usage
54.7% 05:34:45 INFO - TinderboxPrint: I/O read bytes / time
37,645,824 / 218 05:34:45 INFO - TinderboxPrint: I/O write bytes / time
699,399,168 / 1,918 05:34:45 INFO - TinderboxPrint: CPU idle
2,191.0 (45.3%) 05:34:45 INFO - TinderboxPrint: CPU system
978.9 (20.2%) 05:34:45 INFO - TinderboxPrint: CPU user
1,667.8 (34.5%) 05:34:45 INFO - TinderboxPrint: Swap in / out
430,690,304 / 0 05:34:45 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 384014848; Write bytes: 377427456; Read time: 28462; Write time: 755 05:34:45 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 35888640; Write bytes: 298372608; Read time: 205; Write time: 1094 05:34:45 INFO - Running post-run listener: _upload_blobber_files 05:34:45 INFO - Blob upload gear active. 05:34:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:34:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:34:45 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:34:45 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:34:46 INFO - (blobuploader) - INFO - Open directory for files ... 05:34:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:34:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:34:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:47 INFO - (blobuploader) - INFO - Done attempting. 05:34:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:34:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:34:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:48 INFO - (blobuploader) - INFO - Done attempting. 05:34:48 INFO - (blobuploader) - INFO - Iteration through files over. 05:34:48 INFO - Return code: 0 05:34:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:34:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:34:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ccdad01f0e039fc262b8419fd2121eb6258bedc965558d164b3c56e5e9e35c0fdd545da4f27f154935f94d58e0f88ffcd43ebc588ed41d1ebfb4033164c5984", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a6f8e47d34a35ad6fd34855e89184571a5cda94a17aaae35fe8fda93ce45d5a19134038a1b71bc0f6ba7b2ec2c565099e950db0191d7cfc3192a24f09a9bb8"} 05:34:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:34:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:34:48 INFO - Contents: 05:34:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ccdad01f0e039fc262b8419fd2121eb6258bedc965558d164b3c56e5e9e35c0fdd545da4f27f154935f94d58e0f88ffcd43ebc588ed41d1ebfb4033164c5984", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a6f8e47d34a35ad6fd34855e89184571a5cda94a17aaae35fe8fda93ce45d5a19134038a1b71bc0f6ba7b2ec2c565099e950db0191d7cfc3192a24f09a9bb8"} 05:34:48 INFO - Running post-run listener: copy_logs_to_upload_dir 05:34:48 INFO - Copying logs to upload dir... 05:34:48 INFO - mkdir: /builds/slave/test/build/upload/logs 05:34:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1333.105959 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-05-12 05:34:48.812819) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:48.816831) ========= 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.qK2wxqDUmU/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.SIkTc97nAB/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ccdad01f0e039fc262b8419fd2121eb6258bedc965558d164b3c56e5e9e35c0fdd545da4f27f154935f94d58e0f88ffcd43ebc588ed41d1ebfb4033164c5984", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a6f8e47d34a35ad6fd34855e89184571a5cda94a17aaae35fe8fda93ce45d5a19134038a1b71bc0f6ba7b2ec2c565099e950db0191d7cfc3192a24f09a9bb8"} build_url:https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010768 build_url: 'https://queue.taskcluster.net/v1/task/f5fKwXxAT2ykv-FQK9JMaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ccdad01f0e039fc262b8419fd2121eb6258bedc965558d164b3c56e5e9e35c0fdd545da4f27f154935f94d58e0f88ffcd43ebc588ed41d1ebfb4033164c5984", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a6f8e47d34a35ad6fd34855e89184571a5cda94a17aaae35fe8fda93ce45d5a19134038a1b71bc0f6ba7b2ec2c565099e950db0191d7cfc3192a24f09a9bb8"}' ========= master_lag: 0.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:49.077318) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:49.077652) ========= 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.qK2wxqDUmU/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.SIkTc97nAB/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.004563 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:49.125916) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:34:49.126222) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:34:49.126543) ========= ========= Total master_lag: 1.43 =========