builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0001 starttime: 1464609433.39 results: success (0) buildid: 20160530025546 builduid: 3e6ed214271b4a9bac15642936040c82 revision: cad514ad49c199e823a92e8c8d27e16c22c3cac7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:13.393978) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:13.394632) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:13.395084) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:13.420694) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:13.421124) ========= 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.ev9ku8We61/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.XQcpueCa2b/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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-30 04:57:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 140M=0s 2016-05-30 04:57:13 (140 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122334 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:13.564871) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:13.565261) ========= 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.ev9ku8We61/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.XQcpueCa2b/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.027523 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:13.614020) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 04:57:13.614378) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cad514ad49c199e823a92e8c8d27e16c22c3cac7 --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 cad514ad49c199e823a92e8c8d27e16c22c3cac7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ev9ku8We61/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.XQcpueCa2b/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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-30 04:57:13,688 truncating revision to first 12 chars 2016-05-30 04:57:13,688 Setting DEBUG logging. 2016-05-30 04:57:13,688 attempt 1/10 2016-05-30 04:57:13,688 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cad514ad49c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 04:57:14,815 unpacking tar archive at: mozilla-central-cad514ad49c1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.381195 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 04:57:15.015499) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:15.015867) ========= script_repo_revision: cad514ad49c199e823a92e8c8d27e16c22c3cac7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:15.016268) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:15.016574) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 04:57:15.032594) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-30 04:57:15.032979) ========= /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.ev9ku8We61/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.XQcpueCa2b/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:57:15 INFO - MultiFileLogger online at 20160530 04:57:15 in /builds/slave/test 04:57:15 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 04:57:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:57:15 INFO - {'append_to_log': False, 04:57:15 INFO - 'base_work_dir': '/builds/slave/test', 04:57:15 INFO - 'blob_upload_branch': 'Firefox', 04:57:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:57:15 INFO - 'branch': 'Firefox', 04:57:15 INFO - 'buildbot_json_path': 'buildprops.json', 04:57:15 INFO - 'config_files': ('talos/mac_config.py',), 04:57:15 INFO - 'default_actions': ('clobber', 04:57:15 INFO - 'read-buildbot-config', 04:57:15 INFO - 'download-and-extract', 04:57:15 INFO - 'populate-webroot', 04:57:15 INFO - 'create-virtualenv', 04:57:15 INFO - 'install', 04:57:15 INFO - 'run-tests'), 04:57:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:57:15 INFO - 'download_minidump_stackwalk': True, 04:57:15 INFO - 'download_symbols': 'ondemand', 04:57:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:57:15 INFO - 'tooltool.py': '/tools/tooltool.py', 04:57:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:57:15 INFO - '/tools/misc-python/virtualenv.py')}, 04:57:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:57:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:57:15 INFO - 'installer_path': 'installer.exe', 04:57:15 INFO - 'log_level': 'info', 04:57:15 INFO - 'log_name': 'talos', 04:57:15 INFO - 'log_to_console': True, 04:57:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:57:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:57:15 INFO - 'opt_config_files': (), 04:57:15 INFO - 'pip_index': False, 04:57:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:57:15 INFO - 'cmd': ('bash', 04:57:15 INFO - '-c', 04:57:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:57:15 INFO - 'enabled': True, 04:57:15 INFO - 'halt_on_failure': False, 04:57:15 INFO - 'name': 'check_screen_resolution'},), 04:57:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:57:15 INFO - 'cmd': ('bash', 04:57:15 INFO - '-c', 04:57:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:57:15 INFO - 'enabled': True, 04:57:15 INFO - 'halt_on_failure': False, 04:57:15 INFO - 'name': 'check_screen_resolution'},), 04:57:15 INFO - 'run_cmd_checks_enabled': True, 04:57:15 INFO - 'sps_profile': False, 04:57:15 INFO - 'sps_profile_interval': 0, 04:57:15 INFO - 'suite': 'dromaeojs-e10s', 04:57:15 INFO - 'system_bits': '32', 04:57:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:57:15 INFO - 'title': 't-yosemite-r7-0001', 04:57:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:57:15 INFO - 'use_talos_json': True, 04:57:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:57:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:57:15 INFO - 'work_dir': 'build'} 04:57:15 INFO - ##### 04:57:15 INFO - ##### Running clobber step. 04:57:15 INFO - ##### 04:57:15 INFO - Running pre-action listener: _resource_record_pre_action 04:57:15 INFO - Running main action method: clobber 04:57:15 INFO - rmtree: /builds/slave/test/build 04:57:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:57:17 INFO - Running post-action listener: _resource_record_post_action 04:57:17 INFO - ##### 04:57:17 INFO - ##### Finished clobber step (success) 04:57:17 INFO - ##### 04:57:17 INFO - ##### 04:57:17 INFO - ##### Running read-buildbot-config step. 04:57:17 INFO - ##### 04:57:17 INFO - Running pre-action listener: _resource_record_pre_action 04:57:17 INFO - Running main action method: read_buildbot_config 04:57:17 INFO - Using buildbot properties: 04:57:17 INFO - { 04:57:17 INFO - "project": "", 04:57:17 INFO - "product": "firefox", 04:57:17 INFO - "script_repo_revision": "production", 04:57:17 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:57:17 INFO - "repository": "", 04:57:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:57:17 INFO - "buildid": "20160530025546", 04:57:17 INFO - "pgo_build": "False", 04:57:17 INFO - "basedir": "/builds/slave/test", 04:57:17 INFO - "buildnumber": 49, 04:57:17 INFO - "slavename": "t-yosemite-r7-0001", 04:57:17 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:57:17 INFO - "platform": "yosemite_r7", 04:57:17 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:57:17 INFO - "branch": "mozilla-central", 04:57:17 INFO - "repo_path": "mozilla-central", 04:57:17 INFO - "revision": "cad514ad49c199e823a92e8c8d27e16c22c3cac7", 04:57:17 INFO - "stage_platform": "macosx64", 04:57:17 INFO - "builduid": "3e6ed214271b4a9bac15642936040c82", 04:57:17 INFO - "slavebuilddir": "test" 04:57:17 INFO - } 04:57:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:57:17 INFO - Running post-action listener: _resource_record_post_action 04:57:17 INFO - ##### 04:57:17 INFO - ##### Finished read-buildbot-config step (success) 04:57:17 INFO - ##### 04:57:17 INFO - ##### 04:57:17 INFO - ##### Running download-and-extract step. 04:57:17 INFO - ##### 04:57:17 INFO - Running pre-action listener: _resource_record_pre_action 04:57:17 INFO - Running main action method: download_and_extract 04:57:17 INFO - mkdir: /builds/slave/test/build/tests 04:57:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:17 INFO - https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:57:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:57:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:57:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:57:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 04:57:27 INFO - Downloaded 1199 bytes. 04:57:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:57:27 INFO - Using the following test package requirements: 04:57:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:57:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:57:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:57:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:57:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:57:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:57:27 INFO - u'jsshell-mac.zip'], 04:57:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:57:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:57:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:57:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:57:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:57:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:57:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:57:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:57:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:57:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:57:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:57:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:27 INFO - https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:57:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:57:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:57:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:57:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:57:44 INFO - Downloaded 18684726 bytes. 04:57:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:57:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:57:47 INFO - Return code: 0 04:57:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:47 INFO - https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:57:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:57:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:57:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:57:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:57:49 INFO - Downloaded 18684726 bytes. 04:57: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'] 04:57: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 04:57:53 INFO - Return code: 0 04:57: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')]} 04:57:53 INFO - https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:57:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:57:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:57:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:57:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:57:54 INFO - Downloaded 10834034 bytes. 04:57: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'] 04:57: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 04:57:54 INFO - Return code: 0 04:57: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')]} 04:57:54 INFO - https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:57:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:57:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:57:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:57:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 04:57:59 INFO - Downloaded 110479004 bytes. 04:57:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:57:59 INFO - mkdir: /builds/slave/test/properties 04:57:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:57:59 INFO - Writing to file /builds/slave/test/properties/build_url 04:57:59 INFO - Contents: 04:57:59 INFO - build_url:https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:58:00 INFO - Running post-action listener: _resource_record_post_action 04:58:00 INFO - Running post-action listener: set_extra_try_arguments 04:58:00 INFO - ##### 04:58:00 INFO - ##### Finished download-and-extract step (success) 04:58:00 INFO - ##### 04:58:00 INFO - ##### 04:58:00 INFO - ##### Running populate-webroot step. 04:58:00 INFO - ##### 04:58:00 INFO - Running pre-action listener: _resource_record_pre_action 04:58:00 INFO - Running main action method: populate_webroot 04:58:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:58:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:58:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:58:00 INFO - '--tppagecycles', 04:58:00 INFO - '7'], 04:58:00 INFO - 'tests': ['tsvgm']}}, 04:58:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:58:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:58:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:58:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:58:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:58:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:58:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:00 INFO - 'talos_options': ['--disable-e10s'], 04:58:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:58:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:58:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:00 INFO - 'talos_options': ['--disable-e10s'], 04:58:00 INFO - 'tests': ['damp', 'tps']}, 04:58:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:00 INFO - 'tests': ['damp', 'tps']}, 04:58:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:58:00 INFO - 'tests': ['dromaeo_dom']}, 04:58:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:58:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:58:00 INFO - 'tests': ['a11yr', 04:58:00 INFO - 'ts_paint', 04:58:00 INFO - 'tpaint', 04:58:00 INFO - 'sessionrestore', 04:58:00 INFO - 'sessionrestore_no_auto_restore', 04:58:00 INFO - 'tabpaint']}, 04:58:00 INFO - 'other-e10s': {'tests': ['a11yr', 04:58:00 INFO - 'ts_paint', 04:58:00 INFO - 'tpaint', 04:58:00 INFO - 'sessionrestore', 04:58:00 INFO - 'sessionrestore_no_auto_restore', 04:58:00 INFO - 'tabpaint']}, 04:58:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:58:00 INFO - 'ts_paint', 04:58:00 INFO - 'tpaint', 04:58:00 INFO - 'sessionrestore', 04:58:00 INFO - 'sessionrestore_no_auto_restore', 04:58:00 INFO - 'tabpaint']}, 04:58:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:58:00 INFO - 'ts_paint', 04:58:00 INFO - 'tpaint', 04:58:00 INFO - 'sessionrestore', 04:58:00 INFO - 'sessionrestore_no_auto_restore', 04:58:00 INFO - 'tabpaint']}, 04:58:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:58:00 INFO - 'tests': ['a11yr', 04:58:00 INFO - 'ts_paint', 04:58:00 INFO - 'tpaint', 04:58:00 INFO - 'sessionrestore', 04:58:00 INFO - 'sessionrestore_no_auto_restore', 04:58:00 INFO - 'tabpaint']}, 04:58:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:58:00 INFO - 'tests': ['a11yr', 04:58:00 INFO - 'ts_paint', 04:58:00 INFO - 'tpaint', 04:58:00 INFO - 'sessionrestore', 04:58:00 INFO - 'sessionrestore_no_auto_restore', 04:58:00 INFO - 'tabpaint']}, 04:58:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:58:00 INFO - 'tests': ['tsvgx', 04:58:00 INFO - 'tsvgr_opacity', 04:58:00 INFO - 'tart', 04:58:00 INFO - 'tscrollx', 04:58:00 INFO - 'cart']}, 04:58:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:58:00 INFO - 'tsvgr_opacity', 04:58:00 INFO - 'tart', 04:58:00 INFO - 'tscrollx', 04:58:00 INFO - 'cart']}, 04:58:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:00 INFO - 'talos_options': ['--disable-e10s'], 04:58:00 INFO - 'tests': ['tp5o']}, 04:58:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:00 INFO - 'tests': ['tp5o']}, 04:58:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:00 INFO - 'talos_options': ['--disable-e10s', 04:58:00 INFO - '--xperf_path', 04:58:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:58:00 INFO - 'tests': ['tp5n']}, 04:58:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:58:00 INFO - 'talos_options': ['--xperf_path', 04:58:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:58:00 INFO - 'tests': ['tp5n']}}, 04:58:00 INFO - 'talos.zip': {'path': '', 04:58:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:58:00 INFO - Running post-action listener: _resource_record_post_action 04:58:00 INFO - ##### 04:58:00 INFO - ##### Finished populate-webroot step (success) 04:58:00 INFO - ##### 04:58:00 INFO - ##### 04:58:00 INFO - ##### Running create-virtualenv step. 04:58:00 INFO - ##### 04:58:00 INFO - Running pre-action listener: _resource_record_pre_action 04:58:00 INFO - Running main action method: create_virtualenv 04:58:00 INFO - Creating virtualenv /builds/slave/test/build/venv 04:58:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:58:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:58:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:58:00 INFO - Using real prefix '/tools/python27' 04:58:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:58:01 INFO - Installing distribute.............................................................................................................................................................................................done. 04:58:03 INFO - Installing pip.................done. 04:58:03 INFO - Return code: 0 04:58:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:58: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')]} 04:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:04 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 0x1033a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff27bd63580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10324bd30>, '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 0x10306fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103242b20>, '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.ev9ku8We61/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.XQcpueCa2b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:58:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ev9ku8We61/Render', 04:58:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:04 INFO - 'HOME': '/Users/cltbld', 04:58:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:04 INFO - 'LOGNAME': 'cltbld', 04:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:04 INFO - 'MOZ_NO_REMOTE': '1', 04:58:04 INFO - 'NO_EM_RESTART': '1', 04:58:04 INFO - 'PAGER': '/bin/cat', 04:58:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:04 INFO - 'PWD': '/builds/slave/test', 04:58:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:04 INFO - 'SHELL': '/bin/bash', 04:58:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQcpueCa2b/Listeners', 04:58:04 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:58:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:04 INFO - 'USER': 'cltbld', 04:58:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:04 INFO - 'XPC_FLAGS': '0x0', 04:58:04 INFO - 'XPC_SERVICE_NAME': '0', 04:58:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:58:04 INFO - Cleaning up... 04:58:04 INFO - Return code: 0 04:58:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1033a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff27bd63580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10324bd30>, '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 0x10306fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103242b20>, '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.ev9ku8We61/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.XQcpueCa2b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:58:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ev9ku8We61/Render', 04:58:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:04 INFO - 'HOME': '/Users/cltbld', 04:58:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:04 INFO - 'LOGNAME': 'cltbld', 04:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:04 INFO - 'MOZ_NO_REMOTE': '1', 04:58:04 INFO - 'NO_EM_RESTART': '1', 04:58:04 INFO - 'PAGER': '/bin/cat', 04:58:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:04 INFO - 'PWD': '/builds/slave/test', 04:58:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:04 INFO - 'SHELL': '/bin/bash', 04:58:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQcpueCa2b/Listeners', 04:58:04 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:58:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:04 INFO - 'USER': 'cltbld', 04:58:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:04 INFO - 'XPC_FLAGS': '0x0', 04:58:04 INFO - 'XPC_SERVICE_NAME': '0', 04:58:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:04 INFO - Downloading/unpacking psutil>=3.1.1 04:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:58:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:58:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:58:07 INFO - Installing collected packages: psutil 04:58:07 INFO - Running setup.py install for psutil 04:58:07 INFO - building 'psutil._psutil_osx' extension 04:58: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 04:58: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 04:58: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 04:58:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:58:08 INFO - building 'psutil._psutil_posix' extension 04:58: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 04:58:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:58:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:58:08 INFO - ^ 04:58:08 INFO - 1 warning generated. 04:58: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 04:58:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:58:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:58:08 INFO - Successfully installed psutil 04:58:08 INFO - Cleaning up... 04:58:08 INFO - Return code: 0 04:58:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:58: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')]} 04:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58: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')]} 04:58:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58: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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1033a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff27bd63580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10324bd30>, '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 0x10306fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103242b20>, '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.ev9ku8We61/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.XQcpueCa2b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58: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.3'] in /builds/slave/test/build 04:58: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.3 04:58:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ev9ku8We61/Render', 04:58:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:08 INFO - 'HOME': '/Users/cltbld', 04:58:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:08 INFO - 'LOGNAME': 'cltbld', 04:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:08 INFO - 'MOZ_NO_REMOTE': '1', 04:58:08 INFO - 'NO_EM_RESTART': '1', 04:58:08 INFO - 'PAGER': '/bin/cat', 04:58:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:08 INFO - 'PWD': '/builds/slave/test', 04:58:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:08 INFO - 'SHELL': '/bin/bash', 04:58:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQcpueCa2b/Listeners', 04:58:08 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:58:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:08 INFO - 'USER': 'cltbld', 04:58:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:08 INFO - 'XPC_FLAGS': '0x0', 04:58:08 INFO - 'XPC_SERVICE_NAME': '0', 04:58:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:58:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:58:11 INFO - Installing collected packages: mozsystemmonitor 04:58:11 INFO - Running setup.py install for mozsystemmonitor 04:58:11 INFO - Successfully installed mozsystemmonitor 04:58:11 INFO - Cleaning up... 04:58:11 INFO - Return code: 0 04:58:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:58: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')]} 04:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58: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')]} 04:58:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58: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 0x1033a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff27bd63580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10324bd30>, '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 0x10306fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103242b20>, '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.ev9ku8We61/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.XQcpueCa2b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58: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 04:58: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 04:58:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ev9ku8We61/Render', 04:58:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:11 INFO - 'HOME': '/Users/cltbld', 04:58:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:11 INFO - 'LOGNAME': 'cltbld', 04:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:11 INFO - 'MOZ_NO_REMOTE': '1', 04:58:11 INFO - 'NO_EM_RESTART': '1', 04:58:11 INFO - 'PAGER': '/bin/cat', 04:58:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:11 INFO - 'PWD': '/builds/slave/test', 04:58:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:11 INFO - 'SHELL': '/bin/bash', 04:58:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQcpueCa2b/Listeners', 04:58:11 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:58:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:11 INFO - 'USER': 'cltbld', 04:58:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:11 INFO - 'XPC_FLAGS': '0x0', 04:58:11 INFO - 'XPC_SERVICE_NAME': '0', 04:58:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:11 INFO - Downloading/unpacking blobuploader==1.2.4 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58:14 INFO - Downloading blobuploader-1.2.4.tar.gz 04:58:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:58:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:58:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58:15 INFO - Downloading docopt-0.6.1.tar.gz 04:58:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:58:15 INFO - Installing collected packages: blobuploader, requests, docopt 04:58:15 INFO - Running setup.py install for blobuploader 04:58:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:58:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:58:15 INFO - Running setup.py install for requests 04:58:15 INFO - Running setup.py install for docopt 04:58:16 INFO - Successfully installed blobuploader requests docopt 04:58:16 INFO - Cleaning up... 04:58:16 INFO - Return code: 0 04:58:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:58: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')]} 04:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58: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')]} 04:58:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58: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 0x1033a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff27bd63580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10324bd30>, '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 0x10306fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103242b20>, '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.ev9ku8We61/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.XQcpueCa2b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58: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 04:58: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 04:58:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ev9ku8We61/Render', 04:58:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:16 INFO - 'HOME': '/Users/cltbld', 04:58:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:16 INFO - 'LOGNAME': 'cltbld', 04:58:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:16 INFO - 'MOZ_NO_REMOTE': '1', 04:58:16 INFO - 'NO_EM_RESTART': '1', 04:58:16 INFO - 'PAGER': '/bin/cat', 04:58:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:16 INFO - 'PWD': '/builds/slave/test', 04:58:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:16 INFO - 'SHELL': '/bin/bash', 04:58:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQcpueCa2b/Listeners', 04:58:16 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:58:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:16 INFO - 'USER': 'cltbld', 04:58:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:16 INFO - 'XPC_FLAGS': '0x0', 04:58:16 INFO - 'XPC_SERVICE_NAME': '0', 04:58:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:58:16 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-NX4w1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:58:16 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-LkAiJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:58:16 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-KCCJOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:58:16 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-vN9C3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:58:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:58:16 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-8ZfAHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:58:17 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-L6Ygkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:58:17 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-PS8Put-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:58:17 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-ZxEm67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:58:17 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-8KrNDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:58:17 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-bKb6Kg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:58:17 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-kRe9h3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:58:17 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-37i9HH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:58:17 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-KIIezZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:58:17 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-MVMFIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:58:17 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-MbqDuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:58:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-ruc4hO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:58:18 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-9PRqtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:58:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:58:18 INFO - Running setup.py install for manifestparser 04:58:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:58:18 INFO - Running setup.py install for mozcrash 04:58:18 INFO - Running setup.py install for mozdebug 04:58:18 INFO - Running setup.py install for mozdevice 04:58:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:58:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:58:18 INFO - Running setup.py install for mozfile 04:58:18 INFO - Running setup.py install for mozhttpd 04:58:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:58:19 INFO - Running setup.py install for mozinfo 04:58:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:58:19 INFO - Running setup.py install for mozInstall 04:58:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:58:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:58:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:58:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:58:19 INFO - Running setup.py install for mozleak 04:58:19 INFO - Running setup.py install for mozlog 04:58:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:58:19 INFO - Running setup.py install for moznetwork 04:58:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:58:19 INFO - Running setup.py install for mozprocess 04:58:20 INFO - Running setup.py install for mozprofile 04:58:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:58:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:58:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:58:20 INFO - Running setup.py install for mozrunner 04:58:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:58:20 INFO - Running setup.py install for mozscreenshot 04:58:20 INFO - Running setup.py install for moztest 04:58:20 INFO - Running setup.py install for mozversion 04:58:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:58:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:58:20 INFO - Cleaning up... 04:58:20 INFO - Return code: 0 04:58:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:58: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')]} 04:58:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58: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')]} 04:58:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58: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 0x1033a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff27bd63580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10324bd30>, '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 0x10306fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103242b20>, '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.ev9ku8We61/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.XQcpueCa2b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58: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 04:58: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 04:58:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ev9ku8We61/Render', 04:58:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:20 INFO - 'HOME': '/Users/cltbld', 04:58:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:20 INFO - 'LOGNAME': 'cltbld', 04:58:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:20 INFO - 'MOZ_NO_REMOTE': '1', 04:58:20 INFO - 'NO_EM_RESTART': '1', 04:58:20 INFO - 'PAGER': '/bin/cat', 04:58:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:20 INFO - 'PWD': '/builds/slave/test', 04:58:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:20 INFO - 'SHELL': '/bin/bash', 04:58:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQcpueCa2b/Listeners', 04:58:20 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:58:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:20 INFO - 'USER': 'cltbld', 04:58:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:20 INFO - 'XPC_FLAGS': '0x0', 04:58:20 INFO - 'XPC_SERVICE_NAME': '0', 04:58:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:58:21 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-YSkMmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:58: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)) 04:58:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:58:21 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-N1tAUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:58: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)) 04:58:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:58:21 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-AI1hic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:58: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)) 04:58:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:58:21 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-N2Jjlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:58: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)) 04:58:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:58:21 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-sL3uFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:58: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)) 04:58:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:58:21 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-2EraPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:58: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)) 04:58:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:58:21 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-GsCLwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:58: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)) 04:58:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:58:21 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-FNv_LZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:58: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)) 04:58:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:58:21 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-iZallu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:58: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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:58:22 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-UAnqKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:58: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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:58:22 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-1tBRpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:58: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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:58:22 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-07XdBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:58: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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:58:22 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-DQoreV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:58: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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:58:22 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-MF6K93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:58: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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:58:22 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-I2cRJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:58: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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:58:22 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Ezgr_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:58: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)) 04:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:58:22 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-vDa4Rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:58: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)) 04:58: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)) 04:58: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)) 04:58: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)) 04:58: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)) 04:58: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)) 04:58:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58:25 INFO - Downloading blessings-1.6.tar.gz 04:58:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:58:25 INFO - Installing collected packages: blessings 04:58:25 INFO - Running setup.py install for blessings 04:58:25 INFO - Successfully installed blessings 04:58:25 INFO - Cleaning up... 04:58:25 INFO - Return code: 0 04:58:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:58:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:58:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:58:26 INFO - Reading from file tmpfile_stdout 04:58:26 INFO - Current package versions: 04:58:26 INFO - blessings == 1.6 04:58:26 INFO - blobuploader == 1.2.4 04:58:26 INFO - docopt == 0.6.1 04:58:26 INFO - manifestparser == 1.1 04:58:26 INFO - mozInstall == 1.12 04:58:26 INFO - mozcrash == 0.17 04:58:26 INFO - mozdebug == 0.1 04:58:26 INFO - mozdevice == 0.48 04:58:26 INFO - mozfile == 1.2 04:58:26 INFO - mozhttpd == 0.7 04:58:26 INFO - mozinfo == 0.9 04:58:26 INFO - mozleak == 0.1 04:58:26 INFO - mozlog == 3.2 04:58:26 INFO - moznetwork == 0.27 04:58:26 INFO - mozprocess == 0.22 04:58:26 INFO - mozprofile == 0.28 04:58:26 INFO - mozrunner == 6.11 04:58:26 INFO - mozscreenshot == 0.1 04:58:26 INFO - mozsystemmonitor == 0.3 04:58:26 INFO - moztest == 0.7 04:58:26 INFO - mozversion == 1.4 04:58:26 INFO - psutil == 3.1.1 04:58:26 INFO - requests == 1.2.3 04:58:26 INFO - wsgiref == 0.1.2 04:58:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:26 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 0x1033a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff27bd63580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10324bd30>, '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 0x10306fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103242b20>, '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.ev9ku8We61/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.XQcpueCa2b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:58:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:58:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ev9ku8We61/Render', 04:58:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:26 INFO - 'HOME': '/Users/cltbld', 04:58:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:26 INFO - 'LOGNAME': 'cltbld', 04:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:26 INFO - 'MOZ_NO_REMOTE': '1', 04:58:26 INFO - 'NO_EM_RESTART': '1', 04:58:26 INFO - 'PAGER': '/bin/cat', 04:58:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:26 INFO - 'PWD': '/builds/slave/test', 04:58:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:26 INFO - 'SHELL': '/bin/bash', 04:58:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQcpueCa2b/Listeners', 04:58:26 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:58:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:26 INFO - 'USER': 'cltbld', 04:58:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:26 INFO - 'XPC_FLAGS': '0x0', 04:58:26 INFO - 'XPC_SERVICE_NAME': '0', 04:58:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58: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)) 04:58: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)) 04:58: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)) 04:58: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)) 04:58: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)) 04:58: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)) 04:58: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)) 04:58: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)) 04:58: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)) 04:58: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)) 04:58: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)) 04:58:26 INFO - Cleaning up... 04:58:26 INFO - Return code: 0 04:58:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58: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 0x1033a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff27bd63580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10324bd30>, '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 0x10306fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103242b20>, '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.ev9ku8We61/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.XQcpueCa2b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58: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 04:58: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 04:58:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ev9ku8We61/Render', 04:58:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:26 INFO - 'HOME': '/Users/cltbld', 04:58:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:26 INFO - 'LOGNAME': 'cltbld', 04:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:26 INFO - 'MOZ_NO_REMOTE': '1', 04:58:26 INFO - 'NO_EM_RESTART': '1', 04:58:26 INFO - 'PAGER': '/bin/cat', 04:58:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:26 INFO - 'PWD': '/builds/slave/test', 04:58:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:26 INFO - 'SHELL': '/bin/bash', 04:58:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQcpueCa2b/Listeners', 04:58:26 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:58:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:26 INFO - 'USER': 'cltbld', 04:58:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:26 INFO - 'XPC_FLAGS': '0x0', 04:58:26 INFO - 'XPC_SERVICE_NAME': '0', 04:58:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:26 INFO - Downloading/unpacking jsonschema 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:58:29 INFO - Downloading/unpacking functools32 (from jsonschema) 04:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:29 INFO - Downloading functools32-3.2.3-2.tar.gz 04:58:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:58:29 INFO - warning: no files found matching '*.txt' 04:58:29 INFO - no previously-included directories found matching 'build' 04:58:29 INFO - no previously-included directories found matching 'dist' 04:58:29 INFO - no previously-included directories found matching '.git*' 04:58:29 INFO - Installing collected packages: jsonschema, functools32 04:58:29 INFO - Running setup.py install for functools32 04:58:29 INFO - warning: no files found matching '*.txt' 04:58:29 INFO - no previously-included directories found matching 'build' 04:58:29 INFO - no previously-included directories found matching 'dist' 04:58:29 INFO - no previously-included directories found matching '.git*' 04:58:29 INFO - Successfully installed jsonschema functools32 04:58:29 INFO - Cleaning up... 04:58:30 INFO - Return code: 0 04:58:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1033a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff27bd63580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10324bd30>, '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 0x10306fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103242b20>, '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.ev9ku8We61/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.XQcpueCa2b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:58:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ev9ku8We61/Render', 04:58:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:30 INFO - 'HOME': '/Users/cltbld', 04:58:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:30 INFO - 'LOGNAME': 'cltbld', 04:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:30 INFO - 'MOZ_NO_REMOTE': '1', 04:58:30 INFO - 'NO_EM_RESTART': '1', 04:58:30 INFO - 'PAGER': '/bin/cat', 04:58:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:30 INFO - 'PWD': '/builds/slave/test', 04:58:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:30 INFO - 'SHELL': '/bin/bash', 04:58:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQcpueCa2b/Listeners', 04:58:30 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:58:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:30 INFO - 'USER': 'cltbld', 04:58:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:30 INFO - 'XPC_FLAGS': '0x0', 04:58:30 INFO - 'XPC_SERVICE_NAME': '0', 04:58:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:30 INFO - Downloading/unpacking flake8 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:58:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:58:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:58:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:58:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:58:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:58:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:58:33 INFO - no previously-included directories found matching 'docs/_build' 04:58:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:58:34 INFO - Running setup.py install for pep8 04:58:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:58:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:58:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:58:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:58:34 INFO - no previously-included directories found matching 'docs/_build' 04:58:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:58:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:58:34 INFO - Cleaning up... 04:58:34 INFO - Return code: 0 04:58:34 INFO - Running post-action listener: _resource_record_post_action 04:58:34 INFO - Running post-action listener: _start_resource_monitoring 04:58:34 INFO - Starting resource monitoring. 04:58:34 INFO - ##### 04:58:34 INFO - ##### Finished create-virtualenv step (success) 04:58:34 INFO - ##### 04:58:34 INFO - ##### 04:58:34 INFO - ##### Running install step. 04:58:34 INFO - ##### 04:58:34 INFO - Running pre-action listener: _resource_record_pre_action 04:58:34 INFO - Running main action method: install 04:58:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:58:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:58:34 INFO - Reading from file tmpfile_stdout 04:58:34 INFO - Detecting whether we're running mozinstall >=1.0... 04:58:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:58:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:58:34 INFO - Reading from file tmpfile_stdout 04:58:34 INFO - Output received: 04:58:34 INFO - Usage: mozinstall [options] installer 04:58:34 INFO - Options: 04:58:34 INFO - -h, --help show this help message and exit 04:58:34 INFO - -d DEST, --destination=DEST 04:58:34 INFO - Directory to install application into. [default: 04:58:34 INFO - "/builds/slave/test"] 04:58:34 INFO - --app=APP Application being installed. [default: firefox] 04:58:34 INFO - mkdir: /builds/slave/test/build/application 04:58: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'] 04:58: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 04:59:08 INFO - Reading from file tmpfile_stdout 04:59:08 INFO - Output received: 04:59:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:59:08 INFO - Running post-action listener: _resource_record_post_action 04:59:08 INFO - ##### 04:59:08 INFO - ##### Finished install step (success) 04:59:08 INFO - ##### 04:59:08 INFO - ##### 04:59:08 INFO - ##### Running run-tests step. 04:59:08 INFO - ##### 04:59:08 INFO - Running pre-action listener: _resource_record_pre_action 04:59:08 INFO - Running main action method: run_tests 04:59:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:59:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:59:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:59:08 INFO - 2016-05-30 04:59:08.642 screenresolution[1642:7623] starting screenresolution argv=screenresolution get 04:59:08 INFO - 2016-05-30 04:59:08.644 screenresolution[1642:7623] Display 0: 1600x1200x32@60 04:59:08 INFO - 2016-05-30 04:59:08.650 screenresolution[1643:7625] starting screenresolution argv=screenresolution list 04:59:08 INFO - Available Modes on Display 0 04:59:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:59:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:59:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:59:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:59:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:59:08 INFO - Intel Iris: 04:59:08 INFO - Chipset Model: Intel Iris 04:59:08 INFO - Type: GPU 04:59:08 INFO - Bus: Built-In 04:59:08 INFO - VRAM (Dynamic, Max): 1536 MB 04:59:08 INFO - Vendor: Intel (0x8086) 04:59:08 INFO - Device ID: 0x0a2e 04:59:08 INFO - Revision ID: 0x0009 04:59:08 INFO - Displays: 04:59:08 INFO - Display: 04:59:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:59:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:59:08 INFO - Display Serial Number: 1600x1200 60 04:59:08 INFO - Main Display: Yes 04:59:08 INFO - Mirror: Off 04:59:08 INFO - Online: Yes 04:59:08 INFO - Rotation: Supported 04:59:08 INFO - Return code: 0 04:59:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:59:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:59:08 INFO - Python 2.7.3 04:59:08 INFO - Return code: 0 04:59:08 INFO - grabbing minidump binary from tooltool 04:59: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')]} 04:59:08 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 0x10324bd30>, '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 0x10306fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103242b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:59:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:59:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:59:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:59:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:59:09 INFO - Return code: 0 04:59:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:59:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:59:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:59:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:59:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:59:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:59:10 INFO - Return code: 0 04:59:10 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--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 04:59:10 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0001 --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 04:59:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ev9ku8We61/Render', 04:59:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:10 INFO - 'HOME': '/Users/cltbld', 04:59:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:10 INFO - 'LOGNAME': 'cltbld', 04:59:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:59:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:10 INFO - 'MOZ_NO_REMOTE': '1', 04:59:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:59:10 INFO - 'NO_EM_RESTART': '1', 04:59:10 INFO - 'PAGER': '/bin/cat', 04:59:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:10 INFO - 'PWD': '/builds/slave/test', 04:59:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:59:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:10 INFO - 'SHELL': '/bin/bash', 04:59:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XQcpueCa2b/Listeners', 04:59:10 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:59:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:10 INFO - 'USER': 'cltbld', 04:59:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:10 INFO - 'XPC_FLAGS': '0x0', 04:59:10 INFO - 'XPC_SERVICE_NAME': '0', 04:59:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:10 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--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 04:59:10 INFO - mozversion application_buildid: 20160530025546 04:59:10 INFO - mozversion application_changeset: cad514ad49c199e823a92e8c8d27e16c22c3cac7 04:59:10 INFO - mozversion application_display_name: Nightly 04:59:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:59:10 INFO - mozversion application_name: Firefox 04:59:10 INFO - mozversion application_remotingname: firefox 04:59:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:59:10 INFO - mozversion application_vendor: Mozilla 04:59:10 INFO - mozversion application_version: 49.0a1 04:59:10 INFO - mozversion platform_buildid: 20160530025546 04:59:10 INFO - mozversion platform_changeset: cad514ad49c199e823a92e8c8d27e16c22c3cac7 04:59:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:59:10 INFO - mozversion platform_version: 49.0a1 04:59:10 INFO - using testdate: 1464609550 04:59:10 INFO - actual date: 1464609550 04:59:10 INFO - starting webserver on 'localhost:49278' 04:59:10 INFO - SUITE-START | Running 2 tests 04:59:10 INFO - TEST-START | dromaeo_css 04:59:10 INFO - operating with platform_type : mac_ 04:59:10 INFO - Initialising browser for dromaeo_css test... 04:59:10 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmp7Q6BlT/profile http://localhost:49278/getInfo.html) 04:59:13 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 04:59:13 INFO - PROCESS | 1661 | colorDepth:24 04:59:13 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 04:59:13 INFO - PROCESS | 1661 | __metrics 04:59:13 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 04:59:14 INFO - TEST-INFO | 1661: exit 0 04:59:14 INFO - Browser initialized. 04:59:14 INFO - Running cycle 1/1 for dromaeo_css test... 04:59:14 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmp7Q6BlT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:59:26 INFO - PROCESS | 1664 | RSS: Main: 252243968 04:59:26 INFO - PROCESS | 1664 | 05:00:16 INFO - PROCESS | 1664 | 1464609616045 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:02:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 05:02:27 INFO - PROCESS | 1664 | RSS: Main: 265129984 05:02:27 INFO - PROCESS | 1664 | 05:05:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 05:05:29 INFO - PROCESS | 1664 | RSS: Main: 276492288 05:05:29 INFO - PROCESS | 1664 | 05:08:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 05:08:32 INFO - PROCESS | 1664 | RSS: Main: 278462464 05:08:32 INFO - PROCESS | 1664 | 05:11:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 05:11:34 INFO - PROCESS | 1664 | RSS: Main: 280109056 05:11:34 INFO - PROCESS | 1664 | 05:14:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 05:14:38 INFO - PROCESS | 1664 | RSS: Main: 283742208 05:14:38 INFO - PROCESS | 1664 | 05:17:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 05:17:40 INFO - PROCESS | 1664 | RSS: Main: 283787264 05:17:40 INFO - PROCESS | 1664 | 05:17:40 INFO - PROCESS | 1664 | __start_tp_report 05:17:40 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 05:17:40 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 05:17:40 INFO - PROCESS | 1664 | |i|pagename|runs| 05:17:40 INFO - PROCESS | 1664 | |0;dojo.html;3435.1755578661123;3470.913227169322;3482.7387945904034;3503.299340131974;3505.1412403961026;5670.234518340023;5722.771089156434;5734.971325143374;5823.126531020349;5853.297604287481;5296.973515132419;5583.748731307091;5718.313802343725;5809.622374545652;5810.91283630747;4280.678949078816;5183.585313174955;5258.158694608864;5315.813946511873;5381.623286369956;60.3137030579287;5254.343207099113;5323.387810401795;5383.246345511612;5386.461353864622;5242.5281724644965;5255.842324730264;5271.077561426735;5280.996610643984;5291.920621190685;5305.31030965972;5306.5754739621125;5316.6544174628525;5338.065838478289;5347.6256662033675;5121.027004869063;5141.562967147797;5176.404713457963;5220.034293655686;5246.081935661244;5567.916481252784;5634.830955071355;5683.5737319700775;5711.685857277848;5730.656160630376;6114.082887566856;6136.570440069197;6220.222472190976;6242.09489624002;6331.240251169843;6125.693326622278;6148.538859585549;6199.659018753967;6268.091126786636;6272.3100458949475;5978.342382337939;5984.461398474158;5993.041113421875;5995.954753165057;6043.969780151115;6138.664296166039;6230.688465576703;6350.333215012357;6385.712642931079;6400.103985442041;6139.754409823647;6192.225971753531;6198.2252218472695;6216.191895053614;6230.408111229426;6202.34875338083;6210.254769427697;6288.748450062038;6296.150019747367;6309.684515774193;6126.938730612636;6172.105044768484;6177.536684748662;6295.968520157461;6323.30443651198;6117.675163990694;6166.537509686791;6265.8120256392385;6270.529710271748;6303.149074874833;4871.415430148405;4934.950650493521;4959.355283813081;4960.355153829979;4968.503149685002;4357.716748411343;4394.670399720034;4424.159409712144;4498.752568608758;4501.819927202875;5919.763209471659;5945.554083443759;6005.729742161613;6018.909716354258;6034.547408944259;6242.875142497124;6248.4376406123665;6268.279147897959;6286.717097730613;6290.0879372491645;5644.6330988485615;5717.971410143005;5817;5859.472647461664;6016.969915150484;5445.618806683494;5448.945510544923;5460.262864513319;5463.180522921593;5488.835334939959;5994.430529099815;6436.452901503331;6454.354564543602;6454.870902581922;6465.288818229991;6166.506679465542;6182.598131121463;6195.411435913581;6210.099535567408;6268.279147897868;6355.618662880242;6409.647469389229;6431.517636177212;6441.259255185713;6450.8064758057335;6173.753049877954;6245.781397651061;6246.406591373812;6255.812325630239;6266.154069200782;5881.823545293477;5938.66380064482;6192.071189321638;6236.126942228182;6284.4344009039405;6078.54410919174;6140.416660417253;6140.416660417253;6146.139540464249;6151.200343955257;6013.188219590299;6165.075238714228;6166.630002199882;6167.136600875791;6180.536459765445;5451;5499.395066542597;5967.433093856077;6014.759409623566;6063.151158837854;6029.728662210124;6063.757449701962;6136.355682653436;6211.409916057967;6262.655553944394;5388.865278368072;5796.536277097738;5950.375210602998;6016.939830601638;6096.237970253718 05:17:40 INFO - PROCESS | 1664 | |1;ext.html;505.08831559035946;507.6192855358481;520.5367223171376;533.3519773475227;557.8940001399733;36172.09569760754;39405.408918866204;39684.60315396845;39697.404538931936;39775.40336894949;40915.79542102293;41300.38049429261;41440.792796036054;41691;41785.373219401656;9420.246380289578;9606.135447809696;9683.12851843334;9715.125638692516;9730.951345243298;9525.857112143323;9615.567299471506;9641.084097010773;9657.613695452208;9687.709368718915;9642.75893102671;9664.95167524165;9667.12995830375;9690.515474226295;9711.660091896785;9595.376300540478;9618.422894626345;9652.034796520291;9663.855042174373;9667.081627245401;9491.335606507548;9560.330776845623;9595.568199431042;9600.807983840285;9623.615055397775;38783.03042423934;38815.02962425934;39199.41200881989;39205.823825285;39243.60756392457;3453.2748122894222;3548.840302186408;3577.928441431183;3691.0956815580084;3964.9008774780573;30277.394452111053;30917.917872874565;31123.37753244945;31567.36865262705;31685;3023.516237402027;3168.9683103168904;3179.60254968129;3180.3003339265324;3180.6819318068237;447780;514950;522738;524700;527001;33409;61224;61506.38493615007;61961;62049;28404.153730007485;35077.824610876785;35348.469772953424;35431;35590.8220458896;14470.27648617565;14577.271136443136;14683.632909177357;14759.704805903822;14794.112353258839;12362.814557781641;12475.625731228078;12553.748925021448;12573.434195461226;12583.433745481476;7717.729879454192;7745.806354841061;7774.781555454718;7812.765617031499;7814.765557033298;2864.957025644617;3262.6411094779555;3280.622138701751;3282.5240340150544;3296.1706253029797;934.7476181430976;945.664289177346;1020.2552136940076;1120.3613940054254;1131.5530365505745;25774.484510310063;27279.45441091207;29557.704422955925;29585;30229.395412091635;1051.747580580655;1077.1275267033727;1085.8914108589079;1103.194667892443;1164.8660404053473;14474.782878256834;15433.5369938902;15500.92249538745;15701.371945122068;15764.605884852741;675552.6222368857;677694;699870;702945;710786;55471.44528554744;57555.712221438625;57619.55951101172;57639.677024223354;57803.71098144483;1407.1205496564646;1425.49394964786;1438.2521089033644;1446.5298777897226;1450.1951416963475;29190.41619167647;29334.70665293405;29401.41197176002;30034.399312014073;30101.397972040875;41323.58676413318;49475.50524494709;49576;50084.74788130326;50131;555196;556831;561383;563016;563948;26330;26906.05828796061;27665.170044898685;27776.16671499858;28503;46192.769036154605;47066.52933470622;47124.528754713385;47571.524284756706;48401;38307.808460957516;38601.6139838598;39098.2180356397;39435.40846887356;39727;37508.24983500369;38231.42652860265;38461.230775384895;38850.61149388583;38988.41517377185;37169;37462.8126859364;37583.496660134384;37670.24659506849;38523.614763852005;32916.50625240576;34163;34334.65665343415;34379.484307735904;34514.96455106351;20648.793512064687;24558.754412455648;25048.874755626104;25129;25463 05:17:40 INFO - PROCESS | 1664 | |2;jquery.html;374.2570996571803;377.7997661239544;381.62600651361663;383.1914660067259;404.24204616344355;14349.856501434982;21263.25578604749;21279.97840205178;22020.559588808235;22093.889530552366;18528;20394.89802550989;21187.258445954394;21291;21599.568008639817;5718.857028574298;5734.025215713339;5945.732442040118;5970.611910225822;5982.312034116088;5799.65202087874;5873.676947767871;5911.497522710553;5911.674857882815;5918.792842250522;5575.968445837533;5856.590038697293;5865.85335366615;5893.38119497453;5905.557083218754;5764.769409223626;5783.826485205451;5814.912776308359;5836.15375770513;5855.8536036599;5046.697198168122;5080.314157588752;5092.618053645954;5105.851183483724;5121.078205922932;17447.65104697899;25615.615765763527;25955.831987560607;26583.468330633474;27526;2166.3176099528596;2181.6291230490856;2482.900683972639;2513.1078467144266;2566.371239046427;14776.113433193936;15293.388264469408;15392.769108463383;15488.07071575709;15679.372825086983;1836.540864783804;1851.3705340184401;1854.2953677602425;1883.5761953560477;1904.238304678126;116273.25590116155;119298;119857.20214196794;120396.39801801022;121307.78692213142;9342.851144236864;10758;10956.452177391098;11453.885461145283;11505.252158609663;13793.517226897211;13853.792193117111;13914.443422263199;13939.65150871216;13942;8489;8562.100979397073;8709.782255443664;8753.299736021103;8783.429077109968;8750.212480876751;8800.207981281588;8802.955985220033;8862.42394244372;8899.199072083544;5578.41426650204;5641.435856414408;6549.967250163719;6790.898136527956;6804.421624161902;1822.6992546229724;1853.7219417087545;1860.7487989121296;1871.4292140896885;1903.3148066695978;781.1719577248135;876.4434584039182;922.598669578722;981.0875885426622;1030.5156576409076;18054.548636283937;20620.793792061886;21479.248226312004;21921;21948.670769938464;958.520739630185;966.6665000574791;1034.2243317511866;1073.1575713065206;1102.4873433853297;10134.746631334276;10766.73083172927;11441.885581144083;11812.822807657893;11882.643520694393;540900;555689;572895;613163;617087;73436.63281683557;110456.58173672963;112668.43665781619;114957;117382;1012.3318609717551;1019.4291196929595;1037.9429131397847;1040.287403128859;1043.118564812732;15949.920250398674;19377.806221938165;19471;19482.22071117196;19596.314129005415;38012;49235.52293431203;51747.482525174266;51832.039315479204;51850.740746296025;71698;71843.64078179575;72070.27929720846;72499;73015;7127;7928.682852685828;7955.154281178734;8179.918200818153;8228.189798244377;10264.332818366782;11072;12085.456154473246;12562.43469043877;13042.282674452996;8792.47245165292;11022.173336999918;11242.213045086766;11254.7749045017;11293.265937714023;10897.455127243764;11044.558217671203;11054.834177487504;11136.38749868765;11138.3873886937;10994.90050994894;11169.441527923735;11338.943305283421;11370.54517819311;11484.770304594029;7423.257674232534;7931.603419829101;8013.278804907586;8161.918380816116;8176.754697359089;10786.946065269623;11068.667939961813;11214.439278035903;11252.93097155769;11255.718607034889 05:17:40 INFO - PROCESS | 1664 | |3;mootools.html;1522.8553287437694;1528.3733669195633;1747.239950621479;1878.71819227116;1947.376839411389;7957.92042079579;8447;8492.235698787108;8952.955235223815;8975.685850995216;8587.398882078258;8729.476231426119;8828.558572071403;8847.513386763725;8859.95570022149;2344.636581329892;2363.4682196505732;2365.124903785602;2371.7983971362455;2382.6664267002634;2346.4368551547677;2352.870592117433;2396.928092157238;2401.2796161151673;2413.5655581995234;2361.811055115595;2370.9881450592725;2377.3699969508093;2392.079049565912;2392.712874455068;2335.556244313575;2338.2283846330756;2338.5673650374733;2358.056777289081;2364.9763502365013;2333.5099629077913;2344.906203751848;2347.542229265294;2359.3393849722106;2361.3506285771377;8773.034966153718;8846.823063538694;8878.067802880669;8878.911210887873;8893.688720894737;5199.376074871039;5335.359756829205;5369.194620806871;5376.327959005124;5376.354837419495;3426.3832510148163;3466.376052310583;3476.3221171871496;3523.925202813141;3574.660407261306;916.4592890194817;1051.6582110814015;1052.4685034057752;1056.3661802918264;1060.199549340251;11439.59961401347;11633.76732465346;11642;11646.94176529112;11759.7060073498;3948.545917219499;4686.414198225222;4820.566149046603;4821.421429428491;4834.057358814997;9937.503124843728;9938.850917236246;9942.602295908082;9946.850797238047;9968.900310996942;5293.894122117536;5347.759350829144;5351.919721204185;5359.1157459019605;5403.081476149064;4362.476502819682;4691.695039822401;4835.492273311322;4847.830325938575;4923.8276660316715;3804.6956243500454;3900.1419687668667;3908.4332771747936;3918.6669133123432;3990.3615421532418;1112.7718817642365;1308.6662900960193;1319.0436933223527;1323.5103011885665;1327.7477279317093;828.2297463359134;919.7838507950627;936.2509992006504;946.0161432110665;946.8248374050822;5906.379830117861;5946.43508866657;6000.489958353589;6028.4272994065495;6098.0852872069545;878.5870640798814;984.4388698441962;988.0712130597216;1007.1992765751245;1013.1843865688045;3936.389859571771;4006.779627120536;4019.83920643171;4043.0700938783657;4046.393041043808;10463.424511651932;10695.625653102104;10843.783124337628;10865.891341086488;10871.782564348827;10704.357738535713;10805.02754752076;10839.403832789114;10859.40273284961;10862.076723478593;7853.921460785434;8103.716369927024;8169.346452283803;8210.384221183434;8244.917550824535;5635.8591035224445;5829.300483941955;5846.795362162474;5862.062070068769;5916.585838991401;9893.703188904345;10042.548085336324;10048.246381521269;10053;10083.495825208563;9742;10056.044675755791;10215;10244.948775256076;10248.333858299187;6525.282218956006;6833.4191593715095;7009.859802803813;7042.577445353295;7158.391536719437;8381;8409.285210756909;8482.363822713445;8484.533350665773;8518.74443766688;7272.854542909218;7349.742759003409;7505.849883002418;7598.696052157852;7627.4279429041935;7166.677499512639;7349.448791340564;7379.9262007381385;7416.147143078615;7475.43934204926;6972.9302706972285;7061.046758687511;7065.364117229474;7088.468364872552;7090.929090709027;4830.372051633265;4875.414950205856;5003.999200159909;5030.471800461045;5057.797688092583;4997.8750531235555;5065.544101030925;5097.6941383517105;5100.362454693163;5162.354705661793 05:17:40 INFO - PROCESS | 1664 | |4;prototype.html;267.29700886668064;297.8451205373206;314.4418656884031;317.35735136348904;318.37757184703906;5361.437049109841;5372.838814835552;5395.136778115502;5617.775288988436;5649.3220813502385;5255.7109358985335;5496.7526461309235;5547.833564993057;5566.805161819337;5582.358028826686;3472.8958131256104;4188.329867221245;4216.451861258032;4232.0054787125;4273.059926816096;3898.894596917661;4158.521769996459;4165.250254954107;4250.362445633149;4253.893652658693;4106.240345536085;4110.28070087735;4124.401961715549;4132.938005929898;4153.854615088472;4181.560936101711;4187.267228235047;4194.022244206586;4203.831846726127;4212.010177608275;3092.087834088949;3224.91937701557;3443.3974054540604;3642.3807952648112;3651.561812582507;5156.215643552478;5327.893442131174;5369.9463005369835;5370.5435038021815;5376.677399356051;4025.4565633639377;4238.004069043773;4580.9541904580865;4752.884966190047;4822.614190864722;5690.703518843288;5832.329282132524;5837.526671630373;5843.707814609253;5872.533535914926;3504.9846338355546;3967.841286348543;3988.641022308006;4010.3783913493453;4029.294873397144;5008.474110218411;6066.484348830355;6170.598911070766;6178.845528861724;6263.43629073381;3746.3256613809776;3799.297130030953;3857.8528082311627;4034.636882680573;4053.1893621275863;3644.1436262478296;3984.3425834737523;4099.098198396348;4114.753114813121;4139.19285739278;4283.850065247701;4604.447466304065;4612.0545288215835;4698.3187437821325;4752.809887604526;4565.383673204141;5273.630845840754;5536.4186760390385;5931.881362372728;5933.6736479493175;1103.8023744237432;1377.22875189894;1378.0422606288532;1397.8886785005946;1403.5719105672663;808.3492788305471;839.5130824121994;851.9446235994641;872.2542226396466;876.7895705030875;4068.0506632691063;4127.997921008308;4516.6160876325885;4594.105713400603;4624.14453326129;4469.776511174493;4947.282644016669;5060.392752869606;5168.90659282991;5176.844694659091;4182.2681030819485;4341.196878577473;4391.845128630978;4501.887452813706;4717.976410117927;2545.5290771207387;2565.127456956432;2605.1272823603927;2653.349929267303;2712.8372297662204;4564.908701825945;4568.428946381702;4685.5782979532005;4902.656814022984;5371.301730774974;4776.331313616027;4816.662833601685;4826.348442960156;4891.755412229374;5110.488951104934;4214.178235244098;4588.875725447287;5158;5213.504717051874;5384.546172533473;4293.91412171761;4658.8136474542;4970.975145124396;5072.365954255718;5113.386393632788;4488.329443980826;4590.403247577793;4924.926126108039;5020;5073.822416215415;4338.761368124658;4659.136345461842;5280.577553795764;5294.258803767552;5359.651622644516;2943.7792165588094;3498.003069125287;3505.807180605091;3520.436730123168;3584.874529391459;4742.976285118691;4871.318015477765;4874.658773885794;4881.414230292387;5774.682392468455;5440.700761458158;5868.68187066115;5877.500412464989;5901.232839730807;5902.586818922634;5172.5516855279075;5398.568114550905;5864.706764661835;5878.2358293421585;5918.674472904032;5492.203630473638;5570.610057296112;5672.858178545569;5718.885622287614;5747.885042299215;4913.975430122827;5570.331560212639;5866.914620795166;5919.260092488439;5946.643201407929;4756.904861902812;5536.695481748542;5756.942430575808;5762.818622182359;5764.7405866736935 05:17:40 INFO - PROCESS | 1664 | |5;yui.html;739.9741009064683;815.9959200203995;833.1127349372917;843.5697794124994;884.3676771108658;14403.639909002268;14780.039297445659;14842.703145937076;14987.775183372258;15027.624309392271;10014.599416023368;13642.31788410578;13871.167729936185;13926.373313200906;13940.58178254654;706.9752558660448;871.6600525794945;889.5463313709989;904.7692838326251;904.9773755656096;892.8660700894885;902.2466240689;903.412781691899;904.398574947659;905.1582028713294;944.187998321443;947.943123412594;948.8766460360143;952.7856232347701;956.297121615612;912.771807048238;942.7737343037655;948.4498990585513;948.6063283737241;948.6964171465169;704.4470090978557;705.9858802823966;709.3332267668375;709.5458906299972;711.6441779110445;14642;14698.191599462025;14698.55904322872;14701.70596588073;14715.852841471555;13885.652858678513;13964.371603277776;14006.789898151535;14040.1575905446;14307;9526.90473095274;11576.710582235428;11634;11693.532258709642;11750.361046009062;3770.2082562661876;3777.1690228149764;3823.254465379252;3850.5571859236265;3852.383618621035;86159.13840861573;86859.13140868573;87601.56199218963;88040.55979720125;88782;9443.291753118543;10279.074883260542;10638;10740;10756.784864302826;21138.471538211666;21179.258725944517;21185.470363240733;21232;21241.893790530947;7790.649420776079;7812.218778122256;7903.960480197562;7947.960260198662;8069.031716194094;4352.7823608819435;4392.1874453226255;4439.267520859022;4450.977745111254;4554.840580579664;3387.187075101958;3502.194495266103;3506.263684626257;3512.841922113511;3514.7715398499017;636.0554576453962;647.0876064748776;649.331188875459;665.5773583774338;665.816900352407;651.6253154436218;682.7200847652439;688.0264425837385;702.1714377035148;707.9362857342863;9145.862812057825;9220.49287289192;9232.353735238468;9336.346455748168;9423.246140308758;661.5567569728294;664.2261765043767;670.872534218497;680.2483256002047;697.9825504362432;4421.2262854001065;4445.599896009375;4451.554844515587;4469.128519938646;4476.597106260452;90723;93612.53193733988;94690.05309946812;95923;96231.03768962361;64931.67534162394;79682.20317796864;83148.58425707954;84087.57956210303;85057.57471212729;75602.9999999989;75722.24277757262;76127.23872761201;76337;76399.61800191074;7475.2151024141685;8408.117147699404;8753.431026983226;8804.207621314112;8862.468251904906;44252.77873610611;44917.631177661155;46319.76840115778;46422.767886160356;46518;59665;60267.69866150641;62755.37244627495;64211.678941604994;64515.70968580508;11766.294022358687;11868.465919033491;11891.881081189078;11944.64166075019;11972.281663099893;11436.656900293005;12188.329641869428;12436.316002619942;12784;12972.545960891324;11428.885711142782;11531.654050378502;11707.707307317385;11761.705957351134;11877.881221187678;9379;11632.651020469399;11820.40897955116;12152.817707734568;12207.633770986884;11357.602483913024;11438.94280528592;11609.419529023684;11734.413279335828;11980.58067967617;8326.6669333226;8933.597988090421;9189.586468608795;9462.763430914281;10166.237532184969;9503.239740820856;9799.559019844266;9832;9855.556499957376;9929.751756206153 05:17:40 INFO - PROCESS | 1664 | __end_tp_report 05:17:40 INFO - PROCESS | 1664 | __start_cc_report 05:17:40 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,416 05:17:40 INFO - PROCESS | 1664 | __end_cc_report 05:17:40 INFO - PROCESS | 1664 | __startTimestamp1464610660686__endTimestamp 05:17:40 INFO - PROCESS | 1664 | 05:17:40 INFO - PROCESS | 1664 | ------- Summary: start ------- 05:17:40 INFO - PROCESS | 1664 | Number of tests: 6 05:17:40 INFO - PROCESS | 1664 | 05:17:40 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5745.06 Median:6016.94 stddev:776.66 (12.9%) stddev-sans-first:757.60 05:17:40 INFO - PROCESS | 1664 | Values: 3435.2 3470.9 3482.7 3503.3 3505.1 5670.2 5722.8 5735.0 5823.1 5853.3 5297.0 5583.7 5718.3 5809.6 5810.9 4280.7 5183.6 5258.2 5315.8 5381.6 60.3 5254.3 5323.4 5383.2 5386.5 5242.5 5255.8 5271.1 5281.0 5291.9 5305.3 5306.6 5316.7 5338.1 5347.6 5121.0 5141.6 5176.4 5220.0 5246.1 5567.9 5634.8 5683.6 5711.7 5730.7 6114.1 6136.6 6220.2 6242.1 6331.2 6125.7 6148.5 6199.7 6268.1 6272.3 5978.3 5984.5 5993.0 5996.0 6044.0 6138.7 6230.7 6350.3 6385.7 6400.1 6139.8 6192.2 6198.2 6216.2 6230.4 6202.3 6210.3 6288.7 6296.2 6309.7 6126.9 6172.1 6177.5 6296.0 6323.3 6117.7 6166.5 6265.8 6270.5 6303.1 4871.4 4935.0 4959.4 4960.4 4968.5 4357.7 4394.7 4424.2 4498.8 4501.8 5919.8 5945.6 6005.7 6018.9 6034.5 6242.9 6248.4 6268.3 6286.7 6290.1 5644.6 5718.0 5817.0 5859.5 6017.0 5445.6 5448.9 5460.3 5463.2 5488.8 5994.4 6436.5 6454.4 6454.9 6465.3 6166.5 6182.6 6195.4 6210.1 6268.3 6355.6 6409.6 6431.5 6441.3 6450.8 6173.8 6245.8 6246.4 6255.8 6266.2 5881.8 5938.7 6192.1 6236.1 6284.4 6078.5 6140.4 6140.4 6146.1 6151.2 6013.2 6165.1 6166.6 6167.1 6180.5 5451.0 5499.4 5967.4 6014.8 6063.2 6029.7 6063.8 6136.4 6211.4 6262.7 5388.9 5796.5 5950.4 6016.9 6096.2 05:17:40 INFO - PROCESS | 1664 | 05:17:40 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:70151.38 Median:38832.82 stddev:158877.41 (409.1%) stddev-sans-first:159237.05 05:17:40 INFO - PROCESS | 1664 | Values: 505.1 507.6 520.5 533.4 557.9 36172.1 39405.4 39684.6 39697.4 39775.4 40915.8 41300.4 41440.8 41691.0 41785.4 9420.2 9606.1 9683.1 9715.1 9731.0 9525.9 9615.6 9641.1 9657.6 9687.7 9642.8 9665.0 9667.1 9690.5 9711.7 9595.4 9618.4 9652.0 9663.9 9667.1 9491.3 9560.3 9595.6 9600.8 9623.6 38783.0 38815.0 39199.4 39205.8 39243.6 3453.3 3548.8 3577.9 3691.1 3964.9 30277.4 30917.9 31123.4 31567.4 31685.0 3023.5 3169.0 3179.6 3180.3 3180.7 447780.0 514950.0 522738.0 524700.0 527001.0 33409.0 61224.0 61506.4 61961.0 62049.0 28404.2 35077.8 35348.5 35431.0 35590.8 14470.3 14577.3 14683.6 14759.7 14794.1 12362.8 12475.6 12553.7 12573.4 12583.4 7717.7 7745.8 7774.8 7812.8 7814.8 2865.0 3262.6 3280.6 3282.5 3296.2 934.7 945.7 1020.3 1120.4 1131.6 25774.5 27279.5 29557.7 29585.0 30229.4 1051.7 1077.1 1085.9 1103.2 1164.9 14474.8 15433.5 15500.9 15701.4 15764.6 675552.6 677694.0 699870.0 702945.0 710786.0 55471.4 57555.7 57619.6 57639.7 57803.7 1407.1 1425.5 1438.3 1446.5 1450.2 29190.4 29334.7 29401.4 30034.4 30101.4 41323.6 49475.5 49576.0 50084.7 50131.0 555196.0 556831.0 561383.0 563016.0 563948.0 26330.0 26906.1 27665.2 27776.2 28503.0 46192.8 47066.5 47124.5 47571.5 48401.0 38307.8 38601.6 39098.2 39435.4 39727.0 37508.2 38231.4 38461.2 38850.6 38988.4 37169.0 37462.8 37583.5 37670.2 38523.6 32916.5 34163.0 34334.7 34379.5 34515.0 20648.8 24558.8 25048.9 25129.0 25463.0 05:17:40 INFO - PROCESS | 1664 | 05:17:40 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:33767.78 Median:21934.84 stddev:96530.49 (440.1%) stddev-sans-first:96768.72 05:17:40 INFO - PROCESS | 1664 | Values: 374.3 377.8 381.6 383.2 404.2 14349.9 21263.3 21280.0 22020.6 22093.9 18528.0 20394.9 21187.3 21291.0 21599.6 5718.9 5734.0 5945.7 5970.6 5982.3 5799.7 5873.7 5911.5 5911.7 5918.8 5576.0 5856.6 5865.9 5893.4 5905.6 5764.8 5783.8 5814.9 5836.2 5855.9 5046.7 5080.3 5092.6 5105.9 5121.1 17447.7 25615.6 25955.8 26583.5 27526.0 2166.3 2181.6 2482.9 2513.1 2566.4 14776.1 15293.4 15392.8 15488.1 15679.4 1836.5 1851.4 1854.3 1883.6 1904.2 116273.3 119298.0 119857.2 120396.4 121307.8 9342.9 10758.0 10956.5 11453.9 11505.3 13793.5 13853.8 13914.4 13939.7 13942.0 8489.0 8562.1 8709.8 8753.3 8783.4 8750.2 8800.2 8803.0 8862.4 8899.2 5578.4 5641.4 6550.0 6790.9 6804.4 1822.7 1853.7 1860.7 1871.4 1903.3 781.2 876.4 922.6 981.1 1030.5 18054.5 20620.8 21479.2 21921.0 21948.7 958.5 966.7 1034.2 1073.2 1102.5 10134.7 10766.7 11441.9 11812.8 11882.6 540900.0 555689.0 572895.0 613163.0 617087.0 73436.6 110456.6 112668.4 114957.0 117382.0 1012.3 1019.4 1037.9 1040.3 1043.1 15949.9 19377.8 19471.0 19482.2 19596.3 38012.0 49235.5 51747.5 51832.0 51850.7 71698.0 71843.6 72070.3 72499.0 73015.0 7127.0 7928.7 7955.2 8179.9 8228.2 10264.3 11072.0 12085.5 12562.4 13042.3 8792.5 11022.2 11242.2 11254.8 11293.3 10897.5 11044.6 11054.8 11136.4 11138.4 10994.9 11169.4 11338.9 11370.5 11484.8 7423.3 7931.6 8013.3 8161.9 8176.8 10786.9 11068.7 11214.4 11252.9 11255.7 05:17:40 INFO - PROCESS | 1664 | 05:17:40 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5718.50 Median:4960.85 stddev:3197.34 (64.5%) stddev-sans-first:3190.76 05:17:40 INFO - PROCESS | 1664 | Values: 1522.9 1528.4 1747.2 1878.7 1947.4 7957.9 8447.0 8492.2 8953.0 8975.7 8587.4 8729.5 8828.6 8847.5 8860.0 2344.6 2363.5 2365.1 2371.8 2382.7 2346.4 2352.9 2396.9 2401.3 2413.6 2361.8 2371.0 2377.4 2392.1 2392.7 2335.6 2338.2 2338.6 2358.1 2365.0 2333.5 2344.9 2347.5 2359.3 2361.4 8773.0 8846.8 8878.1 8878.9 8893.7 5199.4 5335.4 5369.2 5376.3 5376.4 3426.4 3466.4 3476.3 3523.9 3574.7 916.5 1051.7 1052.5 1056.4 1060.2 11439.6 11633.8 11642.0 11646.9 11759.7 3948.5 4686.4 4820.6 4821.4 4834.1 9937.5 9938.9 9942.6 9946.9 9968.9 5293.9 5347.8 5351.9 5359.1 5403.1 4362.5 4691.7 4835.5 4847.8 4923.8 3804.7 3900.1 3908.4 3918.7 3990.4 1112.8 1308.7 1319.0 1323.5 1327.7 828.2 919.8 936.3 946.0 946.8 5906.4 5946.4 6000.5 6028.4 6098.1 878.6 984.4 988.1 1007.2 1013.2 3936.4 4006.8 4019.8 4043.1 4046.4 10463.4 10695.6 10843.8 10865.9 10871.8 10704.4 10805.0 10839.4 10859.4 10862.1 7853.9 8103.7 8169.3 8210.4 8244.9 5635.9 5829.3 5846.8 5862.1 5916.6 9893.7 10042.5 10048.2 10053.0 10083.5 9742.0 10056.0 10215.0 10244.9 10248.3 6525.3 6833.4 7009.9 7042.6 7158.4 8381.0 8409.3 8482.4 8484.5 8518.7 7272.9 7349.7 7505.8 7598.7 7627.4 7166.7 7349.4 7379.9 7416.1 7475.4 6972.9 7061.0 7065.4 7088.5 7090.9 4830.4 4875.4 5004.0 5030.5 5057.8 4997.9 5065.5 5097.7 5100.4 5162.4 05:17:40 INFO - PROCESS | 1664 | 05:17:40 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4419.98 Median:4747.89 stddev:1355.95 (28.6%) stddev-sans-first:1323.44 05:17:40 INFO - PROCESS | 1664 | Values: 267.3 297.8 314.4 317.4 318.4 5361.4 5372.8 5395.1 5617.8 5649.3 5255.7 5496.8 5547.8 5566.8 5582.4 3472.9 4188.3 4216.5 4232.0 4273.1 3898.9 4158.5 4165.3 4250.4 4253.9 4106.2 4110.3 4124.4 4132.9 4153.9 4181.6 4187.3 4194.0 4203.8 4212.0 3092.1 3224.9 3443.4 3642.4 3651.6 5156.2 5327.9 5369.9 5370.5 5376.7 4025.5 4238.0 4581.0 4752.9 4822.6 5690.7 5832.3 5837.5 5843.7 5872.5 3505.0 3967.8 3988.6 4010.4 4029.3 5008.5 6066.5 6170.6 6178.8 6263.4 3746.3 3799.3 3857.9 4034.6 4053.2 3644.1 3984.3 4099.1 4114.8 4139.2 4283.9 4604.4 4612.1 4698.3 4752.8 4565.4 5273.6 5536.4 5931.9 5933.7 1103.8 1377.2 1378.0 1397.9 1403.6 808.3 839.5 851.9 872.3 876.8 4068.1 4128.0 4516.6 4594.1 4624.1 4469.8 4947.3 5060.4 5168.9 5176.8 4182.3 4341.2 4391.8 4501.9 4718.0 2545.5 2565.1 2605.1 2653.3 2712.8 4564.9 4568.4 4685.6 4902.7 5371.3 4776.3 4816.7 4826.3 4891.8 5110.5 4214.2 4588.9 5158.0 5213.5 5384.5 4293.9 4658.8 4971.0 5072.4 5113.4 4488.3 4590.4 4924.9 5020.0 5073.8 4338.8 4659.1 5280.6 5294.3 5359.7 2943.8 3498.0 3505.8 3520.4 3584.9 4743.0 4871.3 4874.7 4881.4 5774.7 5440.7 5868.7 5877.5 5901.2 5902.6 5172.6 5398.6 5864.7 5878.2 5918.7 5492.2 5570.6 5672.9 5718.9 5747.9 4914.0 5570.3 5866.9 5919.3 5946.6 4756.9 5536.7 5756.9 5762.8 5764.7 05:17:40 INFO - PROCESS | 1664 | 05:17:40 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18653.68 Median:32790.50 stddev:26311.29 (80.2%) stddev-sans-first:26350.72 05:17:40 INFO - PROCESS | 1664 | Values: 740.0 816.0 833.1 843.6 884.4 14403.6 14780.0 14842.7 14987.8 15027.6 10014.6 13642.3 13871.2 13926.4 13940.6 707.0 871.7 889.5 904.8 905.0 892.9 902.2 903.4 904.4 905.2 944.2 947.9 948.9 952.8 956.3 912.8 942.8 948.4 948.6 948.7 704.4 706.0 709.3 709.5 711.6 14642.0 14698.2 14698.6 14701.7 14715.9 13885.7 13964.4 14006.8 14040.2 14307.0 9526.9 11576.7 11634.0 11693.5 11750.4 3770.2 3777.2 3823.3 3850.6 3852.4 86159.1 86859.1 87601.6 88040.6 88782.0 9443.3 10279.1 10638.0 10740.0 10756.8 21138.5 21179.3 21185.5 21232.0 21241.9 7790.6 7812.2 7904.0 7948.0 8069.0 4352.8 4392.2 4439.3 4451.0 4554.8 3387.2 3502.2 3506.3 3512.8 3514.8 636.1 647.1 649.3 665.6 665.8 651.6 682.7 688.0 702.2 707.9 9145.9 9220.5 9232.4 9336.3 9423.2 661.6 664.2 670.9 680.2 698.0 4421.2 4445.6 4451.6 4469.1 4476.6 90723.0 93612.5 94690.1 95923.0 96231.0 64931.7 79682.2 83148.6 84087.6 85057.6 75603.0 75722.2 76127.2 76337.0 76399.6 7475.2 8408.1 8753.4 8804.2 8862.5 44252.8 44917.6 46319.8 46422.8 46518.0 59665.0 60267.7 62755.4 64211.7 64515.7 11766.3 11868.5 11891.9 11944.6 11972.3 11436.7 12188.3 12436.3 12784.0 12972.5 11428.9 11531.7 11707.7 11761.7 11877.9 9379.0 11632.7 11820.4 12152.8 12207.6 11357.6 11438.9 11609.4 11734.4 11980.6 8326.7 8933.6 9189.6 9462.8 10166.2 9503.2 9799.6 9832.0 9855.6 9929.8 05:17:40 INFO - PROCESS | 1664 | -------- Summary: end -------- 05:17:40 INFO - PROCESS | 1664 | 05:17:41 INFO - TEST-INFO | 1664: exit 0 05:17:41 INFO - TEST-OK | dromaeo_css | took 1110899ms 05:17:41 INFO - TEST-START | kraken 05:17:41 INFO - operating with platform_type : mac_ 05:17:41 INFO - Initialising browser for kraken test... 05:17:41 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpsIJGXI/profile http://localhost:49278/getInfo.html) 05:17:44 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 05:17:44 INFO - PROCESS | 1672 | colorDepth:24 05:17:44 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 05:17:44 INFO - PROCESS | 1672 | __metrics 05:17:44 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 05:17:45 INFO - TEST-INFO | 1672: exit 0 05:17:45 INFO - Browser initialized. 05:17:45 INFO - Running cycle 1/1 for kraken test... 05:17:45 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpsIJGXI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:17:57 INFO - PROCESS | 1674 | RSS: Main: 252915712 05:17:57 INFO - PROCESS | 1674 | 05:18:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 05:18:01 INFO - PROCESS | 1674 | RSS: Main: 265760768 05:18:01 INFO - PROCESS | 1674 | 05:18:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 05:18:04 INFO - PROCESS | 1674 | RSS: Main: 270606336 05:18:04 INFO - PROCESS | 1674 | 05:18:08 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 05:18:08 INFO - PROCESS | 1674 | RSS: Main: 270778368 05:18:08 INFO - PROCESS | 1674 | 05:18:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 05:18:11 INFO - PROCESS | 1674 | RSS: Main: 271683584 05:18:11 INFO - PROCESS | 1674 | 05:18:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:18:13 INFO - PROCESS | 1674 | RSS: Main: 272191488 05:18:13 INFO - PROCESS | 1674 | 05:18:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 05:18:17 INFO - PROCESS | 1674 | RSS: Main: 272125952 05:18:17 INFO - PROCESS | 1674 | 05:18:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 05:18:20 INFO - PROCESS | 1674 | RSS: Main: 272465920 05:18:20 INFO - PROCESS | 1674 | 05:18:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 05:18:24 INFO - PROCESS | 1674 | RSS: Main: 272904192 05:18:24 INFO - PROCESS | 1674 | 05:18:25 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:18:26 INFO - PROCESS | 1674 | RSS: Main: 271962112 05:18:26 INFO - PROCESS | 1674 | 05:18:28 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:18:29 INFO - PROCESS | 1674 | RSS: Main: 272052224 05:18:29 INFO - PROCESS | 1674 | 05:18:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:18:32 INFO - PROCESS | 1674 | RSS: Main: 272203776 05:18:32 INFO - PROCESS | 1674 | 05:18:34 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:18:35 INFO - PROCESS | 1674 | RSS: Main: 272117760 05:18:35 INFO - PROCESS | 1674 | 05:18:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:18:39 INFO - PROCESS | 1674 | RSS: Main: 272429056 05:18:39 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 05:18:40 INFO - PROCESS | 1674 | RSS: Main: 273104896 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | __start_tp_report 05:18:40 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 05:18:40 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 05:18:40 INFO - PROCESS | 1674 | |i|pagename|runs| 05:18:40 INFO - PROCESS | 1674 | |0;ai-astar;117;113;117;112;116;120;149;172;125;117 05:18:40 INFO - PROCESS | 1674 | |1;audio-beat-detection;158;127;179;162;120;133;128;135;168;159 05:18:40 INFO - PROCESS | 1674 | |2;audio-dft;200;199;203;268;271;199;267;273;191;276 05:18:40 INFO - PROCESS | 1674 | |3;audio-fft;72;79;77;140;59;57;59;66;163;55 05:18:40 INFO - PROCESS | 1674 | |4;audio-oscillator;68;75;115;71;67;90;113;68;83;129 05:18:40 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;88;91;89;87;90;86;90;87;89;86 05:18:40 INFO - PROCESS | 1674 | |6;imaging-darkroom;95;102;95;97;97;122;96;96;94;97 05:18:40 INFO - PROCESS | 1674 | |7;imaging-desaturate;92;92;92;92;96;90;92;95;91;110 05:18:40 INFO - PROCESS | 1674 | |8;json-parse-financial;58;59;57;61;77;84;50;51;80;78 05:18:40 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;66;75;98;101;86;77;81;71;69;71 05:18:40 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;87;86;88;90;93;82;90;89;118;89 05:18:40 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;166;151;155;140;142;142;143;134;138;141 05:18:40 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;171;169;166;163;189;225;195;168;164;168 05:18:40 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;68;66;69;65;69;64;70;65;69;69 05:18:40 INFO - PROCESS | 1674 | __end_tp_report 05:18:40 INFO - PROCESS | 1674 | __start_cc_report 05:18:40 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1088 05:18:40 INFO - PROCESS | 1674 | __end_cc_report 05:18:40 INFO - PROCESS | 1674 | __startTimestamp1464610720946__endTimestamp 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | ------- Summary: start ------- 05:18:40 INFO - PROCESS | 1674 | Number of tests: 14 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:125.80 Median:118.50 stddev:19.41 (16.4%) stddev-sans-first:20.32 05:18:40 INFO - PROCESS | 1674 | Values: 117.0 113.0 117.0 112.0 116.0 120.0 149.0 172.0 125.0 117.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:146.90 Median:158.50 stddev:20.51 (12.9%) stddev-sans-first:21.35 05:18:40 INFO - PROCESS | 1674 | Values: 158.0 127.0 179.0 162.0 120.0 133.0 128.0 135.0 168.0 159.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:234.70 Median:267.50 stddev:38.46 (14.4%) stddev-sans-first:38.68 05:18:40 INFO - PROCESS | 1674 | Values: 200.0 199.0 203.0 268.0 271.0 199.0 267.0 273.0 191.0 276.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:82.70 Median:62.50 stddev:37.60 (60.2%) stddev-sans-first:39.68 05:18:40 INFO - PROCESS | 1674 | Values: 72.0 79.0 77.0 140.0 59.0 57.0 59.0 66.0 163.0 55.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:69.50 stddev:23.00 (33.1%) stddev-sans-first:23.25 05:18:40 INFO - PROCESS | 1674 | Values: 68.0 75.0 115.0 71.0 67.0 90.0 113.0 68.0 83.0 129.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.00 stddev:1.77 (2.0%) stddev-sans-first:1.87 05:18:40 INFO - PROCESS | 1674 | Values: 88.0 91.0 89.0 87.0 90.0 86.0 90.0 87.0 89.0 86.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:96.00 stddev:8.33 (8.7%) stddev-sans-first:8.71 05:18:40 INFO - PROCESS | 1674 | Values: 95.0 102.0 95.0 97.0 97.0 122.0 96.0 96.0 94.0 97.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:92.00 stddev:5.83 (6.3%) stddev-sans-first:6.13 05:18:40 INFO - PROCESS | 1674 | Values: 92.0 92.0 92.0 92.0 96.0 90.0 92.0 95.0 91.0 110.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:69.00 stddev:12.83 (18.6%) stddev-sans-first:13.32 05:18:40 INFO - PROCESS | 1674 | Values: 58.0 59.0 57.0 61.0 77.0 84.0 50.0 51.0 80.0 78.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:79.50 Median:76.00 stddev:12.08 (15.9%) stddev-sans-first:11.78 05:18:40 INFO - PROCESS | 1674 | Values: 66.0 75.0 98.0 101.0 86.0 77.0 81.0 71.0 69.0 71.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:89.00 stddev:9.85 (11.1%) stddev-sans-first:10.33 05:18:40 INFO - PROCESS | 1674 | Values: 87.0 86.0 88.0 90.0 93.0 82.0 90.0 89.0 118.0 89.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:142.50 stddev:9.48 (6.7%) stddev-sans-first:6.41 05:18:40 INFO - PROCESS | 1674 | Values: 166.0 151.0 155.0 140.0 142.0 142.0 143.0 134.0 138.0 141.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.80 Median:170.00 stddev:19.76 (11.6%) stddev-sans-first:20.80 05:18:40 INFO - PROCESS | 1674 | Values: 171.0 169.0 166.0 163.0 189.0 225.0 195.0 168.0 164.0 168.0 05:18:40 INFO - PROCESS | 1674 | 05:18:40 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:69.00 stddev:2.17 (3.1%) stddev-sans-first:2.29 05:18:40 INFO - PROCESS | 1674 | Values: 68.0 66.0 69.0 65.0 69.0 64.0 70.0 65.0 69.0 69.0 05:18:40 INFO - PROCESS | 1674 | -------- Summary: end -------- 05:18:40 INFO - PROCESS | 1674 | 05:18:41 INFO - TEST-INFO | 1674: exit 0 05:18:41 INFO - TEST-OK | kraken | took 60488ms 05:18:41 INFO - SUITE-END | took 1171s 05:18:42 INFO - Completed test suite (00:19:31) 05:18:42 INFO - javascript benchmark 05:18:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7159.679418442312, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3435.1755578661123, 3470.913227169322, 3482.7387945904034, 3503.299340131974, 3505.1412403961026, 5670.234518340023, 5722.771089156434, 5734.971325143374, 5823.126531020349, 5853.297604287481, 5296.973515132419, 5583.748731307091, 5718.313802343725, 5809.622374545652, 5810.91283630747, 4280.678949078816, 5183.585313174955, 5258.158694608864, 5315.813946511873, 5381.623286369956, 60.3137030579287, 5254.343207099113, 5323.387810401795, 5383.246345511612, 5386.461353864622, 5242.5281724644965, 5255.842324730264, 5271.077561426735, 5280.996610643984, 5291.920621190685, 5305.31030965972, 5306.5754739621125, 5316.6544174628525, 5338.065838478289, 5347.6256662033675, 5121.027004869063, 5141.562967147797, 5176.404713457963, 5220.034293655686, 5246.081935661244, 5567.916481252784, 5634.830955071355, 5683.5737319700775, 5711.685857277848, 5730.656160630376, 6114.082887566856, 6136.570440069197, 6220.222472190976, 6242.09489624002, 6331.240251169843, 6125.693326622278, 6148.538859585549, 6199.659018753967, 6268.091126786636, 6272.3100458949475, 5978.342382337939, 5984.461398474158, 5993.041113421875, 5995.954753165057, 6043.969780151115, 6138.664296166039, 6230.688465576703, 6350.333215012357, 6385.712642931079, 6400.103985442041, 6139.754409823647, 6192.225971753531, 6198.2252218472695, 6216.191895053614, 6230.408111229426, 6202.34875338083, 6210.254769427697, 6288.748450062038, 6296.150019747367, 6309.684515774193, 6126.938730612636, 6172.105044768484, 6177.536684748662, 6295.968520157461, 6323.30443651198, 6117.675163990694, 6166.537509686791, 6265.8120256392385, 6270.529710271748, 6303.149074874833, 4871.415430148405, 4934.950650493521, 4959.355283813081, 4960.355153829979, 4968.503149685002, 4357.716748411343, 4394.670399720034, 4424.159409712144, 4498.752568608758, 4501.819927202875, 5919.763209471659, 5945.554083443759, 6005.729742161613, 6018.909716354258, 6034.547408944259, 6242.875142497124, 6248.4376406123665, 6268.279147897959, 6286.717097730613, 6290.0879372491645, 5644.6330988485615, 5717.971410143005, 5817.0, 5859.472647461664, 6016.969915150484, 5445.618806683494, 5448.945510544923, 5460.262864513319, 5463.180522921593, 5488.835334939959, 5994.430529099815, 6436.452901503331, 6454.354564543602, 6454.870902581922, 6465.288818229991, 6166.506679465542, 6182.598131121463, 6195.411435913581, 6210.099535567408, 6268.279147897868, 6355.618662880242, 6409.647469389229, 6431.517636177212, 6441.259255185713, 6450.8064758057335, 6173.753049877954, 6245.781397651061, 6246.406591373812, 6255.812325630239, 6266.154069200782, 5881.823545293477, 5938.66380064482, 6192.071189321638, 6236.126942228182, 6284.4344009039405, 6078.54410919174, 6140.416660417253, 6140.416660417253, 6146.139540464249, 6151.200343955257, 6013.188219590299, 6165.075238714228, 6166.630002199882, 6167.136600875791, 6180.536459765445, 5451.0, 5499.395066542597, 5967.433093856077, 6014.759409623566, 6063.151158837854, 6029.728662210124, 6063.757449701962, 6136.355682653436, 6211.409916057967, 6262.655553944394, 5388.865278368072, 5796.536277097738, 5950.375210602998, 6016.939830601638, 6096.237970253718], "value": 5699.126878461927, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.08831559035946, 507.6192855358481, 520.5367223171376, 533.3519773475227, 557.8940001399733, 36172.09569760754, 39405.408918866204, 39684.60315396845, 39697.404538931936, 39775.40336894949, 40915.79542102293, 41300.38049429261, 41440.792796036054, 41691.0, 41785.373219401656, 9420.246380289578, 9606.135447809696, 9683.12851843334, 9715.125638692516, 9730.951345243298, 9525.857112143323, 9615.567299471506, 9641.084097010773, 9657.613695452208, 9687.709368718915, 9642.75893102671, 9664.95167524165, 9667.12995830375, 9690.515474226295, 9711.660091896785, 9595.376300540478, 9618.422894626345, 9652.034796520291, 9663.855042174373, 9667.081627245401, 9491.335606507548, 9560.330776845623, 9595.568199431042, 9600.807983840285, 9623.615055397775, 38783.03042423934, 38815.02962425934, 39199.41200881989, 39205.823825285, 39243.60756392457, 3453.2748122894222, 3548.840302186408, 3577.928441431183, 3691.0956815580084, 3964.9008774780573, 30277.394452111053, 30917.917872874565, 31123.37753244945, 31567.36865262705, 31685.0, 3023.516237402027, 3168.9683103168904, 3179.60254968129, 3180.3003339265324, 3180.6819318068237, 447780.0, 514950.0, 522738.0, 524700.0, 527001.0, 33409.0, 61224.0, 61506.38493615007, 61961.0, 62049.0, 28404.153730007485, 35077.824610876785, 35348.469772953424, 35431.0, 35590.8220458896, 14470.27648617565, 14577.271136443136, 14683.632909177357, 14759.704805903822, 14794.112353258839, 12362.814557781641, 12475.625731228078, 12553.748925021448, 12573.434195461226, 12583.433745481476, 7717.729879454192, 7745.806354841061, 7774.781555454718, 7812.765617031499, 7814.765557033298, 2864.957025644617, 3262.6411094779555, 3280.622138701751, 3282.5240340150544, 3296.1706253029797, 934.7476181430976, 945.664289177346, 1020.2552136940076, 1120.3613940054254, 1131.5530365505745, 25774.484510310063, 27279.45441091207, 29557.704422955925, 29585.0, 30229.395412091635, 1051.747580580655, 1077.1275267033727, 1085.8914108589079, 1103.194667892443, 1164.8660404053473, 14474.782878256834, 15433.5369938902, 15500.92249538745, 15701.371945122068, 15764.605884852741, 675552.6222368857, 677694.0, 699870.0, 702945.0, 710786.0, 55471.44528554744, 57555.712221438625, 57619.55951101172, 57639.677024223354, 57803.71098144483, 1407.1205496564646, 1425.49394964786, 1438.2521089033644, 1446.5298777897226, 1450.1951416963475, 29190.41619167647, 29334.70665293405, 29401.41197176002, 30034.399312014073, 30101.397972040875, 41323.58676413318, 49475.50524494709, 49576.0, 50084.74788130326, 50131.0, 555196.0, 556831.0, 561383.0, 563016.0, 563948.0, 26330.0, 26906.05828796061, 27665.170044898685, 27776.16671499858, 28503.0, 46192.769036154605, 47066.52933470622, 47124.528754713385, 47571.524284756706, 48401.0, 38307.808460957516, 38601.6139838598, 39098.2180356397, 39435.40846887356, 39727.0, 37508.24983500369, 38231.42652860265, 38461.230775384895, 38850.61149388583, 38988.41517377185, 37169.0, 37462.8126859364, 37583.496660134384, 37670.24659506849, 38523.614763852005, 32916.50625240576, 34163.0, 34334.65665343415, 34379.484307735904, 34514.96455106351, 20648.793512064687, 24558.754412455648, 25048.874755626104, 25129.0, 25463.0], "value": 18677.78651779761, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.2570996571803, 377.7997661239544, 381.62600651361663, 383.1914660067259, 404.24204616344355, 14349.856501434982, 21263.25578604749, 21279.97840205178, 22020.559588808235, 22093.889530552366, 18528.0, 20394.89802550989, 21187.258445954394, 21291.0, 21599.568008639817, 5718.857028574298, 5734.025215713339, 5945.732442040118, 5970.611910225822, 5982.312034116088, 5799.65202087874, 5873.676947767871, 5911.497522710553, 5911.674857882815, 5918.792842250522, 5575.968445837533, 5856.590038697293, 5865.85335366615, 5893.38119497453, 5905.557083218754, 5764.769409223626, 5783.826485205451, 5814.912776308359, 5836.15375770513, 5855.8536036599, 5046.697198168122, 5080.314157588752, 5092.618053645954, 5105.851183483724, 5121.078205922932, 17447.65104697899, 25615.615765763527, 25955.831987560607, 26583.468330633474, 27526.0, 2166.3176099528596, 2181.6291230490856, 2482.900683972639, 2513.1078467144266, 2566.371239046427, 14776.113433193936, 15293.388264469408, 15392.769108463383, 15488.07071575709, 15679.372825086983, 1836.540864783804, 1851.3705340184401, 1854.2953677602425, 1883.5761953560477, 1904.238304678126, 116273.25590116155, 119298.0, 119857.20214196794, 120396.39801801022, 121307.78692213142, 9342.851144236864, 10758.0, 10956.452177391098, 11453.885461145283, 11505.252158609663, 13793.517226897211, 13853.792193117111, 13914.443422263199, 13939.65150871216, 13942.0, 8489.0, 8562.100979397073, 8709.782255443664, 8753.299736021103, 8783.429077109968, 8750.212480876751, 8800.207981281588, 8802.955985220033, 8862.42394244372, 8899.199072083544, 5578.41426650204, 5641.435856414408, 6549.967250163719, 6790.898136527956, 6804.421624161902, 1822.6992546229724, 1853.7219417087545, 1860.7487989121296, 1871.4292140896885, 1903.3148066695978, 781.1719577248135, 876.4434584039182, 922.598669578722, 981.0875885426622, 1030.5156576409076, 18054.548636283937, 20620.793792061886, 21479.248226312004, 21921.0, 21948.670769938464, 958.520739630185, 966.6665000574791, 1034.2243317511866, 1073.1575713065206, 1102.4873433853297, 10134.746631334276, 10766.73083172927, 11441.885581144083, 11812.822807657893, 11882.643520694393, 540900.0, 555689.0, 572895.0, 613163.0, 617087.0, 73436.63281683557, 110456.58173672963, 112668.43665781619, 114957.0, 117382.0, 1012.3318609717551, 1019.4291196929595, 1037.9429131397847, 1040.287403128859, 1043.118564812732, 15949.920250398674, 19377.806221938165, 19471.0, 19482.22071117196, 19596.314129005415, 38012.0, 49235.52293431203, 51747.482525174266, 51832.039315479204, 51850.740746296025, 71698.0, 71843.64078179575, 72070.27929720846, 72499.0, 73015.0, 7127.0, 7928.682852685828, 7955.154281178734, 8179.918200818153, 8228.189798244377, 10264.332818366782, 11072.0, 12085.456154473246, 12562.43469043877, 13042.282674452996, 8792.47245165292, 11022.173336999918, 11242.213045086766, 11254.7749045017, 11293.265937714023, 10897.455127243764, 11044.558217671203, 11054.834177487504, 11136.38749868765, 11138.3873886937, 10994.90050994894, 11169.441527923735, 11338.943305283421, 11370.54517819311, 11484.770304594029, 7423.257674232534, 7931.603419829101, 8013.278804907586, 8161.918380816116, 8176.754697359089, 10786.946065269623, 11068.667939961813, 11214.439278035903, 11252.93097155769, 11255.718607034889], "value": 9630.314672789893, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1522.8553287437694, 1528.3733669195633, 1747.239950621479, 1878.71819227116, 1947.376839411389, 7957.92042079579, 8447.0, 8492.235698787108, 8952.955235223815, 8975.685850995216, 8587.398882078258, 8729.476231426119, 8828.558572071403, 8847.513386763725, 8859.95570022149, 2344.636581329892, 2363.4682196505732, 2365.124903785602, 2371.7983971362455, 2382.6664267002634, 2346.4368551547677, 2352.870592117433, 2396.928092157238, 2401.2796161151673, 2413.5655581995234, 2361.811055115595, 2370.9881450592725, 2377.3699969508093, 2392.079049565912, 2392.712874455068, 2335.556244313575, 2338.2283846330756, 2338.5673650374733, 2358.056777289081, 2364.9763502365013, 2333.5099629077913, 2344.906203751848, 2347.542229265294, 2359.3393849722106, 2361.3506285771377, 8773.034966153718, 8846.823063538694, 8878.067802880669, 8878.911210887873, 8893.688720894737, 5199.376074871039, 5335.359756829205, 5369.194620806871, 5376.327959005124, 5376.354837419495, 3426.3832510148163, 3466.376052310583, 3476.3221171871496, 3523.925202813141, 3574.660407261306, 916.4592890194817, 1051.6582110814015, 1052.4685034057752, 1056.3661802918264, 1060.199549340251, 11439.59961401347, 11633.76732465346, 11642.0, 11646.94176529112, 11759.7060073498, 3948.545917219499, 4686.414198225222, 4820.566149046603, 4821.421429428491, 4834.057358814997, 9937.503124843728, 9938.850917236246, 9942.602295908082, 9946.850797238047, 9968.900310996942, 5293.894122117536, 5347.759350829144, 5351.919721204185, 5359.1157459019605, 5403.081476149064, 4362.476502819682, 4691.695039822401, 4835.492273311322, 4847.830325938575, 4923.8276660316715, 3804.6956243500454, 3900.1419687668667, 3908.4332771747936, 3918.6669133123432, 3990.3615421532418, 1112.7718817642365, 1308.6662900960193, 1319.0436933223527, 1323.5103011885665, 1327.7477279317093, 828.2297463359134, 919.7838507950627, 936.2509992006504, 946.0161432110665, 946.8248374050822, 5906.379830117861, 5946.43508866657, 6000.489958353589, 6028.4272994065495, 6098.0852872069545, 878.5870640798814, 984.4388698441962, 988.0712130597216, 1007.1992765751245, 1013.1843865688045, 3936.389859571771, 4006.779627120536, 4019.83920643171, 4043.0700938783657, 4046.393041043808, 10463.424511651932, 10695.625653102104, 10843.783124337628, 10865.891341086488, 10871.782564348827, 10704.357738535713, 10805.02754752076, 10839.403832789114, 10859.40273284961, 10862.076723478593, 7853.921460785434, 8103.716369927024, 8169.346452283803, 8210.384221183434, 8244.917550824535, 5635.8591035224445, 5829.300483941955, 5846.795362162474, 5862.062070068769, 5916.585838991401, 9893.703188904345, 10042.548085336324, 10048.246381521269, 10053.0, 10083.495825208563, 9742.0, 10056.044675755791, 10215.0, 10244.948775256076, 10248.333858299187, 6525.282218956006, 6833.4191593715095, 7009.859802803813, 7042.577445353295, 7158.391536719437, 8381.0, 8409.285210756909, 8482.363822713445, 8484.533350665773, 8518.74443766688, 7272.854542909218, 7349.742759003409, 7505.849883002418, 7598.696052157852, 7627.4279429041935, 7166.677499512639, 7349.448791340564, 7379.9262007381385, 7416.147143078615, 7475.43934204926, 6972.9302706972285, 7061.046758687511, 7065.364117229474, 7088.468364872552, 7090.929090709027, 4830.372051633265, 4875.414950205856, 5003.999200159909, 5030.471800461045, 5057.797688092583, 4997.8750531235555, 5065.544101030925, 5097.6941383517105, 5100.362454693163, 5162.354705661793], "value": 4604.517571647566, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [267.29700886668064, 297.8451205373206, 314.4418656884031, 317.35735136348904, 318.37757184703906, 5361.437049109841, 5372.838814835552, 5395.136778115502, 5617.775288988436, 5649.3220813502385, 5255.7109358985335, 5496.7526461309235, 5547.833564993057, 5566.805161819337, 5582.358028826686, 3472.8958131256104, 4188.329867221245, 4216.451861258032, 4232.0054787125, 4273.059926816096, 3898.894596917661, 4158.521769996459, 4165.250254954107, 4250.362445633149, 4253.893652658693, 4106.240345536085, 4110.28070087735, 4124.401961715549, 4132.938005929898, 4153.854615088472, 4181.560936101711, 4187.267228235047, 4194.022244206586, 4203.831846726127, 4212.010177608275, 3092.087834088949, 3224.91937701557, 3443.3974054540604, 3642.3807952648112, 3651.561812582507, 5156.215643552478, 5327.893442131174, 5369.9463005369835, 5370.5435038021815, 5376.677399356051, 4025.4565633639377, 4238.004069043773, 4580.9541904580865, 4752.884966190047, 4822.614190864722, 5690.703518843288, 5832.329282132524, 5837.526671630373, 5843.707814609253, 5872.533535914926, 3504.9846338355546, 3967.841286348543, 3988.641022308006, 4010.3783913493453, 4029.294873397144, 5008.474110218411, 6066.484348830355, 6170.598911070766, 6178.845528861724, 6263.43629073381, 3746.3256613809776, 3799.297130030953, 3857.8528082311627, 4034.636882680573, 4053.1893621275863, 3644.1436262478296, 3984.3425834737523, 4099.098198396348, 4114.753114813121, 4139.19285739278, 4283.850065247701, 4604.447466304065, 4612.0545288215835, 4698.3187437821325, 4752.809887604526, 4565.383673204141, 5273.630845840754, 5536.4186760390385, 5931.881362372728, 5933.6736479493175, 1103.8023744237432, 1377.22875189894, 1378.0422606288532, 1397.8886785005946, 1403.5719105672663, 808.3492788305471, 839.5130824121994, 851.9446235994641, 872.2542226396466, 876.7895705030875, 4068.0506632691063, 4127.997921008308, 4516.6160876325885, 4594.105713400603, 4624.14453326129, 4469.776511174493, 4947.282644016669, 5060.392752869606, 5168.90659282991, 5176.844694659091, 4182.2681030819485, 4341.196878577473, 4391.845128630978, 4501.887452813706, 4717.976410117927, 2545.5290771207387, 2565.127456956432, 2605.1272823603927, 2653.349929267303, 2712.8372297662204, 4564.908701825945, 4568.428946381702, 4685.5782979532005, 4902.656814022984, 5371.301730774974, 4776.331313616027, 4816.662833601685, 4826.348442960156, 4891.755412229374, 5110.488951104934, 4214.178235244098, 4588.875725447287, 5158.0, 5213.504717051874, 5384.546172533473, 4293.91412171761, 4658.8136474542, 4970.975145124396, 5072.365954255718, 5113.386393632788, 4488.329443980826, 4590.403247577793, 4924.926126108039, 5020.0, 5073.822416215415, 4338.761368124658, 4659.136345461842, 5280.577553795764, 5294.258803767552, 5359.651622644516, 2943.7792165588094, 3498.003069125287, 3505.807180605091, 3520.436730123168, 3584.874529391459, 4742.976285118691, 4871.318015477765, 4874.658773885794, 4881.414230292387, 5774.682392468455, 5440.700761458158, 5868.68187066115, 5877.500412464989, 5901.232839730807, 5902.586818922634, 5172.5516855279075, 5398.568114550905, 5864.706764661835, 5878.2358293421585, 5918.674472904032, 5492.203630473638, 5570.610057296112, 5672.858178545569, 5718.885622287614, 5747.885042299215, 4913.975430122827, 5570.331560212639, 5866.914620795166, 5919.260092488439, 5946.643201407929, 4756.904861902812, 5536.695481748542, 5756.942430575808, 5762.818622182359, 5764.7405866736935], "value": 3989.8012682368744, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.9741009064683, 815.9959200203995, 833.1127349372917, 843.5697794124994, 884.3676771108658, 14403.639909002268, 14780.039297445659, 14842.703145937076, 14987.775183372258, 15027.624309392271, 10014.599416023368, 13642.31788410578, 13871.167729936185, 13926.373313200906, 13940.58178254654, 706.9752558660448, 871.6600525794945, 889.5463313709989, 904.7692838326251, 904.9773755656096, 892.8660700894885, 902.2466240689, 903.412781691899, 904.398574947659, 905.1582028713294, 944.187998321443, 947.943123412594, 948.8766460360143, 952.7856232347701, 956.297121615612, 912.771807048238, 942.7737343037655, 948.4498990585513, 948.6063283737241, 948.6964171465169, 704.4470090978557, 705.9858802823966, 709.3332267668375, 709.5458906299972, 711.6441779110445, 14642.0, 14698.191599462025, 14698.55904322872, 14701.70596588073, 14715.852841471555, 13885.652858678513, 13964.371603277776, 14006.789898151535, 14040.1575905446, 14307.0, 9526.90473095274, 11576.710582235428, 11634.0, 11693.532258709642, 11750.361046009062, 3770.2082562661876, 3777.1690228149764, 3823.254465379252, 3850.5571859236265, 3852.383618621035, 86159.13840861573, 86859.13140868573, 87601.56199218963, 88040.55979720125, 88782.0, 9443.291753118543, 10279.074883260542, 10638.0, 10740.0, 10756.784864302826, 21138.471538211666, 21179.258725944517, 21185.470363240733, 21232.0, 21241.893790530947, 7790.649420776079, 7812.218778122256, 7903.960480197562, 7947.960260198662, 8069.031716194094, 4352.7823608819435, 4392.1874453226255, 4439.267520859022, 4450.977745111254, 4554.840580579664, 3387.187075101958, 3502.194495266103, 3506.263684626257, 3512.841922113511, 3514.7715398499017, 636.0554576453962, 647.0876064748776, 649.331188875459, 665.5773583774338, 665.816900352407, 651.6253154436218, 682.7200847652439, 688.0264425837385, 702.1714377035148, 707.9362857342863, 9145.862812057825, 9220.49287289192, 9232.353735238468, 9336.346455748168, 9423.246140308758, 661.5567569728294, 664.2261765043767, 670.872534218497, 680.2483256002047, 697.9825504362432, 4421.2262854001065, 4445.599896009375, 4451.554844515587, 4469.128519938646, 4476.597106260452, 90723.0, 93612.53193733988, 94690.05309946812, 95923.0, 96231.03768962361, 64931.67534162394, 79682.20317796864, 83148.58425707954, 84087.57956210303, 85057.57471212729, 75602.9999999989, 75722.24277757262, 76127.23872761201, 76337.0, 76399.61800191074, 7475.2151024141685, 8408.117147699404, 8753.431026983226, 8804.207621314112, 8862.468251904906, 44252.77873610611, 44917.631177661155, 46319.76840115778, 46422.767886160356, 46518.0, 59665.0, 60267.69866150641, 62755.37244627495, 64211.678941604994, 64515.70968580508, 11766.294022358687, 11868.465919033491, 11891.881081189078, 11944.64166075019, 11972.281663099893, 11436.656900293005, 12188.329641869428, 12436.316002619942, 12784.0, 12972.545960891324, 11428.885711142782, 11531.654050378502, 11707.707307317385, 11761.705957351134, 11877.881221187678, 9379.0, 11632.651020469399, 11820.40897955116, 12152.817707734568, 12207.633770986884, 11357.602483913024, 11438.94280528592, 11609.419529023684, 11734.413279335828, 11980.58067967617, 8326.6669333226, 8933.597988090421, 9189.586468608795, 9462.763430914281, 10166.237532184969, 9503.239740820856, 9799.559019844266, 9832.0, 9855.556499957376, 9929.751756206153], "value": 7151.668323585466, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 117.0, 112.0, 116.0, 120.0, 149.0, 172.0, 125.0, 117.0], "value": 125.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 127.0, 179.0, 162.0, 120.0, 133.0, 128.0, 135.0, 168.0, 159.0], "value": 146.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 199.0, 203.0, 268.0, 271.0, 199.0, 267.0, 273.0, 191.0, 276.0], "value": 234.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 77.0, 140.0, 59.0, 57.0, 59.0, 66.0, 163.0, 55.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 115.0, 71.0, 67.0, 90.0, 113.0, 68.0, 83.0, 129.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 89.0, 87.0, 90.0, 86.0, 90.0, 87.0, 89.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 102.0, 95.0, 97.0, 97.0, 122.0, 96.0, 96.0, 94.0, 97.0], "value": 99.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 92.0, 96.0, 90.0, 92.0, 95.0, 91.0, 110.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 57.0, 61.0, 77.0, 84.0, 50.0, 51.0, 80.0, 78.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 75.0, 98.0, 101.0, 86.0, 77.0, 81.0, 71.0, 69.0, 71.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 90.0, 93.0, 82.0, 90.0, 89.0, 118.0, 89.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 151.0, 155.0, 140.0, 142.0, 142.0, 143.0, 134.0, 138.0, 141.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 166.0, 163.0, 189.0, 225.0, 195.0, 168.0, 164.0, 168.0], "value": 177.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 69.0, 65.0, 69.0, 64.0, 70.0, 65.0, 69.0, 69.0], "value": 67.4, "unit": "score"}]}]} 05:18:42 INFO - Return code: 0 05:18:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:18:42 ERROR - # TBPL SUCCESS # 05:18:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:18:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:18:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:18:42 INFO - 2016-05-30 05:18:42.245 screenresolution[1679:10338] starting screenresolution argv=screenresolution get 05:18:42 INFO - 2016-05-30 05:18:42.248 screenresolution[1679:10338] Display 0: 1600x1200x32@60 05:18:42 INFO - 2016-05-30 05:18:42.254 screenresolution[1680:10341] starting screenresolution argv=screenresolution list 05:18:42 INFO - Available Modes on Display 0 05:18:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:18:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:18:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:18:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:18:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:18:42 INFO - Intel Iris: 05:18:42 INFO - Chipset Model: Intel Iris 05:18:42 INFO - Type: GPU 05:18:42 INFO - Bus: Built-In 05:18:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:18:42 INFO - Vendor: Intel (0x8086) 05:18:42 INFO - Device ID: 0x0a2e 05:18:42 INFO - Revision ID: 0x0009 05:18:42 INFO - Displays: 05:18:42 INFO - Display: 05:18:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:18:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:18:42 INFO - Display Serial Number: 1600x1200 60 05:18:42 INFO - Main Display: Yes 05:18:42 INFO - Mirror: Off 05:18:42 INFO - Online: Yes 05:18:42 INFO - Rotation: Supported 05:18:42 INFO - Return code: 0 05:18:42 INFO - Running post-action listener: _resource_record_post_action 05:18:42 INFO - ##### 05:18:42 INFO - ##### Finished run-tests step (success) 05:18:42 INFO - ##### 05:18:42 INFO - Running post-run listener: _resource_record_post_run 05:18:42 INFO - Total resource usage - Wall time: 1207s; CPU: 55.0%; Read bytes: 37316096; Write bytes: 697528320; Read time: 392; Write time: 2224 05:18:42 INFO - TinderboxPrint: CPU usage
54.6% 05:18:42 INFO - TinderboxPrint: I/O read bytes / time
37,316,096 / 392 05:18:42 INFO - TinderboxPrint: I/O write bytes / time
697,528,320 / 2,224 05:18:42 INFO - TinderboxPrint: CPU idle
2,190.4 (45.4%) 05:18:42 INFO - TinderboxPrint: CPU system
971.7 (20.1%) 05:18:42 INFO - TinderboxPrint: CPU user
1,667.6 (34.5%) 05:18:42 INFO - TinderboxPrint: Swap in / out
435,306,496 / 0 05:18:42 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 399241728; Write bytes: 387978752; Read time: 29293; Write time: 726 05:18:42 INFO - run-tests - Wall time: 1174s; CPU: 55.0%; Read bytes: 30182912; Write bytes: 294785536; Read time: 303; Write time: 1398 05:18:42 INFO - Running post-run listener: _upload_blobber_files 05:18:42 INFO - Blob upload gear active. 05:18:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:18:42 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:18:42 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:18:42 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:18:43 INFO - (blobuploader) - INFO - Open directory for files ... 05:18:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:18:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:18:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:18:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:18:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:18:44 INFO - (blobuploader) - INFO - Done attempting. 05:18:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:18:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:18:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:18:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:18:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:18:45 INFO - (blobuploader) - INFO - Done attempting. 05:18:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:18:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:18:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:18:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:18:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:18:47 INFO - (blobuploader) - INFO - Done attempting. 05:18:47 INFO - (blobuploader) - INFO - Iteration through files over. 05:18:47 INFO - Return code: 0 05:18:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:18:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:18:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30cf5773f326a1f5a7ac82572180619c2f412266e0d4bd01a269b7d07fd632964b94734e183c29afc4da024b51189b44ad0e159eefeb306ff220135f036a2f3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac6458f58799e5560bb53de635fbe1b97eec00d43fcb8c637fd21859cadb52fa3be2377ea3a82f733a631034e5bb08fd580440f93d1f5e555310a35820d79826", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8545ef12421051777845c6d9d8421dcedb0b92c696824cfe77ef9b3a5354be726c30774e0eb4c4d11072ffbaae34deb182196d6949848a8eee6cf97d6c7110e9"} 05:18:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:18:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:18:47 INFO - Contents: 05:18:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30cf5773f326a1f5a7ac82572180619c2f412266e0d4bd01a269b7d07fd632964b94734e183c29afc4da024b51189b44ad0e159eefeb306ff220135f036a2f3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac6458f58799e5560bb53de635fbe1b97eec00d43fcb8c637fd21859cadb52fa3be2377ea3a82f733a631034e5bb08fd580440f93d1f5e555310a35820d79826", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8545ef12421051777845c6d9d8421dcedb0b92c696824cfe77ef9b3a5354be726c30774e0eb4c4d11072ffbaae34deb182196d6949848a8eee6cf97d6c7110e9"} 05:18:47 INFO - Running post-run listener: copy_logs_to_upload_dir 05:18:47 INFO - Copying logs to upload dir... 05:18:47 INFO - mkdir: /builds/slave/test/build/upload/logs 05:18:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1292.945554 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-30 05:18:48.017181) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 05:18:48.020765) ========= 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.ev9ku8We61/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.XQcpueCa2b/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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/30cf5773f326a1f5a7ac82572180619c2f412266e0d4bd01a269b7d07fd632964b94734e183c29afc4da024b51189b44ad0e159eefeb306ff220135f036a2f3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac6458f58799e5560bb53de635fbe1b97eec00d43fcb8c637fd21859cadb52fa3be2377ea3a82f733a631034e5bb08fd580440f93d1f5e555310a35820d79826", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8545ef12421051777845c6d9d8421dcedb0b92c696824cfe77ef9b3a5354be726c30774e0eb4c4d11072ffbaae34deb182196d6949848a8eee6cf97d6c7110e9"} build_url:https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011227 build_url: 'https://queue.taskcluster.net/v1/task/PS5lx4tqSnSym3gSOGr97A/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/30cf5773f326a1f5a7ac82572180619c2f412266e0d4bd01a269b7d07fd632964b94734e183c29afc4da024b51189b44ad0e159eefeb306ff220135f036a2f3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac6458f58799e5560bb53de635fbe1b97eec00d43fcb8c637fd21859cadb52fa3be2377ea3a82f733a631034e5bb08fd580440f93d1f5e555310a35820d79826", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8545ef12421051777845c6d9d8421dcedb0b92c696824cfe77ef9b3a5354be726c30774e0eb4c4d11072ffbaae34deb182196d6949848a8eee6cf97d6c7110e9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 05:18:48.051315) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:18:48.051655) ========= 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.ev9ku8We61/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.XQcpueCa2b/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.004545 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:18:48.100238) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 05:18:48.100580) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 05:18:48.100934) ========= ========= Total master_lag: 0.16 =========