builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0066 starttime: 1462104649.19 results: success (0) buildid: 20160501030217 builduid: d628f44785ef404384c251c21ad92714 revision: 1461a4071341c282afcf7b72e33036412d2251d4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:49.191392) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:49.191913) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:49.192201) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:49.874836) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:49.875285) ========= 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.KM8nhNzts0/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.JEsHSPMma8/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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-01 05:10:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.23M=0.002s 2016-05-01 05:10:50 (7.23 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.520823 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:50.558690) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:50.558988) ========= 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.KM8nhNzts0/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.JEsHSPMma8/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.020888 ========= master_lag: 0.12 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:50.696925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-01 05:10:50.697242) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1461a4071341c282afcf7b72e33036412d2251d4 --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 1461a4071341c282afcf7b72e33036412d2251d4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KM8nhNzts0/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.JEsHSPMma8/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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-01 05:10:50,803 truncating revision to first 12 chars 2016-05-01 05:10:50,803 Setting DEBUG logging. 2016-05-01 05:10:50,803 attempt 1/10 2016-05-01 05:10:50,803 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1461a4071341?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-01 05:10:51,880 unpacking tar archive at: mozilla-central-1461a4071341/testing/mozharness/ program finished with exit code 0 elapsedTime=1.330838 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-01 05:10:52.074608) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:52.074929) ========= script_repo_revision: 1461a4071341c282afcf7b72e33036412d2251d4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:52.075373) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:52.075946) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-01 05:10:52.093082) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 46 secs) (at 2016-05-01 05:10:52.093456) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.KM8nhNzts0/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.JEsHSPMma8/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:10:52 INFO - MultiFileLogger online at 20160501 05:10:52 in /builds/slave/test 05:10:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:10:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:10:52 INFO - {'append_to_log': False, 05:10:52 INFO - 'base_work_dir': '/builds/slave/test', 05:10:52 INFO - 'blob_upload_branch': 'Firefox', 05:10:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:10:52 INFO - 'branch': 'Firefox', 05:10:52 INFO - 'buildbot_json_path': 'buildprops.json', 05:10:52 INFO - 'config_files': ('talos/mac_config.py',), 05:10:52 INFO - 'default_actions': ('clobber', 05:10:52 INFO - 'read-buildbot-config', 05:10:52 INFO - 'download-and-extract', 05:10:52 INFO - 'populate-webroot', 05:10:52 INFO - 'create-virtualenv', 05:10:52 INFO - 'install', 05:10:52 INFO - 'run-tests'), 05:10:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:10:52 INFO - 'download_minidump_stackwalk': True, 05:10:52 INFO - 'download_symbols': 'ondemand', 05:10:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:10:52 INFO - 'tooltool.py': '/tools/tooltool.py', 05:10:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:10:52 INFO - '/tools/misc-python/virtualenv.py')}, 05:10:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:10:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:10:52 INFO - 'installer_path': 'installer.exe', 05:10:52 INFO - 'log_level': 'info', 05:10:52 INFO - 'log_name': 'talos', 05:10:52 INFO - 'log_to_console': True, 05:10:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:10:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:10:52 INFO - 'opt_config_files': (), 05:10:52 INFO - 'pip_index': False, 05:10:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:52 INFO - 'cmd': ('bash', 05:10:52 INFO - '-c', 05:10:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:52 INFO - 'enabled': True, 05:10:52 INFO - 'halt_on_failure': False, 05:10:52 INFO - 'name': 'check_screen_resolution'},), 05:10:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:52 INFO - 'cmd': ('bash', 05:10:52 INFO - '-c', 05:10:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:52 INFO - 'enabled': True, 05:10:52 INFO - 'halt_on_failure': False, 05:10:52 INFO - 'name': 'check_screen_resolution'},), 05:10:52 INFO - 'run_cmd_checks_enabled': True, 05:10:52 INFO - 'sps_profile': False, 05:10:52 INFO - 'sps_profile_interval': 0, 05:10:52 INFO - 'suite': 'dromaeojs', 05:10:52 INFO - 'system_bits': '32', 05:10:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:10:52 INFO - 'title': 't-yosemite-r7-0066', 05:10:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:10:52 INFO - 'use_talos_json': True, 05:10:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:10:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:10:52 INFO - 'work_dir': 'build'} 05:10:52 INFO - ##### 05:10:52 INFO - ##### Running clobber step. 05:10:52 INFO - ##### 05:10:52 INFO - Running pre-action listener: _resource_record_pre_action 05:10:52 INFO - Running main action method: clobber 05:10:52 INFO - rmtree: /builds/slave/test/build 05:10:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:10:54 INFO - Running post-action listener: _resource_record_post_action 05:10:54 INFO - ##### 05:10:54 INFO - ##### Running read-buildbot-config step. 05:10:54 INFO - ##### 05:10:54 INFO - Running pre-action listener: _resource_record_pre_action 05:10:54 INFO - Running main action method: read_buildbot_config 05:10:54 INFO - Using buildbot properties: 05:10:54 INFO - { 05:10:54 INFO - "project": "", 05:10:54 INFO - "product": "firefox", 05:10:54 INFO - "script_repo_revision": "production", 05:10:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:10:54 INFO - "repository": "", 05:10:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:10:54 INFO - "buildid": "20160501030217", 05:10:54 INFO - "stage_platform": "macosx64", 05:10:54 INFO - "basedir": "/builds/slave/test", 05:10:54 INFO - "nightly_build": "True", 05:10:54 INFO - "buildnumber": 234, 05:10:54 INFO - "slavename": "t-yosemite-r7-0066", 05:10:54 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:10:54 INFO - "platform": "yosemite_r7", 05:10:54 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:10:54 INFO - "branch": "mozilla-central", 05:10:54 INFO - "repo_path": "mozilla-central", 05:10:54 INFO - "revision": "1461a4071341c282afcf7b72e33036412d2251d4", 05:10:54 INFO - "pgo_build": "False", 05:10:54 INFO - "builduid": "d628f44785ef404384c251c21ad92714", 05:10:54 INFO - "slavebuilddir": "test" 05:10:54 INFO - } 05:10:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:10:54 INFO - Running post-action listener: _resource_record_post_action 05:10:54 INFO - ##### 05:10:54 INFO - ##### Running download-and-extract step. 05:10:54 INFO - ##### 05:10:54 INFO - Running pre-action listener: _resource_record_pre_action 05:10:54 INFO - Running main action method: download_and_extract 05:10:54 INFO - mkdir: /builds/slave/test/build/tests 05:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:54 INFO - https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:10:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 05:11:24 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json: timed out 05:11:24 INFO - retry: attempt #1 caught exception: timed out 05:11:24 INFO - retry: Failed, sleeping 30 seconds before retrying 05:11:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #2 05:12:24 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json: timed out 05:12:24 INFO - retry: attempt #2 caught exception: timed out 05:12:24 INFO - retry: Failed, sleeping 60 seconds before retrying 05:13:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/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 #3 05:13:27 INFO - Downloaded 1199 bytes. 05:13:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:13:27 INFO - Using the following test package requirements: 05:13:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:13:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:13:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:13:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:27 INFO - u'jsshell-mac.zip'], 05:13:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:13:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:13:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:13:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:13:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:13:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:13:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:13:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:27 INFO - https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:13:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:13:28 INFO - Downloaded 18127869 bytes. 05:13:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:13:31 INFO - Return code: 0 05:13:31 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:31 INFO - https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:13:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:13:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:13:34 INFO - Downloaded 18127869 bytes. 05:13:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:13:38 INFO - Return code: 0 05:13:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:38 INFO - https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:13:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:13:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:13:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:13:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:13:39 INFO - Downloaded 10840152 bytes. 05:13:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:13:39 INFO - Return code: 0 05:13: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')]} 05:13:39 INFO - https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:13:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:13:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 05:13:44 INFO - Downloaded 110657922 bytes. 05:13:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:44 INFO - mkdir: /builds/slave/test/properties 05:13:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:13:44 INFO - Writing to file /builds/slave/test/properties/build_url 05:13:44 INFO - Contents: 05:13:44 INFO - build_url:https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:45 INFO - Running post-action listener: _resource_record_post_action 05:13:45 INFO - Running post-action listener: set_extra_try_arguments 05:13:45 INFO - ##### 05:13:45 INFO - ##### Running populate-webroot step. 05:13:45 INFO - ##### 05:13:45 INFO - Running pre-action listener: _resource_record_pre_action 05:13:45 INFO - Running main action method: populate_webroot 05:13:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:13:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:13:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:13:45 INFO - '--tppagecycles', 05:13:45 INFO - '7'], 05:13:45 INFO - 'tests': ['tsvgm']}}, 05:13:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:13:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:13:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:13:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:13:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:13:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:13:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:45 INFO - 'talos_options': ['--disable-e10s'], 05:13:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:45 INFO - 'talos_options': ['--disable-e10s'], 05:13:45 INFO - 'tests': ['damp', 'tps']}, 05:13:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:45 INFO - 'tests': ['damp', 'tps']}, 05:13:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:13:45 INFO - 'tests': ['dromaeo_dom']}, 05:13:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:13:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:13:45 INFO - 'tests': ['a11yr', 05:13:45 INFO - 'ts_paint', 05:13:45 INFO - 'tpaint', 05:13:45 INFO - 'sessionrestore', 05:13:45 INFO - 'sessionrestore_no_auto_restore', 05:13:45 INFO - 'tabpaint']}, 05:13:45 INFO - 'other-e10s': {'tests': ['a11yr', 05:13:45 INFO - 'ts_paint', 05:13:45 INFO - 'tpaint', 05:13:45 INFO - 'sessionrestore', 05:13:45 INFO - 'sessionrestore_no_auto_restore', 05:13:45 INFO - 'tabpaint']}, 05:13:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:13:45 INFO - 'ts_paint', 05:13:45 INFO - 'tpaint', 05:13:45 INFO - 'sessionrestore', 05:13:45 INFO - 'sessionrestore_no_auto_restore', 05:13:45 INFO - 'tabpaint']}, 05:13:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:13:45 INFO - 'ts_paint', 05:13:45 INFO - 'tpaint', 05:13:45 INFO - 'sessionrestore', 05:13:45 INFO - 'sessionrestore_no_auto_restore', 05:13:45 INFO - 'tabpaint']}, 05:13:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:13:45 INFO - 'tests': ['a11yr', 05:13:45 INFO - 'ts_paint', 05:13:45 INFO - 'tpaint', 05:13:45 INFO - 'sessionrestore', 05:13:45 INFO - 'sessionrestore_no_auto_restore', 05:13:45 INFO - 'tabpaint']}, 05:13:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:13:45 INFO - 'tests': ['a11yr', 05:13:45 INFO - 'ts_paint', 05:13:45 INFO - 'tpaint', 05:13:45 INFO - 'sessionrestore', 05:13:45 INFO - 'sessionrestore_no_auto_restore', 05:13:45 INFO - 'tabpaint']}, 05:13:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:13:45 INFO - 'tests': ['tsvgx', 05:13:45 INFO - 'tsvgr_opacity', 05:13:45 INFO - 'tart', 05:13:45 INFO - 'tscrollx', 05:13:45 INFO - 'cart']}, 05:13:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:13:45 INFO - 'tsvgr_opacity', 05:13:45 INFO - 'tart', 05:13:45 INFO - 'tscrollx', 05:13:45 INFO - 'cart']}, 05:13:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:45 INFO - 'talos_options': ['--disable-e10s'], 05:13:45 INFO - 'tests': ['tp5o']}, 05:13:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:45 INFO - 'tests': ['tp5o']}, 05:13:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:45 INFO - 'talos_options': ['--disable-e10s', 05:13:45 INFO - '--xperf_path', 05:13:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:45 INFO - 'tests': ['tp5n']}, 05:13:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:45 INFO - 'talos_options': ['--xperf_path', 05:13:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:45 INFO - 'tests': ['tp5n']}}, 05:13:45 INFO - 'talos.zip': {'path': '', 05:13:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:13:45 INFO - Running post-action listener: _resource_record_post_action 05:13:45 INFO - ##### 05:13:45 INFO - ##### Running create-virtualenv step. 05:13:45 INFO - ##### 05:13:45 INFO - Running pre-action listener: _resource_record_pre_action 05:13:45 INFO - Running main action method: create_virtualenv 05:13:45 INFO - Creating virtualenv /builds/slave/test/build/venv 05:13:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:13:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:13:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:13:45 INFO - Using real prefix '/tools/python27' 05:13:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:13:46 INFO - Installing distribute.............................................................................................................................................................................................done. 05:13:48 INFO - Installing pip.................done. 05:13:48 INFO - Return code: 0 05:13:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd59148f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10becbe00>, '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 0x10bcf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec6b20>, '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.KM8nhNzts0/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.JEsHSPMma8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:13:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KM8nhNzts0/Render', 05:13:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:48 INFO - 'HOME': '/Users/cltbld', 05:13:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:48 INFO - 'LOGNAME': 'cltbld', 05:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:48 INFO - 'MOZ_NO_REMOTE': '1', 05:13:48 INFO - 'NO_EM_RESTART': '1', 05:13:48 INFO - 'PAGER': '/bin/cat', 05:13:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:48 INFO - 'PWD': '/builds/slave/test', 05:13:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:48 INFO - 'SHELL': '/bin/bash', 05:13:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JEsHSPMma8/Listeners', 05:13:48 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 05:13:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:48 INFO - 'USER': 'cltbld', 05:13:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:48 INFO - 'XPC_FLAGS': '0x0', 05:13:48 INFO - 'XPC_SERVICE_NAME': '0', 05:13:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:13:49 INFO - Cleaning up... 05:13:49 INFO - Return code: 0 05:13:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:49 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 0x10c02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd59148f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10becbe00>, '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 0x10bcf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec6b20>, '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.KM8nhNzts0/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.JEsHSPMma8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KM8nhNzts0/Render', 05:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:49 INFO - 'HOME': '/Users/cltbld', 05:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:49 INFO - 'LOGNAME': 'cltbld', 05:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:49 INFO - 'MOZ_NO_REMOTE': '1', 05:13:49 INFO - 'NO_EM_RESTART': '1', 05:13:49 INFO - 'PAGER': '/bin/cat', 05:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:49 INFO - 'PWD': '/builds/slave/test', 05:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:49 INFO - 'SHELL': '/bin/bash', 05:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JEsHSPMma8/Listeners', 05:13:49 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 05:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:49 INFO - 'USER': 'cltbld', 05:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:49 INFO - 'XPC_FLAGS': '0x0', 05:13:49 INFO - 'XPC_SERVICE_NAME': '0', 05:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:49 INFO - Downloading/unpacking psutil>=0.7.1 05:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:13:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:52 INFO - Installing collected packages: psutil 05:13:52 INFO - Running setup.py install for psutil 05:13:52 INFO - building 'psutil._psutil_osx' extension 05:13:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:13:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:13:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:13:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:13:53 INFO - building 'psutil._psutil_posix' extension 05:13:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:13:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:13:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:13:53 INFO - ^ 05:13:53 INFO - 1 warning generated. 05:13:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:13:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:53 INFO - Successfully installed psutil 05:13:53 INFO - Cleaning up... 05:13:53 INFO - Return code: 0 05:13:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:53 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 0x10c02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd59148f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10becbe00>, '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 0x10bcf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec6b20>, '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.KM8nhNzts0/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.JEsHSPMma8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:13:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:13:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KM8nhNzts0/Render', 05:13:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:53 INFO - 'HOME': '/Users/cltbld', 05:13:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:53 INFO - 'LOGNAME': 'cltbld', 05:13:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:53 INFO - 'MOZ_NO_REMOTE': '1', 05:13:53 INFO - 'NO_EM_RESTART': '1', 05:13:53 INFO - 'PAGER': '/bin/cat', 05:13:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:53 INFO - 'PWD': '/builds/slave/test', 05:13:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:53 INFO - 'SHELL': '/bin/bash', 05:13:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JEsHSPMma8/Listeners', 05:13:53 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 05:13:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:53 INFO - 'USER': 'cltbld', 05:13:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:53 INFO - 'XPC_FLAGS': '0x0', 05:13:53 INFO - 'XPC_SERVICE_NAME': '0', 05:13:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:13:56 INFO - Installing collected packages: mozsystemmonitor 05:13:56 INFO - Running setup.py install for mozsystemmonitor 05:13:56 INFO - Successfully installed mozsystemmonitor 05:13:56 INFO - Cleaning up... 05:13:56 INFO - Return code: 0 05:13:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:56 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 0x10c02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd59148f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10becbe00>, '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 0x10bcf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec6b20>, '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.KM8nhNzts0/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.JEsHSPMma8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:13:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:13:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:13:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KM8nhNzts0/Render', 05:13:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:56 INFO - 'HOME': '/Users/cltbld', 05:13:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:56 INFO - 'LOGNAME': 'cltbld', 05:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:56 INFO - 'MOZ_NO_REMOTE': '1', 05:13:56 INFO - 'NO_EM_RESTART': '1', 05:13:56 INFO - 'PAGER': '/bin/cat', 05:13:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:56 INFO - 'PWD': '/builds/slave/test', 05:13:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:56 INFO - 'SHELL': '/bin/bash', 05:13:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JEsHSPMma8/Listeners', 05:13:56 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 05:13:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:56 INFO - 'USER': 'cltbld', 05:13:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:56 INFO - 'XPC_FLAGS': '0x0', 05:13:56 INFO - 'XPC_SERVICE_NAME': '0', 05:13:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:57 INFO - Downloading/unpacking blobuploader==1.2.4 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:59 INFO - Downloading blobuploader-1.2.4.tar.gz 05:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:13:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:14:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:00 INFO - Downloading docopt-0.6.1.tar.gz 05:14:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:14:00 INFO - Installing collected packages: blobuploader, requests, docopt 05:14:00 INFO - Running setup.py install for blobuploader 05:14:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:14:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:14:00 INFO - Running setup.py install for requests 05:14:01 INFO - Running setup.py install for docopt 05:14:01 INFO - Successfully installed blobuploader requests docopt 05:14:01 INFO - Cleaning up... 05:14:01 INFO - Return code: 0 05:14:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:01 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 0x10c02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd59148f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10becbe00>, '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 0x10bcf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec6b20>, '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.KM8nhNzts0/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.JEsHSPMma8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KM8nhNzts0/Render', 05:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:01 INFO - 'HOME': '/Users/cltbld', 05:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:01 INFO - 'LOGNAME': 'cltbld', 05:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:01 INFO - 'MOZ_NO_REMOTE': '1', 05:14:01 INFO - 'NO_EM_RESTART': '1', 05:14:01 INFO - 'PAGER': '/bin/cat', 05:14:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:01 INFO - 'PWD': '/builds/slave/test', 05:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:01 INFO - 'SHELL': '/bin/bash', 05:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JEsHSPMma8/Listeners', 05:14:01 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 05:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:01 INFO - 'USER': 'cltbld', 05:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:01 INFO - 'XPC_FLAGS': '0x0', 05:14:01 INFO - 'XPC_SERVICE_NAME': '0', 05:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:01 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-EGoSd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:01 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-z6MUPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-TX7iok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-E6gKot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Cg4uJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-xjDnwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-WlnNHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-yiJsbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Rygt_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-DMpR7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-zPj1qL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-oMOr2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-_ljJe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-XPwYfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-XDxxFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-4wM1Vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-1GxSn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:14:03 INFO - Running setup.py install for manifestparser 05:14:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:14:03 INFO - Running setup.py install for mozcrash 05:14:03 INFO - Running setup.py install for mozdebug 05:14:04 INFO - Running setup.py install for mozdevice 05:14:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:14:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:14:04 INFO - Running setup.py install for mozfile 05:14:04 INFO - Running setup.py install for mozhttpd 05:14:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:14:04 INFO - Running setup.py install for mozinfo 05:14:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:14:04 INFO - Running setup.py install for mozInstall 05:14:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:14:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:14:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:14:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:14:04 INFO - Running setup.py install for mozleak 05:14:04 INFO - Running setup.py install for mozlog 05:14:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:14:05 INFO - Running setup.py install for moznetwork 05:14:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:14:05 INFO - Running setup.py install for mozprocess 05:14:05 INFO - Running setup.py install for mozprofile 05:14:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:14:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:14:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:14:05 INFO - Running setup.py install for mozrunner 05:14:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:14:05 INFO - Running setup.py install for mozscreenshot 05:14:05 INFO - Running setup.py install for moztest 05:14:06 INFO - Running setup.py install for mozversion 05:14:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:14:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:14:06 INFO - Cleaning up... 05:14:06 INFO - Return code: 0 05:14:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:06 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 0x10c02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd59148f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10becbe00>, '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 0x10bcf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec6b20>, '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.KM8nhNzts0/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.JEsHSPMma8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:14:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KM8nhNzts0/Render', 05:14:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:06 INFO - 'HOME': '/Users/cltbld', 05:14:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:06 INFO - 'LOGNAME': 'cltbld', 05:14:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:06 INFO - 'MOZ_NO_REMOTE': '1', 05:14:06 INFO - 'NO_EM_RESTART': '1', 05:14:06 INFO - 'PAGER': '/bin/cat', 05:14:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:06 INFO - 'PWD': '/builds/slave/test', 05:14:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:06 INFO - 'SHELL': '/bin/bash', 05:14:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JEsHSPMma8/Listeners', 05:14:06 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 05:14:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:06 INFO - 'USER': 'cltbld', 05:14:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:06 INFO - 'XPC_FLAGS': '0x0', 05:14:06 INFO - 'XPC_SERVICE_NAME': '0', 05:14:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:06 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-hH3Q91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:06 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-DCwSi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:06 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-dvLvTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:06 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-YV3w7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:06 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-PAL0xl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:07 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-28UbrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:07 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-8zgYSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:07 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-6IPxjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:07 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-mA2D68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:07 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-nEMUlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:07 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-9siVbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:07 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-PlQfWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:07 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-xM8BpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:07 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-ARkEfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:07 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Srcox1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:08 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-D1UGvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:08 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-IfHejq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:10 INFO - Downloading blessings-1.6.tar.gz 05:14:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:14:10 INFO - Installing collected packages: blessings 05:14:10 INFO - Running setup.py install for blessings 05:14:10 INFO - Successfully installed blessings 05:14:10 INFO - Cleaning up... 05:14:11 INFO - Return code: 0 05:14:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:14:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:11 INFO - Reading from file tmpfile_stdout 05:14:11 INFO - Current package versions: 05:14:11 INFO - blessings == 1.6 05:14:11 INFO - blobuploader == 1.2.4 05:14:11 INFO - docopt == 0.6.1 05:14:11 INFO - manifestparser == 1.1 05:14:11 INFO - mozInstall == 1.12 05:14:11 INFO - mozcrash == 0.17 05:14:11 INFO - mozdebug == 0.1 05:14:11 INFO - mozdevice == 0.48 05:14:11 INFO - mozfile == 1.2 05:14:11 INFO - mozhttpd == 0.7 05:14:11 INFO - mozinfo == 0.9 05:14:11 INFO - mozleak == 0.1 05:14:11 INFO - mozlog == 3.1 05:14:11 INFO - moznetwork == 0.27 05:14:11 INFO - mozprocess == 0.22 05:14:11 INFO - mozprofile == 0.28 05:14:11 INFO - mozrunner == 6.11 05:14:11 INFO - mozscreenshot == 0.1 05:14:11 INFO - mozsystemmonitor == 0.0 05:14:11 INFO - moztest == 0.7 05:14:11 INFO - mozversion == 1.4 05:14:11 INFO - psutil == 3.1.1 05:14:11 INFO - requests == 1.2.3 05:14:11 INFO - wsgiref == 0.1.2 05:14:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-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 0x10c02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd59148f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10becbe00>, '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 0x10bcf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec6b20>, '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.KM8nhNzts0/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.JEsHSPMma8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KM8nhNzts0/Render', 05:14:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:11 INFO - 'HOME': '/Users/cltbld', 05:14:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:11 INFO - 'LOGNAME': 'cltbld', 05:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:11 INFO - 'MOZ_NO_REMOTE': '1', 05:14:11 INFO - 'NO_EM_RESTART': '1', 05:14:11 INFO - 'PAGER': '/bin/cat', 05:14:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:11 INFO - 'PWD': '/builds/slave/test', 05:14:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:11 INFO - 'SHELL': '/bin/bash', 05:14:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JEsHSPMma8/Listeners', 05:14:11 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 05:14:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:11 INFO - 'USER': 'cltbld', 05:14:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:11 INFO - 'XPC_FLAGS': '0x0', 05:14:11 INFO - 'XPC_SERVICE_NAME': '0', 05:14:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:14:11 INFO - Cleaning up... 05:14:11 INFO - Return code: 0 05:14:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd59148f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10becbe00>, '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 0x10bcf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec6b20>, '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.KM8nhNzts0/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.JEsHSPMma8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:14:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KM8nhNzts0/Render', 05:14:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:11 INFO - 'HOME': '/Users/cltbld', 05:14:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:11 INFO - 'LOGNAME': 'cltbld', 05:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:11 INFO - 'MOZ_NO_REMOTE': '1', 05:14:11 INFO - 'NO_EM_RESTART': '1', 05:14:11 INFO - 'PAGER': '/bin/cat', 05:14:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:11 INFO - 'PWD': '/builds/slave/test', 05:14:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:11 INFO - 'SHELL': '/bin/bash', 05:14:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JEsHSPMma8/Listeners', 05:14:11 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 05:14:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:11 INFO - 'USER': 'cltbld', 05:14:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:11 INFO - 'XPC_FLAGS': '0x0', 05:14:11 INFO - 'XPC_SERVICE_NAME': '0', 05:14:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:11 INFO - Downloading/unpacking jsonschema 05:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:14:14 INFO - Downloading/unpacking functools32 (from jsonschema) 05:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:14 INFO - Downloading functools32-3.2.3-2.tar.gz 05:14:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:14:14 INFO - warning: no files found matching '*.txt' 05:14:14 INFO - no previously-included directories found matching 'build' 05:14:14 INFO - no previously-included directories found matching 'dist' 05:14:14 INFO - no previously-included directories found matching '.git*' 05:14:14 INFO - Installing collected packages: jsonschema, functools32 05:14:14 INFO - Running setup.py install for functools32 05:14:15 INFO - warning: no files found matching '*.txt' 05:14:15 INFO - no previously-included directories found matching 'build' 05:14:15 INFO - no previously-included directories found matching 'dist' 05:14:15 INFO - no previously-included directories found matching '.git*' 05:14:15 INFO - Successfully installed jsonschema functools32 05:14:15 INFO - Cleaning up... 05:14:15 INFO - Return code: 0 05:14:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:15 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 0x10c02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd59148f640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10becbe00>, '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 0x10bcf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec6b20>, '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.KM8nhNzts0/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.JEsHSPMma8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:14:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:14:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KM8nhNzts0/Render', 05:14:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:15 INFO - 'HOME': '/Users/cltbld', 05:14:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:15 INFO - 'LOGNAME': 'cltbld', 05:14:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:15 INFO - 'MOZ_NO_REMOTE': '1', 05:14:15 INFO - 'NO_EM_RESTART': '1', 05:14:15 INFO - 'PAGER': '/bin/cat', 05:14:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:15 INFO - 'PWD': '/builds/slave/test', 05:14:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:15 INFO - 'SHELL': '/bin/bash', 05:14:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JEsHSPMma8/Listeners', 05:14:15 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 05:14:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:15 INFO - 'USER': 'cltbld', 05:14:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:15 INFO - 'XPC_FLAGS': '0x0', 05:14:15 INFO - 'XPC_SERVICE_NAME': '0', 05:14:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:15 INFO - Downloading/unpacking flake8 05:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:14:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:14:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:14:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:14:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:14:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:14:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:14:19 INFO - no previously-included directories found matching 'docs/_build' 05:14:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:14:19 INFO - Running setup.py install for pep8 05:14:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:14:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:14:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:14:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:14:19 INFO - no previously-included directories found matching 'docs/_build' 05:14:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:14:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:14:19 INFO - Cleaning up... 05:14:19 INFO - Return code: 0 05:14:19 INFO - Running post-action listener: _resource_record_post_action 05:14:19 INFO - Running post-action listener: _start_resource_monitoring 05:14:19 INFO - Starting resource monitoring. 05:14:19 INFO - ##### 05:14:19 INFO - ##### Running install step. 05:14:19 INFO - ##### 05:14:19 INFO - Running pre-action listener: _resource_record_pre_action 05:14:19 INFO - Running main action method: install 05:14:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:19 INFO - Reading from file tmpfile_stdout 05:14:19 INFO - Detecting whether we're running mozinstall >=1.0... 05:14:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:14:19 INFO - Reading from file tmpfile_stdout 05:14:19 INFO - Output received: 05:14:19 INFO - Usage: mozinstall [options] installer 05:14:19 INFO - Options: 05:14:19 INFO - -h, --help show this help message and exit 05:14:19 INFO - -d DEST, --destination=DEST 05:14:19 INFO - Directory to install application into. [default: 05:14:19 INFO - "/builds/slave/test"] 05:14:19 INFO - --app=APP Application being installed. [default: firefox] 05:14:19 INFO - mkdir: /builds/slave/test/build/application 05:14:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:14:53 INFO - Reading from file tmpfile_stdout 05:14:53 INFO - Output received: 05:14:53 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:14:53 INFO - Running post-action listener: _resource_record_post_action 05:14:53 INFO - ##### 05:14:53 INFO - ##### Running run-tests step. 05:14:53 INFO - ##### 05:14:53 INFO - Running pre-action listener: _resource_record_pre_action 05:14:53 INFO - Running main action method: run_tests 05:14:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:14:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:14:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:14:53 INFO - 2016-05-01 05:14:53.732 screenresolution[6897:171113] starting screenresolution argv=screenresolution get 05:14:53 INFO - 2016-05-01 05:14:53.734 screenresolution[6897:171113] Display 0: 1600x1200x32@60 05:14:53 INFO - 2016-05-01 05:14:53.740 screenresolution[6898:171115] starting screenresolution argv=screenresolution list 05:14:53 INFO - Available Modes on Display 0 05:14:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:14:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:14:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:14:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:14:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:14:53 INFO - Intel Iris: 05:14:53 INFO - Chipset Model: Intel Iris 05:14:53 INFO - Type: GPU 05:14:53 INFO - Bus: Built-In 05:14:53 INFO - VRAM (Dynamic, Max): 1536 MB 05:14:53 INFO - Vendor: Intel (0x8086) 05:14:53 INFO - Device ID: 0x0a2e 05:14:53 INFO - Revision ID: 0x0009 05:14:53 INFO - Displays: 05:14:53 INFO - Display: 05:14:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:14:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:14:53 INFO - Display Serial Number: 1600x1200 60 05:14:53 INFO - Main Display: Yes 05:14:53 INFO - Mirror: Off 05:14:53 INFO - Online: Yes 05:14:53 INFO - Rotation: Supported 05:14:53 INFO - Return code: 0 05:14:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:14:53 INFO - Python 2.7.3 05:14:53 INFO - Return code: 0 05:14:53 INFO - grabbing minidump binary from tooltool 05:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:53 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10becbe00>, '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 0x10bcf4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:14:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:14:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:14:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:14:54 INFO - Return code: 0 05:14:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:14:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:14:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:14:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:14:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:14:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:14:55 INFO - Return code: 0 05:14:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:14:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KM8nhNzts0/Render', 05:14:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:55 INFO - 'HOME': '/Users/cltbld', 05:14:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:55 INFO - 'LOGNAME': 'cltbld', 05:14:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:14:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:55 INFO - 'MOZ_NO_REMOTE': '1', 05:14:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:14:55 INFO - 'NO_EM_RESTART': '1', 05:14:55 INFO - 'PAGER': '/bin/cat', 05:14:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:55 INFO - 'PWD': '/builds/slave/test', 05:14:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:14:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:55 INFO - 'SHELL': '/bin/bash', 05:14:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JEsHSPMma8/Listeners', 05:14:55 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 05:14:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:55 INFO - 'USER': 'cltbld', 05:14:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:55 INFO - 'XPC_FLAGS': '0x0', 05:14:55 INFO - 'XPC_SERVICE_NAME': '0', 05:14:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:14:55 INFO - mozversion application_buildid: 20160501030217 05:14:55 INFO - mozversion application_changeset: 1461a4071341c282afcf7b72e33036412d2251d4 05:14:55 INFO - mozversion application_display_name: Nightly 05:14:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:14:55 INFO - mozversion application_name: Firefox 05:14:55 INFO - mozversion application_remotingname: firefox 05:14:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:14:55 INFO - mozversion application_vendor: Mozilla 05:14:55 INFO - mozversion application_version: 49.0a1 05:14:55 INFO - mozversion platform_buildid: 20160501030217 05:14:55 INFO - mozversion platform_changeset: 1461a4071341c282afcf7b72e33036412d2251d4 05:14:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:14:55 INFO - mozversion platform_version: 49.0a1 05:14:55 INFO - using testdate: 1462104895 05:14:55 INFO - actual date: 1462104895 05:14:55 INFO - starting webserver on 'localhost:61184' 05:14:55 INFO - SUITE-START | Running 2 tests 05:14:55 INFO - TEST-START | dromaeo_css 05:14:55 INFO - operating with platform_type : mac_ 05:14:55 INFO - Initialising browser for dromaeo_css test... 05:14:55 INFO - TEST-INFO | started process 6916 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpAUbniI/profile http://localhost:61184/getInfo.html) 05:14:57 INFO - PROCESS | 6916 | __metrics Screen width/height:1600/1200 05:14:57 INFO - PROCESS | 6916 | colorDepth:24 05:14:57 INFO - PROCESS | 6916 | Browser inner width/height: 1024/689 05:14:57 INFO - PROCESS | 6916 | __metrics 05:14:58 INFO - PROCESS | 6916 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:14:58 ERROR - PROCESS | 6916 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:14:58 INFO - TEST-INFO | 6916: exit 0 05:14:58 INFO - Browser initialized. 05:14:58 INFO - Running cycle 1/1 for dromaeo_css test... 05:14:58 INFO - TEST-INFO | started process 6918 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpAUbniI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:10 INFO - PROCESS | 6918 | RSS: Main: 239820800 05:15:10 INFO - PROCESS | 6918 | 05:16:02 INFO - PROCESS | 6918 | 1462104962432 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:18:12 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:61184/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61184/tests/dromaeo/cssquery-ext.html) 05:18:14 INFO - PROCESS | 6918 | RSS: Main: 350208000 05:18:14 INFO - PROCESS | 6918 | 05:21:17 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:61184/tests/dromaeo/cssquery-ext.html (next: http://localhost:61184/tests/dromaeo/cssquery-jquery.html) 05:21:17 INFO - PROCESS | 6918 | RSS: Main: 370974720 05:21:17 INFO - PROCESS | 6918 | 05:24:19 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:61184/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61184/tests/dromaeo/cssquery-mootools.html) 05:24:20 INFO - PROCESS | 6918 | RSS: Main: 369803264 05:24:20 INFO - PROCESS | 6918 | 05:27:22 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:61184/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61184/tests/dromaeo/cssquery-prototype.html) 05:27:23 INFO - PROCESS | 6918 | RSS: Main: 367570944 05:27:23 INFO - PROCESS | 6918 | 05:30:26 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:61184/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61184/tests/dromaeo/cssquery-yui.html) 05:30:27 INFO - PROCESS | 6918 | RSS: Main: 334983168 05:30:27 INFO - PROCESS | 6918 | 05:33:30 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:61184/tests/dromaeo/cssquery-yui.html (next: http://localhost:61184/tests/dromaeo/cssquery-dojo.html) 05:33:30 INFO - PROCESS | 6918 | RSS: Main: 375386112 05:33:30 INFO - PROCESS | 6918 | 05:33:30 INFO - PROCESS | 6918 | __start_tp_report 05:33:30 INFO - PROCESS | 6918 | _x_x_mozilla_page_load 05:33:30 INFO - PROCESS | 6918 | _x_x_mozilla_page_load_details 05:33:30 INFO - PROCESS | 6918 | |i|pagename|runs| 05:33:30 INFO - PROCESS | 6918 | |0;dojo.html;3329.4173519634064;3380.08737640837;3395.7453191010663;3403.76173667843;3457.515947767313;5621.213030175779;5649.943500564993;5652.095664693655;5694.2312787773635;5696.287964004499;5609.915851262234;5685.289338832636;5732.856678583037;5869.882602347961;5889.087191485316;5102.438731739506;5127.538521533062;5155.871103222412;5178.663386879841;5223.451537588556;5228.032813929435;5282.392524859632;5289.418164001957;5295.258663787073;5321.680699158043;5067.5185857343495;5153.613478989091;5169.302144210521;5170.948290517084;5229.477052294778;5062.645614806928;5117;5148.897022059537;5175.637705360627;5187.662801917863;5043.344365232496;5047.116754567936;5047.974760126176;5060.822871199527;5070.163423035195;5468.808591699271;5501.9174712379345;5504.174373843955;5505.201745746885;5538.6122971392015;6107.84730381744;6114.663693496856;6116.327204007555;6145.047517634774;6153.323134455206;6132.69336533176;6154.96922515389;6182.350853160398;6188.226471691039;6198.132261483396;5686.886262274731;5836.153757705109;5838.562107841929;5839.532837373;5854.590178687494;6140.723667434976;6160.9383906161265;6207.379262073847;6216.378362163748;6217.285012223652;6056.454919057306;6091.725872335755;6103.992841181244;6116.082587611805;6142.754289828356;6164.383561643889;6174.9073763893575;6182.258129024546;6190.195274614362;6191.504679625709;6069.119977603223;6095.952232173814;6107.7862274821055;6116.97495384395;6146.985747351637;6127.5866022601795;6130.459285710702;6136.263648362135;6141.539384546177;6142.617910970085;4875.000624871893;4899.593194345511;4907.09218794517;4915.803367865316;4963.751812409438;4381.9123617527475;4431.180231657089;4505.797239124247;4512.4133862597655;4546.88632784183;5671.744771485293;5679.744411501492;5682.687452190169;5687.317521897398;5695.886082278331;6132.478739307119;6144.41628045335;6145.508359331332;6211.81364559064;6219.40915613016;4781.163296423112;5515.145152501369;5613.635113717595;5615.157726341082;5725.971370143206;5324.041672498987;5351.438098999627;5361.329833770778;5367.973160134175;5372.597055220874;6256.874862502725;6298.212723409482;6304.558680892385;6310.842228944313;6324.209473815773;6135.877282454237;6146.477549408351;6198.318184999736;6229.688515574294;6236.781712640036;6220.595661282002;6238.906416403848;6252.218472690914;6268.091126786499;6274.560780745486;5516.365617953827;5896.528277737856;6138.079288106641;6161.753529858755;6167.414095660905;6085.087236914498;6146.186874871834;6168.074788781719;6184.072389141665;6224.06639004153;6082.635041897501;6105.908411373922;6106.80159637959;6138.969305153446;6139.508839292756;5957.046872500379;6076.149339092619;6081.939180608138;6084.330723620486;6099.725512351866;4951.801927922843;5225.843224703265;5909.054551271776;5961.821145365646;5962.9266731988655;5187.740612969412;6123.141741795795;6135.76632123851;6137.938620613737;6151.415615516519;5750.971245143747;5925.170476133431;5981;5993.101034844633;5995.430434108753 05:33:30 INFO - PROCESS | 6918 | |1;ext.html;487.468659161514;518.1062666899599;518.4608007672022;541.4071591607194;574.4714862326659;30560;38555.42166867496;39761.403578946265;39861.00347491311;39881.601183988154;43255.56744432555;44411.111777764425;44438.555614443845;44489;44501.77749111258;8496.277816385615;9473.857892131606;9694.466804325759;9701.466419346933;9703.369280996712;8581.056083830774;11317.717057073598;11615.709607259843;11622.476988535536;11661;11535.519863229403;11696.707582310426;11709.941450292737;11719.472623731952;11743.060555155567;8439.987201535794;11239.60661376848;11556.191066625339;11599.246049006872;11732.824007639892;11420.54317827286;11612.593559225386;11614.012808911168;11616.883831161666;11630.302181869114;38451;38981.805090974645;39069;39083.80458097691;39186.82439526819;3157.163351711798;3178.2054486378406;3219.581454410935;3219.7263232625255;3265.96734032659;29052.27369315763;29053.56419653707;29139.562906556417;29154.708452915413;29157.125286241448;2672.7594516493414;2711.6339294195227;2712.471068141713;2728.345197152689;2826.773858091348;469869;470243.64878175734;471363;472362;473691;59302;60133.69933150306;60268.397316026276;60344.79310413855;60417;51776;63938.72122557523;63965.36034639687;63988.36011639917;64061.679691601246;16787.496375108767;17078;17201.225944832513;17219.65560688804;17246.91376543109;9719.368241064309;11684.415779211005;12568;12806.487740490462;12980.545680901123;19699.606007879764;20121.899390502953;20153.496162596053;20156.59686806285;20218.797812021985;6887.827804304932;7910.485818421784;8008.55845947735;8011.439199255996;8019.639116239783;941.204682043672;947.4694171264114;992.3847214726916;1045.5660900726266;1145.7078444996473;29076.273093172418;29328.7067129326;29691.554626680616;29776.553351699742;29874.40251194964;1098.0227597438127;1107.6510899066768;1140.3556990300453;1158.4150004247801;1184.1592469346688;12572.559960401342;13072.542461013818;15127.319270632848;15382.015379033117;15660.13869237203;631032;632300;632621;641168;643481;58222.708886456145;59724;59902;59992.400075999554;60256.769187697755;1285.8585555589066;1313.7175507265792;1357;1420.2969530082262;1441.8630230128122;28585.571216431355;28668.713312866603;28727.856360718062;29280.26799330034;29293.414131717673;46755.53244467512;46811.82970425767;47428.288575670704;47474.81312967203;47475.28787068266;353830;359156;360084;360406;360408;22135.335939921828;24776.50446991014;25320;25567.87216063908;25573.3606659835;39891.202175956896;42361.57638423576;43318.566814331454;43519.56480435282;44130;33111.66888331086;35392.823035884656;35868;36683.08292292714;36810.263794724495;34555.13612159616;34969;35698.10754731049;35774.4633830498;36704.449433257985;36006.91979240627;36696.449553256185;36866.26267474689;36892.89321320257;37045.6295437053;27499.86250068737;31789.36421271608;31964.20089497781;32564.51153232751;32588.022359329258;19364.70952935735;21416.89291553532;23714.52570948537;24102.87948560246;24496.38759030967 05:33:30 INFO - PROCESS | 6918 | |2;jquery.html;340.50796598914565;383.6738772043763;396.6430212808472;399.67426547363897;401.37586053686505;19222.615547689038;20237.59524809503;20965.685514717272;20965.790342096574;21005.894970525165;18388.264469421207;18490.907545462287;19184.232630694754;19233.32683356083;19373.418797436065;5687.914681279784;5844.123381492767;5940.821775346746;5958.672273024982;6583.374579414948;6480.773172938948;6487.513436492282;6488.448481879046;6532.869342613145;6540.574862633937;6374.936250637504;6451.29035806061;6451.354864513534;6453.741850325981;6470.417662410382;5626.493615574618;6253.43719065284;6394;6454.9677251613675;6476.060971159202;5615.326160860723;5625.746841392106;5660.547156227533;5672.205891175238;5685.317761868602;25436.872815635805;25644;26023.609645855326;26027;26049.869750651316;1883.2561138350275;2126.8830214338204;2142.678598210265;2155.267209148881;2171.489699920533;9636.22910167185;9688.951555242249;9804.215662747034;9919.652812151613;10059.597616095347;1738.9739153912574;1792.5339411752907;1833.5324492254524;1833.7799464064399;1902.7241050047803;93049.67375815591;107631;117696;118591;119129.40435297854;11342.092632589372;11533.653990380302;11605.187636865507;11606.129540284512;11737.589184378676;14746.778798318033;14853.331600077807;14853.331600078023;14866.851331486763;14890.776638350433;7223.92776072243;7872.881906771403;8191.959040204761;8545.74362769118;8740.125987401208;8631.611577478903;8635.661472471777;8636.913630863612;8775.692850750193;8803.911960880308;4454.5990860822685;4559.224931761609;4571.81712731487;4586.403767510269;4667.6265898728025;1765.3556451895222;1895.981040189608;1948.074664534335;1957.5889063296581;1973.4178417366911;704.8378872859271;875.5578432891349;909.2089881802874;1007.9647212347679;1009.7929924365487;15430.5370838875;15460.768088478682;20639.58720825575;20726.896365518376;20770.792292076887;945.9195968342768;949.0035462764071;952.1763674421576;966.5505539923969;997.1275134257525;8306.70926517569;10797.892021079846;10812.297200681931;11009;11020.44897755109;531293;544270;549808;549860;557761;121668.56662866693;123605.38197308956;124305;125798;128006.71993280134;901.6799036342135;997.5660587644253;1021.3463383434756;1021.7292417509366;1023.6263763726336;14605.488807892098;17798.555036124202;18140.092995350442;18141.909290453463;18494.907525462288;47424.330544660515;48467.51532484771;49097.50902490929;49109.263361050325;50033;58081.4191858076;69691.95462068175;72820;74595.254047461;79350;7605.543667379975;7890.013748281465;7931.762047138595;8200.015998080029;8209.79475513093;9048.502332371783;11699.65453972804;11713.941430292794;11794.823077654013;12315.50737970507;5456.809011684572;7184.892226616709;10092.94024127456;10624.893751062391;10970.561177553127;10549.52527136296;10634.41510716918;10671.306365086244;10809.35143891369;10930.562777489129;9350.81298374015;10856.34861908288;11005.669829905115;11039.337639741641;11190.272632278877;7751.147373788992;7809.882851757115;7873.5275883447175;7997.7600671979935;8019.91980080215;10349.430781307101;10588.735281618021;10681.946590266998;10711.678649640524;10731.892681073401 05:33:30 INFO - PROCESS | 6918 | |3;mootools.html;1387.5698533454624;1585.064811761061;1588.3408385520006;1800.5948661551154;1922.6346994071116;6800.523963322563;7492.250774922505;8515.956170519077;8530.872036919436;8537.573121343923;8205.343572514199;8449.831003379928;8452.661893524251;8456.492610443378;8465.026521949962;2312.6184179610345;2356.0929042318744;2360.5868972929757;2361.2798096580536;2362.397588614901;2311.687922130516;2334.8832558372096;2357.7642235776457;2363.652717950769;2365.6451532270125;2105.5788842231536;2312.8727919964394;2330.0796185506715;2343.8828058597087;2350.1421980976907;2277.7266727992583;2297.7472478027403;2306.4003359126664;2308.2382813671447;2330.021391015777;2292.7592602776635;2297.322289924476;2302.3783578433895;2304.043821813945;2306.688597039395;7781.922180778233;8038.553060449117;8528.01927778301;8599.441036332619;8616.396852220347;5040.218766091261;5205.453427390145;5240.737963101829;5245.737713114329;5248.396434410015;3031.9534314337734;3371.2920286739813;3450.896473105811;3522.0842580929143;3525.2773181497732;913.7578541686458;975.8338785151747;1002.5688953749884;1031.283258135596;1044.2481413382352;11065.668029959113;11171.83242251367;11323.037541808957;11330.263532870336;11344.886551134465;4305.06143681425;4586.839460618862;4601.3558101865765;4604.7927843247135;4605.907881842344;8967.686130985385;9016.774580635405;9018.819623607622;9028.63885444588;9032.954835225913;5180.352455943007;5186.2479940409185;5197.714125723046;5206.55744261742;5208.947910520922;3929.2927273090545;4349.151915376535;4783.617310615143;4873.853784386474;4878.756062196876;3542.1144713821545;3634.636536346397;3656.0494271489074;3665.157013886821;3730.4963829882618;988.3279370028306;1074.6722249713912;1164.132721122769;1167.311286341063;1168.258156070884;788.3456730913329;899.9145081217274;932.9020452852353;946.068122898944;950.6245033211843;5155.97422012895;5661.924234395452;5730.598858079894;5801.796937107181;5825.7087145642545;882.3250213586612;923.2060428031887;933.2160984772823;945.3550821570556;951.6336210558846;3747.981260093682;3913.9823645852284;3950.604939506084;3991.44119823225;3994.560598334181;9744.415335079917;10156.847647285296;10588.788224235472;10618.522166502526;10633.946830265953;9365.812683746288;10435.68692939213;10437.165026797838;10583.25917185805;10606.946965265279;7697.230276972258;7827.25641064098;7834.529928204326;7914.16901225363;7926.097736543188;5720.971395142998;5761.596688231783;5833.737481813244;5995.2206213192;6053.757849685963;9360.95319523398;9752.219822414048;9852;9855.014498550088;9879.259055570717;9026.774330641787;9334.953325233331;9939;9967.800643986935;10054.64808731691;7124.893126603209;7324.230955749783;7371.926280737339;7375.078115235596;7387.852242955003;7594.6202689866395;7878.921210787818;7920.920790792018;7928.881066783887;7930.325922296669;7006.789796306119;7185.317394847481;7216.89174662391;7276.05411296528;7276.163241227115;7076.540024898365;7107.928920710933;7109.973657507586;7240.52936559122;7257.963710181415;6777.796666100025;6799.694013769294;6856.554323968926;6879.277675844164;6880.58716477013;4822.228443449031;4872.342233798392;4883.267509873547;4927.531884470969;5044.697318160922;4898.828541001048;4939.936441203086;4964.131277026579;4994.850154495371;5039.823606173766 05:33:30 INFO - PROCESS | 6918 | |4;prototype.html;299.60003395467066;310.73587450666935;319.4904127915973;320.5048200530181;322.06279725997337;4612.86161415158;4822.6624136310475;5214.556762675173;5274.947250527489;5289.312389389379;4216.786187251694;4842.467328593861;4860.756962151887;4994.375703037121;5090.9236361454605;3854.4411060396365;4017.799110044486;4031.153457773871;4048.4736984192014;4054.2296963576828;3375.6793104655017;3549.6982756465604;4047.433359329696;4048.9595104048876;4067.755934643916;3309.8179600121985;3863.9613603864022;3881.592432794558;3897.349802134547;3997.8400863965553;3383.390989621867;3813.389857622795;4045.2111838191563;4054.797260136996;4084.448599439082;2964;3108.0054382597577;3409.590849098099;3518.683318501347;3544.2025544252338;4141.4409054777525;4547.408836851188;4807.495213002654;5012.523810238022;5020.7740651670765;4235;4569.634429245652;4574.702644328108;4587.9541204588195;4607.424071991001;4846.982133751916;5585.720713964285;5593.720313984325;5608;5630.859228519279;3260.96739032609;3511.7015053720465;3808.485854409647;3819.618038196186;3821.808909554539;5446.482584154539;5802.82591522255;5833.7374818133285;5883.499902508292;5893;3183;3636.2727454509204;3784.4890939723336;3834.9041273968373;3847.5382954045695;3402.8128452935325;3531.152523394418;3900.902477438087;3909.237698648793;3937.684985201177;4433.401490798766;4503.909921801545;4548.385967894358;4550.226461501553;4562;4608.861734147981;5279.260903473517;5535.363433205152;5575.553955683536;5578.97210513953;1154.486253617149;1295.8898493628146;1335.504235256519;1426.21558143023;1429.6497358147321;761.584936209767;882.8278485695228;924.8531820542613;933.9066093390607;945.8675785390051;5156.075947470157;5563.777448902079;5593.412691667317;5597.272354593958;5612.747426365823;5561.582881283839;5608.803691870765;6140.508759299245;6159.476444502178;6177.536684748572;4429.712068715523;4838.758062096881;4864.464908860023;5306.310179676618;5335.493128152819;3271.214908421962;3292.456744637156;3294.061192560157;3317.7345812334956;3320.5849268841393;5752.02215623345;6050.334463209044;6559.639219842955;6591.472682185414;6611.834704132435;4820.566149046603;5271.736413179326;5719.598698318855;5902.321233058167;5972.372900845436;5685.829425117253;5738.913916291259;5866.30150130936;5973.940260597338;5989.790357337472;4871.439784424695;5568.220449137205;5783.508401785895;5784.4136635286395;5797.217375654402;4838.588719958843;5640.971795140998;5738.68437235956;5762.942370576408;5800.796972105956;5115.283860259641;5794.1308803679785;6007.30915944672;6064.9393506066135;6088.299845517645;3422.7775194612273;3777.56557995834;3810.8666196684094;3845.384738441836;3865.8260378282484;5489.698066606375;5702.203805801159;5781.653100813796;5805.912911306418;5817.7963771267805;4567.680262381601;4852.75736213195;5656.971715141398;5681.4034526374135;5717.22817419643;5537.729530892476;5562.72186390687;5583.804566840141;5600.93582219377;5608.775648973995;4292.506361768436;4485;5305.655132416535;5389.59578031657;5571.492828227233;5501.972490137524;5539.362973258127;5550.916736249041;5570.2201691762375;5583.692896890548;5285.735713214401;5464.726763661722;5466.316710411199;5467.261919640798;5493.285872836505 05:33:30 INFO - PROCESS | 6918 | |5;yui.html;638.9552731308808;648.4714957309792;668.4719071933172;682.7678589279647;722.5339655921928;13701.451941922323;13759.243241621707;13964.720705585882;14003.29983500826;14011;12287.569935052275;12535;12573.308468034254;12772.744545109092;12780.297083660394;712.5724565260828;733.6991833348328;859.10223816112;886.3352485635774;904.9321300902426;898.7573355194094;899.95050272235;905.0949050949051;905.3571963905634;905.8460061789478;950.2635457520408;957.2820384711466;957.5834511987273;957.6313119448993;962.191758922505;732.1324230786535;908.1236606674524;945.5886689290146;947.1381043250609;949.4588084791671;697.9844326504923;702.2065066474851;705.6542294275819;707.476467414115;707.8548897476056;13490.865091349058;13550.051496395256;13553.254570998592;13566;13589.388477518536;11731;11794.233374830608;11914.702132446671;12022.579209727704;12207.328596927166;9130.908690913073;9142.908570914338;9192.632294708204;9267.304952128618;9506.47714375709;3334.683205095512;3365.7812242204177;3401.693847553707;3420.2817408344276;3483.1988642612087;75420;76081.23918760852;76357.61821190914;76386.23613763847;77973.61013194898;8848.513331766815;8872.484087176912;8919.108089191159;9160.496172710566;9275.489848058287;20826.85452300138;21015.264465743625;21075.26236581712;21131.577368452854;21347.825869577013;6923.099997000459;6992.370686638227;7165.140183178052;7190.06529151207;7305.525140865827;3840.2127563849904;3927.626875446885;3928.8821335359985;3929.5284565851707;4005.4814999377104;3425.6403077676487;3514.578250609943;3521.137321356233;3521.841517131735;3543.9291214175573;535.611681530887;615.2739767074895;617.8331850400367;629.5530173576811;630.1335663462738;504.63918298416075;558.7206396801599;603.8248907816684;605.0923614578132;616.608453631947;7143.82126949058;7218.242084581148;8027.237412445808;8039.115697273295;8155.918440815634;480.39710163744274;585.508173134569;591.5977135547869;592.2744637818638;594.6105301027834;3530.5586801649793;3677.411614141778;3694.51971243742;3885.0481631999774;3918.186667198399;90338.09661903429;91564.54217728869;91590.54204729067;94770;94909.25453727286;74884.62557687177;75296.62351688207;77255.45489090186;77786.22213777789;78682.60658696669;68974.65512672404;69199.61600767956;70002;70184.64907675429;70439.2956070443;6585.473162147113;7483;7591.658375373232;7608.80977975555;7786.649600767866;44267;44358.3346249813;44568.77715611401;45035;45053.774731126134;60903.69548152231;61279.693601531704;61555.69222153861;62430.687846560475;63872;9882.407055576688;10129.240306976859;10238.232132589937;10256.897431025594;10417.374957502574;10656;10996.450177491253;11077.667669969915;11362.14783891195;11743.295402275891;10084.798304034024;10108.89891101109;10323.070923616911;10465.319754215952;10637.095846853104;10162.644307449204;10189.694309170736;10206.846897296695;10219.437930913871;10533.210009249184;8489.915100849159;10191.133753630727;10210.642627508001;10520.473976301308;10658.893411066101;8746.825063498822;8784.121587841164;8955.776105597412;9077.68228112013;9287.442753434816;8476.321894248322;8758.562071896253;8916.910830891607;8979.32655050887;9002.909970900208 05:33:30 INFO - PROCESS | 6918 | __end_tp_report 05:33:30 INFO - PROCESS | 6918 | __start_cc_report 05:33:30 INFO - PROCESS | 6918 | _x_x_mozilla_cycle_collect,1136 05:33:30 INFO - PROCESS | 6918 | __end_cc_report 05:33:30 INFO - PROCESS | 6918 | __startTimestamp1462106010515__endTimestamp 05:33:30 INFO - PROCESS | 6918 | 05:33:30 INFO - PROCESS | 6918 | ------- Summary: start ------- 05:33:30 INFO - PROCESS | 6918 | Number of tests: 6 05:33:30 INFO - PROCESS | 6918 | 05:33:30 INFO - PROCESS | 6918 | [#0] dojo.html Cycles:165 Average:5682.14 Median:5925.17 stddev:628.34 (10.6%) stddev-sans-first:602.55 05:33:30 INFO - PROCESS | 6918 | Values: 3329.4 3380.1 3395.7 3403.8 3457.5 5621.2 5649.9 5652.1 5694.2 5696.3 5609.9 5685.3 5732.9 5869.9 5889.1 5102.4 5127.5 5155.9 5178.7 5223.5 5228.0 5282.4 5289.4 5295.3 5321.7 5067.5 5153.6 5169.3 5170.9 5229.5 5062.6 5117.0 5148.9 5175.6 5187.7 5043.3 5047.1 5048.0 5060.8 5070.2 5468.8 5501.9 5504.2 5505.2 5538.6 6107.8 6114.7 6116.3 6145.0 6153.3 6132.7 6155.0 6182.4 6188.2 6198.1 5686.9 5836.2 5838.6 5839.5 5854.6 6140.7 6160.9 6207.4 6216.4 6217.3 6056.5 6091.7 6104.0 6116.1 6142.8 6164.4 6174.9 6182.3 6190.2 6191.5 6069.1 6096.0 6107.8 6117.0 6147.0 6127.6 6130.5 6136.3 6141.5 6142.6 4875.0 4899.6 4907.1 4915.8 4963.8 4381.9 4431.2 4505.8 4512.4 4546.9 5671.7 5679.7 5682.7 5687.3 5695.9 6132.5 6144.4 6145.5 6211.8 6219.4 4781.2 5515.1 5613.6 5615.2 5726.0 5324.0 5351.4 5361.3 5368.0 5372.6 6256.9 6298.2 6304.6 6310.8 6324.2 6135.9 6146.5 6198.3 6229.7 6236.8 6220.6 6238.9 6252.2 6268.1 6274.6 5516.4 5896.5 6138.1 6161.8 6167.4 6085.1 6146.2 6168.1 6184.1 6224.1 6082.6 6105.9 6106.8 6139.0 6139.5 5957.0 6076.1 6081.9 6084.3 6099.7 4951.8 5225.8 5909.1 5961.8 5962.9 5187.7 6123.1 6135.8 6137.9 6151.4 5751.0 5925.2 5981.0 5993.1 5995.4 05:33:30 INFO - PROCESS | 6918 | 05:33:30 INFO - PROCESS | 6918 | [#1] ext.html Cycles:180 Average:63003.97 Median:33833.40 stddev:134021.42 (396.1%) stddev-sans-first:134315.19 05:33:30 INFO - PROCESS | 6918 | Values: 487.5 518.1 518.5 541.4 574.5 30560.0 38555.4 39761.4 39861.0 39881.6 43255.6 44411.1 44438.6 44489.0 44501.8 8496.3 9473.9 9694.5 9701.5 9703.4 8581.1 11317.7 11615.7 11622.5 11661.0 11535.5 11696.7 11709.9 11719.5 11743.1 8440.0 11239.6 11556.2 11599.2 11732.8 11420.5 11612.6 11614.0 11616.9 11630.3 38451.0 38981.8 39069.0 39083.8 39186.8 3157.2 3178.2 3219.6 3219.7 3266.0 29052.3 29053.6 29139.6 29154.7 29157.1 2672.8 2711.6 2712.5 2728.3 2826.8 469869.0 470243.6 471363.0 472362.0 473691.0 59302.0 60133.7 60268.4 60344.8 60417.0 51776.0 63938.7 63965.4 63988.4 64061.7 16787.5 17078.0 17201.2 17219.7 17246.9 9719.4 11684.4 12568.0 12806.5 12980.5 19699.6 20121.9 20153.5 20156.6 20218.8 6887.8 7910.5 8008.6 8011.4 8019.6 941.2 947.5 992.4 1045.6 1145.7 29076.3 29328.7 29691.6 29776.6 29874.4 1098.0 1107.7 1140.4 1158.4 1184.2 12572.6 13072.5 15127.3 15382.0 15660.1 631032.0 632300.0 632621.0 641168.0 643481.0 58222.7 59724.0 59902.0 59992.4 60256.8 1285.9 1313.7 1357.0 1420.3 1441.9 28585.6 28668.7 28727.9 29280.3 29293.4 46755.5 46811.8 47428.3 47474.8 47475.3 353830.0 359156.0 360084.0 360406.0 360408.0 22135.3 24776.5 25320.0 25567.9 25573.4 39891.2 42361.6 43318.6 43519.6 44130.0 33111.7 35392.8 35868.0 36683.1 36810.3 34555.1 34969.0 35698.1 35774.5 36704.4 36006.9 36696.4 36866.3 36892.9 37045.6 27499.9 31789.4 31964.2 32564.5 32588.0 19364.7 21416.9 23714.5 24102.9 24496.4 05:33:30 INFO - PROCESS | 6918 | 05:33:30 INFO - PROCESS | 6918 | [#2] jquery.html Cycles:180 Average:32763.46 Median:362.09 stddev:91449.92 (25256.1%) stddev-sans-first:91674.06 05:33:30 INFO - PROCESS | 6918 | Values: 340.5 383.7 396.6 399.7 401.4 19222.6 20237.6 20965.7 20965.8 21005.9 18388.3 18490.9 19184.2 19233.3 19373.4 5687.9 5844.1 5940.8 5958.7 6583.4 6480.8 6487.5 6488.4 6532.9 6540.6 6374.9 6451.3 6451.4 6453.7 6470.4 5626.5 6253.4 6394.0 6455.0 6476.1 5615.3 5625.7 5660.5 5672.2 5685.3 25436.9 25644.0 26023.6 26027.0 26049.9 1883.3 2126.9 2142.7 2155.3 2171.5 9636.2 9689.0 9804.2 9919.7 10059.6 1739.0 1792.5 1833.5 1833.8 1902.7 93049.7 107631.0 117696.0 118591.0 119129.4 11342.1 11533.7 11605.2 11606.1 11737.6 14746.8 14853.3 14853.3 14866.9 14890.8 7223.9 7872.9 8192.0 8545.7 8740.1 8631.6 8635.7 8636.9 8775.7 8803.9 4454.6 4559.2 4571.8 4586.4 4667.6 1765.4 1896.0 1948.1 1957.6 1973.4 704.8 875.6 909.2 1008.0 1009.8 15430.5 15460.8 20639.6 20726.9 20770.8 945.9 949.0 952.2 966.6 997.1 8306.7 10797.9 10812.3 11009.0 11020.4 531293.0 544270.0 549808.0 549860.0 557761.0 121668.6 123605.4 124305.0 125798.0 128006.7 901.7 997.6 1021.3 1021.7 1023.6 14605.5 17798.6 18140.1 18141.9 18494.9 47424.3 48467.5 49097.5 49109.3 50033.0 58081.4 69692.0 72820.0 74595.3 79350.0 7605.5 7890.0 7931.8 8200.0 8209.8 9048.5 11699.7 11713.9 11794.8 12315.5 5456.8 7184.9 10092.9 10624.9 10970.6 10549.5 10634.4 10671.3 10809.4 10930.6 9350.8 10856.3 11005.7 11039.3 11190.3 7751.1 7809.9 7873.5 7997.8 8019.9 10349.4 10588.7 10681.9 10711.7 10731.9 05:33:30 INFO - PROCESS | 6918 | 05:33:30 INFO - PROCESS | 6918 | [#3] mootools.html Cycles:180 Average:5505.60 Median:5201.58 stddev:3050.19 (58.6%) stddev-sans-first:3043.05 05:33:30 INFO - PROCESS | 6918 | Values: 1387.6 1585.1 1588.3 1800.6 1922.6 6800.5 7492.3 8516.0 8530.9 8537.6 8205.3 8449.8 8452.7 8456.5 8465.0 2312.6 2356.1 2360.6 2361.3 2362.4 2311.7 2334.9 2357.8 2363.7 2365.6 2105.6 2312.9 2330.1 2343.9 2350.1 2277.7 2297.7 2306.4 2308.2 2330.0 2292.8 2297.3 2302.4 2304.0 2306.7 7781.9 8038.6 8528.0 8599.4 8616.4 5040.2 5205.5 5240.7 5245.7 5248.4 3032.0 3371.3 3450.9 3522.1 3525.3 913.8 975.8 1002.6 1031.3 1044.2 11065.7 11171.8 11323.0 11330.3 11344.9 4305.1 4586.8 4601.4 4604.8 4605.9 8967.7 9016.8 9018.8 9028.6 9033.0 5180.4 5186.2 5197.7 5206.6 5208.9 3929.3 4349.2 4783.6 4873.9 4878.8 3542.1 3634.6 3656.0 3665.2 3730.5 988.3 1074.7 1164.1 1167.3 1168.3 788.3 899.9 932.9 946.1 950.6 5156.0 5661.9 5730.6 5801.8 5825.7 882.3 923.2 933.2 945.4 951.6 3748.0 3914.0 3950.6 3991.4 3994.6 9744.4 10156.8 10588.8 10618.5 10633.9 9365.8 10435.7 10437.2 10583.3 10606.9 7697.2 7827.3 7834.5 7914.2 7926.1 5721.0 5761.6 5833.7 5995.2 6053.8 9361.0 9752.2 9852.0 9855.0 9879.3 9026.8 9335.0 9939.0 9967.8 10054.6 7124.9 7324.2 7371.9 7375.1 7387.9 7594.6 7878.9 7920.9 7928.9 7930.3 7006.8 7185.3 7216.9 7276.1 7276.2 7076.5 7107.9 7110.0 7240.5 7258.0 6777.8 6799.7 6856.6 6879.3 6880.6 4822.2 4872.3 4883.3 4927.5 5044.7 4898.8 4939.9 4964.1 4994.9 5039.8 05:33:30 INFO - PROCESS | 6918 | 05:33:30 INFO - PROCESS | 6918 | [#4] prototype.html Cycles:180 Average:4531.74 Median:5003.45 stddev:1425.00 (28.5%) stddev-sans-first:1393.15 05:33:30 INFO - PROCESS | 6918 | Values: 299.6 310.7 319.5 320.5 322.1 4612.9 4822.7 5214.6 5274.9 5289.3 4216.8 4842.5 4860.8 4994.4 5090.9 3854.4 4017.8 4031.2 4048.5 4054.2 3375.7 3549.7 4047.4 4049.0 4067.8 3309.8 3864.0 3881.6 3897.3 3997.8 3383.4 3813.4 4045.2 4054.8 4084.4 2964.0 3108.0 3409.6 3518.7 3544.2 4141.4 4547.4 4807.5 5012.5 5020.8 4235.0 4569.6 4574.7 4588.0 4607.4 4847.0 5585.7 5593.7 5608.0 5630.9 3261.0 3511.7 3808.5 3819.6 3821.8 5446.5 5802.8 5833.7 5883.5 5893.0 3183.0 3636.3 3784.5 3834.9 3847.5 3402.8 3531.2 3900.9 3909.2 3937.7 4433.4 4503.9 4548.4 4550.2 4562.0 4608.9 5279.3 5535.4 5575.6 5579.0 1154.5 1295.9 1335.5 1426.2 1429.6 761.6 882.8 924.9 933.9 945.9 5156.1 5563.8 5593.4 5597.3 5612.7 5561.6 5608.8 6140.5 6159.5 6177.5 4429.7 4838.8 4864.5 5306.3 5335.5 3271.2 3292.5 3294.1 3317.7 3320.6 5752.0 6050.3 6559.6 6591.5 6611.8 4820.6 5271.7 5719.6 5902.3 5972.4 5685.8 5738.9 5866.3 5973.9 5989.8 4871.4 5568.2 5783.5 5784.4 5797.2 4838.6 5641.0 5738.7 5762.9 5800.8 5115.3 5794.1 6007.3 6064.9 6088.3 3422.8 3777.6 3810.9 3845.4 3865.8 5489.7 5702.2 5781.7 5805.9 5817.8 4567.7 4852.8 5657.0 5681.4 5717.2 5537.7 5562.7 5583.8 5600.9 5608.8 4292.5 4485.0 5305.7 5389.6 5571.5 5502.0 5539.4 5550.9 5570.2 5583.7 5285.7 5464.7 5466.3 5467.3 5493.3 05:33:30 INFO - PROCESS | 6918 | 05:33:30 INFO - PROCESS | 6918 | [#5] yui.html Cycles:180 Average:17412.78 Median:629.84 stddev:24975.36 (3965.3%) stddev-sans-first:25013.66 05:33:30 INFO - PROCESS | 6918 | Values: 639.0 648.5 668.5 682.8 722.5 13701.5 13759.2 13964.7 14003.3 14011.0 12287.6 12535.0 12573.3 12772.7 12780.3 712.6 733.7 859.1 886.3 904.9 898.8 900.0 905.1 905.4 905.8 950.3 957.3 957.6 957.6 962.2 732.1 908.1 945.6 947.1 949.5 698.0 702.2 705.7 707.5 707.9 13490.9 13550.1 13553.3 13566.0 13589.4 11731.0 11794.2 11914.7 12022.6 12207.3 9130.9 9142.9 9192.6 9267.3 9506.5 3334.7 3365.8 3401.7 3420.3 3483.2 75420.0 76081.2 76357.6 76386.2 77973.6 8848.5 8872.5 8919.1 9160.5 9275.5 20826.9 21015.3 21075.3 21131.6 21347.8 6923.1 6992.4 7165.1 7190.1 7305.5 3840.2 3927.6 3928.9 3929.5 4005.5 3425.6 3514.6 3521.1 3521.8 3543.9 535.6 615.3 617.8 629.6 630.1 504.6 558.7 603.8 605.1 616.6 7143.8 7218.2 8027.2 8039.1 8155.9 480.4 585.5 591.6 592.3 594.6 3530.6 3677.4 3694.5 3885.0 3918.2 90338.1 91564.5 91590.5 94770.0 94909.3 74884.6 75296.6 77255.5 77786.2 78682.6 68974.7 69199.6 70002.0 70184.6 70439.3 6585.5 7483.0 7591.7 7608.8 7786.6 44267.0 44358.3 44568.8 45035.0 45053.8 60903.7 61279.7 61555.7 62430.7 63872.0 9882.4 10129.2 10238.2 10256.9 10417.4 10656.0 10996.5 11077.7 11362.1 11743.3 10084.8 10108.9 10323.1 10465.3 10637.1 10162.6 10189.7 10206.8 10219.4 10533.2 8489.9 10191.1 10210.6 10520.5 10658.9 8746.8 8784.1 8955.8 9077.7 9287.4 8476.3 8758.6 8916.9 8979.3 9002.9 05:33:30 INFO - PROCESS | 6918 | -------- Summary: end -------- 05:33:30 INFO - PROCESS | 6918 | 05:33:31 INFO - TEST-INFO | 6918: exit 0 05:33:31 INFO - TEST-OK | dromaeo_css | took 1115640ms 05:33:31 INFO - TEST-START | kraken 05:33:31 INFO - operating with platform_type : mac_ 05:33:31 INFO - Initialising browser for kraken test... 05:33:31 INFO - TEST-INFO | started process 6921 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmp0bzjZm/profile http://localhost:61184/getInfo.html) 05:33:33 INFO - PROCESS | 6921 | __metrics Screen width/height:1600/1200 05:33:33 INFO - PROCESS | 6921 | colorDepth:24 05:33:33 INFO - PROCESS | 6921 | Browser inner width/height: 1024/689 05:33:33 INFO - PROCESS | 6921 | __metrics 05:33:33 INFO - PROCESS | 6921 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:33:33 ERROR - PROCESS | 6921 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:33:34 INFO - TEST-INFO | 6921: exit 0 05:33:34 INFO - Browser initialized. 05:33:34 INFO - Running cycle 1/1 for kraken test... 05:33:34 INFO - TEST-INFO | started process 6924 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmp0bzjZm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:46 INFO - PROCESS | 6924 | RSS: Main: 239804416 05:33:46 INFO - PROCESS | 6924 | 05:33:48 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61184/tests/kraken/driver.html?testName=audio-beat-detection) 05:33:49 INFO - PROCESS | 6924 | RSS: Main: 287117312 05:33:49 INFO - PROCESS | 6924 | 05:33:52 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61184/tests/kraken/driver.html?testName=audio-dft) 05:33:53 INFO - PROCESS | 6924 | RSS: Main: 296140800 05:33:53 INFO - PROCESS | 6924 | 05:33:56 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61184/tests/kraken/driver.html?testName=audio-fft) 05:33:57 INFO - PROCESS | 6924 | RSS: Main: 378896384 05:33:57 INFO - PROCESS | 6924 | 05:33:59 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61184/tests/kraken/driver.html?testName=audio-oscillator) 05:34:00 INFO - PROCESS | 6924 | RSS: Main: 281145344 05:34:00 INFO - PROCESS | 6924 | 05:34:02 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61184/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:34:03 INFO - PROCESS | 6924 | RSS: Main: 285179904 05:34:03 INFO - PROCESS | 6924 | 05:34:05 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61184/tests/kraken/driver.html?testName=imaging-darkroom) 05:34:06 INFO - PROCESS | 6924 | RSS: Main: 322957312 05:34:06 INFO - PROCESS | 6924 | 05:34:09 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61184/tests/kraken/driver.html?testName=imaging-desaturate) 05:34:10 INFO - PROCESS | 6924 | RSS: Main: 324993024 05:34:10 INFO - PROCESS | 6924 | 05:34:12 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61184/tests/kraken/driver.html?testName=json-parse-financial) 05:34:13 INFO - PROCESS | 6924 | RSS: Main: 321437696 05:34:13 INFO - PROCESS | 6924 | 05:34:15 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61184/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:34:15 INFO - PROCESS | 6924 | RSS: Main: 313479168 05:34:15 INFO - PROCESS | 6924 | 05:34:17 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61184/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:34:18 INFO - PROCESS | 6924 | RSS: Main: 319987712 05:34:18 INFO - PROCESS | 6924 | 05:34:20 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61184/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:34:21 INFO - PROCESS | 6924 | RSS: Main: 321949696 05:34:21 INFO - PROCESS | 6924 | 05:34:24 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61184/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:34:24 INFO - PROCESS | 6924 | RSS: Main: 326881280 05:34:24 INFO - PROCESS | 6924 | 05:34:27 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61184/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:34:28 INFO - PROCESS | 6924 | RSS: Main: 324517888 05:34:28 INFO - PROCESS | 6924 | 05:34:29 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:61184/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61184/tests/kraken/driver.html?testName=ai-astar) 05:34:30 INFO - PROCESS | 6924 | RSS: Main: 389939200 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | __start_tp_report 05:34:30 INFO - PROCESS | 6924 | _x_x_mozilla_page_load 05:34:30 INFO - PROCESS | 6924 | _x_x_mozilla_page_load_details 05:34:30 INFO - PROCESS | 6924 | |i|pagename|runs| 05:34:30 INFO - PROCESS | 6924 | |0;ai-astar;112;112;109;116;162;163;112;111;105;110 05:34:30 INFO - PROCESS | 6924 | |1;audio-beat-detection;161;147;186;205;136;142;143;122;174;122 05:34:30 INFO - PROCESS | 6924 | |2;audio-dft;185;187;184;184;175;183;226;261;184;183 05:34:30 INFO - PROCESS | 6924 | |3;audio-fft;79;80;122;122;85;51;61;108;106;104 05:34:30 INFO - PROCESS | 6924 | |4;audio-oscillator;92;94;141;78;80;80;126;80;77;130 05:34:30 INFO - PROCESS | 6924 | |5;imaging-gaussian-blur;88;84;87;85;87;84;88;86;88;84 05:34:30 INFO - PROCESS | 6924 | |6;imaging-darkroom;97;93;123;138;92;92;93;94;105;147 05:34:30 INFO - PROCESS | 6924 | |7;imaging-desaturate;83;83;84;85;83;83;86;84;83;85 05:34:30 INFO - PROCESS | 6924 | |8;json-parse-financial;60;62;59;58;58;58;59;78;60;57 05:34:30 INFO - PROCESS | 6924 | |9;json-stringify-tinderbox;66;73;73;74;71;70;69;70;70;70 05:34:30 INFO - PROCESS | 6924 | |10;stanford-crypto-aes;84;83;84;104;81;83;86;84;84;85 05:34:30 INFO - PROCESS | 6924 | |11;stanford-crypto-ccm;140;139;138;134;138;135;136;137;180;137 05:34:30 INFO - PROCESS | 6924 | |12;stanford-crypto-pbkdf2;173;171;171;172;178;171;170;171;172;171 05:34:30 INFO - PROCESS | 6924 | |13;stanford-crypto-sha256-iterative;70;69;71;68;69;70;70;71;67;68 05:34:30 INFO - PROCESS | 6924 | __end_tp_report 05:34:30 INFO - PROCESS | 6924 | __start_cc_report 05:34:30 INFO - PROCESS | 6924 | _x_x_mozilla_cycle_collect,2030 05:34:30 INFO - PROCESS | 6924 | __end_cc_report 05:34:30 INFO - PROCESS | 6924 | __startTimestamp1462106070168__endTimestamp 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | ------- Summary: start ------- 05:34:30 INFO - PROCESS | 6924 | Number of tests: 14 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#0] ai-astar Cycles:10 Average:121.20 Median:112.00 stddev:21.94 (19.6%) stddev-sans-first:23.02 05:34:30 INFO - PROCESS | 6924 | Values: 112.0 112.0 109.0 116.0 162.0 163.0 112.0 111.0 105.0 110.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#1] audio-beat-detection Cycles:10 Average:153.80 Median:154.00 stddev:27.41 (17.8%) stddev-sans-first:28.94 05:34:30 INFO - PROCESS | 6924 | Values: 161.0 147.0 186.0 205.0 136.0 142.0 143.0 122.0 174.0 122.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#2] audio-dft Cycles:10 Average:195.20 Median:184.50 stddev:26.94 (14.6%) stddev-sans-first:28.32 05:34:30 INFO - PROCESS | 6924 | Values: 185.0 187.0 184.0 184.0 175.0 183.0 226.0 261.0 184.0 183.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#3] audio-fft Cycles:10 Average:91.80 Median:56.00 stddev:24.49 (43.7%) stddev-sans-first:25.54 05:34:30 INFO - PROCESS | 6924 | Values: 79.0 80.0 122.0 122.0 85.0 51.0 61.0 108.0 106.0 104.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:80.00 stddev:24.77 (31.0%) stddev-sans-first:26.18 05:34:30 INFO - PROCESS | 6924 | Values: 92.0 94.0 141.0 78.0 80.0 80.0 126.0 80.0 77.0 130.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:1.73 (2.0%) stddev-sans-first:1.69 05:34:30 INFO - PROCESS | 6924 | Values: 88.0 84.0 87.0 85.0 87.0 84.0 88.0 86.0 88.0 84.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#6] imaging-darkroom Cycles:10 Average:107.40 Median:92.50 stddev:20.90 (22.6%) stddev-sans-first:21.82 05:34:30 INFO - PROCESS | 6924 | Values: 97.0 93.0 123.0 138.0 92.0 92.0 93.0 94.0 105.0 147.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.00 stddev:1.10 (1.3%) stddev-sans-first:1.12 05:34:30 INFO - PROCESS | 6924 | Values: 83.0 83.0 84.0 85.0 83.0 83.0 86.0 84.0 83.0 85.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#8] json-parse-financial Cycles:10 Average:60.90 Median:59.50 stddev:6.17 (10.4%) stddev-sans-first:6.54 05:34:30 INFO - PROCESS | 6924 | Values: 60.0 62.0 59.0 58.0 58.0 58.0 59.0 78.0 60.0 57.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:70.50 stddev:2.32 (3.3%) stddev-sans-first:1.76 05:34:30 INFO - PROCESS | 6924 | Values: 66.0 73.0 73.0 74.0 71.0 70.0 69.0 70.0 70.0 70.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:84.00 stddev:6.53 (7.8%) stddev-sans-first:6.89 05:34:30 INFO - PROCESS | 6924 | Values: 84.0 83.0 84.0 104.0 81.0 83.0 86.0 84.0 84.0 85.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:138.00 stddev:13.68 (9.9%) stddev-sans-first:14.50 05:34:30 INFO - PROCESS | 6924 | Values: 140.0 139.0 138.0 134.0 138.0 135.0 136.0 137.0 180.0 137.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:171.50 stddev:2.26 (1.3%) stddev-sans-first:2.37 05:34:30 INFO - PROCESS | 6924 | Values: 173.0 171.0 171.0 172.0 178.0 171.0 170.0 171.0 172.0 171.0 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - PROCESS | 6924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:70.00 stddev:1.34 (1.9%) stddev-sans-first:1.39 05:34:30 INFO - PROCESS | 6924 | Values: 70.0 69.0 71.0 68.0 69.0 70.0 70.0 71.0 67.0 68.0 05:34:30 INFO - PROCESS | 6924 | -------- Summary: end -------- 05:34:30 INFO - PROCESS | 6924 | 05:34:30 INFO - TEST-INFO | 6924: exit 0 05:34:30 INFO - TEST-OK | kraken | took 59888ms 05:34:30 INFO - SUITE-END | took 1175s 05:34:31 INFO - Completed test suite (00:19:35) 05:34:31 INFO - javascript benchmark 05:34:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3329.4173519634064, 3380.08737640837, 3395.7453191010663, 3403.76173667843, 3457.515947767313, 5621.213030175779, 5649.943500564993, 5652.095664693655, 5694.2312787773635, 5696.287964004499, 5609.915851262234, 5685.289338832636, 5732.856678583037, 5869.882602347961, 5889.087191485316, 5102.438731739506, 5127.538521533062, 5155.871103222412, 5178.663386879841, 5223.451537588556, 5228.032813929435, 5282.392524859632, 5289.418164001957, 5295.258663787073, 5321.680699158043, 5067.5185857343495, 5153.613478989091, 5169.302144210521, 5170.948290517084, 5229.477052294778, 5062.645614806928, 5117.0, 5148.897022059537, 5175.637705360627, 5187.662801917863, 5043.344365232496, 5047.116754567936, 5047.974760126176, 5060.822871199527, 5070.163423035195, 5468.808591699271, 5501.9174712379345, 5504.174373843955, 5505.201745746885, 5538.6122971392015, 6107.84730381744, 6114.663693496856, 6116.327204007555, 6145.047517634774, 6153.323134455206, 6132.69336533176, 6154.96922515389, 6182.350853160398, 6188.226471691039, 6198.132261483396, 5686.886262274731, 5836.153757705109, 5838.562107841929, 5839.532837373, 5854.590178687494, 6140.723667434976, 6160.9383906161265, 6207.379262073847, 6216.378362163748, 6217.285012223652, 6056.454919057306, 6091.725872335755, 6103.992841181244, 6116.082587611805, 6142.754289828356, 6164.383561643889, 6174.9073763893575, 6182.258129024546, 6190.195274614362, 6191.504679625709, 6069.119977603223, 6095.952232173814, 6107.7862274821055, 6116.97495384395, 6146.985747351637, 6127.5866022601795, 6130.459285710702, 6136.263648362135, 6141.539384546177, 6142.617910970085, 4875.000624871893, 4899.593194345511, 4907.09218794517, 4915.803367865316, 4963.751812409438, 4381.9123617527475, 4431.180231657089, 4505.797239124247, 4512.4133862597655, 4546.88632784183, 5671.744771485293, 5679.744411501492, 5682.687452190169, 5687.317521897398, 5695.886082278331, 6132.478739307119, 6144.41628045335, 6145.508359331332, 6211.81364559064, 6219.40915613016, 4781.163296423112, 5515.145152501369, 5613.635113717595, 5615.157726341082, 5725.971370143206, 5324.041672498987, 5351.438098999627, 5361.329833770778, 5367.973160134175, 5372.597055220874, 6256.874862502725, 6298.212723409482, 6304.558680892385, 6310.842228944313, 6324.209473815773, 6135.877282454237, 6146.477549408351, 6198.318184999736, 6229.688515574294, 6236.781712640036, 6220.595661282002, 6238.906416403848, 6252.218472690914, 6268.091126786499, 6274.560780745486, 5516.365617953827, 5896.528277737856, 6138.079288106641, 6161.753529858755, 6167.414095660905, 6085.087236914498, 6146.186874871834, 6168.074788781719, 6184.072389141665, 6224.06639004153, 6082.635041897501, 6105.908411373922, 6106.80159637959, 6138.969305153446, 6139.508839292756, 5957.046872500379, 6076.149339092619, 6081.939180608138, 6084.330723620486, 6099.725512351866, 4951.801927922843, 5225.843224703265, 5909.054551271776, 5961.821145365646, 5962.9266731988655, 5187.740612969412, 6123.141741795795, 6135.76632123851, 6137.938620613737, 6151.415615516519, 5750.971245143747, 5925.170476133431, 5981.0, 5993.101034844633, 5995.430434108753], "value": 5643.210987311202, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.468659161514, 518.1062666899599, 518.4608007672022, 541.4071591607194, 574.4714862326659, 30560.0, 38555.42166867496, 39761.403578946265, 39861.00347491311, 39881.601183988154, 43255.56744432555, 44411.111777764425, 44438.555614443845, 44489.0, 44501.77749111258, 8496.277816385615, 9473.857892131606, 9694.466804325759, 9701.466419346933, 9703.369280996712, 8581.056083830774, 11317.717057073598, 11615.709607259843, 11622.476988535536, 11661.0, 11535.519863229403, 11696.707582310426, 11709.941450292737, 11719.472623731952, 11743.060555155567, 8439.987201535794, 11239.60661376848, 11556.191066625339, 11599.246049006872, 11732.824007639892, 11420.54317827286, 11612.593559225386, 11614.012808911168, 11616.883831161666, 11630.302181869114, 38451.0, 38981.805090974645, 39069.0, 39083.80458097691, 39186.82439526819, 3157.163351711798, 3178.2054486378406, 3219.581454410935, 3219.7263232625255, 3265.96734032659, 29052.27369315763, 29053.56419653707, 29139.562906556417, 29154.708452915413, 29157.125286241448, 2672.7594516493414, 2711.6339294195227, 2712.471068141713, 2728.345197152689, 2826.773858091348, 469869.0, 470243.64878175734, 471363.0, 472362.0, 473691.0, 59302.0, 60133.69933150306, 60268.397316026276, 60344.79310413855, 60417.0, 51776.0, 63938.72122557523, 63965.36034639687, 63988.36011639917, 64061.679691601246, 16787.496375108767, 17078.0, 17201.225944832513, 17219.65560688804, 17246.91376543109, 9719.368241064309, 11684.415779211005, 12568.0, 12806.487740490462, 12980.545680901123, 19699.606007879764, 20121.899390502953, 20153.496162596053, 20156.59686806285, 20218.797812021985, 6887.827804304932, 7910.485818421784, 8008.55845947735, 8011.439199255996, 8019.639116239783, 941.204682043672, 947.4694171264114, 992.3847214726916, 1045.5660900726266, 1145.7078444996473, 29076.273093172418, 29328.7067129326, 29691.554626680616, 29776.553351699742, 29874.40251194964, 1098.0227597438127, 1107.6510899066768, 1140.3556990300453, 1158.4150004247801, 1184.1592469346688, 12572.559960401342, 13072.542461013818, 15127.319270632848, 15382.015379033117, 15660.13869237203, 631032.0, 632300.0, 632621.0, 641168.0, 643481.0, 58222.708886456145, 59724.0, 59902.0, 59992.400075999554, 60256.769187697755, 1285.8585555589066, 1313.7175507265792, 1357.0, 1420.2969530082262, 1441.8630230128122, 28585.571216431355, 28668.713312866603, 28727.856360718062, 29280.26799330034, 29293.414131717673, 46755.53244467512, 46811.82970425767, 47428.288575670704, 47474.81312967203, 47475.28787068266, 353830.0, 359156.0, 360084.0, 360406.0, 360408.0, 22135.335939921828, 24776.50446991014, 25320.0, 25567.87216063908, 25573.3606659835, 39891.202175956896, 42361.57638423576, 43318.566814331454, 43519.56480435282, 44130.0, 33111.66888331086, 35392.823035884656, 35868.0, 36683.08292292714, 36810.263794724495, 34555.13612159616, 34969.0, 35698.10754731049, 35774.4633830498, 36704.449433257985, 36006.91979240627, 36696.449553256185, 36866.26267474689, 36892.89321320257, 37045.6295437053, 27499.86250068737, 31789.36421271608, 31964.20089497781, 32564.51153232751, 32588.022359329258, 19364.70952935735, 21416.89291553532, 23714.52570948537, 24102.87948560246, 24496.38759030967], "value": 19577.730713885798, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.50796598914565, 383.6738772043763, 396.6430212808472, 399.67426547363897, 401.37586053686505, 19222.615547689038, 20237.59524809503, 20965.685514717272, 20965.790342096574, 21005.894970525165, 18388.264469421207, 18490.907545462287, 19184.232630694754, 19233.32683356083, 19373.418797436065, 5687.914681279784, 5844.123381492767, 5940.821775346746, 5958.672273024982, 6583.374579414948, 6480.773172938948, 6487.513436492282, 6488.448481879046, 6532.869342613145, 6540.574862633937, 6374.936250637504, 6451.29035806061, 6451.354864513534, 6453.741850325981, 6470.417662410382, 5626.493615574618, 6253.43719065284, 6394.0, 6454.9677251613675, 6476.060971159202, 5615.326160860723, 5625.746841392106, 5660.547156227533, 5672.205891175238, 5685.317761868602, 25436.872815635805, 25644.0, 26023.609645855326, 26027.0, 26049.869750651316, 1883.2561138350275, 2126.8830214338204, 2142.678598210265, 2155.267209148881, 2171.489699920533, 9636.22910167185, 9688.951555242249, 9804.215662747034, 9919.652812151613, 10059.597616095347, 1738.9739153912574, 1792.5339411752907, 1833.5324492254524, 1833.7799464064399, 1902.7241050047803, 93049.67375815591, 107631.0, 117696.0, 118591.0, 119129.40435297854, 11342.092632589372, 11533.653990380302, 11605.187636865507, 11606.129540284512, 11737.589184378676, 14746.778798318033, 14853.331600077807, 14853.331600078023, 14866.851331486763, 14890.776638350433, 7223.92776072243, 7872.881906771403, 8191.959040204761, 8545.74362769118, 8740.125987401208, 8631.611577478903, 8635.661472471777, 8636.913630863612, 8775.692850750193, 8803.911960880308, 4454.5990860822685, 4559.224931761609, 4571.81712731487, 4586.403767510269, 4667.6265898728025, 1765.3556451895222, 1895.981040189608, 1948.074664534335, 1957.5889063296581, 1973.4178417366911, 704.8378872859271, 875.5578432891349, 909.2089881802874, 1007.9647212347679, 1009.7929924365487, 15430.5370838875, 15460.768088478682, 20639.58720825575, 20726.896365518376, 20770.792292076887, 945.9195968342768, 949.0035462764071, 952.1763674421576, 966.5505539923969, 997.1275134257525, 8306.70926517569, 10797.892021079846, 10812.297200681931, 11009.0, 11020.44897755109, 531293.0, 544270.0, 549808.0, 549860.0, 557761.0, 121668.56662866693, 123605.38197308956, 124305.0, 125798.0, 128006.71993280134, 901.6799036342135, 997.5660587644253, 1021.3463383434756, 1021.7292417509366, 1023.6263763726336, 14605.488807892098, 17798.555036124202, 18140.092995350442, 18141.909290453463, 18494.907525462288, 47424.330544660515, 48467.51532484771, 49097.50902490929, 49109.263361050325, 50033.0, 58081.4191858076, 69691.95462068175, 72820.0, 74595.254047461, 79350.0, 7605.543667379975, 7890.013748281465, 7931.762047138595, 8200.015998080029, 8209.79475513093, 9048.502332371783, 11699.65453972804, 11713.941430292794, 11794.823077654013, 12315.50737970507, 5456.809011684572, 7184.892226616709, 10092.94024127456, 10624.893751062391, 10970.561177553127, 10549.52527136296, 10634.41510716918, 10671.306365086244, 10809.35143891369, 10930.562777489129, 9350.81298374015, 10856.34861908288, 11005.669829905115, 11039.337639741641, 11190.272632278877, 7751.147373788992, 7809.882851757115, 7873.5275883447175, 7997.7600671979935, 8019.91980080215, 10349.430781307101, 10588.735281618021, 10681.946590266998, 10711.678649640524, 10731.892681073401], "value": 9320.682546416903, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.5698533454624, 1585.064811761061, 1588.3408385520006, 1800.5948661551154, 1922.6346994071116, 6800.523963322563, 7492.250774922505, 8515.956170519077, 8530.872036919436, 8537.573121343923, 8205.343572514199, 8449.831003379928, 8452.661893524251, 8456.492610443378, 8465.026521949962, 2312.6184179610345, 2356.0929042318744, 2360.5868972929757, 2361.2798096580536, 2362.397588614901, 2311.687922130516, 2334.8832558372096, 2357.7642235776457, 2363.652717950769, 2365.6451532270125, 2105.5788842231536, 2312.8727919964394, 2330.0796185506715, 2343.8828058597087, 2350.1421980976907, 2277.7266727992583, 2297.7472478027403, 2306.4003359126664, 2308.2382813671447, 2330.021391015777, 2292.7592602776635, 2297.322289924476, 2302.3783578433895, 2304.043821813945, 2306.688597039395, 7781.922180778233, 8038.553060449117, 8528.01927778301, 8599.441036332619, 8616.396852220347, 5040.218766091261, 5205.453427390145, 5240.737963101829, 5245.737713114329, 5248.396434410015, 3031.9534314337734, 3371.2920286739813, 3450.896473105811, 3522.0842580929143, 3525.2773181497732, 913.7578541686458, 975.8338785151747, 1002.5688953749884, 1031.283258135596, 1044.2481413382352, 11065.668029959113, 11171.83242251367, 11323.037541808957, 11330.263532870336, 11344.886551134465, 4305.06143681425, 4586.839460618862, 4601.3558101865765, 4604.7927843247135, 4605.907881842344, 8967.686130985385, 9016.774580635405, 9018.819623607622, 9028.63885444588, 9032.954835225913, 5180.352455943007, 5186.2479940409185, 5197.714125723046, 5206.55744261742, 5208.947910520922, 3929.2927273090545, 4349.151915376535, 4783.617310615143, 4873.853784386474, 4878.756062196876, 3542.1144713821545, 3634.636536346397, 3656.0494271489074, 3665.157013886821, 3730.4963829882618, 988.3279370028306, 1074.6722249713912, 1164.132721122769, 1167.311286341063, 1168.258156070884, 788.3456730913329, 899.9145081217274, 932.9020452852353, 946.068122898944, 950.6245033211843, 5155.97422012895, 5661.924234395452, 5730.598858079894, 5801.796937107181, 5825.7087145642545, 882.3250213586612, 923.2060428031887, 933.2160984772823, 945.3550821570556, 951.6336210558846, 3747.981260093682, 3913.9823645852284, 3950.604939506084, 3991.44119823225, 3994.560598334181, 9744.415335079917, 10156.847647285296, 10588.788224235472, 10618.522166502526, 10633.946830265953, 9365.812683746288, 10435.68692939213, 10437.165026797838, 10583.25917185805, 10606.946965265279, 7697.230276972258, 7827.25641064098, 7834.529928204326, 7914.16901225363, 7926.097736543188, 5720.971395142998, 5761.596688231783, 5833.737481813244, 5995.2206213192, 6053.757849685963, 9360.95319523398, 9752.219822414048, 9852.0, 9855.014498550088, 9879.259055570717, 9026.774330641787, 9334.953325233331, 9939.0, 9967.800643986935, 10054.64808731691, 7124.893126603209, 7324.230955749783, 7371.926280737339, 7375.078115235596, 7387.852242955003, 7594.6202689866395, 7878.921210787818, 7920.920790792018, 7928.881066783887, 7930.325922296669, 7006.789796306119, 7185.317394847481, 7216.89174662391, 7276.05411296528, 7276.163241227115, 7076.540024898365, 7107.928920710933, 7109.973657507586, 7240.52936559122, 7257.963710181415, 6777.796666100025, 6799.694013769294, 6856.554323968926, 6879.277675844164, 6880.58716477013, 4822.228443449031, 4872.342233798392, 4883.267509873547, 4927.531884470969, 5044.697318160922, 4898.828541001048, 4939.936441203086, 4964.131277026579, 4994.850154495371, 5039.823606173766], "value": 4439.622699462164, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.60003395467066, 310.73587450666935, 319.4904127915973, 320.5048200530181, 322.06279725997337, 4612.86161415158, 4822.6624136310475, 5214.556762675173, 5274.947250527489, 5289.312389389379, 4216.786187251694, 4842.467328593861, 4860.756962151887, 4994.375703037121, 5090.9236361454605, 3854.4411060396365, 4017.799110044486, 4031.153457773871, 4048.4736984192014, 4054.2296963576828, 3375.6793104655017, 3549.6982756465604, 4047.433359329696, 4048.9595104048876, 4067.755934643916, 3309.8179600121985, 3863.9613603864022, 3881.592432794558, 3897.349802134547, 3997.8400863965553, 3383.390989621867, 3813.389857622795, 4045.2111838191563, 4054.797260136996, 4084.448599439082, 2964.0, 3108.0054382597577, 3409.590849098099, 3518.683318501347, 3544.2025544252338, 4141.4409054777525, 4547.408836851188, 4807.495213002654, 5012.523810238022, 5020.7740651670765, 4235.0, 4569.634429245652, 4574.702644328108, 4587.9541204588195, 4607.424071991001, 4846.982133751916, 5585.720713964285, 5593.720313984325, 5608.0, 5630.859228519279, 3260.96739032609, 3511.7015053720465, 3808.485854409647, 3819.618038196186, 3821.808909554539, 5446.482584154539, 5802.82591522255, 5833.7374818133285, 5883.499902508292, 5893.0, 3183.0, 3636.2727454509204, 3784.4890939723336, 3834.9041273968373, 3847.5382954045695, 3402.8128452935325, 3531.152523394418, 3900.902477438087, 3909.237698648793, 3937.684985201177, 4433.401490798766, 4503.909921801545, 4548.385967894358, 4550.226461501553, 4562.0, 4608.861734147981, 5279.260903473517, 5535.363433205152, 5575.553955683536, 5578.97210513953, 1154.486253617149, 1295.8898493628146, 1335.504235256519, 1426.21558143023, 1429.6497358147321, 761.584936209767, 882.8278485695228, 924.8531820542613, 933.9066093390607, 945.8675785390051, 5156.075947470157, 5563.777448902079, 5593.412691667317, 5597.272354593958, 5612.747426365823, 5561.582881283839, 5608.803691870765, 6140.508759299245, 6159.476444502178, 6177.536684748572, 4429.712068715523, 4838.758062096881, 4864.464908860023, 5306.310179676618, 5335.493128152819, 3271.214908421962, 3292.456744637156, 3294.061192560157, 3317.7345812334956, 3320.5849268841393, 5752.02215623345, 6050.334463209044, 6559.639219842955, 6591.472682185414, 6611.834704132435, 4820.566149046603, 5271.736413179326, 5719.598698318855, 5902.321233058167, 5972.372900845436, 5685.829425117253, 5738.913916291259, 5866.30150130936, 5973.940260597338, 5989.790357337472, 4871.439784424695, 5568.220449137205, 5783.508401785895, 5784.4136635286395, 5797.217375654402, 4838.588719958843, 5640.971795140998, 5738.68437235956, 5762.942370576408, 5800.796972105956, 5115.283860259641, 5794.1308803679785, 6007.30915944672, 6064.9393506066135, 6088.299845517645, 3422.7775194612273, 3777.56557995834, 3810.8666196684094, 3845.384738441836, 3865.8260378282484, 5489.698066606375, 5702.203805801159, 5781.653100813796, 5805.912911306418, 5817.7963771267805, 4567.680262381601, 4852.75736213195, 5656.971715141398, 5681.4034526374135, 5717.22817419643, 5537.729530892476, 5562.72186390687, 5583.804566840141, 5600.93582219377, 5608.775648973995, 4292.506361768436, 4485.0, 5305.655132416535, 5389.59578031657, 5571.492828227233, 5501.972490137524, 5539.362973258127, 5550.916736249041, 5570.2201691762375, 5583.692896890548, 5285.735713214401, 5464.726763661722, 5466.316710411199, 5467.261919640798, 5493.285872836505], "value": 4084.842803516705, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.9552731308808, 648.4714957309792, 668.4719071933172, 682.7678589279647, 722.5339655921928, 13701.451941922323, 13759.243241621707, 13964.720705585882, 14003.29983500826, 14011.0, 12287.569935052275, 12535.0, 12573.308468034254, 12772.744545109092, 12780.297083660394, 712.5724565260828, 733.6991833348328, 859.10223816112, 886.3352485635774, 904.9321300902426, 898.7573355194094, 899.95050272235, 905.0949050949051, 905.3571963905634, 905.8460061789478, 950.2635457520408, 957.2820384711466, 957.5834511987273, 957.6313119448993, 962.191758922505, 732.1324230786535, 908.1236606674524, 945.5886689290146, 947.1381043250609, 949.4588084791671, 697.9844326504923, 702.2065066474851, 705.6542294275819, 707.476467414115, 707.8548897476056, 13490.865091349058, 13550.051496395256, 13553.254570998592, 13566.0, 13589.388477518536, 11731.0, 11794.233374830608, 11914.702132446671, 12022.579209727704, 12207.328596927166, 9130.908690913073, 9142.908570914338, 9192.632294708204, 9267.304952128618, 9506.47714375709, 3334.683205095512, 3365.7812242204177, 3401.693847553707, 3420.2817408344276, 3483.1988642612087, 75420.0, 76081.23918760852, 76357.61821190914, 76386.23613763847, 77973.61013194898, 8848.513331766815, 8872.484087176912, 8919.108089191159, 9160.496172710566, 9275.489848058287, 20826.85452300138, 21015.264465743625, 21075.26236581712, 21131.577368452854, 21347.825869577013, 6923.099997000459, 6992.370686638227, 7165.140183178052, 7190.06529151207, 7305.525140865827, 3840.2127563849904, 3927.626875446885, 3928.8821335359985, 3929.5284565851707, 4005.4814999377104, 3425.6403077676487, 3514.578250609943, 3521.137321356233, 3521.841517131735, 3543.9291214175573, 535.611681530887, 615.2739767074895, 617.8331850400367, 629.5530173576811, 630.1335663462738, 504.63918298416075, 558.7206396801599, 603.8248907816684, 605.0923614578132, 616.608453631947, 7143.82126949058, 7218.242084581148, 8027.237412445808, 8039.115697273295, 8155.918440815634, 480.39710163744274, 585.508173134569, 591.5977135547869, 592.2744637818638, 594.6105301027834, 3530.5586801649793, 3677.411614141778, 3694.51971243742, 3885.0481631999774, 3918.186667198399, 90338.09661903429, 91564.54217728869, 91590.54204729067, 94770.0, 94909.25453727286, 74884.62557687177, 75296.62351688207, 77255.45489090186, 77786.22213777789, 78682.60658696669, 68974.65512672404, 69199.61600767956, 70002.0, 70184.64907675429, 70439.2956070443, 6585.473162147113, 7483.0, 7591.658375373232, 7608.80977975555, 7786.649600767866, 44267.0, 44358.3346249813, 44568.77715611401, 45035.0, 45053.774731126134, 60903.69548152231, 61279.693601531704, 61555.69222153861, 62430.687846560475, 63872.0, 9882.407055576688, 10129.240306976859, 10238.232132589937, 10256.897431025594, 10417.374957502574, 10656.0, 10996.450177491253, 11077.667669969915, 11362.14783891195, 11743.295402275891, 10084.798304034024, 10108.89891101109, 10323.070923616911, 10465.319754215952, 10637.095846853104, 10162.644307449204, 10189.694309170736, 10206.846897296695, 10219.437930913871, 10533.210009249184, 8489.915100849159, 10191.133753630727, 10210.642627508001, 10520.473976301308, 10658.893411066101, 8746.825063498822, 8784.121587841164, 8955.776105597412, 9077.68228112013, 9287.442753434816, 8476.321894248322, 8758.562071896253, 8916.910830891607, 8979.32655050887, 9002.909970900208], "value": 6527.887928036799, "unit": "score"}], "name": "dromaeo_css", "value": 7041.731594633892, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 109.0, 116.0, 162.0, 163.0, 112.0, 111.0, 105.0, 110.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 147.0, 186.0, 205.0, 136.0, 142.0, 143.0, 122.0, 174.0, 122.0], "value": 153.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 184.0, 184.0, 175.0, 183.0, 226.0, 261.0, 184.0, 183.0], "value": 195.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 122.0, 122.0, 85.0, 51.0, 61.0, 108.0, 106.0, 104.0], "value": 91.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 141.0, 78.0, 80.0, 80.0, 126.0, 80.0, 77.0, 130.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 85.0, 87.0, 84.0, 88.0, 86.0, 88.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 93.0, 123.0, 138.0, 92.0, 92.0, 93.0, 94.0, 105.0, 147.0], "value": 107.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 85.0, 83.0, 83.0, 86.0, 84.0, 83.0, 85.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 59.0, 58.0, 58.0, 58.0, 59.0, 78.0, 60.0, 57.0], "value": 60.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 73.0, 74.0, 71.0, 70.0, 69.0, 70.0, 70.0, 70.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 104.0, 81.0, 83.0, 86.0, 84.0, 84.0, 85.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 138.0, 134.0, 138.0, 135.0, 136.0, 137.0, 180.0, 137.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 171.0, 172.0, 178.0, 171.0, 170.0, 171.0, 172.0, 171.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 71.0, 68.0, 69.0, 70.0, 70.0, 71.0, 67.0, 68.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1537.1999999999998, "alertThreshold": 2.0}]} 05:34:31 INFO - Return code: 0 05:34:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:34:31 ERROR - # TBPL SUCCESS # 05:34:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:34:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:34:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:34:31 INFO - 2016-05-01 05:34:31.496 screenresolution[6928:172984] starting screenresolution argv=screenresolution get 05:34:31 INFO - 2016-05-01 05:34:31.498 screenresolution[6928:172984] Display 0: 1600x1200x32@60 05:34:31 INFO - 2016-05-01 05:34:31.505 screenresolution[6929:172987] starting screenresolution argv=screenresolution list 05:34:31 INFO - Available Modes on Display 0 05:34:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:34:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:34:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:34:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:34:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:34:31 INFO - Intel Iris: 05:34:31 INFO - Chipset Model: Intel Iris 05:34:31 INFO - Type: GPU 05:34:31 INFO - Bus: Built-In 05:34:31 INFO - VRAM (Dynamic, Max): 1536 MB 05:34:31 INFO - Vendor: Intel (0x8086) 05:34:31 INFO - Device ID: 0x0a2e 05:34:31 INFO - Revision ID: 0x0009 05:34:31 INFO - Displays: 05:34:31 INFO - Display: 05:34:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:34:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:34:31 INFO - Display Serial Number: 1600x1200 60 05:34:31 INFO - Main Display: Yes 05:34:31 INFO - Mirror: Off 05:34:31 INFO - Online: Yes 05:34:31 INFO - Rotation: Supported 05:34:31 INFO - Return code: 0 05:34:31 INFO - Running post-action listener: _resource_record_post_action 05:34:31 INFO - Running post-run listener: _resource_record_post_run 05:34:32 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 20480; Write bytes: 697163776; Read time: 0; Write time: 2055 05:34:32 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398737920; Write bytes: 401196032; Read time: 29861; Write time: 839 05:34:32 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 20480; Write bytes: 295058432; Read time: 0; Write time: 1212 05:34:32 INFO - Running post-run listener: _upload_blobber_files 05:34:32 INFO - Blob upload gear active. 05:34:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:34:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:34:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:34:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:34:33 INFO - (blobuploader) - INFO - Open directory for files ... 05:34:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:34:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:34:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:34 INFO - (blobuploader) - INFO - Done attempting. 05:34:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:34:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:34:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:35 INFO - (blobuploader) - INFO - Done attempting. 05:34:35 INFO - (blobuploader) - INFO - Iteration through files over. 05:34:35 INFO - Return code: 0 05:34:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:34:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:34:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57069a1c8de30e8b318aa45b9656ce8efbf705bbc6a489ba52ec74a768561a29cd899b79639990c003ea1c2ec33712f261188e9c0d9410428dcef0cbcc97db32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/757f7d3735fca9de8afaf41c0f78844f43a9549a86666c39f65b07f83a045b40f271dfa32f9307252d9628818f74e8cbfe6d44f1700bec9289669c7bdf80de93"} 05:34:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:34:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:34:35 INFO - Contents: 05:34:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57069a1c8de30e8b318aa45b9656ce8efbf705bbc6a489ba52ec74a768561a29cd899b79639990c003ea1c2ec33712f261188e9c0d9410428dcef0cbcc97db32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/757f7d3735fca9de8afaf41c0f78844f43a9549a86666c39f65b07f83a045b40f271dfa32f9307252d9628818f74e8cbfe6d44f1700bec9289669c7bdf80de93"} 05:34:35 INFO - Running post-run listener: copy_logs_to_upload_dir 05:34:35 INFO - Copying logs to upload dir... 05:34:35 INFO - mkdir: /builds/slave/test/build/upload/logs 05:34:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1423.281657 ========= master_lag: 3.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 46 secs) (at 2016-05-01 05:34:38.476655) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-01 05:34:38.477785) ========= 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.KM8nhNzts0/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.JEsHSPMma8/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57069a1c8de30e8b318aa45b9656ce8efbf705bbc6a489ba52ec74a768561a29cd899b79639990c003ea1c2ec33712f261188e9c0d9410428dcef0cbcc97db32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/757f7d3735fca9de8afaf41c0f78844f43a9549a86666c39f65b07f83a045b40f271dfa32f9307252d9628818f74e8cbfe6d44f1700bec9289669c7bdf80de93"} build_url:https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/Cs1KhQJbRnmnCJKKhB2nTg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57069a1c8de30e8b318aa45b9656ce8efbf705bbc6a489ba52ec74a768561a29cd899b79639990c003ea1c2ec33712f261188e9c0d9410428dcef0cbcc97db32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/757f7d3735fca9de8afaf41c0f78844f43a9549a86666c39f65b07f83a045b40f271dfa32f9307252d9628818f74e8cbfe6d44f1700bec9289669c7bdf80de93"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-01 05:34:38.509307) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:34:38.509633) ========= 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.KM8nhNzts0/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.JEsHSPMma8/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.005364 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 05:34:38.560611) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-01 05:34:38.562013) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-01 05:34:38.562422) ========= ========= Total master_lag: 3.49 =========