builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0048 starttime: 1463140568.93 results: success (0) buildid: 20160513025845 builduid: 0b7ad7f59ffe4a82bbbc32c5b1f91521 revision: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:08.932649) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:08.933666) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:08.934388) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:08.954252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:08.954999) ========= 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.f5gnTKWZCd/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.z3jlXfDkec/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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-13 04:56:08-- 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% 14.9M=0.001s 2016-05-13 04:56:09 (14.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140766 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:09.118659) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:09.119414) ========= 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.f5gnTKWZCd/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.z3jlXfDkec/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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.028174 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:09.172438) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 04:56:09.173434) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 --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 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f5gnTKWZCd/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.z3jlXfDkec/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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-13 04:56:09,250 truncating revision to first 12 chars 2016-05-13 04:56:09,250 Setting DEBUG logging. 2016-05-13 04:56:09,250 attempt 1/10 2016-05-13 04:56:09,250 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/943ca7ad1ea3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 04:56:10,619 unpacking tar archive at: mozilla-central-943ca7ad1ea3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.617216 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 04:56:10.808759) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:10.809140) ========= script_repo_revision: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:10.809608) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:10.809934) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 04:56:10.823425) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-13 04:56:10.823760) ========= /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.f5gnTKWZCd/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.z3jlXfDkec/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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:56:11 INFO - MultiFileLogger online at 20160513 04:56:11 in /builds/slave/test 04:56:11 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:56:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:56:11 INFO - {'append_to_log': False, 04:56:11 INFO - 'base_work_dir': '/builds/slave/test', 04:56:11 INFO - 'blob_upload_branch': 'Firefox', 04:56:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:56:11 INFO - 'branch': 'Firefox', 04:56:11 INFO - 'buildbot_json_path': 'buildprops.json', 04:56:11 INFO - 'config_files': ('talos/mac_config.py',), 04:56:11 INFO - 'default_actions': ('clobber', 04:56:11 INFO - 'read-buildbot-config', 04:56:11 INFO - 'download-and-extract', 04:56:11 INFO - 'populate-webroot', 04:56:11 INFO - 'create-virtualenv', 04:56:11 INFO - 'install', 04:56:11 INFO - 'run-tests'), 04:56:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:56:11 INFO - 'download_minidump_stackwalk': True, 04:56:11 INFO - 'download_symbols': 'ondemand', 04:56:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:56:11 INFO - 'tooltool.py': '/tools/tooltool.py', 04:56:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:56:11 INFO - '/tools/misc-python/virtualenv.py')}, 04:56:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:56:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:56:11 INFO - 'installer_path': 'installer.exe', 04:56:11 INFO - 'log_level': 'info', 04:56:11 INFO - 'log_name': 'talos', 04:56:11 INFO - 'log_to_console': True, 04:56:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:56:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:56:11 INFO - 'opt_config_files': (), 04:56:11 INFO - 'pip_index': False, 04:56:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:56:11 INFO - 'cmd': ('bash', 04:56:11 INFO - '-c', 04:56:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:56:11 INFO - 'enabled': True, 04:56:11 INFO - 'halt_on_failure': False, 04:56:11 INFO - 'name': 'check_screen_resolution'},), 04:56:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:56:11 INFO - 'cmd': ('bash', 04:56:11 INFO - '-c', 04:56:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:56:11 INFO - 'enabled': True, 04:56:11 INFO - 'halt_on_failure': False, 04:56:11 INFO - 'name': 'check_screen_resolution'},), 04:56:11 INFO - 'run_cmd_checks_enabled': True, 04:56:11 INFO - 'sps_profile': False, 04:56:11 INFO - 'sps_profile_interval': 0, 04:56:11 INFO - 'suite': 'dromaeojs-e10s', 04:56:11 INFO - 'system_bits': '32', 04:56:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:56:11 INFO - 'title': 't-yosemite-r7-0048', 04:56:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:56:11 INFO - 'use_talos_json': True, 04:56:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:56:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:56:11 INFO - 'work_dir': 'build'} 04:56:11 INFO - ##### 04:56:11 INFO - ##### Running clobber step. 04:56:11 INFO - ##### 04:56:11 INFO - Running pre-action listener: _resource_record_pre_action 04:56:11 INFO - Running main action method: clobber 04:56:11 INFO - rmtree: /builds/slave/test/build 04:56:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:56:12 INFO - Running post-action listener: _resource_record_post_action 04:56:12 INFO - ##### 04:56:12 INFO - ##### Finished clobber step (success) 04:56:12 INFO - ##### 04:56:12 INFO - ##### 04:56:12 INFO - ##### Running read-buildbot-config step. 04:56:12 INFO - ##### 04:56:12 INFO - Running pre-action listener: _resource_record_pre_action 04:56:12 INFO - Running main action method: read_buildbot_config 04:56:12 INFO - Using buildbot properties: 04:56:12 INFO - { 04:56:12 INFO - "project": "", 04:56:12 INFO - "product": "firefox", 04:56:12 INFO - "script_repo_revision": "production", 04:56:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:56:12 INFO - "repository": "", 04:56:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:56:12 INFO - "buildid": "20160513025845", 04:56:12 INFO - "pgo_build": "False", 04:56:12 INFO - "basedir": "/builds/slave/test", 04:56:12 INFO - "buildnumber": 11, 04:56:12 INFO - "slavename": "t-yosemite-r7-0048", 04:56:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:56:12 INFO - "platform": "yosemite_r7", 04:56:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:56:12 INFO - "branch": "mozilla-central", 04:56:12 INFO - "repo_path": "mozilla-central", 04:56:12 INFO - "revision": "943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15", 04:56:12 INFO - "stage_platform": "macosx64", 04:56:12 INFO - "builduid": "0b7ad7f59ffe4a82bbbc32c5b1f91521", 04:56:12 INFO - "slavebuilddir": "test" 04:56:12 INFO - } 04:56:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:56:12 INFO - Running post-action listener: _resource_record_post_action 04:56:12 INFO - ##### 04:56:12 INFO - ##### Finished read-buildbot-config step (success) 04:56:12 INFO - ##### 04:56:12 INFO - ##### 04:56:12 INFO - ##### Running download-and-extract step. 04:56:12 INFO - ##### 04:56:12 INFO - Running pre-action listener: _resource_record_pre_action 04:56:12 INFO - Running main action method: download_and_extract 04:56:12 INFO - mkdir: /builds/slave/test/build/tests 04:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:12 INFO - https://queue.taskcluster.net/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:56:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:56:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:56:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/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:56:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/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:56:14 INFO - Downloaded 1199 bytes. 04:56:14 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:56:14 INFO - Using the following test package requirements: 04:56:14 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:56:14 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:56:14 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:56:14 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:56:14 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:56:14 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:56:14 INFO - u'jsshell-mac.zip'], 04:56:14 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:56:14 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:56:14 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:56:14 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:56:14 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:56:14 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:56:14 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:56:14 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:56:14 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:56:14 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:56:14 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:56:14 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:56:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:14 INFO - https://queue.taskcluster.net/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:56:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:56:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:56:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/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:56:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/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:56:15 INFO - Downloaded 18151067 bytes. 04:56:15 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:56:15 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:56:18 INFO - Return code: 0 04:56:18 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:56:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:18 INFO - https://queue.taskcluster.net/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:56:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:56:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:56:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/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:56:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/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:56:20 INFO - Downloaded 18151067 bytes. 04:56:20 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:56:20 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:56:24 INFO - Return code: 0 04:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:24 INFO - https://queue.taskcluster.net/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:56:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:56:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:56:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/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:56:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/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:56:25 INFO - Downloaded 10840239 bytes. 04:56:25 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:56:25 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:56:25 INFO - Return code: 0 04:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:25 INFO - https://queue.taskcluster.net/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:56:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:56:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:56:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:56:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZTlVMz9_TTKroKQe0U3KAw/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:56:29 INFO - Downloaded 109858440 bytes. 04:56:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:56:29 INFO - mkdir: /builds/slave/test/properties 04:56:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:56:29 INFO - Writing to file /builds/slave/test/properties/build_url 04:56:29 INFO - Contents: 04:56:29 INFO - build_url:https://queue.taskcluster.net/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:56:30 INFO - Running post-action listener: _resource_record_post_action 04:56:30 INFO - Running post-action listener: set_extra_try_arguments 04:56:30 INFO - ##### 04:56:30 INFO - ##### Finished download-and-extract step (success) 04:56:30 INFO - ##### 04:56:30 INFO - ##### 04:56:30 INFO - ##### Running populate-webroot step. 04:56:30 INFO - ##### 04:56:30 INFO - Running pre-action listener: _resource_record_pre_action 04:56:30 INFO - Running main action method: populate_webroot 04:56:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:56:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:56:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:56:30 INFO - '--tppagecycles', 04:56:30 INFO - '7'], 04:56:30 INFO - 'tests': ['tsvgm']}}, 04:56:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:56:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:56:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:56:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:56:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:30 INFO - 'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:56:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:56:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:30 INFO - 'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['damp', 'tps']}, 04:56:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:30 INFO - 'tests': ['damp', 'tps']}, 04:56:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['dromaeo_dom']}, 04:56:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:56:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'other-e10s': {'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['tsvgx', 04:56:30 INFO - 'tsvgr_opacity', 04:56:30 INFO - 'tart', 04:56:30 INFO - 'tscrollx', 04:56:30 INFO - 'cart']}, 04:56:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:56:30 INFO - 'tsvgr_opacity', 04:56:30 INFO - 'tart', 04:56:30 INFO - 'tscrollx', 04:56:30 INFO - 'cart']}, 04:56:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:30 INFO - 'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['tp5o']}, 04:56:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:30 INFO - 'tests': ['tp5o']}, 04:56:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:30 INFO - 'talos_options': ['--disable-e10s', 04:56:30 INFO - '--xperf_path', 04:56:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:56:30 INFO - 'tests': ['tp5n']}, 04:56:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:30 INFO - 'talos_options': ['--xperf_path', 04:56:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:56:30 INFO - 'tests': ['tp5n']}}, 04:56:30 INFO - 'talos.zip': {'path': '', 04:56:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:56:30 INFO - Running post-action listener: _resource_record_post_action 04:56:30 INFO - ##### 04:56:30 INFO - ##### Finished populate-webroot step (success) 04:56:30 INFO - ##### 04:56:30 INFO - ##### 04:56:30 INFO - ##### Running create-virtualenv step. 04:56:30 INFO - ##### 04:56:30 INFO - Running pre-action listener: _resource_record_pre_action 04:56:30 INFO - Running main action method: create_virtualenv 04:56:30 INFO - Creating virtualenv /builds/slave/test/build/venv 04:56:30 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:56:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:56:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:56:30 INFO - Using real prefix '/tools/python27' 04:56:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:56:31 INFO - Installing distribute.............................................................................................................................................................................................done. 04:56:34 INFO - Installing pip.................done. 04:56:34 INFO - Return code: 0 04:56:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:56:34 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:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:34 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:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:34 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 0x10bc77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8268d5c220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb1bd30>, '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 0x10b93fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb12b20>, '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.f5gnTKWZCd/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.z3jlXfDkec/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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:56:34 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:56:34 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:56:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f5gnTKWZCd/Render', 04:56:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:34 INFO - 'HOME': '/Users/cltbld', 04:56:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:34 INFO - 'LOGNAME': 'cltbld', 04:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:34 INFO - 'MOZ_NO_REMOTE': '1', 04:56:34 INFO - 'NO_EM_RESTART': '1', 04:56:34 INFO - 'PAGER': '/bin/cat', 04:56:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:34 INFO - 'PWD': '/builds/slave/test', 04:56:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:34 INFO - 'SHELL': '/bin/bash', 04:56:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z3jlXfDkec/Listeners', 04:56:34 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 04:56:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:34 INFO - 'USER': 'cltbld', 04:56:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:34 INFO - 'XPC_FLAGS': '0x0', 04:56:34 INFO - 'XPC_SERVICE_NAME': '0', 04:56:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:34 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:56:34 INFO - Cleaning up... 04:56:34 INFO - Return code: 0 04:56:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:56:34 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:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:34 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:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bc77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8268d5c220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb1bd30>, '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 0x10b93fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb12b20>, '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.f5gnTKWZCd/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.z3jlXfDkec/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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:56:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:56:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:56:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f5gnTKWZCd/Render', 04:56:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:34 INFO - 'HOME': '/Users/cltbld', 04:56:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:34 INFO - 'LOGNAME': 'cltbld', 04:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:34 INFO - 'MOZ_NO_REMOTE': '1', 04:56:34 INFO - 'NO_EM_RESTART': '1', 04:56:34 INFO - 'PAGER': '/bin/cat', 04:56:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:34 INFO - 'PWD': '/builds/slave/test', 04:56:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:34 INFO - 'SHELL': '/bin/bash', 04:56:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z3jlXfDkec/Listeners', 04:56:34 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 04:56:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:34 INFO - 'USER': 'cltbld', 04:56:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:34 INFO - 'XPC_FLAGS': '0x0', 04:56:34 INFO - 'XPC_SERVICE_NAME': '0', 04:56:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:34 INFO - Downloading/unpacking psutil>=0.7.1 04:56:34 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:56:34 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:56:34 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:56:34 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:56:34 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:56:34 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:56:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:56:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:56:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:56:37 INFO - Installing collected packages: psutil 04:56:37 INFO - Running setup.py install for psutil 04:56:37 INFO - building 'psutil._psutil_osx' extension 04:56:37 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:56:38 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:56:38 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:56:38 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:56:38 INFO - building 'psutil._psutil_posix' extension 04:56:38 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:56:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:56:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:56:38 INFO - ^ 04:56:38 INFO - 1 warning generated. 04:56:38 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:56:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:56:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:56:38 INFO - Successfully installed psutil 04:56:38 INFO - Cleaning up... 04:56:39 INFO - Return code: 0 04:56:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:56:39 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:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:39 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:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bc77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8268d5c220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb1bd30>, '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 0x10b93fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb12b20>, '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.f5gnTKWZCd/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.z3jlXfDkec/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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:56:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:56:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f5gnTKWZCd/Render', 04:56:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:39 INFO - 'HOME': '/Users/cltbld', 04:56:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:39 INFO - 'LOGNAME': 'cltbld', 04:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:39 INFO - 'MOZ_NO_REMOTE': '1', 04:56:39 INFO - 'NO_EM_RESTART': '1', 04:56:39 INFO - 'PAGER': '/bin/cat', 04:56:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:39 INFO - 'PWD': '/builds/slave/test', 04:56:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:39 INFO - 'SHELL': '/bin/bash', 04:56:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z3jlXfDkec/Listeners', 04:56:39 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 04:56:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:39 INFO - 'USER': 'cltbld', 04:56:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:39 INFO - 'XPC_FLAGS': '0x0', 04:56:39 INFO - 'XPC_SERVICE_NAME': '0', 04:56:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:56:39 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:56:39 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:56:39 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:56:39 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:56:39 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:56:39 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:56:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:56:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:56:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:56:41 INFO - Installing collected packages: mozsystemmonitor 04:56:41 INFO - Running setup.py install for mozsystemmonitor 04:56:42 INFO - Successfully installed mozsystemmonitor 04:56:42 INFO - Cleaning up... 04:56:42 INFO - Return code: 0 04:56:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:42 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 0x10bc77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8268d5c220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb1bd30>, '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 0x10b93fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb12b20>, '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.f5gnTKWZCd/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.z3jlXfDkec/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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:56:42 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:56:42 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:56:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f5gnTKWZCd/Render', 04:56:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:42 INFO - 'HOME': '/Users/cltbld', 04:56:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:42 INFO - 'LOGNAME': 'cltbld', 04:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:42 INFO - 'MOZ_NO_REMOTE': '1', 04:56:42 INFO - 'NO_EM_RESTART': '1', 04:56:42 INFO - 'PAGER': '/bin/cat', 04:56:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:42 INFO - 'PWD': '/builds/slave/test', 04:56:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:42 INFO - 'SHELL': '/bin/bash', 04:56:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z3jlXfDkec/Listeners', 04:56:42 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 04:56:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:42 INFO - 'USER': 'cltbld', 04:56:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:42 INFO - 'XPC_FLAGS': '0x0', 04:56:42 INFO - 'XPC_SERVICE_NAME': '0', 04:56:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:42 INFO - Downloading/unpacking blobuploader==1.2.4 04:56:42 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:56:42 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:56:42 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:56:42 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:56:42 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:56:42 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:56:44 INFO - Downloading blobuploader-1.2.4.tar.gz 04:56:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:56:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:56:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:56:45 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:56:45 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:56:45 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:56:45 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:56:45 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:56:45 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:56:46 INFO - Downloading docopt-0.6.1.tar.gz 04:56:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:56:46 INFO - Installing collected packages: blobuploader, requests, docopt 04:56:46 INFO - Running setup.py install for blobuploader 04:56:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:56:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:56:46 INFO - Running setup.py install for requests 04:56:46 INFO - Running setup.py install for docopt 04:56:47 INFO - Successfully installed blobuploader requests docopt 04:56:47 INFO - Cleaning up... 04:56:47 INFO - Return code: 0 04:56:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56: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:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56: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:56:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:47 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 0x10bc77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8268d5c220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb1bd30>, '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 0x10b93fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb12b20>, '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.f5gnTKWZCd/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.z3jlXfDkec/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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:56:47 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:56:47 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:56:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f5gnTKWZCd/Render', 04:56:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:47 INFO - 'HOME': '/Users/cltbld', 04:56:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:47 INFO - 'LOGNAME': 'cltbld', 04:56:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:47 INFO - 'MOZ_NO_REMOTE': '1', 04:56:47 INFO - 'NO_EM_RESTART': '1', 04:56:47 INFO - 'PAGER': '/bin/cat', 04:56:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:47 INFO - 'PWD': '/builds/slave/test', 04:56:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:47 INFO - 'SHELL': '/bin/bash', 04:56:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z3jlXfDkec/Listeners', 04:56:47 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 04:56:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:47 INFO - 'USER': 'cltbld', 04:56:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:47 INFO - 'XPC_FLAGS': '0x0', 04:56:47 INFO - 'XPC_SERVICE_NAME': '0', 04:56:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:47 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-MWtw26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:47 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-jqeNn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:47 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-2kQF_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:47 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-4u9nqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:47 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-u9T7xP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:47 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-6nbpjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:48 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-B5E6A_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:48 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-cgOY0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:48 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-FNlyad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:48 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-OlmfuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:48 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-QYAjrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:48 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-haA5YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:48 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-A7gp4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:48 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-QzMX8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:48 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Toxwc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:48 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-X3W54Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:49 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-BGrbmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:56:49 INFO - Running setup.py install for manifestparser 04:56:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:56:49 INFO - Running setup.py install for mozcrash 04:56:49 INFO - Running setup.py install for mozdebug 04:56:49 INFO - Running setup.py install for mozdevice 04:56:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:56:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:56:49 INFO - Running setup.py install for mozfile 04:56:49 INFO - Running setup.py install for mozhttpd 04:56:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Running setup.py install for mozinfo 04:56:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Running setup.py install for mozInstall 04:56:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Running setup.py install for mozleak 04:56:50 INFO - Running setup.py install for mozlog 04:56:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Running setup.py install for moznetwork 04:56:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Running setup.py install for mozprocess 04:56:50 INFO - Running setup.py install for mozprofile 04:56:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:56:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:56:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:56:51 INFO - Running setup.py install for mozrunner 04:56:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:56:51 INFO - Running setup.py install for mozscreenshot 04:56:51 INFO - Running setup.py install for moztest 04:56:51 INFO - Running setup.py install for mozversion 04:56:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:56:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:56:51 INFO - Cleaning up... 04:56:51 INFO - Return code: 0 04:56:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:51 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 0x10bc77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8268d5c220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb1bd30>, '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 0x10b93fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb12b20>, '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.f5gnTKWZCd/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.z3jlXfDkec/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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:56:51 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:56:51 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:56:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f5gnTKWZCd/Render', 04:56:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:51 INFO - 'HOME': '/Users/cltbld', 04:56:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:51 INFO - 'LOGNAME': 'cltbld', 04:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:51 INFO - 'MOZ_NO_REMOTE': '1', 04:56:51 INFO - 'NO_EM_RESTART': '1', 04:56:51 INFO - 'PAGER': '/bin/cat', 04:56:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:51 INFO - 'PWD': '/builds/slave/test', 04:56:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:51 INFO - 'SHELL': '/bin/bash', 04:56:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z3jlXfDkec/Listeners', 04:56:51 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 04:56:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:51 INFO - 'USER': 'cltbld', 04:56:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:51 INFO - 'XPC_FLAGS': '0x0', 04:56:51 INFO - 'XPC_SERVICE_NAME': '0', 04:56:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:52 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Y5etgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:52 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:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:52 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-8FzcBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:52 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:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:52 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-uz0UjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:52 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:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:52 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-OfFxKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:52 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:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:52 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Fm2BQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:52 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:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:52 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-BvQHjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:52 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:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:52 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-VpBeGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:52 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:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:52 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-LvoJ6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:52 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:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:52 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-4zwA6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:53 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:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:53 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-asPEUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:53 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:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:53 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-8ttjiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:53 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:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:53 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-TP9AwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:53 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:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:53 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-ZwhfB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:53 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:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:53 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-HhVKGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:53 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:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:53 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-37eABd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:53 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:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:53 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-0BGkG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:53 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:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:53 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-ksqEl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:53 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:56:53 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:56:53 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:56:53 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:56:53 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:56:53 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:56:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:56:53 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:56:53 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:56:53 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:56:53 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:56:53 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:56:53 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:56:56 INFO - Downloading blessings-1.6.tar.gz 04:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:56:56 INFO - Installing collected packages: blessings 04:56:56 INFO - Running setup.py install for blessings 04:56:56 INFO - Successfully installed blessings 04:56:56 INFO - Cleaning up... 04:56:56 INFO - Return code: 0 04:56:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:56:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:56:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:56:57 INFO - Reading from file tmpfile_stdout 04:56:57 INFO - Current package versions: 04:56:57 INFO - blessings == 1.6 04:56:57 INFO - blobuploader == 1.2.4 04:56:57 INFO - docopt == 0.6.1 04:56:57 INFO - manifestparser == 1.1 04:56:57 INFO - mozInstall == 1.12 04:56:57 INFO - mozcrash == 0.17 04:56:57 INFO - mozdebug == 0.1 04:56:57 INFO - mozdevice == 0.48 04:56:57 INFO - mozfile == 1.2 04:56:57 INFO - mozhttpd == 0.7 04:56:57 INFO - mozinfo == 0.9 04:56:57 INFO - mozleak == 0.1 04:56:57 INFO - mozlog == 3.2 04:56:57 INFO - moznetwork == 0.27 04:56:57 INFO - mozprocess == 0.22 04:56:57 INFO - mozprofile == 0.28 04:56:57 INFO - mozrunner == 6.11 04:56:57 INFO - mozscreenshot == 0.1 04:56:57 INFO - mozsystemmonitor == 0.0 04:56:57 INFO - moztest == 0.7 04:56:57 INFO - mozversion == 1.4 04:56:57 INFO - psutil == 3.1.1 04:56:57 INFO - requests == 1.2.3 04:56:57 INFO - wsgiref == 0.1.2 04:56:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:57 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:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:57 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:56:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:57 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 0x10bc77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8268d5c220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb1bd30>, '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 0x10b93fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb12b20>, '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.f5gnTKWZCd/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.z3jlXfDkec/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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:56:57 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:56:57 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:56:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f5gnTKWZCd/Render', 04:56:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:57 INFO - 'HOME': '/Users/cltbld', 04:56:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:57 INFO - 'LOGNAME': 'cltbld', 04:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:57 INFO - 'MOZ_NO_REMOTE': '1', 04:56:57 INFO - 'NO_EM_RESTART': '1', 04:56:57 INFO - 'PAGER': '/bin/cat', 04:56:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:57 INFO - 'PWD': '/builds/slave/test', 04:56:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:57 INFO - 'SHELL': '/bin/bash', 04:56:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z3jlXfDkec/Listeners', 04:56:57 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 04:56:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:57 INFO - 'USER': 'cltbld', 04:56:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:57 INFO - 'XPC_FLAGS': '0x0', 04:56:57 INFO - 'XPC_SERVICE_NAME': '0', 04:56:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:57 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:56:57 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:56:57 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:56:57 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:56:57 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:56:57 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:56:57 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:56:57 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:56:57 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:56:57 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:56:57 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:56:57 INFO - Cleaning up... 04:56:57 INFO - Return code: 0 04:56:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:56:57 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:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:57 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:56:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:57 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 0x10bc77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8268d5c220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb1bd30>, '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 0x10b93fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb12b20>, '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.f5gnTKWZCd/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.z3jlXfDkec/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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:56:57 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:56:57 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:56:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f5gnTKWZCd/Render', 04:56:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:57 INFO - 'HOME': '/Users/cltbld', 04:56:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:57 INFO - 'LOGNAME': 'cltbld', 04:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:57 INFO - 'MOZ_NO_REMOTE': '1', 04:56:57 INFO - 'NO_EM_RESTART': '1', 04:56:57 INFO - 'PAGER': '/bin/cat', 04:56:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:57 INFO - 'PWD': '/builds/slave/test', 04:56:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:57 INFO - 'SHELL': '/bin/bash', 04:56:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z3jlXfDkec/Listeners', 04:56:57 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 04:56:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:57 INFO - 'USER': 'cltbld', 04:56:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:57 INFO - 'XPC_FLAGS': '0x0', 04:56:57 INFO - 'XPC_SERVICE_NAME': '0', 04:56:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:57 INFO - Downloading/unpacking jsonschema 04:56:57 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:56:57 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:56:57 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:56:57 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:56:57 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:56:57 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:56:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:56:59 INFO - Downloading/unpacking functools32 (from jsonschema) 04:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:00 INFO - Downloading functools32-3.2.3-2.tar.gz 04:57:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:57:00 INFO - warning: no files found matching '*.txt' 04:57:00 INFO - no previously-included directories found matching 'build' 04:57:00 INFO - no previously-included directories found matching 'dist' 04:57:00 INFO - no previously-included directories found matching '.git*' 04:57:00 INFO - Installing collected packages: jsonschema, functools32 04:57:00 INFO - Running setup.py install for functools32 04:57:00 INFO - warning: no files found matching '*.txt' 04:57:00 INFO - no previously-included directories found matching 'build' 04:57:00 INFO - no previously-included directories found matching 'dist' 04:57:00 INFO - no previously-included directories found matching '.git*' 04:57:00 INFO - Successfully installed jsonschema functools32 04:57:00 INFO - Cleaning up... 04:57:00 INFO - Return code: 0 04:57:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:57:00 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:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:00 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:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:00 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 0x10bc77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8268d5c220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb1bd30>, '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 0x10b93fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb12b20>, '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.f5gnTKWZCd/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.z3jlXfDkec/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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:57:00 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:57:00 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:57:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f5gnTKWZCd/Render', 04:57:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:00 INFO - 'HOME': '/Users/cltbld', 04:57:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:00 INFO - 'LOGNAME': 'cltbld', 04:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:00 INFO - 'MOZ_NO_REMOTE': '1', 04:57:00 INFO - 'NO_EM_RESTART': '1', 04:57:00 INFO - 'PAGER': '/bin/cat', 04:57:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:00 INFO - 'PWD': '/builds/slave/test', 04:57:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:00 INFO - 'SHELL': '/bin/bash', 04:57:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z3jlXfDkec/Listeners', 04:57:00 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 04:57:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:00 INFO - 'USER': 'cltbld', 04:57:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:00 INFO - 'XPC_FLAGS': '0x0', 04:57:00 INFO - 'XPC_SERVICE_NAME': '0', 04:57:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:01 INFO - Downloading/unpacking flake8 04:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:57:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:57:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:57: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:57: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:57: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:57: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:57: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:57: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:57:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:57:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:57:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:57:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:57:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:57:04 INFO - no previously-included directories found matching 'docs/_build' 04:57:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:57:04 INFO - Running setup.py install for pep8 04:57:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:57:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:57:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:57:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:57:05 INFO - no previously-included directories found matching 'docs/_build' 04:57:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:57:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:57:05 INFO - Cleaning up... 04:57:05 INFO - Return code: 0 04:57:05 INFO - Running post-action listener: _resource_record_post_action 04:57:05 INFO - Running post-action listener: _start_resource_monitoring 04:57:05 INFO - Starting resource monitoring. 04:57:05 INFO - ##### 04:57:05 INFO - ##### Finished create-virtualenv step (success) 04:57:05 INFO - ##### 04:57:05 INFO - ##### 04:57:05 INFO - ##### Running install step. 04:57:05 INFO - ##### 04:57:05 INFO - Running pre-action listener: _resource_record_pre_action 04:57:05 INFO - Running main action method: install 04:57:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:57:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:57:05 INFO - Reading from file tmpfile_stdout 04:57:05 INFO - Detecting whether we're running mozinstall >=1.0... 04:57:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:57:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:57:05 INFO - Reading from file tmpfile_stdout 04:57:05 INFO - Output received: 04:57:05 INFO - Usage: mozinstall [options] installer 04:57:05 INFO - Options: 04:57:05 INFO - -h, --help show this help message and exit 04:57:05 INFO - -d DEST, --destination=DEST 04:57:05 INFO - Directory to install application into. [default: 04:57:05 INFO - "/builds/slave/test"] 04:57:05 INFO - --app=APP Application being installed. [default: firefox] 04:57:05 INFO - mkdir: /builds/slave/test/build/application 04:57:05 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:57:05 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:57:38 INFO - Reading from file tmpfile_stdout 04:57:38 INFO - Output received: 04:57:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:57:38 INFO - Running post-action listener: _resource_record_post_action 04:57:38 INFO - ##### 04:57:38 INFO - ##### Finished install step (success) 04:57:38 INFO - ##### 04:57:38 INFO - ##### 04:57:38 INFO - ##### Running run-tests step. 04:57:38 INFO - ##### 04:57:38 INFO - Running pre-action listener: _resource_record_pre_action 04:57:38 INFO - Running main action method: run_tests 04:57:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:57:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:57:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:57:38 INFO - 2016-05-13 04:57:38.801 screenresolution[1643:7788] starting screenresolution argv=screenresolution get 04:57:38 INFO - 2016-05-13 04:57:38.804 screenresolution[1643:7788] Display 0: 1600x1200x32@60 04:57:38 INFO - 2016-05-13 04:57:38.809 screenresolution[1644:7790] starting screenresolution argv=screenresolution list 04:57:38 INFO - Available Modes on Display 0 04:57:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:57:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:57:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:57:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:57:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:57:39 INFO - Intel Iris: 04:57:39 INFO - Chipset Model: Intel Iris 04:57:39 INFO - Type: GPU 04:57:39 INFO - Bus: Built-In 04:57:39 INFO - VRAM (Dynamic, Max): 1536 MB 04:57:39 INFO - Vendor: Intel (0x8086) 04:57:39 INFO - Device ID: 0x0a2e 04:57:39 INFO - Revision ID: 0x0009 04:57:39 INFO - Displays: 04:57:39 INFO - Display: 04:57:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:57:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:57:39 INFO - Display Serial Number: 1600x1200 60 04:57:39 INFO - Main Display: Yes 04:57:39 INFO - Mirror: Off 04:57:39 INFO - Online: Yes 04:57:39 INFO - Rotation: Supported 04:57:39 INFO - Return code: 0 04:57:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:57:39 INFO - Python 2.7.3 04:57:39 INFO - Return code: 0 04:57:39 INFO - grabbing minidump binary from tooltool 04:57:39 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:39 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 0x10bb1bd30>, '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 0x10b93fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb12b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:57:39 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:57:39 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:57:39 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:57:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:57:39 INFO - Return code: 0 04:57:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:57:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:57:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:57:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:57:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:57:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:57:40 INFO - Return code: 0 04:57:40 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/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0048', '--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:57:40 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/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0048 --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:57:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f5gnTKWZCd/Render', 04:57:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:40 INFO - 'HOME': '/Users/cltbld', 04:57:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:40 INFO - 'LOGNAME': 'cltbld', 04:57:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:57:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:57:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:40 INFO - 'MOZ_NO_REMOTE': '1', 04:57:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:57:40 INFO - 'NO_EM_RESTART': '1', 04:57:40 INFO - 'PAGER': '/bin/cat', 04:57:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:40 INFO - 'PWD': '/builds/slave/test', 04:57:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:57:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:40 INFO - 'SHELL': '/bin/bash', 04:57:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z3jlXfDkec/Listeners', 04:57:40 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 04:57:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:40 INFO - 'USER': 'cltbld', 04:57:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:40 INFO - 'XPC_FLAGS': '0x0', 04:57:40 INFO - 'XPC_SERVICE_NAME': '0', 04:57:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:40 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/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0048', '--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:57:40 INFO - mozversion application_buildid: 20160513025845 04:57:40 INFO - mozversion application_changeset: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 04:57:40 INFO - mozversion application_display_name: Nightly 04:57:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:57:40 INFO - mozversion application_name: Firefox 04:57:40 INFO - mozversion application_remotingname: firefox 04:57:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:57:40 INFO - mozversion application_vendor: Mozilla 04:57:40 INFO - mozversion application_version: 49.0a1 04:57:40 INFO - mozversion platform_buildid: 20160513025845 04:57:40 INFO - mozversion platform_changeset: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 04:57:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:57:40 INFO - mozversion platform_version: 49.0a1 04:57:40 INFO - using testdate: 1463140660 04:57:40 INFO - actual date: 1463140660 04:57:40 INFO - starting webserver on 'localhost:49277' 04:57:40 INFO - SUITE-START | Running 2 tests 04:57:40 INFO - TEST-START | dromaeo_css 04:57:40 INFO - operating with platform_type : mac_ 04:57:40 INFO - Initialising browser for dromaeo_css test... 04:57:40 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmpd8PQ3W/profile http://localhost:49277/getInfo.html) 04:57:43 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 04:57:43 INFO - PROCESS | 1662 | colorDepth:24 04:57:43 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 04:57:43 INFO - PROCESS | 1662 | __metrics 04:57:43 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 04:57:44 INFO - TEST-INFO | 1662: exit 0 04:57:44 INFO - Browser initialized. 04:57:44 INFO - Running cycle 1/1 for dromaeo_css test... 04:57:44 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmpd8PQ3W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:57:56 INFO - PROCESS | 1665 | RSS: Main: 249643008 04:57:56 INFO - PROCESS | 1665 | 04:58:45 INFO - PROCESS | 1665 | 1463140725784 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:00:56 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 05:00:57 INFO - PROCESS | 1665 | RSS: Main: 263417856 05:00:57 INFO - PROCESS | 1665 | 05:03:59 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 05:04:00 INFO - PROCESS | 1665 | RSS: Main: 275267584 05:04:00 INFO - PROCESS | 1665 | 05:07:02 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 05:07:03 INFO - PROCESS | 1665 | RSS: Main: 276905984 05:07:03 INFO - PROCESS | 1665 | 05:10:04 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 05:10:05 INFO - PROCESS | 1665 | RSS: Main: 278646784 05:10:05 INFO - PROCESS | 1665 | 05:13:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 05:13:08 INFO - PROCESS | 1665 | RSS: Main: 281620480 05:13:08 INFO - PROCESS | 1665 | 05:16:11 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 05:16:11 INFO - PROCESS | 1665 | RSS: Main: 282476544 05:16:11 INFO - PROCESS | 1665 | 05:16:11 INFO - PROCESS | 1665 | __start_tp_report 05:16:11 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 05:16:11 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 05:16:11 INFO - PROCESS | 1665 | |i|pagename|runs| 05:16:11 INFO - PROCESS | 1665 | |0;dojo.html;3489.703375213106;3494.8602055917763;3505.702015328697;3520.119970007498;3555.093451169951;5262.315898933138;5588.804391846286;5997.760089596416;6018.548608854331;6043.486303664183;4814.253790662453;5832.066869300913;5860.648361098337;6027.336992930785;6124.387561243873;5295.4704529547125;5362.731863406833;5373.785048598071;5396.622236443451;5438.939406815673;198.04761570334992;5280.287161233242;5328.653637513549;5401.054815407308;5409.29679141711;5322.095243808542;5354.982553314858;5360.544353729938;5369.382521010095;5394.32570928634;5367.865803354947;5406.702631355274;5420.110913466566;5427.972860135714;5488.451154884519;4288.871333859989;5288.2596436498925;5325.520703136736;5358.276632654581;5382.1926710993275;5745.3105627324985;5878.676672782995;5886.941130588725;5946.940530594682;6029.547783916224;6219.968900155471;6339.270983836872;6343;6352.872942541123;6364.681765911732;6267.4045965633195;6278.121063051177;6312.810615681537;6396.744130234785;6405.711742971577;6066.241719785027;6080.027195648675;6089.665068421235;6097.115918191837;6117.418845209653;6205.410486003822;6398.936010639927;6510.414062734377;6547.083408322838;6559.5408321418;6212.502999760009;6220.098085777537;6256.6871656417;6283.465905398001;6297.4647154992335;6348.873022539523;6407.391297826699;6429.164208652939;6434.388733070351;6437.710303036374;6227;6268.623882567061;6283.968580157161;6309.432151106423;6328.8417789554705;6261.373862613702;6271.78048768291;6286.18279623646;6291.087792270187;6304.716287767062;5038.622103342264;5073.568746656501;5076.670016448919;5080.314157588752;5082.415522214918;4379.146066516999;4436.223660859401;4477.686561940698;4511.030128522383;4571.26859702446;4807.326974223612;5953.22608060958;5955.076963070731;6030.18592490017;6048.274207095177;6350.7777227796805;6365.809025729235;6368.840778980562;6376.681165941684;6382.84042898922;5973.223480947449;6084.421979911901;6118.405988989689;6132.264128304543;6188.412100850412;4805.6155507559315;5601.271834661468;5633.943660563424;5635.520980716603;5638.746256418471;5547.362053363834;6658.300878407795;6663.46692264608;6666.733330666816;6677.69950352245;6225.501959843111;6341.587796793194;6404.263509696445;6418.839529011812;6456.418922296828;6001.559625689716;6290.2766181889665;6622.1722284714415;6649;6667.466602671678;5165.251038599457;6128.594790794166;6321.209848768904;6340.619562826245;6341.207349081365;5470.288862447856;6205.627662340274;6205.937940620536;6253.8749225014335;6268.843278918063;6088.756449741961;6128.662923539248;6143.416375444325;6149.508039356751;6164.445199932027;4492.618127459072;6153.507719382528;6158.32258451561;6165.753369865155;6216.937830621636;4704.717716936994;4868.221084626584;6129.114290130661;6238.719257633326;6248.750249950119;6273.466755325849;6279.497640188682;6294.307626161027;6306.148669929685;6311.179546658905;6078.179445774941;6099.420555047263;6135.263768347649;6213.751449941951;6253.155823963707 05:16:11 INFO - PROCESS | 1665 | |1;ext.html;533.3546408845294;536.0538649284014;542.6337222374896;548.008105329354;549.9780008799648;38704.612953870455;41225.58774412259;41259.793701031456;41262.76211713653;41355.58644413555;41899;42387.364189537184;42527;42570.93572660685;42571.57428425715;9637.662681806138;9815.607375704998;9827.968063353352;9830.311878168532;9847.55685994132;9779.217662586976;9845.90154098457;9861.359011664219;9882.160016398615;9898.752531186707;9834.26243031772;9843.557039933221;9856.35933664313;9896.55465504054;9928.305018648698;9774.853377199348;9815.754606134904;9823.65617203398;9874.061964113398;9881.555330010167;9647.517624118766;9686.75783105421;9729.318947673666;9776.902230977741;9778.168855647207;39427;39599.60400395988;39703.80148099241;39748.80125599382;39763.403548946786;2813.6482939632547;3023.334866329426;3111.7821752477334;3389.0849470643034;3404.1829960809473;26728;27667.446651066864;28696.687165245075;28917.710822891713;29235.122946311643;2646.2193652872256;2673.946521069587;2701.3381721478167;2771.5565509518583;2773.0017193810413;454603.7269813663;455870;458025.4197458016;458319;460706.69646651886;35080;35469;36212;36291.54833806618;36374;37063.81468092642;37526;37580.624193757714;37629.43555846665;37668.43497347542;12520.373981300898;15366.925465062073;15466.767998480032;15470.5358839235;15629.06225626466;10859.674209773719;12766.744665106646;12993.285369304589;13067.542636007694;13080.738385232242;7911.723089691834;7981.68073277074;8167.305779008732;8234.752957411287;8244.340452763765;3497.387957107496;3497.7726447780815;3508.6315936826777;3543.3799085159994;3560.8219589020446;865.8051938313829;1000.4497526360473;1071.973200669974;1110.8889111088988;1111.9714264305485;27607.723922760517;27672.861635691694;30223.69776302209;30345.78616855345;30969.0709278722;869.9826003479895;1059.7933402986343;1101.755016830992;1105.2870898270571;1154.7575009247985;15337.6165595861;15408.075515469107;15750.842491575168;16438.67122657564;17019.574510637332;673313;676457;676950;685254;686662;60458.697706511186;61461.38538614646;61713.69143154255;61861.07208391878;62104;1455.2941823215945;1468.0751126790267;1472.3963175098372;1493.402638944431;1494.6039299585618;28033.719662803112;31002.844985774926;31025;31080.378392432478;31292.904748333698;53703.73148134234;54018;54366;54914.72542637261;55264.89470210654;523234;614439;619007;619310;619758;27219.72780272251;27320.726792731817;27466.17601471959;27497.725022749517;27840.44319113647;46147;46552;46951.29573056475;47321;47340.7632961833;37283.44074838825;37298.06754831151;37447.62552374441;38073.66742164011;38115.80942095272;34052.48921266232;37073.07317317089;37702;38048.23903521859;38442;33579.83210083934;37189.6281037197;37697.43453848249;38097.04757381088;38434.61565384422;24371;31793.523097154022;32570.34859302848;33666.83166584151;33978;23467.53064938726;24076.277711668678;24145.275641730776;24247.545147291217;24321.878390608643 05:16:11 INFO - PROCESS | 1665 | |2;jquery.html;382.50274642964143;391.54971782450195;397.39000634026775;400.7515340488897;416.8374334009737;14659.560213193594;20371;20638.89680551599;20968.16127354906;21184.57630847382;20645.63667503089;20653.483662908435;20829.895850520727;21414.571708565858;21584.1366345346;5655.321361436623;5909.734061967221;6001.369856165084;6022.698865056752;6029.909551356734;5698.373178950312;5908.497777688902;5927.673977931212;5952.7321270542925;5964.582479226434;5840.561957853157;5877.7355019024035;5892.23400957877;5893.970530147343;5905.232319798421;5709.571782116358;5784.363719990798;5859.912101318484;5864.091065884816;5885.058390657496;5163.277141200235;5183.870403239949;5184.11869982104;5196.870078248036;5212.218167274901;25219.991200351964;25912.74087259122;26933.730662693317;26942.461150776875;26968;2145.395799676898;2287.462446325112;2511.962320565193;2538.365408647838;2554.540182767101;11992.64022079339;15178.772318415122;15427.691446171126;15447.459338923196;15584.688306233926;1589.284821830181;1691.796984361872;1715.9828401715947;1752.071402156859;1759.2171483689763;116601.4169929145;117183;117719;118593;119025.80974190234;9701.417914925127;10724.410157441416;10741.19441041925;10851.077658398966;11265.774684506265;11360.943195284137;13764.380602872894;13782.586522404345;13843.446262149602;13844.861551384358;6877;8764.8685269721;8775.166359195993;8858.822823543493;8864.335174861912;6521.6413097279155;7446.66490007951;8776.78058048554;8834.425762325429;8861.601227944759;6129.662868542273;6661.700223489955;6828.9658551706925;6834.760783372558;6846.144231971004;1597.5526852481323;1738.2351765223261;1874.1097978460396;1885.2270569066616;1891.4420246027455;879.6261588824851;941.9591351556568;966.3622009473713;968.801395713877;1030.5311083457009;20112.497187570134;20351.77889326645;21558.676619850714;21577.78422215789;21712.34862954114;943.9008904065112;945.2485274207022;1001.5242759689235;1049.9370037797603;1086.5327908999047;11173.273737207055;11665.300081995107;11696.88303116975;11703.707407314714;11703.824442633368;511847;589726;613074;627108;633546;127050;128624;131159;131596.3420182912;131668;1046.6022911293812;1099.7305660113323;1114.7324642085841;1123.2081382625229;1124.7188202949262;18894;19083.618327633092;19204.807951920862;19350.70973935364;19393.224271029565;51395.70124017941;51943.961120776614;51953.70115747137;52569.47430525646;52577.7371113142;70362.64818675874;72740;73286;74450.62774686092;74716;7911.802204944923;7966.203379661987;8110.513369197631;8150.510969341858;8212.753617391489;12312.753744924872;12497.687557811127;12536.310502922426;12899.419526121535;12932.806007909701;8442.00956747862;10787.671232876637;11045.723856903642;11234.213605047567;11351.659450216506;10732.087772539422;10804.567817287534;10865.565377384815;11002.834957475805;11027.842076581843;9875.703728888146;11469.541218351505;11493.080553555863;11520.94239528797;11563.942180289045;7964;7988.760337189894;8063.911371964709;8120.918790812017;8377.70678026266;9164.312676549418;9484.383515071499;9616.605592189233;11077.446127693744;11149.72125696864 05:16:11 INFO - PROCESS | 1665 | |3;mootools.html;1558.9298481568328;1582.027053362182;1613.9106103380223;1814.7822261328643;1841.1070630744086;8074.596270186496;8649.308055355572;8669.956650216747;9169.128932751386;9190.126937940893;8729.825403491926;8848;8882.822343553125;8980.910190898088;8987.59555819988;2397.62836760302;2423.6243382275775;2433.221369161869;2438.6951631046118;2442.9633555496594;2016.5664382157834;2402.3513651314224;2417.2627348658652;2419.9758002420012;2454.3986723252738;2217.855839370444;2237.2281562860785;2429.3805079704653;2429.7813196812285;2461.310832966772;2421;2421.055788242587;2427.381017840457;2433.7687919647783;2434.196715084027;2395.688560487143;2403.291029146406;2414.9034038638433;2417.7944874685672;2424.830261881669;8990;9041.141091596288;9058.411203271766;9069.863952040725;9073.274138068939;5367.2485851980755;5480.123180291134;5512.3936367;5549.5005449509335;5566.165075238706;3503;3517.085557755002;3567.1973805893726;3615.8011309377976;3645.325614761278;1045.5556388534842;1046.7697106636529;1050.023478165306;1050.774083572028;1054.6783231114507;11518.366489843056;11605.535778568847;11878;12090.214136081127;12103.152779305452;4832.251001094836;4833.806647734122;4948.233023881304;4977.776000080005;4979.3277907482025;8556.443831150955;9698.951505242429;9892.950535247277;9912.752181195528;10053.04496072872;5310.309659744208;5380.677159370451;5470.890582188414;5483.643563168461;5501.559875209973;4478.149151661174;4835.178019736653;4958.950410495849;4986.825461108844;4998.950220453746;3953.5453422856162;4014;4024.335984562573;4090.6113919178224;4159.771212583277;1199.7540504196618;1318.1102755639904;1349.9662508437368;1362.9659258518418;1370.9588712338646;828.4201059258543;937.301710225886;941.9340646154841;952.9571169297399;983.5770618633914;5408.5402740766685;5956.315023772322;5992.161097446361;6022.45797878193;6220.160278362363;926.7127190570944;963.1331801378815;967.8385936875777;1053.9367637941748;1062.3625824505234;3956.872291396938;3961.8217180226957;4021.095253567982;4049.068714195693;4051.2707712612014;10488.4231367274;10840.078593319498;10856.34861908288;10892.564297428014;10955.50700218492;8887.377883548219;10664.413457259925;10860.131189504978;11105.72235694098;11152.665420037412;8000.399970002157;8111.229433203836;8222.958885205535;8444.493330400195;8505.192006759347;5293.417724050429;5985.850353741192;6013.128096426081;6080.118382834376;6103.23709536308;8188.83622327562;10343.27597068198;10365.948170259402;10392.636257731245;10433.843492347469;10246;10261;10465.633702820669;10609.257351985287;10612.363258204532;6192.81421557354;7010.649467526705;7610.961945190239;7671.424643151674;7738.806529836799;8494.065652778312;8517.531535765593;8566.271866891384;8568.35737319716;8578.828423431622;6007.939920600738;7540.547567145989;7543.660535276035;7561.659725312485;7707.229277072248;7403.704851496053;7441.218672039358;7505.136909255505;7512.248775122663;7535.924640753522;7145.964270178616;7192.1659976705605;7222.74720384784;7226.458015648953;7245.094363204599;4844.224924012141;4888.2911977762315;5033.119204139334;5040.697558146523;5201.245819356248;4946.134426475424;5062.898742025212;5069.645124841226;5084.186530155157;5262.210668399771 05:16:11 INFO - PROCESS | 1665 | |4;prototype.html;309.58824763065127;318.7004216036925;325.3395606917956;328.6746121339874;330.3285375997453;4303.096349766545;5501.477359650837;5560.776629141583;5617.354004289503;5704.9144262836035;4614.446266448031;4645.490215679901;5544.22380866679;5548.805791797288;5581.7209139543065;3493.812103884679;4073.940775398403;4214.639801711036;4261.488621365441;4290.356446533014;3938.9606103939022;4226.1336426032585;4270.402143699885;4274.187904298174;4279.111599670226;4137.3173426384765;4187.539370669224;4194;4202;4204.936925946114;4181.853635122787;4195.81118849651;4211.410402543646;4217.536071032184;4234.771916144315;3357.4818885835625;3465.376232278189;3581.767185132958;3621.130928577149;3642.7267954903486;4696.671233013691;4872.780724867354;5080.415752188472;5219.295395121648;5318.686371428441;4765.189228093337;4769.99830035693;4786.904261914742;4788.712677239377;4795.400574928134;5599.608027438081;5857.121431785224;5888.5877988540815;5890.352061273256;5899.144624029492;3756.586775454698;3868.323043467382;3929.764214147132;3993.7803420811842;4013.7391069580676;5079.399989003452;5414.159185876821;5755.482006619424;6102.4507794298725;6253.374662533755;3284.5730055093163;3341.2649217172184;3623.804144632265;3745.3408080263316;3946.8223929923224;3940.66017554033;4058.6144316289906;4067.898302542401;4069.369247766601;4116.579779975963;3627.222660896142;3938.1926705025403;4165.812538435777;4542.886992686746;4557.475890272661;5548.944510554924;5771.278590176228;5836.795712150054;5863.501602363847;5883.735231914574;1171.77736230116;1355.3426588104762;1359.211657238799;1372;1384.3424373422745;797.8843067755143;818.0265484073933;846.8645016797283;849.9757791860793;855.7903313688187;4054.3646629690347;4386.605205531454;4476.9104617908115;4484.170428470743;4542.954570454253;4788.712677239377;4865.9756701216265;5027.692799872059;5070.645054846199;5112.9744351278005;3874.3994680824517;4194.846417235285;4401.471823381215;4433.379326894237;4626.195783644382;2420.3178790740267;2445.9143929962365;2449.7383847318647;2704.269847141261;2723.251105945841;4491.752953587584;4982.526659967298;5054.78685115577;5145.253938178943;5325.562098233455;4396.692231543761;4690.686607749835;4811.412233962784;4892.608591312686;5069.9746501267255;4709.5055019223855;5158.509941555471;5331.760070796823;5367.838964831061;5401.432849550819;4357.433533640733;5071.6780508766815;5097.464766199644;5104.515071068243;5127;4148.170365926887;4255.638270747021;4641.885947372673;4716.363290955815;5063.924041139459;4161.8335266589;4855.761780745887;4884.5603895649565;5309.123994540858;5346.893062138658;3154.397621961845;3337.849796759103;3349.882754103595;3486.8082255476193;3533.787972721645;4316.676249281254;4703.882402939954;4881.316615673737;5099.781595006184;5712.143178523089;4555.863324100283;5912.970435147969;5937.139114828412;5961.195238642727;5965.28416590012;5884.352041581391;5942.86057019464;5951.7024148791525;5965.164876917322;5987.550933680079;4696.7788375022055;4863.7324947128245;5599.440055994368;5803;5833;5019.7741101651245;5209.921851172311;5887.852803679943;5906.675132867734;5915.763369465173;4752.883072478034;5057.14028615129;5429.918551221814;5528.253685752372;5669.922714684197 05:16:11 INFO - PROCESS | 1665 | |5;yui.html;667.432682220113;691.3812138136365;694.3507820188123;696.658637267739;720.8666396716604;14453.421863125475;15280.388784448609;15283.847161528382;15545.922270388646;15685.921570392133;14388.352524136413;14439.205843678594;14479.78280325796;14526.927365363184;14527.491537796179;743.2864450127884;908.591133989704;913.3560839608077;928.2620316848122;942.0626476655738;930.1256818590504;935.8549424839126;939.8167357365317;941.3692825806726;942.726609283307;937.690562114504;975.1272611013175;976.077606661705;980.2550061952932;985.3595163143978;938.2447130060299;955.4171955107379;963.393062370709;964.7346979580636;969.5249327829383;694.1600663197537;694.3577191098265;695.2005194026909;725.5066554742773;728.6101935464554;14850.331735071948;15026.323815428332;15088.24558772057;15095.245237738069;15159.09045457276;12474.313912734797;12486.092470725602;12578.119531632788;12704.555340563036;12705.364731763375;9890.258230632659;9901.554430050664;10049.89950100497;10111.03945125212;10493.160547156285;3392.508086327469;3550.8757193498363;4044.0698639313123;4053.3514637658127;4089.7137200395982;81202;83532.58233708792;84842.72735908967;85154.14845851524;85682;7697.538147711155;9238.030006849318;9271.211946984582;9388.389754665925;9557.426554406758;19691;19709.507262318555;20293.898530507548;20323.695144572845;20366;7271.091113610799;7326.6702998364135;7329.120505539369;7408.888866667005;7589.354904833151;3964.484616999829;3972.0569830118416;4020.959790402117;4021.1354558770004;4113.753174809462;3485.4597537381746;3570.535830342091;3572.678458938656;3578;3612.422012477991;562.8452175651729;623.7567348733998;639.7121295417081;643.1960049937578;651.879402310574;479.8920242945379;480.2388214679702;523.588982648619;552.4558310064583;560.2996254681648;7572.810679733051;7658.693652253847;7699.230076992368;7708.190639982833;7760.223977602308;528.9603279754034;542.7259234086761;549.5960469055241;566.8497848070265;574.5317566183547;3457.22212502185;3647.76289541179;3679.8712045078296;3690.5479527123484;3737.4980689593267;82026.58986705028;85635.57182214173;85716;86065.56967215124;87526;68767;72507.91238131432;73078.2692173082;75716;75789.62105189438;64850.67574662097;71545.64227178831;74502.62748686221;75642;76981;7857.410694197843;7875.632178181005;7997.920020799717;8017.118117007008;8152.877706834521;32626;43136.13727725365;43358;43417.78291108524;43441.56558434375;56985.43014569967;59912.70043649754;60273.09590356061;60351.396486036334;60639.69680151571;11441.656750297172;11541.942290288494;11593.304401735923;11827.763444731228;11874.526534029854;11842.28946263227;12090.335031573348;12218.20581662189;12312.015038797053;12371.319577423328;11377.943110284396;11398.829017564578;11613.187076904536;11776.587819426279;11944.64166075019;11242.94378528102;11410.201285909907;11809.173357864867;11849.170558060852;11905.107116966436;10217;11223.775524489301;11523.654290370967;11669.474873630537;12200.328981905725;9619.086186812241;9796.069373409515;9964.601415943282;10049.949750251202;10086.991300869855;9686.225101991682;9698.321117521706;9699.127078562964;9740.659076932274;9773.609055637979 05:16:11 INFO - PROCESS | 1665 | __end_tp_report 05:16:11 INFO - PROCESS | 1665 | __start_cc_report 05:16:11 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,414 05:16:11 INFO - PROCESS | 1665 | __end_cc_report 05:16:11 INFO - PROCESS | 1665 | __startTimestamp1463141771727__endTimestamp 05:16:11 INFO - PROCESS | 1665 | 05:16:11 INFO - PROCESS | 1665 | ------- Summary: start ------- 05:16:11 INFO - PROCESS | 1665 | Number of tests: 6 05:16:11 INFO - PROCESS | 1665 | 05:16:11 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5807.53 Median:6124.39 stddev:814.93 (13.3%) stddev-sans-first:796.89 05:16:11 INFO - PROCESS | 1665 | Values: 3489.7 3494.9 3505.7 3520.1 3555.1 5262.3 5588.8 5997.8 6018.5 6043.5 4814.3 5832.1 5860.6 6027.3 6124.4 5295.5 5362.7 5373.8 5396.6 5438.9 198.0 5280.3 5328.7 5401.1 5409.3 5322.1 5355.0 5360.5 5369.4 5394.3 5367.9 5406.7 5420.1 5428.0 5488.5 4288.9 5288.3 5325.5 5358.3 5382.2 5745.3 5878.7 5886.9 5946.9 6029.5 6220.0 6339.3 6343.0 6352.9 6364.7 6267.4 6278.1 6312.8 6396.7 6405.7 6066.2 6080.0 6089.7 6097.1 6117.4 6205.4 6398.9 6510.4 6547.1 6559.5 6212.5 6220.1 6256.7 6283.5 6297.5 6348.9 6407.4 6429.2 6434.4 6437.7 6227.0 6268.6 6284.0 6309.4 6328.8 6261.4 6271.8 6286.2 6291.1 6304.7 5038.6 5073.6 5076.7 5080.3 5082.4 4379.1 4436.2 4477.7 4511.0 4571.3 4807.3 5953.2 5955.1 6030.2 6048.3 6350.8 6365.8 6368.8 6376.7 6382.8 5973.2 6084.4 6118.4 6132.3 6188.4 4805.6 5601.3 5633.9 5635.5 5638.7 5547.4 6658.3 6663.5 6666.7 6677.7 6225.5 6341.6 6404.3 6418.8 6456.4 6001.6 6290.3 6622.2 6649.0 6667.5 5165.3 6128.6 6321.2 6340.6 6341.2 5470.3 6205.6 6205.9 6253.9 6268.8 6088.8 6128.7 6143.4 6149.5 6164.4 4492.6 6153.5 6158.3 6165.8 6216.9 4704.7 4868.2 6129.1 6238.7 6248.8 6273.5 6279.5 6294.3 6306.1 6311.2 6078.2 6099.4 6135.3 6213.8 6253.2 05:16:11 INFO - PROCESS | 1665 | 05:16:11 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:69225.33 Median:37699.72 stddev:157339.49 (417.3%) stddev-sans-first:157696.34 05:16:11 INFO - PROCESS | 1665 | Values: 533.4 536.1 542.6 548.0 550.0 38704.6 41225.6 41259.8 41262.8 41355.6 41899.0 42387.4 42527.0 42570.9 42571.6 9637.7 9815.6 9828.0 9830.3 9847.6 9779.2 9845.9 9861.4 9882.2 9898.8 9834.3 9843.6 9856.4 9896.6 9928.3 9774.9 9815.8 9823.7 9874.1 9881.6 9647.5 9686.8 9729.3 9776.9 9778.2 39427.0 39599.6 39703.8 39748.8 39763.4 2813.6 3023.3 3111.8 3389.1 3404.2 26728.0 27667.4 28696.7 28917.7 29235.1 2646.2 2673.9 2701.3 2771.6 2773.0 454603.7 455870.0 458025.4 458319.0 460706.7 35080.0 35469.0 36212.0 36291.5 36374.0 37063.8 37526.0 37580.6 37629.4 37668.4 12520.4 15366.9 15466.8 15470.5 15629.1 10859.7 12766.7 12993.3 13067.5 13080.7 7911.7 7981.7 8167.3 8234.8 8244.3 3497.4 3497.8 3508.6 3543.4 3560.8 865.8 1000.4 1072.0 1110.9 1112.0 27607.7 27672.9 30223.7 30345.8 30969.1 870.0 1059.8 1101.8 1105.3 1154.8 15337.6 15408.1 15750.8 16438.7 17019.6 673313.0 676457.0 676950.0 685254.0 686662.0 60458.7 61461.4 61713.7 61861.1 62104.0 1455.3 1468.1 1472.4 1493.4 1494.6 28033.7 31002.8 31025.0 31080.4 31292.9 53703.7 54018.0 54366.0 54914.7 55264.9 523234.0 614439.0 619007.0 619310.0 619758.0 27219.7 27320.7 27466.2 27497.7 27840.4 46147.0 46552.0 46951.3 47321.0 47340.8 37283.4 37298.1 37447.6 38073.7 38115.8 34052.5 37073.1 37702.0 38048.2 38442.0 33579.8 37189.6 37697.4 38097.0 38434.6 24371.0 31793.5 32570.3 33666.8 33978.0 23467.5 24076.3 24145.3 24247.5 24321.9 05:16:11 INFO - PROCESS | 1665 | 05:16:11 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:34956.36 Median:13865.98 stddev:99582.30 (718.2%) stddev-sans-first:99827.82 05:16:11 INFO - PROCESS | 1665 | Values: 382.5 391.5 397.4 400.8 416.8 14659.6 20371.0 20638.9 20968.2 21184.6 20645.6 20653.5 20829.9 21414.6 21584.1 5655.3 5909.7 6001.4 6022.7 6029.9 5698.4 5908.5 5927.7 5952.7 5964.6 5840.6 5877.7 5892.2 5894.0 5905.2 5709.6 5784.4 5859.9 5864.1 5885.1 5163.3 5183.9 5184.1 5196.9 5212.2 25220.0 25912.7 26933.7 26942.5 26968.0 2145.4 2287.5 2512.0 2538.4 2554.5 11992.6 15178.8 15427.7 15447.5 15584.7 1589.3 1691.8 1716.0 1752.1 1759.2 116601.4 117183.0 117719.0 118593.0 119025.8 9701.4 10724.4 10741.2 10851.1 11265.8 11360.9 13764.4 13782.6 13843.4 13844.9 6877.0 8764.9 8775.2 8858.8 8864.3 6521.6 7446.7 8776.8 8834.4 8861.6 6129.7 6661.7 6829.0 6834.8 6846.1 1597.6 1738.2 1874.1 1885.2 1891.4 879.6 942.0 966.4 968.8 1030.5 20112.5 20351.8 21558.7 21577.8 21712.3 943.9 945.2 1001.5 1049.9 1086.5 11173.3 11665.3 11696.9 11703.7 11703.8 511847.0 589726.0 613074.0 627108.0 633546.0 127050.0 128624.0 131159.0 131596.3 131668.0 1046.6 1099.7 1114.7 1123.2 1124.7 18894.0 19083.6 19204.8 19350.7 19393.2 51395.7 51944.0 51953.7 52569.5 52577.7 70362.6 72740.0 73286.0 74450.6 74716.0 7911.8 7966.2 8110.5 8150.5 8212.8 12312.8 12497.7 12536.3 12899.4 12932.8 8442.0 10787.7 11045.7 11234.2 11351.7 10732.1 10804.6 10865.6 11002.8 11027.8 9875.7 11469.5 11493.1 11520.9 11563.9 7964.0 7988.8 8063.9 8120.9 8377.7 9164.3 9484.4 9616.6 11077.4 11149.7 05:16:11 INFO - PROCESS | 1665 | 05:16:11 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5778.65 Median:4978.55 stddev:3217.53 (64.6%) stddev-sans-first:3210.92 05:16:11 INFO - PROCESS | 1665 | Values: 1558.9 1582.0 1613.9 1814.8 1841.1 8074.6 8649.3 8670.0 9169.1 9190.1 8729.8 8848.0 8882.8 8980.9 8987.6 2397.6 2423.6 2433.2 2438.7 2443.0 2016.6 2402.4 2417.3 2420.0 2454.4 2217.9 2237.2 2429.4 2429.8 2461.3 2421.0 2421.1 2427.4 2433.8 2434.2 2395.7 2403.3 2414.9 2417.8 2424.8 8990.0 9041.1 9058.4 9069.9 9073.3 5367.2 5480.1 5512.4 5549.5 5566.2 3503.0 3517.1 3567.2 3615.8 3645.3 1045.6 1046.8 1050.0 1050.8 1054.7 11518.4 11605.5 11878.0 12090.2 12103.2 4832.3 4833.8 4948.2 4977.8 4979.3 8556.4 9699.0 9893.0 9912.8 10053.0 5310.3 5380.7 5470.9 5483.6 5501.6 4478.1 4835.2 4959.0 4986.8 4999.0 3953.5 4014.0 4024.3 4090.6 4159.8 1199.8 1318.1 1350.0 1363.0 1371.0 828.4 937.3 941.9 953.0 983.6 5408.5 5956.3 5992.2 6022.5 6220.2 926.7 963.1 967.8 1053.9 1062.4 3956.9 3961.8 4021.1 4049.1 4051.3 10488.4 10840.1 10856.3 10892.6 10955.5 8887.4 10664.4 10860.1 11105.7 11152.7 8000.4 8111.2 8223.0 8444.5 8505.2 5293.4 5985.9 6013.1 6080.1 6103.2 8188.8 10343.3 10365.9 10392.6 10433.8 10246.0 10261.0 10465.6 10609.3 10612.4 6192.8 7010.6 7611.0 7671.4 7738.8 8494.1 8517.5 8566.3 8568.4 8578.8 6007.9 7540.5 7543.7 7561.7 7707.2 7403.7 7441.2 7505.1 7512.2 7535.9 7146.0 7192.2 7222.7 7226.5 7245.1 4844.2 4888.3 5033.1 5040.7 5201.2 4946.1 5062.9 5069.6 5084.2 5262.2 05:16:11 INFO - PROCESS | 1665 | 05:16:11 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4384.46 Median:4706.69 stddev:1350.90 (28.7%) stddev-sans-first:1319.61 05:16:11 INFO - PROCESS | 1665 | Values: 309.6 318.7 325.3 328.7 330.3 4303.1 5501.5 5560.8 5617.4 5704.9 4614.4 4645.5 5544.2 5548.8 5581.7 3493.8 4073.9 4214.6 4261.5 4290.4 3939.0 4226.1 4270.4 4274.2 4279.1 4137.3 4187.5 4194.0 4202.0 4204.9 4181.9 4195.8 4211.4 4217.5 4234.8 3357.5 3465.4 3581.8 3621.1 3642.7 4696.7 4872.8 5080.4 5219.3 5318.7 4765.2 4770.0 4786.9 4788.7 4795.4 5599.6 5857.1 5888.6 5890.4 5899.1 3756.6 3868.3 3929.8 3993.8 4013.7 5079.4 5414.2 5755.5 6102.5 6253.4 3284.6 3341.3 3623.8 3745.3 3946.8 3940.7 4058.6 4067.9 4069.4 4116.6 3627.2 3938.2 4165.8 4542.9 4557.5 5548.9 5771.3 5836.8 5863.5 5883.7 1171.8 1355.3 1359.2 1372.0 1384.3 797.9 818.0 846.9 850.0 855.8 4054.4 4386.6 4476.9 4484.2 4543.0 4788.7 4866.0 5027.7 5070.6 5113.0 3874.4 4194.8 4401.5 4433.4 4626.2 2420.3 2445.9 2449.7 2704.3 2723.3 4491.8 4982.5 5054.8 5145.3 5325.6 4396.7 4690.7 4811.4 4892.6 5070.0 4709.5 5158.5 5331.8 5367.8 5401.4 4357.4 5071.7 5097.5 5104.5 5127.0 4148.2 4255.6 4641.9 4716.4 5063.9 4161.8 4855.8 4884.6 5309.1 5346.9 3154.4 3337.8 3349.9 3486.8 3533.8 4316.7 4703.9 4881.3 5099.8 5712.1 4555.9 5913.0 5937.1 5961.2 5965.3 5884.4 5942.9 5951.7 5965.2 5987.6 4696.8 4863.7 5599.4 5803.0 5833.0 5019.8 5209.9 5887.9 5906.7 5915.8 4752.9 5057.1 5429.9 5528.3 5669.9 05:16:11 INFO - PROCESS | 1665 | 05:16:11 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:17659.99 Median:28779.98 stddev:24568.66 (85.4%) stddev-sans-first:24604.45 05:16:11 INFO - PROCESS | 1665 | Values: 667.4 691.4 694.4 696.7 720.9 14453.4 15280.4 15283.8 15545.9 15685.9 14388.4 14439.2 14479.8 14526.9 14527.5 743.3 908.6 913.4 928.3 942.1 930.1 935.9 939.8 941.4 942.7 937.7 975.1 976.1 980.3 985.4 938.2 955.4 963.4 964.7 969.5 694.2 694.4 695.2 725.5 728.6 14850.3 15026.3 15088.2 15095.2 15159.1 12474.3 12486.1 12578.1 12704.6 12705.4 9890.3 9901.6 10049.9 10111.0 10493.2 3392.5 3550.9 4044.1 4053.4 4089.7 81202.0 83532.6 84842.7 85154.1 85682.0 7697.5 9238.0 9271.2 9388.4 9557.4 19691.0 19709.5 20293.9 20323.7 20366.0 7271.1 7326.7 7329.1 7408.9 7589.4 3964.5 3972.1 4021.0 4021.1 4113.8 3485.5 3570.5 3572.7 3578.0 3612.4 562.8 623.8 639.7 643.2 651.9 479.9 480.2 523.6 552.5 560.3 7572.8 7658.7 7699.2 7708.2 7760.2 529.0 542.7 549.6 566.8 574.5 3457.2 3647.8 3679.9 3690.5 3737.5 82026.6 85635.6 85716.0 86065.6 87526.0 68767.0 72507.9 73078.3 75716.0 75789.6 64850.7 71545.6 74502.6 75642.0 76981.0 7857.4 7875.6 7997.9 8017.1 8152.9 32626.0 43136.1 43358.0 43417.8 43441.6 56985.4 59912.7 60273.1 60351.4 60639.7 11441.7 11541.9 11593.3 11827.8 11874.5 11842.3 12090.3 12218.2 12312.0 12371.3 11377.9 11398.8 11613.2 11776.6 11944.6 11242.9 11410.2 11809.2 11849.2 11905.1 10217.0 11223.8 11523.7 11669.5 12200.3 9619.1 9796.1 9964.6 10049.9 10087.0 9686.2 9698.3 9699.1 9740.7 9773.6 05:16:11 INFO - PROCESS | 1665 | -------- Summary: end -------- 05:16:11 INFO - PROCESS | 1665 | 05:16:12 INFO - TEST-INFO | 1665: exit 0 05:16:12 INFO - TEST-OK | dromaeo_css | took 1111900ms 05:16:12 INFO - TEST-START | kraken 05:16:12 INFO - operating with platform_type : mac_ 05:16:12 INFO - Initialising browser for kraken test... 05:16:12 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmpPV2BVF/profile http://localhost:49277/getInfo.html) 05:16:15 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 05:16:15 INFO - PROCESS | 1674 | colorDepth:24 05:16:15 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 05:16:15 INFO - PROCESS | 1674 | __metrics 05:16:15 ERROR - PROCESS | 1674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:16:16 INFO - TEST-INFO | 1674: exit 0 05:16:16 INFO - Browser initialized. 05:16:16 INFO - Running cycle 1/1 for kraken test... 05:16:16 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmpPV2BVF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:16:27 INFO - PROCESS | 1677 | RSS: Main: 249913344 05:16:27 INFO - PROCESS | 1677 | 05:16:30 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 05:16:31 INFO - PROCESS | 1677 | RSS: Main: 261701632 05:16:31 INFO - PROCESS | 1677 | 05:16:34 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 05:16:35 INFO - PROCESS | 1677 | RSS: Main: 266973184 05:16:35 INFO - PROCESS | 1677 | 05:16:38 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 05:16:39 INFO - PROCESS | 1677 | RSS: Main: 267333632 05:16:39 INFO - PROCESS | 1677 | 05:16:41 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 05:16:41 INFO - PROCESS | 1677 | RSS: Main: 267984896 05:16:41 INFO - PROCESS | 1677 | 05:16:44 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:16:44 INFO - PROCESS | 1677 | RSS: Main: 267829248 05:16:44 INFO - PROCESS | 1677 | 05:16:47 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 05:16:48 INFO - PROCESS | 1677 | RSS: Main: 268570624 05:16:48 INFO - PROCESS | 1677 | 05:16:51 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 05:16:52 INFO - PROCESS | 1677 | RSS: Main: 269250560 05:16:52 INFO - PROCESS | 1677 | 05:16:55 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 05:16:56 INFO - PROCESS | 1677 | RSS: Main: 269123584 05:16:56 INFO - PROCESS | 1677 | 05:16:58 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:16:58 INFO - PROCESS | 1677 | RSS: Main: 268541952 05:16:58 INFO - PROCESS | 1677 | 05:17:00 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:17:01 INFO - PROCESS | 1677 | RSS: Main: 267952128 05:17:01 INFO - PROCESS | 1677 | 05:17:03 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:17:04 INFO - PROCESS | 1677 | RSS: Main: 268107776 05:17:04 INFO - PROCESS | 1677 | 05:17:07 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:17:07 INFO - PROCESS | 1677 | RSS: Main: 268124160 05:17:07 INFO - PROCESS | 1677 | 05:17:11 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:17:11 INFO - PROCESS | 1677 | RSS: Main: 268247040 05:17:11 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 05:17:13 INFO - PROCESS | 1677 | RSS: Main: 270950400 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | __start_tp_report 05:17:13 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 05:17:13 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 05:17:13 INFO - PROCESS | 1677 | |i|pagename|runs| 05:17:13 INFO - PROCESS | 1677 | |0;ai-astar;115;115;110;115;117;115;108;117;117;115 05:17:13 INFO - PROCESS | 1677 | |1;audio-beat-detection;183;154;162;256;131;143;122;174;191;122 05:17:13 INFO - PROCESS | 1677 | |2;audio-dft;197;204;211;202;267;279;214;194;194;197 05:17:13 INFO - PROCESS | 1677 | |3;audio-fft;66;78;74;170;57;58;59;66;129;60 05:17:13 INFO - PROCESS | 1677 | |4;audio-oscillator;80;88;143;78;106;64;118;116;114;160 05:17:13 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;89;86;88;86;88;86;89;88;107;111 05:17:13 INFO - PROCESS | 1677 | |6;imaging-darkroom;94;107;97;154;151;95;99;94;109;108 05:17:13 INFO - PROCESS | 1677 | |7;imaging-desaturate;89;133;134;79;93;140;105;116;107;124 05:17:13 INFO - PROCESS | 1677 | |8;json-parse-financial;60;62;70;96;96;96;91;110;89;93 05:17:13 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;66;67;71;68;71;99;57;63;84;103 05:17:13 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;86;85;90;89;89;88;86;87;86;89 05:17:13 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;140;130;150;151;174;149;150;138;227;158 05:17:13 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;171;164;168;196;228;152;231;238;214;165 05:17:13 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;68;67;66;64;68;65;64;68;69;66 05:17:13 INFO - PROCESS | 1677 | __end_tp_report 05:17:13 INFO - PROCESS | 1677 | __start_cc_report 05:17:13 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1069 05:17:13 INFO - PROCESS | 1677 | __end_cc_report 05:17:13 INFO - PROCESS | 1677 | __startTimestamp1463141833734__endTimestamp 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | ------- Summary: start ------- 05:17:13 INFO - PROCESS | 1677 | Number of tests: 14 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.00 stddev:3.03 (2.6%) stddev-sans-first:3.20 05:17:13 INFO - PROCESS | 1677 | Values: 115.0 115.0 110.0 115.0 117.0 115.0 108.0 117.0 117.0 115.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:163.80 Median:168.00 stddev:40.57 (24.2%) stddev-sans-first:42.44 05:17:13 INFO - PROCESS | 1677 | Values: 183.0 154.0 162.0 256.0 131.0 143.0 122.0 174.0 191.0 122.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:215.90 Median:207.50 stddev:30.96 (14.9%) stddev-sans-first:32.08 05:17:13 INFO - PROCESS | 1677 | Values: 197.0 204.0 211.0 202.0 267.0 279.0 214.0 194.0 194.0 197.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:81.70 Median:63.00 stddev:37.65 (59.8%) stddev-sans-first:39.50 05:17:13 INFO - PROCESS | 1677 | Values: 66.0 78.0 74.0 170.0 57.0 58.0 59.0 66.0 129.0 60.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:106.70 Median:112.00 stddev:30.07 (26.8%) stddev-sans-first:30.30 05:17:13 INFO - PROCESS | 1677 | Values: 80.0 88.0 143.0 78.0 106.0 64.0 118.0 116.0 114.0 160.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:88.00 stddev:9.19 (10.4%) stddev-sans-first:9.69 05:17:13 INFO - PROCESS | 1677 | Values: 89.0 86.0 88.0 86.0 88.0 86.0 89.0 88.0 107.0 111.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:110.80 Median:94.00 stddev:22.73 (24.2%) stddev-sans-first:23.29 05:17:13 INFO - PROCESS | 1677 | Values: 94.0 107.0 97.0 154.0 151.0 95.0 99.0 94.0 109.0 108.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:112.00 Median:137.00 stddev:20.88 (15.2%) stddev-sans-first:20.42 05:17:13 INFO - PROCESS | 1677 | Values: 89.0 133.0 134.0 79.0 93.0 140.0 105.0 116.0 107.0 124.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:86.30 Median:92.00 stddev:16.55 (18.0%) stddev-sans-first:14.57 05:17:13 INFO - PROCESS | 1677 | Values: 60.0 62.0 70.0 96.0 96.0 96.0 91.0 110.0 89.0 93.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:74.90 Median:69.50 stddev:15.40 (22.2%) stddev-sans-first:16.00 05:17:13 INFO - PROCESS | 1677 | Values: 66.0 67.0 71.0 68.0 71.0 99.0 57.0 63.0 84.0 103.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:88.50 stddev:1.72 (1.9%) stddev-sans-first:1.73 05:17:13 INFO - PROCESS | 1677 | Values: 86.0 85.0 90.0 89.0 89.0 88.0 86.0 87.0 86.0 89.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:156.70 Median:150.50 stddev:27.42 (18.2%) stddev-sans-first:28.41 05:17:13 INFO - PROCESS | 1677 | Values: 140.0 130.0 150.0 151.0 174.0 149.0 150.0 138.0 227.0 158.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.70 Median:205.00 stddev:32.59 (15.9%) stddev-sans-first:33.61 05:17:13 INFO - PROCESS | 1677 | Values: 171.0 164.0 168.0 196.0 228.0 152.0 231.0 238.0 214.0 165.0 05:17:13 INFO - PROCESS | 1677 | 05:17:13 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.50 stddev:1.78 (2.6%) stddev-sans-first:1.80 05:17:13 INFO - PROCESS | 1677 | Values: 68.0 67.0 66.0 64.0 68.0 65.0 64.0 68.0 69.0 66.0 05:17:13 INFO - PROCESS | 1677 | -------- Summary: end -------- 05:17:13 INFO - PROCESS | 1677 | 05:17:14 INFO - TEST-INFO | 1677: exit 0 05:17:14 INFO - TEST-OK | kraken | took 62102ms 05:17:14 INFO - SUITE-END | took 1174s 05:17:14 INFO - Completed test suite (00:19:34) 05:17:14 INFO - javascript benchmark 05:17:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7119.0214338053, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3489.703375213106, 3494.8602055917763, 3505.702015328697, 3520.119970007498, 3555.093451169951, 5262.315898933138, 5588.804391846286, 5997.760089596416, 6018.548608854331, 6043.486303664183, 4814.253790662453, 5832.066869300913, 5860.648361098337, 6027.336992930785, 6124.387561243873, 5295.4704529547125, 5362.731863406833, 5373.785048598071, 5396.622236443451, 5438.939406815673, 198.04761570334992, 5280.287161233242, 5328.653637513549, 5401.054815407308, 5409.29679141711, 5322.095243808542, 5354.982553314858, 5360.544353729938, 5369.382521010095, 5394.32570928634, 5367.865803354947, 5406.702631355274, 5420.110913466566, 5427.972860135714, 5488.451154884519, 4288.871333859989, 5288.2596436498925, 5325.520703136736, 5358.276632654581, 5382.1926710993275, 5745.3105627324985, 5878.676672782995, 5886.941130588725, 5946.940530594682, 6029.547783916224, 6219.968900155471, 6339.270983836872, 6343.0, 6352.872942541123, 6364.681765911732, 6267.4045965633195, 6278.121063051177, 6312.810615681537, 6396.744130234785, 6405.711742971577, 6066.241719785027, 6080.027195648675, 6089.665068421235, 6097.115918191837, 6117.418845209653, 6205.410486003822, 6398.936010639927, 6510.414062734377, 6547.083408322838, 6559.5408321418, 6212.502999760009, 6220.098085777537, 6256.6871656417, 6283.465905398001, 6297.4647154992335, 6348.873022539523, 6407.391297826699, 6429.164208652939, 6434.388733070351, 6437.710303036374, 6227.0, 6268.623882567061, 6283.968580157161, 6309.432151106423, 6328.8417789554705, 6261.373862613702, 6271.78048768291, 6286.18279623646, 6291.087792270187, 6304.716287767062, 5038.622103342264, 5073.568746656501, 5076.670016448919, 5080.314157588752, 5082.415522214918, 4379.146066516999, 4436.223660859401, 4477.686561940698, 4511.030128522383, 4571.26859702446, 4807.326974223612, 5953.22608060958, 5955.076963070731, 6030.18592490017, 6048.274207095177, 6350.7777227796805, 6365.809025729235, 6368.840778980562, 6376.681165941684, 6382.84042898922, 5973.223480947449, 6084.421979911901, 6118.405988989689, 6132.264128304543, 6188.412100850412, 4805.6155507559315, 5601.271834661468, 5633.943660563424, 5635.520980716603, 5638.746256418471, 5547.362053363834, 6658.300878407795, 6663.46692264608, 6666.733330666816, 6677.69950352245, 6225.501959843111, 6341.587796793194, 6404.263509696445, 6418.839529011812, 6456.418922296828, 6001.559625689716, 6290.2766181889665, 6622.1722284714415, 6649.0, 6667.466602671678, 5165.251038599457, 6128.594790794166, 6321.209848768904, 6340.619562826245, 6341.207349081365, 5470.288862447856, 6205.627662340274, 6205.937940620536, 6253.8749225014335, 6268.843278918063, 6088.756449741961, 6128.662923539248, 6143.416375444325, 6149.508039356751, 6164.445199932027, 4492.618127459072, 6153.507719382528, 6158.32258451561, 6165.753369865155, 6216.937830621636, 4704.717716936994, 4868.221084626584, 6129.114290130661, 6238.719257633326, 6248.750249950119, 6273.466755325849, 6279.497640188682, 6294.307626161027, 6306.148669929685, 6311.179546658905, 6078.179445774941, 6099.420555047263, 6135.263768347649, 6213.751449941951, 6253.155823963707], "value": 5761.292734183412, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [533.3546408845294, 536.0538649284014, 542.6337222374896, 548.008105329354, 549.9780008799648, 38704.612953870455, 41225.58774412259, 41259.793701031456, 41262.76211713653, 41355.58644413555, 41899.0, 42387.364189537184, 42527.0, 42570.93572660685, 42571.57428425715, 9637.662681806138, 9815.607375704998, 9827.968063353352, 9830.311878168532, 9847.55685994132, 9779.217662586976, 9845.90154098457, 9861.359011664219, 9882.160016398615, 9898.752531186707, 9834.26243031772, 9843.557039933221, 9856.35933664313, 9896.55465504054, 9928.305018648698, 9774.853377199348, 9815.754606134904, 9823.65617203398, 9874.061964113398, 9881.555330010167, 9647.517624118766, 9686.75783105421, 9729.318947673666, 9776.902230977741, 9778.168855647207, 39427.0, 39599.60400395988, 39703.80148099241, 39748.80125599382, 39763.403548946786, 2813.6482939632547, 3023.334866329426, 3111.7821752477334, 3389.0849470643034, 3404.1829960809473, 26728.0, 27667.446651066864, 28696.687165245075, 28917.710822891713, 29235.122946311643, 2646.2193652872256, 2673.946521069587, 2701.3381721478167, 2771.5565509518583, 2773.0017193810413, 454603.7269813663, 455870.0, 458025.4197458016, 458319.0, 460706.69646651886, 35080.0, 35469.0, 36212.0, 36291.54833806618, 36374.0, 37063.81468092642, 37526.0, 37580.624193757714, 37629.43555846665, 37668.43497347542, 12520.373981300898, 15366.925465062073, 15466.767998480032, 15470.5358839235, 15629.06225626466, 10859.674209773719, 12766.744665106646, 12993.285369304589, 13067.542636007694, 13080.738385232242, 7911.723089691834, 7981.68073277074, 8167.305779008732, 8234.752957411287, 8244.340452763765, 3497.387957107496, 3497.7726447780815, 3508.6315936826777, 3543.3799085159994, 3560.8219589020446, 865.8051938313829, 1000.4497526360473, 1071.973200669974, 1110.8889111088988, 1111.9714264305485, 27607.723922760517, 27672.861635691694, 30223.69776302209, 30345.78616855345, 30969.0709278722, 869.9826003479895, 1059.7933402986343, 1101.755016830992, 1105.2870898270571, 1154.7575009247985, 15337.6165595861, 15408.075515469107, 15750.842491575168, 16438.67122657564, 17019.574510637332, 673313.0, 676457.0, 676950.0, 685254.0, 686662.0, 60458.697706511186, 61461.38538614646, 61713.69143154255, 61861.07208391878, 62104.0, 1455.2941823215945, 1468.0751126790267, 1472.3963175098372, 1493.402638944431, 1494.6039299585618, 28033.719662803112, 31002.844985774926, 31025.0, 31080.378392432478, 31292.904748333698, 53703.73148134234, 54018.0, 54366.0, 54914.72542637261, 55264.89470210654, 523234.0, 614439.0, 619007.0, 619310.0, 619758.0, 27219.72780272251, 27320.726792731817, 27466.17601471959, 27497.725022749517, 27840.44319113647, 46147.0, 46552.0, 46951.29573056475, 47321.0, 47340.7632961833, 37283.44074838825, 37298.06754831151, 37447.62552374441, 38073.66742164011, 38115.80942095272, 34052.48921266232, 37073.07317317089, 37702.0, 38048.23903521859, 38442.0, 33579.83210083934, 37189.6281037197, 37697.43453848249, 38097.04757381088, 38434.61565384422, 24371.0, 31793.523097154022, 32570.34859302848, 33666.83166584151, 33978.0, 23467.53064938726, 24076.277711668678, 24145.275641730776, 24247.545147291217, 24321.878390608643], "value": 18511.26321747151, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.50274642964143, 391.54971782450195, 397.39000634026775, 400.7515340488897, 416.8374334009737, 14659.560213193594, 20371.0, 20638.89680551599, 20968.16127354906, 21184.57630847382, 20645.63667503089, 20653.483662908435, 20829.895850520727, 21414.571708565858, 21584.1366345346, 5655.321361436623, 5909.734061967221, 6001.369856165084, 6022.698865056752, 6029.909551356734, 5698.373178950312, 5908.497777688902, 5927.673977931212, 5952.7321270542925, 5964.582479226434, 5840.561957853157, 5877.7355019024035, 5892.23400957877, 5893.970530147343, 5905.232319798421, 5709.571782116358, 5784.363719990798, 5859.912101318484, 5864.091065884816, 5885.058390657496, 5163.277141200235, 5183.870403239949, 5184.11869982104, 5196.870078248036, 5212.218167274901, 25219.991200351964, 25912.74087259122, 26933.730662693317, 26942.461150776875, 26968.0, 2145.395799676898, 2287.462446325112, 2511.962320565193, 2538.365408647838, 2554.540182767101, 11992.64022079339, 15178.772318415122, 15427.691446171126, 15447.459338923196, 15584.688306233926, 1589.284821830181, 1691.796984361872, 1715.9828401715947, 1752.071402156859, 1759.2171483689763, 116601.4169929145, 117183.0, 117719.0, 118593.0, 119025.80974190234, 9701.417914925127, 10724.410157441416, 10741.19441041925, 10851.077658398966, 11265.774684506265, 11360.943195284137, 13764.380602872894, 13782.586522404345, 13843.446262149602, 13844.861551384358, 6877.0, 8764.8685269721, 8775.166359195993, 8858.822823543493, 8864.335174861912, 6521.6413097279155, 7446.66490007951, 8776.78058048554, 8834.425762325429, 8861.601227944759, 6129.662868542273, 6661.700223489955, 6828.9658551706925, 6834.760783372558, 6846.144231971004, 1597.5526852481323, 1738.2351765223261, 1874.1097978460396, 1885.2270569066616, 1891.4420246027455, 879.6261588824851, 941.9591351556568, 966.3622009473713, 968.801395713877, 1030.5311083457009, 20112.497187570134, 20351.77889326645, 21558.676619850714, 21577.78422215789, 21712.34862954114, 943.9008904065112, 945.2485274207022, 1001.5242759689235, 1049.9370037797603, 1086.5327908999047, 11173.273737207055, 11665.300081995107, 11696.88303116975, 11703.707407314714, 11703.824442633368, 511847.0, 589726.0, 613074.0, 627108.0, 633546.0, 127050.0, 128624.0, 131159.0, 131596.3420182912, 131668.0, 1046.6022911293812, 1099.7305660113323, 1114.7324642085841, 1123.2081382625229, 1124.7188202949262, 18894.0, 19083.618327633092, 19204.807951920862, 19350.70973935364, 19393.224271029565, 51395.70124017941, 51943.961120776614, 51953.70115747137, 52569.47430525646, 52577.7371113142, 70362.64818675874, 72740.0, 73286.0, 74450.62774686092, 74716.0, 7911.802204944923, 7966.203379661987, 8110.513369197631, 8150.510969341858, 8212.753617391489, 12312.753744924872, 12497.687557811127, 12536.310502922426, 12899.419526121535, 12932.806007909701, 8442.00956747862, 10787.671232876637, 11045.723856903642, 11234.213605047567, 11351.659450216506, 10732.087772539422, 10804.567817287534, 10865.565377384815, 11002.834957475805, 11027.842076581843, 9875.703728888146, 11469.541218351505, 11493.080553555863, 11520.94239528797, 11563.942180289045, 7964.0, 7988.760337189894, 8063.911371964709, 8120.918790812017, 8377.70678026266, 9164.312676549418, 9484.383515071499, 9616.605592189233, 11077.446127693744, 11149.72125696864], "value": 9711.4591494683, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1558.9298481568328, 1582.027053362182, 1613.9106103380223, 1814.7822261328643, 1841.1070630744086, 8074.596270186496, 8649.308055355572, 8669.956650216747, 9169.128932751386, 9190.126937940893, 8729.825403491926, 8848.0, 8882.822343553125, 8980.910190898088, 8987.59555819988, 2397.62836760302, 2423.6243382275775, 2433.221369161869, 2438.6951631046118, 2442.9633555496594, 2016.5664382157834, 2402.3513651314224, 2417.2627348658652, 2419.9758002420012, 2454.3986723252738, 2217.855839370444, 2237.2281562860785, 2429.3805079704653, 2429.7813196812285, 2461.310832966772, 2421.0, 2421.055788242587, 2427.381017840457, 2433.7687919647783, 2434.196715084027, 2395.688560487143, 2403.291029146406, 2414.9034038638433, 2417.7944874685672, 2424.830261881669, 8990.0, 9041.141091596288, 9058.411203271766, 9069.863952040725, 9073.274138068939, 5367.2485851980755, 5480.123180291134, 5512.3936367, 5549.5005449509335, 5566.165075238706, 3503.0, 3517.085557755002, 3567.1973805893726, 3615.8011309377976, 3645.325614761278, 1045.5556388534842, 1046.7697106636529, 1050.023478165306, 1050.774083572028, 1054.6783231114507, 11518.366489843056, 11605.535778568847, 11878.0, 12090.214136081127, 12103.152779305452, 4832.251001094836, 4833.806647734122, 4948.233023881304, 4977.776000080005, 4979.3277907482025, 8556.443831150955, 9698.951505242429, 9892.950535247277, 9912.752181195528, 10053.04496072872, 5310.309659744208, 5380.677159370451, 5470.890582188414, 5483.643563168461, 5501.559875209973, 4478.149151661174, 4835.178019736653, 4958.950410495849, 4986.825461108844, 4998.950220453746, 3953.5453422856162, 4014.0, 4024.335984562573, 4090.6113919178224, 4159.771212583277, 1199.7540504196618, 1318.1102755639904, 1349.9662508437368, 1362.9659258518418, 1370.9588712338646, 828.4201059258543, 937.301710225886, 941.9340646154841, 952.9571169297399, 983.5770618633914, 5408.5402740766685, 5956.315023772322, 5992.161097446361, 6022.45797878193, 6220.160278362363, 926.7127190570944, 963.1331801378815, 967.8385936875777, 1053.9367637941748, 1062.3625824505234, 3956.872291396938, 3961.8217180226957, 4021.095253567982, 4049.068714195693, 4051.2707712612014, 10488.4231367274, 10840.078593319498, 10856.34861908288, 10892.564297428014, 10955.50700218492, 8887.377883548219, 10664.413457259925, 10860.131189504978, 11105.72235694098, 11152.665420037412, 8000.399970002157, 8111.229433203836, 8222.958885205535, 8444.493330400195, 8505.192006759347, 5293.417724050429, 5985.850353741192, 6013.128096426081, 6080.118382834376, 6103.23709536308, 8188.83622327562, 10343.27597068198, 10365.948170259402, 10392.636257731245, 10433.843492347469, 10246.0, 10261.0, 10465.633702820669, 10609.257351985287, 10612.363258204532, 6192.81421557354, 7010.649467526705, 7610.961945190239, 7671.424643151674, 7738.806529836799, 8494.065652778312, 8517.531535765593, 8566.271866891384, 8568.35737319716, 8578.828423431622, 6007.939920600738, 7540.547567145989, 7543.660535276035, 7561.659725312485, 7707.229277072248, 7403.704851496053, 7441.218672039358, 7505.136909255505, 7512.248775122663, 7535.924640753522, 7145.964270178616, 7192.1659976705605, 7222.74720384784, 7226.458015648953, 7245.094363204599, 4844.224924012141, 4888.2911977762315, 5033.119204139334, 5040.697558146523, 5201.245819356248, 4946.134426475424, 5062.898742025212, 5069.645124841226, 5084.186530155157, 5262.210668399771], "value": 4662.386621976172, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.58824763065127, 318.7004216036925, 325.3395606917956, 328.6746121339874, 330.3285375997453, 4303.096349766545, 5501.477359650837, 5560.776629141583, 5617.354004289503, 5704.9144262836035, 4614.446266448031, 4645.490215679901, 5544.22380866679, 5548.805791797288, 5581.7209139543065, 3493.812103884679, 4073.940775398403, 4214.639801711036, 4261.488621365441, 4290.356446533014, 3938.9606103939022, 4226.1336426032585, 4270.402143699885, 4274.187904298174, 4279.111599670226, 4137.3173426384765, 4187.539370669224, 4194.0, 4202.0, 4204.936925946114, 4181.853635122787, 4195.81118849651, 4211.410402543646, 4217.536071032184, 4234.771916144315, 3357.4818885835625, 3465.376232278189, 3581.767185132958, 3621.130928577149, 3642.7267954903486, 4696.671233013691, 4872.780724867354, 5080.415752188472, 5219.295395121648, 5318.686371428441, 4765.189228093337, 4769.99830035693, 4786.904261914742, 4788.712677239377, 4795.400574928134, 5599.608027438081, 5857.121431785224, 5888.5877988540815, 5890.352061273256, 5899.144624029492, 3756.586775454698, 3868.323043467382, 3929.764214147132, 3993.7803420811842, 4013.7391069580676, 5079.399989003452, 5414.159185876821, 5755.482006619424, 6102.4507794298725, 6253.374662533755, 3284.5730055093163, 3341.2649217172184, 3623.804144632265, 3745.3408080263316, 3946.8223929923224, 3940.66017554033, 4058.6144316289906, 4067.898302542401, 4069.369247766601, 4116.579779975963, 3627.222660896142, 3938.1926705025403, 4165.812538435777, 4542.886992686746, 4557.475890272661, 5548.944510554924, 5771.278590176228, 5836.795712150054, 5863.501602363847, 5883.735231914574, 1171.77736230116, 1355.3426588104762, 1359.211657238799, 1372.0, 1384.3424373422745, 797.8843067755143, 818.0265484073933, 846.8645016797283, 849.9757791860793, 855.7903313688187, 4054.3646629690347, 4386.605205531454, 4476.9104617908115, 4484.170428470743, 4542.954570454253, 4788.712677239377, 4865.9756701216265, 5027.692799872059, 5070.645054846199, 5112.9744351278005, 3874.3994680824517, 4194.846417235285, 4401.471823381215, 4433.379326894237, 4626.195783644382, 2420.3178790740267, 2445.9143929962365, 2449.7383847318647, 2704.269847141261, 2723.251105945841, 4491.752953587584, 4982.526659967298, 5054.78685115577, 5145.253938178943, 5325.562098233455, 4396.692231543761, 4690.686607749835, 4811.412233962784, 4892.608591312686, 5069.9746501267255, 4709.5055019223855, 5158.509941555471, 5331.760070796823, 5367.838964831061, 5401.432849550819, 4357.433533640733, 5071.6780508766815, 5097.464766199644, 5104.515071068243, 5127.0, 4148.170365926887, 4255.638270747021, 4641.885947372673, 4716.363290955815, 5063.924041139459, 4161.8335266589, 4855.761780745887, 4884.5603895649565, 5309.123994540858, 5346.893062138658, 3154.397621961845, 3337.849796759103, 3349.882754103595, 3486.8082255476193, 3533.787972721645, 4316.676249281254, 4703.882402939954, 4881.316615673737, 5099.781595006184, 5712.143178523089, 4555.863324100283, 5912.970435147969, 5937.139114828412, 5961.195238642727, 5965.28416590012, 5884.352041581391, 5942.86057019464, 5951.7024148791525, 5965.164876917322, 5987.550933680079, 4696.7788375022055, 4863.7324947128245, 5599.440055994368, 5803.0, 5833.0, 5019.7741101651245, 5209.921851172311, 5887.852803679943, 5906.675132867734, 5915.763369465173, 4752.883072478034, 5057.14028615129, 5429.918551221814, 5528.253685752372, 5669.922714684197], "value": 3963.622455202002, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.432682220113, 691.3812138136365, 694.3507820188123, 696.658637267739, 720.8666396716604, 14453.421863125475, 15280.388784448609, 15283.847161528382, 15545.922270388646, 15685.921570392133, 14388.352524136413, 14439.205843678594, 14479.78280325796, 14526.927365363184, 14527.491537796179, 743.2864450127884, 908.591133989704, 913.3560839608077, 928.2620316848122, 942.0626476655738, 930.1256818590504, 935.8549424839126, 939.8167357365317, 941.3692825806726, 942.726609283307, 937.690562114504, 975.1272611013175, 976.077606661705, 980.2550061952932, 985.3595163143978, 938.2447130060299, 955.4171955107379, 963.393062370709, 964.7346979580636, 969.5249327829383, 694.1600663197537, 694.3577191098265, 695.2005194026909, 725.5066554742773, 728.6101935464554, 14850.331735071948, 15026.323815428332, 15088.24558772057, 15095.245237738069, 15159.09045457276, 12474.313912734797, 12486.092470725602, 12578.119531632788, 12704.555340563036, 12705.364731763375, 9890.258230632659, 9901.554430050664, 10049.89950100497, 10111.03945125212, 10493.160547156285, 3392.508086327469, 3550.8757193498363, 4044.0698639313123, 4053.3514637658127, 4089.7137200395982, 81202.0, 83532.58233708792, 84842.72735908967, 85154.14845851524, 85682.0, 7697.538147711155, 9238.030006849318, 9271.211946984582, 9388.389754665925, 9557.426554406758, 19691.0, 19709.507262318555, 20293.898530507548, 20323.695144572845, 20366.0, 7271.091113610799, 7326.6702998364135, 7329.120505539369, 7408.888866667005, 7589.354904833151, 3964.484616999829, 3972.0569830118416, 4020.959790402117, 4021.1354558770004, 4113.753174809462, 3485.4597537381746, 3570.535830342091, 3572.678458938656, 3578.0, 3612.422012477991, 562.8452175651729, 623.7567348733998, 639.7121295417081, 643.1960049937578, 651.879402310574, 479.8920242945379, 480.2388214679702, 523.588982648619, 552.4558310064583, 560.2996254681648, 7572.810679733051, 7658.693652253847, 7699.230076992368, 7708.190639982833, 7760.223977602308, 528.9603279754034, 542.7259234086761, 549.5960469055241, 566.8497848070265, 574.5317566183547, 3457.22212502185, 3647.76289541179, 3679.8712045078296, 3690.5479527123484, 3737.4980689593267, 82026.58986705028, 85635.57182214173, 85716.0, 86065.56967215124, 87526.0, 68767.0, 72507.91238131432, 73078.2692173082, 75716.0, 75789.62105189438, 64850.67574662097, 71545.64227178831, 74502.62748686221, 75642.0, 76981.0, 7857.410694197843, 7875.632178181005, 7997.920020799717, 8017.118117007008, 8152.877706834521, 32626.0, 43136.13727725365, 43358.0, 43417.78291108524, 43441.56558434375, 56985.43014569967, 59912.70043649754, 60273.09590356061, 60351.396486036334, 60639.69680151571, 11441.656750297172, 11541.942290288494, 11593.304401735923, 11827.763444731228, 11874.526534029854, 11842.28946263227, 12090.335031573348, 12218.20581662189, 12312.015038797053, 12371.319577423328, 11377.943110284396, 11398.829017564578, 11613.187076904536, 11776.587819426279, 11944.64166075019, 11242.94378528102, 11410.201285909907, 11809.173357864867, 11849.170558060852, 11905.107116966436, 10217.0, 11223.775524489301, 11523.654290370967, 11669.474873630537, 12200.328981905725, 9619.086186812241, 9796.069373409515, 9964.601415943282, 10049.949750251202, 10086.991300869855, 9686.225101991682, 9698.321117521706, 9699.127078562964, 9740.659076932274, 9773.609055637979], "value": 6800.434310668101, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1661.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 110.0, 115.0, 117.0, 115.0, 108.0, 117.0, 117.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 154.0, 162.0, 256.0, 131.0, 143.0, 122.0, 174.0, 191.0, 122.0], "value": 163.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 204.0, 211.0, 202.0, 267.0, 279.0, 214.0, 194.0, 194.0, 197.0], "value": 215.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 78.0, 74.0, 170.0, 57.0, 58.0, 59.0, 66.0, 129.0, 60.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 88.0, 143.0, 78.0, 106.0, 64.0, 118.0, 116.0, 114.0, 160.0], "value": 106.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 86.0, 88.0, 86.0, 89.0, 88.0, 107.0, 111.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 107.0, 97.0, 154.0, 151.0, 95.0, 99.0, 94.0, 109.0, 108.0], "value": 110.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 133.0, 134.0, 79.0, 93.0, 140.0, 105.0, 116.0, 107.0, 124.0], "value": 112.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 70.0, 96.0, 96.0, 96.0, 91.0, 110.0, 89.0, 93.0], "value": 86.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 71.0, 68.0, 71.0, 99.0, 57.0, 63.0, 84.0, 103.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 90.0, 89.0, 89.0, 88.0, 86.0, 87.0, 86.0, 89.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 130.0, 150.0, 151.0, 174.0, 149.0, 150.0, 138.0, 227.0, 158.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 164.0, 168.0, 196.0, 228.0, 152.0, 231.0, 238.0, 214.0, 165.0], "value": 192.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 64.0, 68.0, 65.0, 64.0, 68.0, 69.0, 66.0], "value": 66.5, "unit": "score"}]}]} 05:17:15 INFO - Return code: 0 05:17:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:17:15 ERROR - # TBPL SUCCESS # 05:17:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:17:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:17:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:17:15 INFO - 2016-05-13 05:17:15.079 screenresolution[1682:10793] starting screenresolution argv=screenresolution get 05:17:15 INFO - 2016-05-13 05:17:15.082 screenresolution[1682:10793] Display 0: 1600x1200x32@60 05:17:15 INFO - 2016-05-13 05:17:15.088 screenresolution[1683:10796] starting screenresolution argv=screenresolution list 05:17:15 INFO - Available Modes on Display 0 05:17:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:17:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:17:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:17:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:17:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:17:15 INFO - Intel Iris: 05:17:15 INFO - Chipset Model: Intel Iris 05:17:15 INFO - Type: GPU 05:17:15 INFO - Bus: Built-In 05:17:15 INFO - VRAM (Dynamic, Max): 1536 MB 05:17:15 INFO - Vendor: Intel (0x8086) 05:17:15 INFO - Device ID: 0x0a2e 05:17:15 INFO - Revision ID: 0x0009 05:17:15 INFO - Displays: 05:17:15 INFO - Display: 05:17:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:17:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:17:15 INFO - Display Serial Number: 1600x1200 60 05:17:15 INFO - Main Display: Yes 05:17:15 INFO - Mirror: Off 05:17:15 INFO - Online: Yes 05:17:15 INFO - Rotation: Supported 05:17:15 INFO - Return code: 0 05:17:15 INFO - Running post-action listener: _resource_record_post_action 05:17:15 INFO - ##### 05:17:15 INFO - ##### Finished run-tests step (success) 05:17:15 INFO - ##### 05:17:15 INFO - Running post-run listener: _resource_record_post_run 05:17:16 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 37934592; Write bytes: 698030592; Read time: 405; Write time: 1963 05:17:16 INFO - TinderboxPrint: CPU usage
54.7% 05:17:16 INFO - TinderboxPrint: I/O read bytes / time
37,934,592 / 405 05:17:16 INFO - TinderboxPrint: I/O write bytes / time
698,030,592 / 1,963 05:17:16 INFO - TinderboxPrint: CPU idle
2,193.2 (45.3%) 05:17:16 INFO - TinderboxPrint: CPU system
973.6 (20.1%) 05:17:16 INFO - TinderboxPrint: CPU user
1,670.9 (34.5%) 05:17:16 INFO - TinderboxPrint: Swap in / out
432,586,752 / 0 05:17:16 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 389386752; Write bytes: 377460224; Read time: 28448; Write time: 680 05:17:16 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 30850560; Write bytes: 301175808; Read time: 322; Write time: 1237 05:17:16 INFO - Running post-run listener: _upload_blobber_files 05:17:16 INFO - Blob upload gear active. 05:17:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:17:16 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:17:16 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:17:16 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:17:16 INFO - (blobuploader) - INFO - Open directory for files ... 05:17:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:17:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:17:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:17:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:17:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:17:17 INFO - (blobuploader) - INFO - Done attempting. 05:17:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:17:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:17:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:17:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:17:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:17:18 INFO - (blobuploader) - INFO - Done attempting. 05:17:18 INFO - (blobuploader) - INFO - Iteration through files over. 05:17:18 INFO - Return code: 0 05:17:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:17:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:17:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27388f00989111f764a7453853254a85b2bfb2e49251ec551d5b2532cc6080dc31c7000511cd6e9104bad955c1e10595e6178125b4fdba5dd4dfe940d29bdd54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/344c6437636c14e7ac84a508921871572dff139c3c8b6e59c53d80bb221009244addc52893073a3f4944d0f5530bd03ff6fb8aa8df1938e3035b95d2a23377f4"} 05:17:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:17:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:17:18 INFO - Contents: 05:17:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27388f00989111f764a7453853254a85b2bfb2e49251ec551d5b2532cc6080dc31c7000511cd6e9104bad955c1e10595e6178125b4fdba5dd4dfe940d29bdd54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/344c6437636c14e7ac84a508921871572dff139c3c8b6e59c53d80bb221009244addc52893073a3f4944d0f5530bd03ff6fb8aa8df1938e3035b95d2a23377f4"} 05:17:18 INFO - Running post-run listener: copy_logs_to_upload_dir 05:17:18 INFO - Copying logs to upload dir... 05:17:18 INFO - mkdir: /builds/slave/test/build/upload/logs 05:17:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.800281 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-13 05:17:18.658648) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 05:17:18.661989) ========= 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.f5gnTKWZCd/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.z3jlXfDkec/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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/27388f00989111f764a7453853254a85b2bfb2e49251ec551d5b2532cc6080dc31c7000511cd6e9104bad955c1e10595e6178125b4fdba5dd4dfe940d29bdd54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/344c6437636c14e7ac84a508921871572dff139c3c8b6e59c53d80bb221009244addc52893073a3f4944d0f5530bd03ff6fb8aa8df1938e3035b95d2a23377f4"} build_url:https://queue.taskcluster.net/v1/task/ZTlVMz9_TTKroKQe0U3KAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011248 build_url: 'https://queue.taskcluster.net/v1/task/ZTlVMz9_TTKroKQe0U3KAw/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/27388f00989111f764a7453853254a85b2bfb2e49251ec551d5b2532cc6080dc31c7000511cd6e9104bad955c1e10595e6178125b4fdba5dd4dfe940d29bdd54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/344c6437636c14e7ac84a508921871572dff139c3c8b6e59c53d80bb221009244addc52893073a3f4944d0f5530bd03ff6fb8aa8df1938e3035b95d2a23377f4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 05:17:18.689944) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:17:18.690268) ========= 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.f5gnTKWZCd/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.z3jlXfDkec/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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.005028 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:17:18.738682) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 05:17:18.739025) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 05:17:18.739414) ========= ========= Total master_lag: 0.16 =========