builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0320 starttime: 1481771232.93 results: success (0) buildid: 20161214171002 builduid: 86c3a9706920433d8140bf2e8d038385 revision: b1dbd3dcc086e90bf9c94ecb9984301a6178340c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:12.925793) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:12.926211) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:12.926479) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:12.953850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:12.954328) ========= 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.COoLMMy84R/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.C8DxWpmPTU/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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-12-14 19:07:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 26.9M=0s 2016-12-14 19:07:13 (26.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106776 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:13.084694) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:13.085020) ========= 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.COoLMMy84R/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.C8DxWpmPTU/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.025715 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:13.132701) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 19:07:13.133023) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b1dbd3dcc086e90bf9c94ecb9984301a6178340c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b1dbd3dcc086e90bf9c94ecb9984301a6178340c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.COoLMMy84R/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.C8DxWpmPTU/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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-12-14 19:07:13,238 truncating revision to first 12 chars 2016-12-14 19:07:13,239 Setting DEBUG logging. 2016-12-14 19:07:13,239 attempt 1/10 2016-12-14 19:07:13,239 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b1dbd3dcc086?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 19:07:14,670 unpacking tar archive at: mozilla-aurora-b1dbd3dcc086/testing/mozharness/ program finished with exit code 0 elapsedTime=1.704108 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 19:07:14.865152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:14.865527) ========= script_repo_revision: b1dbd3dcc086e90bf9c94ecb9984301a6178340c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:14.865970) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:14.866304) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 19:07:14.887036) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-14 19:07:14.887767) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.COoLMMy84R/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.C8DxWpmPTU/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 19:07:15 INFO - MultiFileLogger online at 20161214 19:07:15 in /builds/slave/test 19:07:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 19:07:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:07:15 INFO - {'append_to_log': False, 19:07:15 INFO - 'base_work_dir': '/builds/slave/test', 19:07:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:07:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:07:15 INFO - 'branch': 'Mozilla-Aurora', 19:07:15 INFO - 'buildbot_json_path': 'buildprops.json', 19:07:15 INFO - 'config_files': ('talos/mac_config.py',), 19:07:15 INFO - 'default_actions': ('clobber', 19:07:15 INFO - 'read-buildbot-config', 19:07:15 INFO - 'download-and-extract', 19:07:15 INFO - 'populate-webroot', 19:07:15 INFO - 'create-virtualenv', 19:07:15 INFO - 'install', 19:07:15 INFO - 'run-tests'), 19:07:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:07:15 INFO - 'download_minidump_stackwalk': True, 19:07:15 INFO - 'download_symbols': 'ondemand', 19:07:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:07:15 INFO - 'tooltool.py': '/tools/tooltool.py', 19:07:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:07:15 INFO - '/tools/misc-python/virtualenv.py')}, 19:07:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:07:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:07:15 INFO - 'installer_path': 'installer.exe', 19:07:15 INFO - 'log_level': 'info', 19:07:15 INFO - 'log_name': 'talos', 19:07:15 INFO - 'log_to_console': True, 19:07:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:07:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:07:15 INFO - 'opt_config_files': (), 19:07:15 INFO - 'pip_index': False, 19:07:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:07:15 INFO - 'cmd': ('bash', 19:07:15 INFO - '-c', 19:07:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:07:15 INFO - 'enabled': True, 19:07:15 INFO - 'halt_on_failure': False, 19:07:15 INFO - 'name': 'check_screen_resolution'},), 19:07:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:07:15 INFO - 'cmd': ('bash', 19:07:15 INFO - '-c', 19:07:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:07:15 INFO - 'enabled': True, 19:07:15 INFO - 'halt_on_failure': False, 19:07:15 INFO - 'name': 'check_screen_resolution'},), 19:07:15 INFO - 'run_cmd_checks_enabled': True, 19:07:15 INFO - 'sps_profile': False, 19:07:15 INFO - 'sps_profile_interval': 0, 19:07:15 INFO - 'suite': 'dromaeojs-e10s', 19:07:15 INFO - 'system_bits': '32', 19:07:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:07:15 INFO - 'title': 't-yosemite-r7-0320', 19:07:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:07:15 INFO - 'use_talos_json': True, 19:07:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:07:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:07:15 INFO - 'work_dir': 'build'} 19:07:15 INFO - [mozharness: 2016-12-15 03:07:15.103981Z] Running clobber step. 19:07:15 INFO - Running pre-action listener: _resource_record_pre_action 19:07:15 INFO - Running main action method: clobber 19:07:15 INFO - rmtree: /builds/slave/test/build 19:07:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:07:17 INFO - Running post-action listener: _resource_record_post_action 19:07:17 INFO - [mozharness: 2016-12-15 03:07:17.426292Z] Finished clobber step (success) 19:07:17 INFO - [mozharness: 2016-12-15 03:07:17.426518Z] Running read-buildbot-config step. 19:07:17 INFO - Running pre-action listener: _resource_record_pre_action 19:07:17 INFO - Running main action method: read_buildbot_config 19:07:17 INFO - Using buildbot properties: 19:07:17 INFO - { 19:07:17 INFO - "project": "", 19:07:17 INFO - "product": "firefox", 19:07:17 INFO - "script_repo_revision": "production", 19:07:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 19:07:17 INFO - "repository": "", 19:07:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 19:07:17 INFO - "buildid": "20161214171002", 19:07:17 INFO - "pgo_build": "False", 19:07:17 INFO - "basedir": "/builds/slave/test", 19:07:17 INFO - "buildnumber": 57, 19:07:17 INFO - "slavename": "t-yosemite-r7-0320", 19:07:17 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:07:17 INFO - "platform": "yosemite_r7", 19:07:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 19:07:17 INFO - "branch": "mozilla-aurora", 19:07:17 INFO - "repo_path": "releases/mozilla-aurora", 19:07:17 INFO - "revision": "b1dbd3dcc086e90bf9c94ecb9984301a6178340c", 19:07:17 INFO - "stage_platform": "macosx64", 19:07:17 INFO - "builduid": "86c3a9706920433d8140bf2e8d038385", 19:07:17 INFO - "slavebuilddir": "test" 19:07:17 INFO - } 19:07:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 19:07:17 INFO - Running post-action listener: _resource_record_post_action 19:07:17 INFO - [mozharness: 2016-12-15 03:07:17.432239Z] Finished read-buildbot-config step (success) 19:07:17 INFO - [mozharness: 2016-12-15 03:07:17.432423Z] Running download-and-extract step. 19:07:17 INFO - Running pre-action listener: _resource_record_pre_action 19:07:17 INFO - Running main action method: download_and_extract 19:07:17 INFO - mkdir: /builds/slave/test/build/tests 19:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:17 INFO - trying https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 19:07:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 19:07:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 19:07:18 INFO - Downloaded 1199 bytes. 19:07:18 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 19:07:18 INFO - Using the following test package requirements: 19:07:18 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 19:07:18 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:18 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 19:07:18 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:18 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 19:07:18 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:18 INFO - u'jsshell-mac.zip'], 19:07:18 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:18 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 19:07:18 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 19:07:18 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:18 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 19:07:18 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:18 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 19:07:18 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:18 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 19:07:18 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:18 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 19:07:18 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 19:07:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 19:07:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:07:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 19:07:18 INFO - Http code: 200 19:07:18 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 19:07:18 INFO - x-amz-cf-id: 769rtOmFS-tAAy7A-zwiVWYCBRYiDODMp1US1Aw2hh20DVktBSSXhA== 19:07:18 INFO - x-amz-version-id: r9jRc5iDpaEZMDxXIhHd.chgCvtbgkBa 19:07:19 INFO - Expected file size: 16280921 19:07:19 INFO - Obtained file size: 16280921 19:07:25 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 19:07:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 19:07:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:07:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 19:07:26 INFO - Http code: 200 19:07:26 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 19:07:26 INFO - x-amz-cf-id: 3_N48EfajZCpaHpBreWri8JahHc5LQvUrx2dHbVidMmbFgY7Fdy1xw== 19:07:26 INFO - x-amz-version-id: r9jRc5iDpaEZMDxXIhHd.chgCvtbgkBa 19:07:26 INFO - Expected file size: 16280921 19:07:26 INFO - Obtained file size: 16280921 19:07:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 19:07:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 19:07:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 19:07:33 INFO - Http code: 200 19:07:33 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 19:07:33 INFO - x-amz-cf-id: 2_o7fv9goXK64U_icLf3IOplwE7fDL0_223mLQ6AvrMmJRCngMFIlA== 19:07:33 INFO - x-amz-version-id: P_ynYICOZeDdL86VI7Eohgi4HquTbVdc 19:07:34 INFO - Expected file size: 14409038 19:07:34 INFO - Obtained file size: 14409038 19:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:34 INFO - trying https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:07:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 19:07:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 19:07:37 INFO - Downloaded 109765715 bytes. 19:07:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:07:37 INFO - mkdir: /builds/slave/test/properties 19:07:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:07:37 INFO - Writing to file /builds/slave/test/properties/build_url 19:07:37 INFO - Contents: 19:07:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:07:37 INFO - Running post-action listener: _resource_record_post_action 19:07:37 INFO - Running post-action listener: set_extra_try_arguments 19:07:37 INFO - [mozharness: 2016-12-15 03:07:37.791451Z] Finished download-and-extract step (success) 19:07:37 INFO - [mozharness: 2016-12-15 03:07:37.791623Z] Running populate-webroot step. 19:07:37 INFO - Running pre-action listener: _resource_record_pre_action 19:07:37 INFO - Running main action method: populate_webroot 19:07:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:07:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:07:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:07:37 INFO - '--tppagecycles', 19:07:37 INFO - '7'], 19:07:37 INFO - 'tests': ['tsvgm']}}, 19:07:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:07:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:07:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:07:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:07:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:07:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:07:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:37 INFO - 'talos_options': ['--disable-e10s'], 19:07:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:37 INFO - 'talos_options': ['--disable-e10s'], 19:07:37 INFO - 'tests': ['damp', 'tps']}, 19:07:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:37 INFO - 'tests': ['damp', 'tps']}, 19:07:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:07:37 INFO - 'tests': ['dromaeo_dom']}, 19:07:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:07:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:07:37 INFO - 'tests': ['basic_compositor_video']}, 19:07:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:07:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:07:37 INFO - 'tests': ['a11yr', 19:07:37 INFO - 'ts_paint', 19:07:37 INFO - 'tpaint', 19:07:37 INFO - 'sessionrestore', 19:07:37 INFO - 'sessionrestore_no_auto_restore', 19:07:37 INFO - 'tabpaint']}, 19:07:37 INFO - 'other-e10s': {'tests': ['a11yr', 19:07:37 INFO - 'ts_paint', 19:07:37 INFO - 'tpaint', 19:07:37 INFO - 'sessionrestore', 19:07:37 INFO - 'sessionrestore_no_auto_restore', 19:07:37 INFO - 'tabpaint']}, 19:07:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:07:37 INFO - 'ts_paint', 19:07:37 INFO - 'tpaint', 19:07:37 INFO - 'sessionrestore', 19:07:37 INFO - 'sessionrestore_no_auto_restore', 19:07:37 INFO - 'tabpaint']}, 19:07:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:07:37 INFO - 'ts_paint', 19:07:37 INFO - 'tpaint', 19:07:37 INFO - 'sessionrestore', 19:07:37 INFO - 'sessionrestore_no_auto_restore', 19:07:37 INFO - 'tabpaint']}, 19:07:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:07:37 INFO - 'tests': ['a11yr', 19:07:37 INFO - 'ts_paint', 19:07:37 INFO - 'tpaint', 19:07:37 INFO - 'sessionrestore', 19:07:37 INFO - 'sessionrestore_no_auto_restore', 19:07:37 INFO - 'tabpaint']}, 19:07:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:07:37 INFO - 'tests': ['a11yr', 19:07:37 INFO - 'ts_paint', 19:07:37 INFO - 'tpaint', 19:07:37 INFO - 'sessionrestore', 19:07:37 INFO - 'sessionrestore_no_auto_restore', 19:07:37 INFO - 'tabpaint']}, 19:07:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:07:37 INFO - 'tests': ['tsvgx', 19:07:37 INFO - 'tsvgr_opacity', 19:07:37 INFO - 'tart', 19:07:37 INFO - 'tscrollx', 19:07:37 INFO - 'cart']}, 19:07:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:07:37 INFO - 'tsvgr_opacity', 19:07:37 INFO - 'tart', 19:07:37 INFO - 'tscrollx', 19:07:37 INFO - 'cart']}, 19:07:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:37 INFO - 'talos_options': ['--disable-e10s'], 19:07:37 INFO - 'tests': ['tp5o']}, 19:07:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:37 INFO - 'tests': ['tp5o']}, 19:07:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:37 INFO - 'talos_options': ['--disable-e10s', 19:07:37 INFO - '--xperf_path', 19:07:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:37 INFO - 'tests': ['tp5n']}, 19:07:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:37 INFO - 'talos_options': ['--xperf_path', 19:07:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:37 INFO - 'tests': ['tp5n']}}, 19:07:37 INFO - 'talos.zip': {'path': '', 19:07:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:07:37 INFO - Running post-action listener: _resource_record_post_action 19:07:37 INFO - [mozharness: 2016-12-15 03:07:37.810456Z] Finished populate-webroot step (success) 19:07:37 INFO - [mozharness: 2016-12-15 03:07:37.810584Z] Running create-virtualenv step. 19:07:37 INFO - Running pre-action listener: _resource_record_pre_action 19:07:37 INFO - Running main action method: create_virtualenv 19:07:37 INFO - Creating virtualenv /builds/slave/test/build/venv 19:07:37 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:07:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:07:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:07:37 INFO - Using real prefix '/tools/python27' 19:07:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:07:38 INFO - Installing distribute.............................................................................................................................................................................................done. 19:07:41 INFO - Installing pip.................done. 19:07:41 INFO - Return code: 0 19:07:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:07:41 INFO - Reading from file tmpfile_stdout 19:07:41 INFO - Output received: 19:07:41 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:07:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:41 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 0x105cce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c44570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b94ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2d30>, '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 0x105948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105adab20>, '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.COoLMMy84R/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.C8DxWpmPTU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:07:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:07:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.COoLMMy84R/Render', 19:07:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:41 INFO - 'HOME': '/Users/cltbld', 19:07:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:41 INFO - 'LOGNAME': 'cltbld', 19:07:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:41 INFO - 'MOZ_NO_REMOTE': '1', 19:07:41 INFO - 'NO_EM_RESTART': '1', 19:07:41 INFO - 'PAGER': '/bin/cat', 19:07:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:41 INFO - 'PWD': '/builds/slave/test', 19:07:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:41 INFO - 'SHELL': '/bin/bash', 19:07:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8DxWpmPTU/Listeners', 19:07:41 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 19:07:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:41 INFO - 'USER': 'cltbld', 19:07:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:41 INFO - 'XPC_FLAGS': '0x0', 19:07:41 INFO - 'XPC_SERVICE_NAME': '0', 19:07:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:07:42 INFO - Cleaning up... 19:07:42 INFO - Return code: 0 19:07:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105cce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c44570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b94ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2d30>, '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 0x105948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105adab20>, '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.COoLMMy84R/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.C8DxWpmPTU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:07:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:07:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.COoLMMy84R/Render', 19:07:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:42 INFO - 'HOME': '/Users/cltbld', 19:07:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:42 INFO - 'LOGNAME': 'cltbld', 19:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:42 INFO - 'MOZ_NO_REMOTE': '1', 19:07:42 INFO - 'NO_EM_RESTART': '1', 19:07:42 INFO - 'PAGER': '/bin/cat', 19:07:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:42 INFO - 'PWD': '/builds/slave/test', 19:07:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:42 INFO - 'SHELL': '/bin/bash', 19:07:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8DxWpmPTU/Listeners', 19:07:42 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 19:07:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:42 INFO - 'USER': 'cltbld', 19:07:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:42 INFO - 'XPC_FLAGS': '0x0', 19:07:42 INFO - 'XPC_SERVICE_NAME': '0', 19:07:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:42 INFO - Downloading/unpacking psutil>=3.1.1 19:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:07:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:07:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:07:45 INFO - Installing collected packages: psutil 19:07:45 INFO - Running setup.py install for psutil 19:07:45 INFO - building 'psutil._psutil_osx' extension 19:07:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:07:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:07:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:07:46 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:07:46 INFO - building 'psutil._psutil_posix' extension 19:07:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:07:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:07:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:07:46 INFO - ^ 19:07:46 INFO - 1 warning generated. 19:07:46 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:07:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:07:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:07:46 INFO - Successfully installed psutil 19:07:46 INFO - Cleaning up... 19:07:46 INFO - Return code: 0 19:07:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:07:46 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:46 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105cce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c44570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b94ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2d30>, '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 0x105948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105adab20>, '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.COoLMMy84R/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.C8DxWpmPTU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:07:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:07:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.COoLMMy84R/Render', 19:07:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:46 INFO - 'HOME': '/Users/cltbld', 19:07:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:46 INFO - 'LOGNAME': 'cltbld', 19:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:46 INFO - 'MOZ_NO_REMOTE': '1', 19:07:46 INFO - 'NO_EM_RESTART': '1', 19:07:46 INFO - 'PAGER': '/bin/cat', 19:07:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:46 INFO - 'PWD': '/builds/slave/test', 19:07:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:46 INFO - 'SHELL': '/bin/bash', 19:07:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8DxWpmPTU/Listeners', 19:07:46 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 19:07:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:46 INFO - 'USER': 'cltbld', 19:07:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:46 INFO - 'XPC_FLAGS': '0x0', 19:07:46 INFO - 'XPC_SERVICE_NAME': '0', 19:07:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:07:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:07:49 INFO - Installing collected packages: mozsystemmonitor 19:07:49 INFO - Running setup.py install for mozsystemmonitor 19:07:50 INFO - Successfully installed mozsystemmonitor 19:07:50 INFO - Cleaning up... 19:07:50 INFO - Return code: 0 19:07:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:07:50 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:50 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:50 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105cce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c44570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b94ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2d30>, '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 0x105948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105adab20>, '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.COoLMMy84R/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.C8DxWpmPTU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:07:50 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==2.5.1'] in /builds/slave/test/build 19:07:50 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==2.5.1 19:07:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.COoLMMy84R/Render', 19:07:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:50 INFO - 'HOME': '/Users/cltbld', 19:07:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:50 INFO - 'LOGNAME': 'cltbld', 19:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:50 INFO - 'MOZ_NO_REMOTE': '1', 19:07:50 INFO - 'NO_EM_RESTART': '1', 19:07:50 INFO - 'PAGER': '/bin/cat', 19:07:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:50 INFO - 'PWD': '/builds/slave/test', 19:07:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:50 INFO - 'SHELL': '/bin/bash', 19:07:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8DxWpmPTU/Listeners', 19:07:50 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 19:07:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:50 INFO - 'USER': 'cltbld', 19:07:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:50 INFO - 'XPC_FLAGS': '0x0', 19:07:50 INFO - 'XPC_SERVICE_NAME': '0', 19:07:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:50 INFO - Downloading/unpacking jsonschema==2.5.1 19:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:07:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:53 INFO - Downloading functools32-3.2.3-2.tar.gz 19:07:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:07:53 INFO - warning: no files found matching '*.txt' 19:07:53 INFO - no previously-included directories found matching 'build' 19:07:53 INFO - no previously-included directories found matching 'dist' 19:07:53 INFO - no previously-included directories found matching '.git*' 19:07:53 INFO - Installing collected packages: jsonschema, functools32 19:07:53 INFO - Running setup.py install for functools32 19:07:53 INFO - warning: no files found matching '*.txt' 19:07:53 INFO - no previously-included directories found matching 'build' 19:07:53 INFO - no previously-included directories found matching 'dist' 19:07:53 INFO - no previously-included directories found matching '.git*' 19:07:53 INFO - Successfully installed jsonschema functools32 19:07:53 INFO - Cleaning up... 19:07:53 INFO - Return code: 0 19:07:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:07: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07: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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105cce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c44570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b94ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2d30>, '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 0x105948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105adab20>, '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.COoLMMy84R/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.C8DxWpmPTU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:07: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:07: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 functools32==3.2.3-2 19:07:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.COoLMMy84R/Render', 19:07:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:53 INFO - 'HOME': '/Users/cltbld', 19:07:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:53 INFO - 'LOGNAME': 'cltbld', 19:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:53 INFO - 'MOZ_NO_REMOTE': '1', 19:07:53 INFO - 'NO_EM_RESTART': '1', 19:07:53 INFO - 'PAGER': '/bin/cat', 19:07:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:53 INFO - 'PWD': '/builds/slave/test', 19:07:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:53 INFO - 'SHELL': '/bin/bash', 19:07:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8DxWpmPTU/Listeners', 19:07:53 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 19:07:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:53 INFO - 'USER': 'cltbld', 19:07:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:53 INFO - 'XPC_FLAGS': '0x0', 19:07:53 INFO - 'XPC_SERVICE_NAME': '0', 19:07:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:07:53 INFO - Cleaning up... 19:07:54 INFO - Return code: 0 19:07:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:07: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:54 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 0x105cce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c44570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b94ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2d30>, '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 0x105948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105adab20>, '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.COoLMMy84R/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.C8DxWpmPTU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:07:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:07:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.COoLMMy84R/Render', 19:07:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:54 INFO - 'HOME': '/Users/cltbld', 19:07:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:54 INFO - 'LOGNAME': 'cltbld', 19:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:54 INFO - 'MOZ_NO_REMOTE': '1', 19:07:54 INFO - 'NO_EM_RESTART': '1', 19:07:54 INFO - 'PAGER': '/bin/cat', 19:07:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:54 INFO - 'PWD': '/builds/slave/test', 19:07:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:54 INFO - 'SHELL': '/bin/bash', 19:07:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8DxWpmPTU/Listeners', 19:07:54 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 19:07:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:54 INFO - 'USER': 'cltbld', 19:07:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:54 INFO - 'XPC_FLAGS': '0x0', 19:07:54 INFO - 'XPC_SERVICE_NAME': '0', 19:07:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:54 INFO - Downloading/unpacking blobuploader==1.2.4 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07:56 INFO - Downloading blobuploader-1.2.4.tar.gz 19:07:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:07:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:07:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07:57 INFO - Downloading docopt-0.6.1.tar.gz 19:07:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:07:58 INFO - Installing collected packages: blobuploader, requests, docopt 19:07:58 INFO - Running setup.py install for blobuploader 19:07:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:07:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:07:58 INFO - Running setup.py install for requests 19:07:58 INFO - Running setup.py install for docopt 19:07:58 INFO - Successfully installed blobuploader requests docopt 19:07:58 INFO - Cleaning up... 19:07:58 INFO - Return code: 0 19:07:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:59 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 0x105cce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c44570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b94ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2d30>, '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 0x105948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105adab20>, '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.COoLMMy84R/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.C8DxWpmPTU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:07:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.COoLMMy84R/Render', 19:07:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:59 INFO - 'HOME': '/Users/cltbld', 19:07:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:59 INFO - 'LOGNAME': 'cltbld', 19:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:59 INFO - 'MOZ_NO_REMOTE': '1', 19:07:59 INFO - 'NO_EM_RESTART': '1', 19:07:59 INFO - 'PAGER': '/bin/cat', 19:07:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:59 INFO - 'PWD': '/builds/slave/test', 19:07:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:59 INFO - 'SHELL': '/bin/bash', 19:07:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8DxWpmPTU/Listeners', 19:07:59 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 19:07:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:59 INFO - 'USER': 'cltbld', 19:07:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:59 INFO - 'XPC_FLAGS': '0x0', 19:07:59 INFO - 'XPC_SERVICE_NAME': '0', 19:07:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:07:59 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-V0dlWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:08:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-NfGXs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:08:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-lJLdZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:08:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-GnpO46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:08:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-cnaxjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:08:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-LiQ5rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:08:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Ob2hK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:08:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-xeJWRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:08:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-S_gVWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:08:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-5W74PU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:08:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-aAEIVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:08:01 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-ymLwLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:08:01 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-uNjGWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:08:01 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-YsbGze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:01 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Cx25mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:08:01 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-DGhr9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:08:01 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-M5gt3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:08:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:08:01 INFO - Running setup.py install for manifestparser 19:08:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:08:01 INFO - Running setup.py install for mozcrash 19:08:02 INFO - Running setup.py install for mozdebug 19:08:02 INFO - Running setup.py install for mozdevice 19:08:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:08:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:08:02 INFO - Running setup.py install for mozfile 19:08:02 INFO - Running setup.py install for mozhttpd 19:08:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:08:02 INFO - Running setup.py install for mozinfo 19:08:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:08:02 INFO - Running setup.py install for mozInstall 19:08:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:08:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:08:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:08:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:08:02 INFO - Running setup.py install for mozleak 19:08:03 INFO - Running setup.py install for mozlog 19:08:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:08:03 INFO - Running setup.py install for moznetwork 19:08:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:08:03 INFO - Running setup.py install for mozprocess 19:08:03 INFO - Running setup.py install for mozprofile 19:08:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:08:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:08:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:08:03 INFO - Running setup.py install for mozrunner 19:08:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:08:03 INFO - Running setup.py install for mozscreenshot 19:08:04 INFO - Running setup.py install for moztest 19:08:04 INFO - Running setup.py install for mozversion 19:08:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:08:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:08:04 INFO - Cleaning up... 19:08:04 INFO - Return code: 0 19:08:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:04 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 0x105cce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c44570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b94ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2d30>, '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 0x105948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105adab20>, '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.COoLMMy84R/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.C8DxWpmPTU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:08:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:08:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:08:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.COoLMMy84R/Render', 19:08:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:04 INFO - 'HOME': '/Users/cltbld', 19:08:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:04 INFO - 'LOGNAME': 'cltbld', 19:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:04 INFO - 'MOZ_NO_REMOTE': '1', 19:08:04 INFO - 'NO_EM_RESTART': '1', 19:08:04 INFO - 'PAGER': '/bin/cat', 19:08:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:04 INFO - 'PWD': '/builds/slave/test', 19:08:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:04 INFO - 'SHELL': '/bin/bash', 19:08:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8DxWpmPTU/Listeners', 19:08:04 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 19:08:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:04 INFO - 'USER': 'cltbld', 19:08:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:04 INFO - 'XPC_FLAGS': '0x0', 19:08:04 INFO - 'XPC_SERVICE_NAME': '0', 19:08:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:08:05 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-huA1rZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:08:05 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-YLP40l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:08:05 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-rygKDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:08:05 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Xv_WJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:08:05 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-13DUiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:08:05 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-tHc_Jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:08:05 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-3jIqu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:08:05 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-HF4dd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:08:05 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-zyhoYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:08:06 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-bkhxpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:08:06 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-M2UKzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:08:06 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-8HTj_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:08:06 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-ROxuQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:08:06 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-f_OZvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:06 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-PPqVn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:08:06 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-d7ZHNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:08:06 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-sGyf9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:08:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:09 INFO - Downloading blessings-1.6.tar.gz 19:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:08:09 INFO - Installing collected packages: blessings 19:08:09 INFO - Running setup.py install for blessings 19:08:10 INFO - Successfully installed blessings 19:08:10 INFO - Cleaning up... 19:08:10 INFO - Return code: 0 19:08:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:08:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:08:10 INFO - Reading from file tmpfile_stdout 19:08:10 INFO - Current package versions: 19:08:10 INFO - blessings == 1.6 19:08:10 INFO - blobuploader == 1.2.4 19:08:10 INFO - docopt == 0.6.1 19:08:10 INFO - functools32 == 3.2.3-2 19:08:10 INFO - jsonschema == 2.5.1 19:08:10 INFO - manifestparser == 1.1 19:08:10 INFO - mozInstall == 1.12 19:08:10 INFO - mozcrash == 1.0 19:08:10 INFO - mozdebug == 0.1 19:08:10 INFO - mozdevice == 0.48 19:08:10 INFO - mozfile == 1.2 19:08:10 INFO - mozhttpd == 0.7 19:08:10 INFO - mozinfo == 0.9 19:08:10 INFO - mozleak == 0.1 19:08:10 INFO - mozlog == 3.4 19:08:10 INFO - moznetwork == 0.27 19:08:10 INFO - mozprocess == 0.23 19:08:10 INFO - mozprofile == 0.28 19:08:10 INFO - mozrunner == 6.13 19:08:10 INFO - mozscreenshot == 0.1 19:08:10 INFO - mozsystemmonitor == 0.3 19:08:10 INFO - moztest == 0.7 19:08:10 INFO - mozversion == 1.4 19:08:10 INFO - psutil == 3.1.1 19:08:10 INFO - requests == 1.2.3 19:08:10 INFO - wsgiref == 0.1.2 19:08:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:10 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:10 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:10 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 0x105cce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c44570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b94ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2d30>, '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 0x105948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105adab20>, '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.COoLMMy84R/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.C8DxWpmPTU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:08:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:08:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.COoLMMy84R/Render', 19:08:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:10 INFO - 'HOME': '/Users/cltbld', 19:08:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:10 INFO - 'LOGNAME': 'cltbld', 19:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:10 INFO - 'MOZ_NO_REMOTE': '1', 19:08:10 INFO - 'NO_EM_RESTART': '1', 19:08:10 INFO - 'PAGER': '/bin/cat', 19:08:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:10 INFO - 'PWD': '/builds/slave/test', 19:08:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:10 INFO - 'SHELL': '/bin/bash', 19:08:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8DxWpmPTU/Listeners', 19:08:10 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 19:08:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:10 INFO - 'USER': 'cltbld', 19:08:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:10 INFO - 'XPC_FLAGS': '0x0', 19:08:10 INFO - 'XPC_SERVICE_NAME': '0', 19:08:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:08:10 INFO - Cleaning up... 19:08:10 INFO - Return code: 0 19:08:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:08:10 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:10 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:10 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 0x105cce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c44570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b94ce7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2d30>, '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 0x105948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105adab20>, '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.COoLMMy84R/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.C8DxWpmPTU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:08:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:08:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.COoLMMy84R/Render', 19:08:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:10 INFO - 'HOME': '/Users/cltbld', 19:08:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:10 INFO - 'LOGNAME': 'cltbld', 19:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:10 INFO - 'MOZ_NO_REMOTE': '1', 19:08:10 INFO - 'NO_EM_RESTART': '1', 19:08:10 INFO - 'PAGER': '/bin/cat', 19:08:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:10 INFO - 'PWD': '/builds/slave/test', 19:08:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:10 INFO - 'SHELL': '/bin/bash', 19:08:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8DxWpmPTU/Listeners', 19:08:10 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 19:08:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:10 INFO - 'USER': 'cltbld', 19:08:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:10 INFO - 'XPC_FLAGS': '0x0', 19:08:10 INFO - 'XPC_SERVICE_NAME': '0', 19:08:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:08:11 INFO - Cleaning up... 19:08:11 INFO - Return code: 0 19:08:11 INFO - Running post-action listener: _resource_record_post_action 19:08:11 INFO - Running post-action listener: _start_resource_monitoring 19:08:11 INFO - Starting resource monitoring. 19:08:11 INFO - [mozharness: 2016-12-15 03:08:11.071914Z] Finished create-virtualenv step (success) 19:08:11 INFO - [mozharness: 2016-12-15 03:08:11.072921Z] Running install step. 19:08:11 INFO - Running pre-action listener: _resource_record_pre_action 19:08:11 INFO - Running main action method: install 19:08:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:08:11 INFO - Reading from file tmpfile_stdout 19:08:11 INFO - Detecting whether we're running mozinstall >=1.0... 19:08:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:08:11 INFO - Reading from file tmpfile_stdout 19:08:11 INFO - Output received: 19:08:11 INFO - Usage: mozinstall [options] installer 19:08:11 INFO - Options: 19:08:11 INFO - -h, --help show this help message and exit 19:08:11 INFO - -d DEST, --destination=DEST 19:08:11 INFO - Directory to install application into. [default: 19:08:11 INFO - "/builds/slave/test"] 19:08:11 INFO - --app=APP Application being installed. [default: firefox] 19:08:11 INFO - mkdir: /builds/slave/test/build/application 19:08:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 19:08:42 INFO - Reading from file tmpfile_stdout 19:08:42 INFO - Output received: 19:08:42 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:08:42 INFO - Running post-action listener: _resource_record_post_action 19:08:42 INFO - [mozharness: 2016-12-15 03:08:42.215864Z] Finished install step (success) 19:08:42 INFO - [mozharness: 2016-12-15 03:08:42.216003Z] Running run-tests step. 19:08:42 INFO - Running pre-action listener: _resource_record_pre_action 19:08:42 INFO - Running main action method: run_tests 19:08:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:08:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:08:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:08:42 INFO - 2016-12-14 19:08:42.230 screenresolution[1631:7683] starting screenresolution argv=screenresolution get 19:08:42 INFO - 2016-12-14 19:08:42.233 screenresolution[1631:7683] Display 0: 1600x1200x32@60 19:08:42 INFO - 2016-12-14 19:08:42.238 screenresolution[1632:7686] starting screenresolution argv=screenresolution list 19:08:42 INFO - Available Modes on Display 0 19:08:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:08:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:08:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:08:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:08:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:08:42 INFO - Intel Iris: 19:08:42 INFO - Chipset Model: Intel Iris 19:08:42 INFO - Type: GPU 19:08:42 INFO - Bus: Built-In 19:08:42 INFO - VRAM (Dynamic, Max): 1536 MB 19:08:42 INFO - Vendor: Intel (0x8086) 19:08:42 INFO - Device ID: 0x0a2e 19:08:42 INFO - Revision ID: 0x0009 19:08:42 INFO - Displays: 19:08:42 INFO - Display: 19:08:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:08:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:08:42 INFO - Display Serial Number: 1600x1200 60 19:08:42 INFO - Main Display: Yes 19:08:42 INFO - Mirror: Off 19:08:42 INFO - Online: Yes 19:08:42 INFO - Rotation: Supported 19:08:42 INFO - Return code: 0 19:08:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:08:42 INFO - Python 2.7.3 19:08:42 INFO - Return code: 0 19:08:42 INFO - grabbing minidump binary from tooltool 19:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:42 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ae2d30>, '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 0x105948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105adab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:08:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:08:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:08:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:08:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:08:42 INFO - Return code: 0 19:08:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:08:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:08:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:08:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:08:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:08:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0320 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.COoLMMy84R/Render', 19:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:42 INFO - 'HOME': '/Users/cltbld', 19:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:42 INFO - 'LOGNAME': 'cltbld', 19:08:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:08:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:42 INFO - 'MOZ_NO_REMOTE': '1', 19:08:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:08:42 INFO - 'NO_EM_RESTART': '1', 19:08:42 INFO - 'PAGER': '/bin/cat', 19:08:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:42 INFO - 'PWD': '/builds/slave/test', 19:08:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:42 INFO - 'SHELL': '/bin/bash', 19:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8DxWpmPTU/Listeners', 19:08:42 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 19:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:42 INFO - 'USER': 'cltbld', 19:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:42 INFO - 'XPC_FLAGS': '0x0', 19:08:42 INFO - 'XPC_SERVICE_NAME': '0', 19:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:08:42 INFO - mozversion application_buildid: 20161214171002 19:08:42 INFO - mozversion application_changeset: b1dbd3dcc086e90bf9c94ecb9984301a6178340c 19:08:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:08:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:08:42 INFO - mozversion application_name: Firefox 19:08:42 INFO - mozversion application_remotingname: firefox-dev 19:08:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:08:42 INFO - mozversion application_vendor: Mozilla 19:08:42 INFO - mozversion application_version: 52.0a2 19:08:42 INFO - mozversion platform_buildid: 20161214171002 19:08:42 INFO - mozversion platform_changeset: b1dbd3dcc086e90bf9c94ecb9984301a6178340c 19:08:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:08:42 INFO - mozversion platform_version: 52.0a2 19:08:42 INFO - using testdate: 1481771322 19:08:42 INFO - actual date: 1481771322 19:08:42 INFO - starting webserver on 'localhost:49252' 19:08:42 INFO - SUITE-START | Running 2 tests 19:08:42 INFO - TEST-START | dromaeo_css 19:08:42 INFO - operating with platform_type : mac_ 19:08:42 INFO - Initialising browser for dromaeo_css test... 19:08:42 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpJUu5Mx/profile http://localhost:49252/getInfo.html) 19:08:45 INFO - PROCESS | 1642 | 2016-12-14 19:08:45.795 plugin-container[1644:7860] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 19:08:45 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:45 INFO - PROCESS | 1642 | 2016-12-14 19:08:45.798 plugin-container[1644:7860] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 19:08:45 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:45 INFO - PROCESS | 1642 | 2016-12-14 19:08:45.798 plugin-container[1644:7860] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:08:45 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 19:08:45 INFO - PROCESS | 1642 | colorDepth:24 19:08:45 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 19:08:45 INFO - PROCESS | 1642 | __metrics 19:08:46 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 19:08:46 INFO - TEST-INFO | 1642: exit 0 19:08:46 INFO - Browser initialized. 19:08:46 INFO - Running cycle 1/1 for dromaeo_css test... 19:08:46 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpJUu5Mx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:08:49 INFO - PROCESS | 1645 | 2016-12-14 19:08:49.495 plugin-container[1646:7989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 19:08:49 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:49 INFO - PROCESS | 1645 | 2016-12-14 19:08:49.495 plugin-container[1646:7989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880f, name = 'com.apple.CFPasteboardClient' 19:08:49 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:08:49 INFO - PROCESS | 1645 | 2016-12-14 19:08:49.495 plugin-container[1646:7989] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:08:59 INFO - PROCESS | 1645 | RSS: Main: 229277696 19:08:59 INFO - PROCESS | 1645 | 19:09:48 INFO - PROCESS | 1645 | 1481771388842 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:12:02 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49252/tests/dromaeo/cssquery-ext.html) 19:12:04 INFO - PROCESS | 1645 | RSS: Main: 233664512 19:12:04 INFO - PROCESS | 1645 | 19:15:06 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-ext.html (next: http://localhost:49252/tests/dromaeo/cssquery-jquery.html) 19:15:07 INFO - PROCESS | 1645 | RSS: Main: 243060736 19:15:07 INFO - PROCESS | 1645 | 19:18:08 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49252/tests/dromaeo/cssquery-mootools.html) 19:18:09 INFO - PROCESS | 1645 | RSS: Main: 245948416 19:18:09 INFO - PROCESS | 1645 | 19:21:11 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49252/tests/dromaeo/cssquery-prototype.html) 19:21:12 INFO - PROCESS | 1645 | RSS: Main: 245284864 19:21:12 INFO - PROCESS | 1645 | 19:24:14 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49252/tests/dromaeo/cssquery-yui.html) 19:24:15 INFO - PROCESS | 1645 | RSS: Main: 248684544 19:24:15 INFO - PROCESS | 1645 | 19:27:18 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-yui.html (next: http://localhost:49252/tests/dromaeo/cssquery-dojo.html) 19:27:18 INFO - PROCESS | 1645 | RSS: Main: 250327040 19:27:18 INFO - PROCESS | 1645 | 19:27:18 INFO - PROCESS | 1645 | __start_tp_report 19:27:18 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 19:27:18 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 19:27:18 INFO - PROCESS | 1645 | |i|pagename|runs| 19:27:18 INFO - PROCESS | 1645 | |0;dojo.html;3334.6498617645148;3348.1796959744865;3366;3382;3403.251284717363;4403.3174857897075;5135.64050516464;5478.424765399631;5754.482096611304;5764.452377024181;5704.429557044294;5754.395788442216;5767.307923049229;5772.393898640635;5813.273340832396;4699.318598803172;4952.826651067214;5160.664556803805;5216.921746173811;5220.556252718524;4290.678199135062;5197.558207552363;5267.051930652481;5293.073712100387;5323.520883120518;5090.261912022736;5143.819966301199;5181.481851814826;5220.451852555503;5238.554722848562;4095.9180816383805;5034.748262586856;5112.156494178456;5173.1722924331925;5179.119549676564;5081.085404627165;5094.261332106861;5106.693598384109;5141.254518094893;5142.6914385137015;5497.5601951843755;5644.209810626516;5671.149327600893;5688.175214593861;5719.542436605103;5131.4868513148385;5143.12566863634;6143.986242270064;6211.316755156974;6227.906581401283;4642.744649044301;5205.479452054802;5450.264214331054;6236;6275.497960163176;5846.707664616794;5850.297964244318;5854.297484301911;5866.67733274674;5880.235569375996;6125.723854043132;6172.259328880563;6190.876182476325;6208.317085120633;6221.595596286227;6128.380904898417;6128.890487035018;6134.589663226396;6150.169727086786;6150.630962142286;6210.099535567318;6219.409156130251;6227.906581401283;6276.686165691788;6286.874262514724;6006.159137720722;6017.247844019497;6077.3314935357075;6111.388861113853;6174.969125154345;6119.938800612026;6153.138560601519;6181.567290289637;6191.876162476725;6211.254649442096;4822.132016237111;4841.491643377394;4860.9513904861205;4876.658633895664;4911.214205727138;4256.6807489438415;4386.517483076859;4390.8024138913825;4400.9437734943385;4461.732296062247;4546.568076032838;5593.8321850344555;5700.116481945305;5771.394003629558;5877.206577112121;6232.376762323732;6260.342664020303;6261.530385221035;6275.497960163176;6283.7800676976085;5612.466815652507;5677.37548869624;5693.202951586781;5766.711664416846;5799.18811366409;4410.42664453619;5308;5324.440933701983;5341.732913354394;5382.192671099367;6273.874522509615;6300.779472718433;6308.990561510136;6314.242290925118;6319.020551814476;6239.532035097477;6257.68711564429;6281.497480201481;6291;6310.11658367838;6164.7842325518395;6215.347388524321;6255.24937007562;6307.526935479766;6324.905126423199;5810.273715785527;6180.845478863064;6217.502599792097;6224.657643829632;6230.875382492234;5316.867078323073;5581.6930068846605;6251.281102673251;6255.56211065221;6291.937080629135;5782.595218334676;5831.241938547962;6071.848203794941;6088.482478989158;6092.086187071975;4743.83396581118;5021.949780502295;5883.79333263364;5940.079287710412;6055.636661800306;5829.737661805144;5879.882402351844;5936.7922122725495;5984.102384642339;5990.131430942576;6072.605280656743;6089.208402907594;6103.328633850362;6106.480949119375;6114.908276375947;5742.339630942328;5794.62334948227;5799.246098007232;5813;5990.520758339235 19:27:18 INFO - PROCESS | 1645 | |1;ext.html;478.82522879149076;492.4066499867659;500.8580436604543;503.30795156659593;506.2886644264809;39031.21937561247;40543.98640033997;40859.56991505294;42901.78549107258;43231.78384108083;41774;41987;42540.936476588024;42590.787046064805;42719.14561708764;8746.737597872074;8815.955920220389;9346;9541.522923853814;9589.232861371074;8274.586270686441;8915.554222288893;9384.920734115562;9422.15200631943;9573.617055317778;9478.383905046185;9532.523373831315;9562.043795620451;9575.95212023939;9607.27945404094;8231.670733170666;9420.293477989178;9495.097965693249;9580.61677532898;9592.136707696342;9335;9340;9441.90558094417;9453.52732363386;9456.243500519942;39906.20187596232;40023;40113.39829902554;40201;40226.59773402258;3307.7684562080663;3312.403767321881;3386.6444023377685;3403.6596340366013;3734.607866174067;29007;29517.557236641467;30192.396152077054;30201.0939671808;30324;3134.949791819743;3163.3040731039137;3196.2968147007623;3254.2027203335333;3293.127321259868;373605;376377.1181144077;376871.11564442003;377369;378826;58729;58856.41143588595;59009.70495147497;59021.704891476125;59201;61910.38089619136;62175.6891215541;62409;62492.68753656294;62836;14295.57113286603;14671.266436678123;14744.336504857307;14781.556553303419;14809.777853332209;12086.637400877988;12134.96852767507;12153.696157595954;12182.147249692614;12231.633051008484;15262.618434539003;16329.591760206089;16390.754138687927;16391.754123688155;16430.260638271306;6644.4020038196795;6665.566738162004;6765.627890466071;6781.677572873243;6788.151481064867;869.4609342207872;876.2245412809582;963.7397902566408;1035.3735989726258;1059.86751656043;29801.254968625522;29950.400991980037;30025;30167.64245608953;30403;869.6825658634552;988.9208863290919;1016.7356487313352;1035.4460363705532;1048.2819268800895;11471.197016208785;14306.785398219035;14994.175320357484;15401.614959626098;15820.971636843567;655128;657909;662381;665099;667418;57130;57218;57431.42568574261;57528.84942301131;57538.84922301614;1355.7695191817218;1361.700425906299;1387.861213878604;1389.4164450930432;1393.4217299820461;23721;28784.136475905758;29534.704652954057;30200.395992079597;30264.5460318091;49760;50035.499645004544;50119.74940125276;50192.24711629401;50367;595398;596646;596841;597474;599534;38708.61291387163;39083.609163908;40468;40510.59489405068;40550.39174412445;44741.32888006748;45327.093458129995;46072.308915365626;46540.83647908829;46678;37379.658636439126;37929.86210413692;38251.80874095612;39056;39487.407688884115;37843.864684059525;37916.58750712339;38374.84875453741;38878.41682374823;38999;27850;36269.63730362768;36428.63571364252;37238.8138059308;37434.62565374311;30707.07878763641;33538;34575.65424345724;34600.82699586486;34612.65387346094;20493.79506204919;24886.50226995486;25313.746862531138;25314;25561.105361312264 19:27:18 INFO - PROCESS | 1645 | |2;jquery.html;325.93155437358155;349.6608289958739;373.033842348318;375.2495009980039;401.1055346577133;18420.90789546052;18761.624767504658;19515;19992.60014799705;20237.797622023776;19078.618427631438;19647.21411143556;19683.81897086172;19913.203471861107;20058.29795957138;5620.91568626471;5751.079827227644;5875.676837773921;5896.351401345849;5914.585978981473;5031.547160755531;5682.687452190128;5834.620749651281;5869.8239052828485;5881.14723365114;5518.834434966958;5757.136429535562;5761.395053519383;5799.7680092796445;5801.216835727186;5620.156976453523;5729.369769325371;5734.741936612862;5734.885302293973;5747.971260143693;4836.51634836517;5035.018171456568;5059.822906198266;5074.797008119671;5084.51697088776;24032;24102.638460423106;24294.27117186487;24578.385540361454;24602.277840551167;2410.614301711718;2432.91484798033;2486.9751302487107;2927.5608658701153;3039.1946134274435;6831.28271531487;12947.87052129476;13071.673208169777;14669.92665036668;15057.515509940957;1737.7132773092424;1780.975938835175;1789.3647755046902;1825.4797382745853;1910.0927059646556;83986.16013839905;121930.78069219283;124510;129915;131595;11566.595169169039;11703.414829258503;11730.70673233159;11749.706257343463;11883.405829708652;14587.708245835023;14598.41606335756;14612;14613.488527901472;14679.972401931762;8115.229053239933;8138.104808471064;8153.510789352657;8224.83550329002;8426.820245165682;8070.2333278338465;8085.191480851985;8103.027636683636;8213.425060245725;8257.876131858027;4495.325701144854;4523;4523.11799199153;4530.207213737583;4592.402987611589;1913.339897735279;1952.7656681198064;1960.6372821028156;1961.9705704414446;1962.8037196280259;830.6220669595319;911.2573252798948;935.5228833295068;941.6280569175137;1034.8240799064176;21060.89469552673;21461;21605.89197054036;21627;21648.675269870964;955.254901177083;959.9664011759555;962.1629182611033;965.584798536636;968.4721826604517;8567.614457349435;9271.536423178813;11359.318440893407;11404.315741055563;11440;482418;602011.9597608023;620663;636823;642539;87874;99455.00544994602;110080;115876.42061789805;137408.9388659171;856.5246288309924;996.6262651505685;1022.447878145793;1024.2522958240527;1025.3284098915221;19232.326868559532;19667.311644092388;19771.604567908274;19789.406317810488;19851.404457866287;45147;53602;54961;59327.703361484644;59918.50203744941;70490;74566.2543374581;78972.81540777007;80563;81340.18659813327;7193.892091618734;7525.397968162424;8235.929329187167;8261.586920654063;8322.50064996102;12313.56902508408;12899.613011609667;12925.676858078623;13011.934940325238;13107.344632768132;8916.955415222883;11199.21605487608;11430.885691142983;11476.368799716096;11547.711307217387;10945.398003109905;11048.779024419628;11068.612598559012;11075.889241107487;11520.53917843277;11114.333140011626;11778.646640600795;11805.645830625095;11832.171747977904;11855.932966033099;8067;8084.231997960184;8223.794405139919;8307.501549907025;8317.41778075529;8186.918130818615;10890.183236257153;10979.945100274448;11369.859597496132;11567.190296679151 19:27:18 INFO - PROCESS | 1645 | |3;mootools.html;1457.3369117051745;1544.2047345617007;1659.0294677613595;1733.9739903901436;1778.466460061981;6521;7482.438817088713;7731.652075656609;8420.452670576416;8447.450915690484;7972;8239.794005149874;8284.751457456287;8380.203880631345;8387.874181887277;2291.8854057297153;2307.1002309099463;2307.5615633029756;2321.9535609287805;2325.2094287942095;2295.644175152854;2336.626139817621;2350.518143780521;2358.127492827656;2358.1864256831363;2283.668868014145;2297.3911913342977;2299.275728145637;2317.860928344296;2332.125452955142;2257.221258665758;2262.5588010337824;2267.2858049714287;2276.032686108414;2282.3267136194863;2229.052652622629;2310.7342655594484;2311.214187176368;2330.1262026739973;2337.661039149331;8287.668493260262;8317.251447369705;8326.625301861432;8358.498490090615;8358.582070896491;4995.175795993657;5129.358830146231;5131.1277082895995;5136.563392111675;5142.922856157161;3246.4481038223557;3313.3207692423234;3520.559930008749;3524.259905419865;3538.221591249921;801.6633014134078;1051.6529545250048;1069.8769641491251;1090.1605763562013;1102.4542851288584;10679;10789.028987391173;10882.183836212316;10893.291936024134;10909.781804363869;4527.569880861313;4597.195490789099;4709.740964246999;4734.242521196661;4748.121597504472;7682.846343073107;9338.159565638989;9434.622615095457;9439.52802359893;9443.291753118407;5122.692638441706;5160.638755287169;5164.819231326961;5170.560502357266;5177.404598471148;4205.243056249843;4222.894427639272;4619.099275641285;4659.207934651117;4732.266498692639;3814.5231846019246;3841.2125514270065;3901.122247494291;3928.862489812843;3931.0368959604507;1265.7721610110084;1268.7843066678686;1276.7893297605976;1288.2141893444991;1290.748304080714;805.5046146619903;878.1394233651058;885.0618344554792;894.088030209183;902.2691619787862;5770.307563092456;5771.624844385102;5821.7089145543405;5825.475707186373;5837.591368604242;867.3711559119714;877.5875338590852;881.2025117268882;895.7223260789176;922.8292765838341;3752.5309336332957;3817.0838998640124;3858.7684738915755;3865.1496670732395;3867.2265546890735;9861.950690246646;10239.744006399751;10243.617111689975;10257.692269231935;10262.973702629677;10113.747156321151;10141.391516508886;10180.185585153169;10238.692839214835;10243.7439064024;6165.691715414211;7715.305622494003;7795.532268063935;7806.297433231037;7822.491538050009;4474.395956545824;5490.917636235539;5640.746166422438;5774.653520788766;5843.883122337615;9002.594883230402;9314.394564353295;9318.673846415342;9435.52822358893;9463.479508627091;9212.723618291462;9465.432074075303;9506.809863802824;9561.187299079656;9599.232061434928;7088.964555177397;7172.139343278631;7188.28117188277;7212.74755383559;7274.454415918722;7800.570968596555;7855.567943763148;7869.960650196941;7883.802904927194;7897.723579674684;7087.078679771596;7174.838866775008;7204.855902881808;7308.96345518269;7348;6992.405645520188;7068.363847253566;7074.469414794014;7147.5711457310645;7204.9639751800905;6662.866742665216;6692.297308782508;6736.865262694817;6758.222804377559;6834.453243740588;4807.110684523374;4850.636202284913;4869.415670119608;4871.732054737024;4906.2640603909695;3569.8572057117426;4393.75834329115;4810.903781924412;4823.3729615149805;4916.532929371704 19:27:18 INFO - PROCESS | 1645 | |4;prototype.html;246.6657678845165;298.2186670922183;307.52794460503105;310.93781243751255;314.9873157797681;4769.618430525558;5306.92039619406;5460.5358544523715;5599.468050535198;5611.494965453108;4052.7973601319964;4763.904721905568;4908.484609116045;5166.689998600086;5408.728334099677;3616.5840928293187;4132.938005929906;4141.565135660758;4155.792210389483;4171.478565179353;4036.475258216428;4156.2726522858375;4158.812853421588;4159.563245859186;4179.749215047107;4080.245154646385;4098.9590104099025;4099.7745124018165;4114.465119534471;4120.464339635843;3597.6582224688614;3918.392649139388;4092.242935057009;4109.671226301919;4112.527059388179;3336.6663333666684;3442.431998720208;3449.275652113059;3589.210373717804;3617.475466057433;4436.134953684033;5040.6471546991725;5230.686158830483;5274.551663108602;5296.549793267577;4011;4468.575485328888;4634.841289677547;4722.1736196165875;4726.432828060655;5425.37608175057;5636.689982050985;5714.457126572969;5764.654120752768;5770.527840923276;3014.0709945634812;3440.690337869604;3628.4920111184456;3772.43413487979;3865.1833638190005;4836.685615434985;6019.969900150471;6056.636601803906;6063.695795597689;6109.694515274218;3918.7844668543503;3961.3265744823448;3973.8807835764396;3975.642192202658;3990.3615421532418;3861.189150278417;3979.7015223858325;4004.2191772604647;4029.395590661424;4032.2540330039037;4109.8972525686495;4652.604528615106;4672.696274742131;4684.236300582673;4686.320483529869;4502.842400515966;5238.476152384731;5786.334571524244;5846.766129354864;5866.70666466675;1145.0724912820642;1153.4463457540428;1223.889849913512;1231.9630411087683;1303.7718399280088;854.6837670061992;872.7905302727299;877.3376101043672;882.3779235638859;885.9335549833788;4573.696496498482;4861.489543597942;4989.700617962933;5164.535191832678;5274.498922602346;4612.215923293048;4790.640701947368;5697.316322041382;5699.497397056279;5713.885722285614;4722.813114819597;5055.368078990126;5123.641345105882;5154.458781827854;5180.958852095372;2618.0574993002506;2821.9858900705776;2854.8287102773484;2958.6745457999605;2964.5256758918467;5758.913616295759;5836.795712150054;5896.292444906639;6233.501319894398;6275.246970363494;5014.868680810641;5885.058390657474;6235.971064774261;6248.50011999039;6265.342139075331;5158.819441319536;5228.477152284741;5353;5490.203920431515;6272.498200143887;5547.833564993057;5667.829965101054;5789.2763404574425;6020.578559500793;6059.484943779652;5289.444608316226;5583.246261754611;5960.672163031075;6012.218411606463;6053.727582258897;5568.081266590967;6067.908981365194;6213.254409470893;6264.498840092872;6332.873342533031;3579.3557159710463;3986.60133986599;3987.4218238355857;3995.9810248386475;4002.459667944907;6044.909326360196;6063.724559633148;6064.363241859542;6078.270607527125;6085.330613632408;4717.5940270463;4835.758212089452;4925.433575138905;5570.6657600544095;5792.652440853562;5780;5816.214811000461;5828.912566311424;5830.591858569859;5860.706964651836;4964.379452568429;5169.922451163311;5372.435894231049;5580.3727577073605;5723.7996670116345;5788.739506722123;5795.160371418059;5800.216970709069;5804.796832110856;5828.388019257917;5485.314335708036;5665.745041473061;5690.374058853606;5702.914456283242;5712.914306285492 19:27:18 INFO - PROCESS | 1645 | |5;yui.html;705.9858802823944;714.2857142857142;721.6824597177242;761.0866959648422;775.4339332287426;13687.452501899925;14328.856711432882;14433.422663093465;14445.566633001014;14524.854751452482;12937.41781619827;14106.083104598194;14599.927000364984;14617.634559136028;14956.32696528656;666.167290886392;689.6172624193571;820.1675299570941;832.2717622080664;854.5470900422786;786.7490689803212;840.3109450250797;852.4160949749411;857.0401150711212;862.1507814802414;893.8301722672704;901.900790912999;905.1491597897988;905.7645012296817;907.918287354138;880.2122100719852;886.3042511628373;891.5052146058968;892.6340200517757;894.2220268366563;660.54092405778;664.3522565498648;664.9468042556584;666.540087339734;672.1564436632044;14149.858501414958;14461.20463374514;14478.56564303062;14534.491292804702;14567.708645827024;13115.147515411467;13537.39081741324;13617.931910340385;13746.93126534371;13870.653233669138;10129.594816207344;10782.892171078345;11185.328880267129;11341.659750207507;11636.418179091012;3590.4434812604086;3649.5255616769914;3675.209829886587;3677.42999835026;3687.649673281034;88239;90935.63596546058;91753.08246917579;93332.53333733288;95838.9999999993;8760.386772925962;10207.591696332212;10380.688579342632;10393.740156496147;10523.631672891412;18992.240310387708;19348.903255483634;20238.89880550588;20241.291554795513;20324.898375508026;7582.772516824504;7631.885521717179;7738.264864837832;7819.413543984224;7877.4091943104495;4127.545969943304;4173.1862286854375;4323.567643235651;4352.82588696455;4388.692791504564;3229.6931791479774;3330.250693593922;3330.733541316689;3331.7168040716324;3340.1983523954564;633.5818359882547;637.4549759955264;638.1831255992337;647.7052940911874;666.8032930285577;554.6505701408087;556.7327682712321;602.9155918171459;633.0621154991052;662.5991275278484;7603.771886843404;8666.393352465393;8706.825863482694;9029.006809250977;9145;553.720371212535;641.5893827950116;650.1418128070995;660.9438197753245;672.0691841799144;4275.5724427557;4483.574060464251;4485.327200919823;4488.820447182142;4535.7732113394195;63450.36549634537;93589;107685.4615726932;111637.44181279204;112327.43836280766;80575.59712201518;81017.59491202506;85917;87157;88657;53697.1945420819;56881.72473101113;62911.370886291465;80766;80886;8030.277275045274;9044.638214471348;9356.95321523388;9490.620375185192;9522.761930951781;46506.83732906704;47025.025855221465;47336;51875.74062129665;54182.18726719181;71625.6418717924;72931.63534182508;72963.27036729564;73991;74509;12446.875531244572;12468.563600273947;12495.562655307021;12677.492900283885;13018.869811301765;13689.520866769615;13696.589102326947;13929.651758705708;14114.435422583392;14539.491117810825;10841.349519028883;12373.319467429017;12970.156939798882;13518.932405338304;13779.448822047007;13023.934880325538;13178.802317965046;13291.069625126149;13555.186688798703;13919.025668203129;11081.83377249358;12640.312347486462;12739.235645861278;12860.292683902475;13364.732705346034;9214.769630759016;10713.303635263683;10763.677089687322;10954.452277385939;11139;10446.791064178826;11182.66452006441;11306.208565400342;11377.43112844371;11411.885881141414 19:27:18 INFO - PROCESS | 1645 | __end_tp_report 19:27:18 INFO - PROCESS | 1645 | __start_cc_report 19:27:18 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,365 19:27:18 INFO - PROCESS | 1645 | __end_cc_report 19:27:18 INFO - PROCESS | 1645 | __startTimestamp1481772438644__endTimestamp 19:27:18 INFO - PROCESS | 1645 | 19:27:18 INFO - PROCESS | 1645 | ------- Summary: start ------- 19:27:18 INFO - PROCESS | 1645 | Number of tests: 6 19:27:18 INFO - PROCESS | 1645 | 19:27:18 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5622.52 Median:5813.00 stddev:686.71 (11.8%) stddev-sans-first:664.94 19:27:18 INFO - PROCESS | 1645 | Values: 3334.6 3348.2 3366.0 3382.0 3403.3 4403.3 5135.6 5478.4 5754.5 5764.5 5704.4 5754.4 5767.3 5772.4 5813.3 4699.3 4952.8 5160.7 5216.9 5220.6 4290.7 5197.6 5267.1 5293.1 5323.5 5090.3 5143.8 5181.5 5220.5 5238.6 4095.9 5034.7 5112.2 5173.2 5179.1 5081.1 5094.3 5106.7 5141.3 5142.7 5497.6 5644.2 5671.1 5688.2 5719.5 5131.5 5143.1 6144.0 6211.3 6227.9 4642.7 5205.5 5450.3 6236.0 6275.5 5846.7 5850.3 5854.3 5866.7 5880.2 6125.7 6172.3 6190.9 6208.3 6221.6 6128.4 6128.9 6134.6 6150.2 6150.6 6210.1 6219.4 6227.9 6276.7 6286.9 6006.2 6017.2 6077.3 6111.4 6175.0 6119.9 6153.1 6181.6 6191.9 6211.3 4822.1 4841.5 4861.0 4876.7 4911.2 4256.7 4386.5 4390.8 4400.9 4461.7 4546.6 5593.8 5700.1 5771.4 5877.2 6232.4 6260.3 6261.5 6275.5 6283.8 5612.5 5677.4 5693.2 5766.7 5799.2 4410.4 5308.0 5324.4 5341.7 5382.2 6273.9 6300.8 6309.0 6314.2 6319.0 6239.5 6257.7 6281.5 6291.0 6310.1 6164.8 6215.3 6255.2 6307.5 6324.9 5810.3 6180.8 6217.5 6224.7 6230.9 5316.9 5581.7 6251.3 6255.6 6291.9 5782.6 5831.2 6071.8 6088.5 6092.1 4743.8 5021.9 5883.8 5940.1 6055.6 5829.7 5879.9 5936.8 5984.1 5990.1 6072.6 6089.2 6103.3 6106.5 6114.9 5742.3 5794.6 5799.2 5813.0 5990.5 19:27:18 INFO - PROCESS | 1645 | 19:27:18 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:68137.65 Median:40157.20 stddev:149569.52 (372.5%) stddev-sans-first:149902.84 19:27:18 INFO - PROCESS | 1645 | Values: 478.8 492.4 500.9 503.3 506.3 39031.2 40544.0 40859.6 42901.8 43231.8 41774.0 41987.0 42540.9 42590.8 42719.1 8746.7 8816.0 9346.0 9541.5 9589.2 8274.6 8915.6 9384.9 9422.2 9573.6 9478.4 9532.5 9562.0 9576.0 9607.3 8231.7 9420.3 9495.1 9580.6 9592.1 9335.0 9340.0 9441.9 9453.5 9456.2 39906.2 40023.0 40113.4 40201.0 40226.6 3307.8 3312.4 3386.6 3403.7 3734.6 29007.0 29517.6 30192.4 30201.1 30324.0 3134.9 3163.3 3196.3 3254.2 3293.1 373605.0 376377.1 376871.1 377369.0 378826.0 58729.0 58856.4 59009.7 59021.7 59201.0 61910.4 62175.7 62409.0 62492.7 62836.0 14295.6 14671.3 14744.3 14781.6 14809.8 12086.6 12135.0 12153.7 12182.1 12231.6 15262.6 16329.6 16390.8 16391.8 16430.3 6644.4 6665.6 6765.6 6781.7 6788.2 869.5 876.2 963.7 1035.4 1059.9 29801.3 29950.4 30025.0 30167.6 30403.0 869.7 988.9 1016.7 1035.4 1048.3 11471.2 14306.8 14994.2 15401.6 15821.0 655128.0 657909.0 662381.0 665099.0 667418.0 57130.0 57218.0 57431.4 57528.8 57538.8 1355.8 1361.7 1387.9 1389.4 1393.4 23721.0 28784.1 29534.7 30200.4 30264.5 49760.0 50035.5 50119.7 50192.2 50367.0 595398.0 596646.0 596841.0 597474.0 599534.0 38708.6 39083.6 40468.0 40510.6 40550.4 44741.3 45327.1 46072.3 46540.8 46678.0 37379.7 37929.9 38251.8 39056.0 39487.4 37843.9 37916.6 38374.8 38878.4 38999.0 27850.0 36269.6 36428.6 37238.8 37434.6 30707.1 33538.0 34575.7 34600.8 34612.7 20493.8 24886.5 25313.7 25314.0 25561.1 19:27:18 INFO - PROCESS | 1645 | 19:27:18 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:34520.99 Median:1682.56 stddev:99792.30 (5931.0%) stddev-sans-first:100039.21 19:27:18 INFO - PROCESS | 1645 | Values: 325.9 349.7 373.0 375.2 401.1 18420.9 18761.6 19515.0 19992.6 20237.8 19078.6 19647.2 19683.8 19913.2 20058.3 5620.9 5751.1 5875.7 5896.4 5914.6 5031.5 5682.7 5834.6 5869.8 5881.1 5518.8 5757.1 5761.4 5799.8 5801.2 5620.2 5729.4 5734.7 5734.9 5748.0 4836.5 5035.0 5059.8 5074.8 5084.5 24032.0 24102.6 24294.3 24578.4 24602.3 2410.6 2432.9 2487.0 2927.6 3039.2 6831.3 12947.9 13071.7 14669.9 15057.5 1737.7 1781.0 1789.4 1825.5 1910.1 83986.2 121930.8 124510.0 129915.0 131595.0 11566.6 11703.4 11730.7 11749.7 11883.4 14587.7 14598.4 14612.0 14613.5 14680.0 8115.2 8138.1 8153.5 8224.8 8426.8 8070.2 8085.2 8103.0 8213.4 8257.9 4495.3 4523.0 4523.1 4530.2 4592.4 1913.3 1952.8 1960.6 1962.0 1962.8 830.6 911.3 935.5 941.6 1034.8 21060.9 21461.0 21605.9 21627.0 21648.7 955.3 960.0 962.2 965.6 968.5 8567.6 9271.5 11359.3 11404.3 11440.0 482418.0 602012.0 620663.0 636823.0 642539.0 87874.0 99455.0 110080.0 115876.4 137408.9 856.5 996.6 1022.4 1024.3 1025.3 19232.3 19667.3 19771.6 19789.4 19851.4 45147.0 53602.0 54961.0 59327.7 59918.5 70490.0 74566.3 78972.8 80563.0 81340.2 7193.9 7525.4 8235.9 8261.6 8322.5 12313.6 12899.6 12925.7 13011.9 13107.3 8917.0 11199.2 11430.9 11476.4 11547.7 10945.4 11048.8 11068.6 11075.9 11520.5 11114.3 11778.6 11805.6 11832.2 11855.9 8067.0 8084.2 8223.8 8307.5 8317.4 8186.9 10890.2 10979.9 11369.9 11567.2 19:27:18 INFO - PROCESS | 1645 | 19:27:18 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5425.18 Median:5126.03 stddev:2973.93 (58.0%) stddev-sans-first:2967.33 19:27:18 INFO - PROCESS | 1645 | Values: 1457.3 1544.2 1659.0 1734.0 1778.5 6521.0 7482.4 7731.7 8420.5 8447.5 7972.0 8239.8 8284.8 8380.2 8387.9 2291.9 2307.1 2307.6 2322.0 2325.2 2295.6 2336.6 2350.5 2358.1 2358.2 2283.7 2297.4 2299.3 2317.9 2332.1 2257.2 2262.6 2267.3 2276.0 2282.3 2229.1 2310.7 2311.2 2330.1 2337.7 8287.7 8317.3 8326.6 8358.5 8358.6 4995.2 5129.4 5131.1 5136.6 5142.9 3246.4 3313.3 3520.6 3524.3 3538.2 801.7 1051.7 1069.9 1090.2 1102.5 10679.0 10789.0 10882.2 10893.3 10909.8 4527.6 4597.2 4709.7 4734.2 4748.1 7682.8 9338.2 9434.6 9439.5 9443.3 5122.7 5160.6 5164.8 5170.6 5177.4 4205.2 4222.9 4619.1 4659.2 4732.3 3814.5 3841.2 3901.1 3928.9 3931.0 1265.8 1268.8 1276.8 1288.2 1290.7 805.5 878.1 885.1 894.1 902.3 5770.3 5771.6 5821.7 5825.5 5837.6 867.4 877.6 881.2 895.7 922.8 3752.5 3817.1 3858.8 3865.1 3867.2 9862.0 10239.7 10243.6 10257.7 10263.0 10113.7 10141.4 10180.2 10238.7 10243.7 6165.7 7715.3 7795.5 7806.3 7822.5 4474.4 5490.9 5640.7 5774.7 5843.9 9002.6 9314.4 9318.7 9435.5 9463.5 9212.7 9465.4 9506.8 9561.2 9599.2 7089.0 7172.1 7188.3 7212.7 7274.5 7800.6 7855.6 7870.0 7883.8 7897.7 7087.1 7174.8 7204.9 7309.0 7348.0 6992.4 7068.4 7074.5 7147.6 7205.0 6662.9 6692.3 6736.9 6758.2 6834.5 4807.1 4850.6 4869.4 4871.7 4906.3 3569.9 4393.8 4810.9 4823.4 4916.5 19:27:18 INFO - PROCESS | 1645 | 19:27:18 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4609.62 Median:5139.05 stddev:1457.54 (28.4%) stddev-sans-first:1424.37 19:27:18 INFO - PROCESS | 1645 | Values: 246.7 298.2 307.5 310.9 315.0 4769.6 5306.9 5460.5 5599.5 5611.5 4052.8 4763.9 4908.5 5166.7 5408.7 3616.6 4132.9 4141.6 4155.8 4171.5 4036.5 4156.3 4158.8 4159.6 4179.7 4080.2 4099.0 4099.8 4114.5 4120.5 3597.7 3918.4 4092.2 4109.7 4112.5 3336.7 3442.4 3449.3 3589.2 3617.5 4436.1 5040.6 5230.7 5274.6 5296.5 4011.0 4468.6 4634.8 4722.2 4726.4 5425.4 5636.7 5714.5 5764.7 5770.5 3014.1 3440.7 3628.5 3772.4 3865.2 4836.7 6020.0 6056.6 6063.7 6109.7 3918.8 3961.3 3973.9 3975.6 3990.4 3861.2 3979.7 4004.2 4029.4 4032.3 4109.9 4652.6 4672.7 4684.2 4686.3 4502.8 5238.5 5786.3 5846.8 5866.7 1145.1 1153.4 1223.9 1232.0 1303.8 854.7 872.8 877.3 882.4 885.9 4573.7 4861.5 4989.7 5164.5 5274.5 4612.2 4790.6 5697.3 5699.5 5713.9 4722.8 5055.4 5123.6 5154.5 5181.0 2618.1 2822.0 2854.8 2958.7 2964.5 5758.9 5836.8 5896.3 6233.5 6275.2 5014.9 5885.1 6236.0 6248.5 6265.3 5158.8 5228.5 5353.0 5490.2 6272.5 5547.8 5667.8 5789.3 6020.6 6059.5 5289.4 5583.2 5960.7 6012.2 6053.7 5568.1 6067.9 6213.3 6264.5 6332.9 3579.4 3986.6 3987.4 3996.0 4002.5 6044.9 6063.7 6064.4 6078.3 6085.3 4717.6 4835.8 4925.4 5570.7 5792.7 5780.0 5816.2 5828.9 5830.6 5860.7 4964.4 5169.9 5372.4 5580.4 5723.8 5788.7 5795.2 5800.2 5804.8 5828.4 5485.3 5665.7 5690.4 5702.9 5712.9 19:27:18 INFO - PROCESS | 1645 | 19:27:18 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:19340.53 Median:25521.31 stddev:27619.19 (108.2%) stddev-sans-first:27661.23 19:27:18 INFO - PROCESS | 1645 | Values: 706.0 714.3 721.7 761.1 775.4 13687.5 14328.9 14433.4 14445.6 14524.9 12937.4 14106.1 14599.9 14617.6 14956.3 666.2 689.6 820.2 832.3 854.5 786.7 840.3 852.4 857.0 862.2 893.8 901.9 905.1 905.8 907.9 880.2 886.3 891.5 892.6 894.2 660.5 664.4 664.9 666.5 672.2 14149.9 14461.2 14478.6 14534.5 14567.7 13115.1 13537.4 13617.9 13746.9 13870.7 10129.6 10782.9 11185.3 11341.7 11636.4 3590.4 3649.5 3675.2 3677.4 3687.6 88239.0 90935.6 91753.1 93332.5 95839.0 8760.4 10207.6 10380.7 10393.7 10523.6 18992.2 19348.9 20238.9 20241.3 20324.9 7582.8 7631.9 7738.3 7819.4 7877.4 4127.5 4173.2 4323.6 4352.8 4388.7 3229.7 3330.3 3330.7 3331.7 3340.2 633.6 637.5 638.2 647.7 666.8 554.7 556.7 602.9 633.1 662.6 7603.8 8666.4 8706.8 9029.0 9145.0 553.7 641.6 650.1 660.9 672.1 4275.6 4483.6 4485.3 4488.8 4535.8 63450.4 93589.0 107685.5 111637.4 112327.4 80575.6 81017.6 85917.0 87157.0 88657.0 53697.2 56881.7 62911.4 80766.0 80886.0 8030.3 9044.6 9357.0 9490.6 9522.8 46506.8 47025.0 47336.0 51875.7 54182.2 71625.6 72931.6 72963.3 73991.0 74509.0 12446.9 12468.6 12495.6 12677.5 13018.9 13689.5 13696.6 13929.7 14114.4 14539.5 10841.3 12373.3 12970.2 13518.9 13779.4 13023.9 13178.8 13291.1 13555.2 13919.0 11081.8 12640.3 12739.2 12860.3 13364.7 9214.8 10713.3 10763.7 10954.5 11139.0 10446.8 11182.7 11306.2 11377.4 11411.9 19:27:18 INFO - PROCESS | 1645 | -------- Summary: end -------- 19:27:18 INFO - PROCESS | 1645 | 19:27:19 INFO - TEST-INFO | 1645: exit 0 19:27:19 INFO - TEST-OK | dromaeo_css | took 1116452ms 19:27:19 INFO - TEST-START | kraken 19:27:19 INFO - operating with platform_type : mac_ 19:27:19 INFO - Initialising browser for kraken test... 19:27:19 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpZIRqDO/profile http://localhost:49252/getInfo.html) 19:27:22 INFO - PROCESS | 1656 | 2016-12-14 19:27:22.253 plugin-container[1657:10504] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 19:27:22 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:22 INFO - PROCESS | 1656 | 2016-12-14 19:27:22.256 plugin-container[1657:10504] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.CFPasteboardClient' 19:27:22 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:22 INFO - PROCESS | 1656 | 2016-12-14 19:27:22.256 plugin-container[1657:10504] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:27:22 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 19:27:22 INFO - PROCESS | 1656 | colorDepth:24 19:27:22 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 19:27:22 INFO - PROCESS | 1656 | __metrics 19:27:22 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 19:27:23 INFO - TEST-INFO | 1656: exit 0 19:27:23 INFO - Browser initialized. 19:27:23 INFO - Running cycle 1/1 for kraken test... 19:27:23 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpZIRqDO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:26 INFO - PROCESS | 1658 | 2016-12-14 19:27:26.050 plugin-container[1659:10626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 19:27:26 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:26 INFO - PROCESS | 1658 | 2016-12-14 19:27:26.051 plugin-container[1659:10626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 19:27:26 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:26 INFO - PROCESS | 1658 | 2016-12-14 19:27:26.051 plugin-container[1659:10626] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:27:35 INFO - PROCESS | 1658 | RSS: Main: 229150720 19:27:35 INFO - PROCESS | 1658 | 19:27:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection) 19:27:39 INFO - PROCESS | 1658 | RSS: Main: 241496064 19:27:39 INFO - PROCESS | 1658 | 19:27:42 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-dft) 19:27:43 INFO - PROCESS | 1658 | RSS: Main: 249217024 19:27:43 INFO - PROCESS | 1658 | 19:27:46 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-fft) 19:27:47 INFO - PROCESS | 1658 | RSS: Main: 252723200 19:27:47 INFO - PROCESS | 1658 | 19:27:49 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator) 19:27:49 INFO - PROCESS | 1658 | RSS: Main: 254205952 19:27:49 INFO - PROCESS | 1658 | 19:27:51 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:27:52 INFO - PROCESS | 1658 | RSS: Main: 254509056 19:27:52 INFO - PROCESS | 1658 | 19:27:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom) 19:27:55 INFO - PROCESS | 1658 | RSS: Main: 258732032 19:27:55 INFO - PROCESS | 1658 | 19:27:58 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate) 19:27:59 INFO - PROCESS | 1658 | RSS: Main: 257753088 19:27:59 INFO - PROCESS | 1658 | 19:28:02 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial) 19:28:02 INFO - PROCESS | 1658 | RSS: Main: 257953792 19:28:02 INFO - PROCESS | 1658 | 19:28:04 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:28:04 INFO - PROCESS | 1658 | RSS: Main: 257032192 19:28:04 INFO - PROCESS | 1658 | 19:28:07 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:28:07 INFO - PROCESS | 1658 | RSS: Main: 257093632 19:28:07 INFO - PROCESS | 1658 | 19:28:09 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:28:10 INFO - PROCESS | 1658 | RSS: Main: 257056768 19:28:10 INFO - PROCESS | 1658 | 19:28:13 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:28:13 INFO - PROCESS | 1658 | RSS: Main: 257044480 19:28:13 INFO - PROCESS | 1658 | 19:28:16 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:28:17 INFO - PROCESS | 1658 | RSS: Main: 257523712 19:28:17 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49252/tests/kraken/driver.html?testName=ai-astar) 19:28:19 INFO - PROCESS | 1658 | RSS: Main: 259432448 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | __start_tp_report 19:28:19 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 19:28:19 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 19:28:19 INFO - PROCESS | 1658 | |i|pagename|runs| 19:28:19 INFO - PROCESS | 1658 | |0;ai-astar;141;170;144;116;113;117;118;130;172;156 19:28:19 INFO - PROCESS | 1658 | |1;audio-beat-detection;139;117;112;145;116;129;145;143;128;148 19:28:19 INFO - PROCESS | 1658 | |2;audio-dft;195;192;196;197;197;195;194;195;191;197 19:28:19 INFO - PROCESS | 1658 | |3;audio-fft;83;58;59;88;70;84;86;61;75;70 19:28:19 INFO - PROCESS | 1658 | |4;audio-oscillator;79;70;89;72;70;70;86;69;73;114 19:28:19 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;91;87;88;85;102;85;91;87;90;131 19:28:19 INFO - PROCESS | 1658 | |6;imaging-darkroom;152;152;95;96;106;95;97;95;94;96 19:28:19 INFO - PROCESS | 1658 | |7;imaging-desaturate;89;90;94;86;90;89;113;90;140;139 19:28:19 INFO - PROCESS | 1658 | |8;json-parse-financial;55;56;54;60;60;60;61;58;60;59 19:28:19 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;68;74;107;124;117;74;69;73;72;72 19:28:19 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;98;81;82;88;86;84;85;87;84;83 19:28:19 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;140;139;137;152;128;144;144;144;190;140 19:28:19 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;166;168;165;164;166;163;164;167;163;170 19:28:19 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;68;69;66;69;68;65;67;68;68;67 19:28:19 INFO - PROCESS | 1658 | __end_tp_report 19:28:19 INFO - PROCESS | 1658 | __start_cc_report 19:28:19 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,975 19:28:19 INFO - PROCESS | 1658 | __end_cc_report 19:28:19 INFO - PROCESS | 1658 | __startTimestamp1481772499318__endTimestamp 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | ------- Summary: start ------- 19:28:19 INFO - PROCESS | 1658 | Number of tests: 14 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:137.70 Median:142.50 stddev:22.51 (15.8%) stddev-sans-first:23.85 19:28:19 INFO - PROCESS | 1658 | Values: 141.0 170.0 144.0 116.0 113.0 117.0 118.0 130.0 172.0 156.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:141.00 stddev:13.62 (9.7%) stddev-sans-first:14.22 19:28:19 INFO - PROCESS | 1658 | Values: 139.0 117.0 112.0 145.0 116.0 129.0 145.0 143.0 128.0 148.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:194.90 Median:195.50 stddev:2.08 (1.1%) stddev-sans-first:2.20 19:28:19 INFO - PROCESS | 1658 | Values: 195.0 192.0 196.0 197.0 197.0 195.0 194.0 195.0 191.0 197.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:73.40 Median:79.00 stddev:11.55 (14.6%) stddev-sans-first:11.72 19:28:19 INFO - PROCESS | 1658 | Values: 83.0 58.0 59.0 88.0 70.0 84.0 86.0 61.0 75.0 70.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:79.20 Median:72.50 stddev:14.15 (19.5%) stddev-sans-first:15.01 19:28:19 INFO - PROCESS | 1658 | Values: 79.0 70.0 89.0 72.0 70.0 70.0 86.0 69.0 73.0 114.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:87.50 stddev:13.99 (16.0%) stddev-sans-first:14.81 19:28:19 INFO - PROCESS | 1658 | Values: 91.0 87.0 88.0 85.0 102.0 85.0 91.0 87.0 90.0 131.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:107.80 Median:95.00 stddev:23.54 (24.8%) stddev-sans-first:18.76 19:28:19 INFO - PROCESS | 1658 | Values: 152.0 152.0 95.0 96.0 106.0 95.0 97.0 95.0 94.0 96.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:102.00 Median:89.50 stddev:21.15 (23.6%) stddev-sans-first:21.90 19:28:19 INFO - PROCESS | 1658 | Values: 89.0 90.0 94.0 86.0 90.0 89.0 113.0 90.0 140.0 139.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:60.00 stddev:2.45 (4.1%) stddev-sans-first:2.29 19:28:19 INFO - PROCESS | 1658 | Values: 55.0 56.0 54.0 60.0 60.0 60.0 61.0 58.0 60.0 59.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:85.00 Median:72.00 stddev:21.85 (30.4%) stddev-sans-first:22.30 19:28:19 INFO - PROCESS | 1658 | Values: 68.0 74.0 107.0 124.0 117.0 74.0 69.0 73.0 72.0 72.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:85.50 stddev:4.80 (5.6%) stddev-sans-first:2.30 19:28:19 INFO - PROCESS | 1658 | Values: 98.0 81.0 82.0 88.0 86.0 84.0 85.0 87.0 84.0 83.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:144.00 stddev:16.70 (11.6%) stddev-sans-first:17.58 19:28:19 INFO - PROCESS | 1658 | Values: 140.0 139.0 137.0 152.0 128.0 144.0 144.0 144.0 190.0 140.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:166.00 stddev:2.27 (1.4%) stddev-sans-first:2.40 19:28:19 INFO - PROCESS | 1658 | Values: 166.0 168.0 165.0 164.0 166.0 163.0 164.0 167.0 163.0 170.0 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.27 (1.9%) stddev-sans-first:1.33 19:28:19 INFO - PROCESS | 1658 | Values: 68.0 69.0 66.0 69.0 68.0 65.0 67.0 68.0 68.0 67.0 19:28:19 INFO - PROCESS | 1658 | -------- Summary: end -------- 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | ###!!! [Parent][MessageChannel] Error: (msgtype=0xEC0015,name=PVRManager::Msg_NotifyVSync) Channel error: cannot send/recv 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | 19:28:19 INFO - PROCESS | 1658 | ###!!! [Parent][MessageChannel] Error: (msgtype=0xEC0015,name=PVRManager::Msg_NotifyVSync) Channel error: cannot send/recv 19:28:19 INFO - PROCESS | 1658 | 19:28:20 INFO - TEST-INFO | 1658: exit 0 19:28:20 INFO - TEST-OK | kraken | took 61071ms 19:28:20 INFO - SUITE-END | took 1177s 19:28:20 INFO - Completed test suite (00:19:37) 19:28:20 INFO - javascript benchmark 19:28:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7169.024958680161, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.6498617645148, 3348.1796959744865, 3366.0, 3382.0, 3403.251284717363, 4403.3174857897075, 5135.64050516464, 5478.424765399631, 5754.482096611304, 5764.452377024181, 5704.429557044294, 5754.395788442216, 5767.307923049229, 5772.393898640635, 5813.273340832396, 4699.318598803172, 4952.826651067214, 5160.664556803805, 5216.921746173811, 5220.556252718524, 4290.678199135062, 5197.558207552363, 5267.051930652481, 5293.073712100387, 5323.520883120518, 5090.261912022736, 5143.819966301199, 5181.481851814826, 5220.451852555503, 5238.554722848562, 4095.9180816383805, 5034.748262586856, 5112.156494178456, 5173.1722924331925, 5179.119549676564, 5081.085404627165, 5094.261332106861, 5106.693598384109, 5141.254518094893, 5142.6914385137015, 5497.5601951843755, 5644.209810626516, 5671.149327600893, 5688.175214593861, 5719.542436605103, 5131.4868513148385, 5143.12566863634, 6143.986242270064, 6211.316755156974, 6227.906581401283, 4642.744649044301, 5205.479452054802, 5450.264214331054, 6236.0, 6275.497960163176, 5846.707664616794, 5850.297964244318, 5854.297484301911, 5866.67733274674, 5880.235569375996, 6125.723854043132, 6172.259328880563, 6190.876182476325, 6208.317085120633, 6221.595596286227, 6128.380904898417, 6128.890487035018, 6134.589663226396, 6150.169727086786, 6150.630962142286, 6210.099535567318, 6219.409156130251, 6227.906581401283, 6276.686165691788, 6286.874262514724, 6006.159137720722, 6017.247844019497, 6077.3314935357075, 6111.388861113853, 6174.969125154345, 6119.938800612026, 6153.138560601519, 6181.567290289637, 6191.876162476725, 6211.254649442096, 4822.132016237111, 4841.491643377394, 4860.9513904861205, 4876.658633895664, 4911.214205727138, 4256.6807489438415, 4386.517483076859, 4390.8024138913825, 4400.9437734943385, 4461.732296062247, 4546.568076032838, 5593.8321850344555, 5700.116481945305, 5771.394003629558, 5877.206577112121, 6232.376762323732, 6260.342664020303, 6261.530385221035, 6275.497960163176, 6283.7800676976085, 5612.466815652507, 5677.37548869624, 5693.202951586781, 5766.711664416846, 5799.18811366409, 4410.42664453619, 5308.0, 5324.440933701983, 5341.732913354394, 5382.192671099367, 6273.874522509615, 6300.779472718433, 6308.990561510136, 6314.242290925118, 6319.020551814476, 6239.532035097477, 6257.68711564429, 6281.497480201481, 6291.0, 6310.11658367838, 6164.7842325518395, 6215.347388524321, 6255.24937007562, 6307.526935479766, 6324.905126423199, 5810.273715785527, 6180.845478863064, 6217.502599792097, 6224.657643829632, 6230.875382492234, 5316.867078323073, 5581.6930068846605, 6251.281102673251, 6255.56211065221, 6291.937080629135, 5782.595218334676, 5831.241938547962, 6071.848203794941, 6088.482478989158, 6092.086187071975, 4743.83396581118, 5021.949780502295, 5883.79333263364, 5940.079287710412, 6055.636661800306, 5829.737661805144, 5879.882402351844, 5936.7922122725495, 5984.102384642339, 5990.131430942576, 6072.605280656743, 6089.208402907594, 6103.328633850362, 6106.480949119375, 6114.908276375947, 5742.339630942328, 5794.62334948227, 5799.246098007232, 5813.0, 5990.520758339235], "value": 5581.050497127706, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.82522879149076, 492.4066499867659, 500.8580436604543, 503.30795156659593, 506.2886644264809, 39031.21937561247, 40543.98640033997, 40859.56991505294, 42901.78549107258, 43231.78384108083, 41774.0, 41987.0, 42540.936476588024, 42590.787046064805, 42719.14561708764, 8746.737597872074, 8815.955920220389, 9346.0, 9541.522923853814, 9589.232861371074, 8274.586270686441, 8915.554222288893, 9384.920734115562, 9422.15200631943, 9573.617055317778, 9478.383905046185, 9532.523373831315, 9562.043795620451, 9575.95212023939, 9607.27945404094, 8231.670733170666, 9420.293477989178, 9495.097965693249, 9580.61677532898, 9592.136707696342, 9335.0, 9340.0, 9441.90558094417, 9453.52732363386, 9456.243500519942, 39906.20187596232, 40023.0, 40113.39829902554, 40201.0, 40226.59773402258, 3307.7684562080663, 3312.403767321881, 3386.6444023377685, 3403.6596340366013, 3734.607866174067, 29007.0, 29517.557236641467, 30192.396152077054, 30201.0939671808, 30324.0, 3134.949791819743, 3163.3040731039137, 3196.2968147007623, 3254.2027203335333, 3293.127321259868, 373605.0, 376377.1181144077, 376871.11564442003, 377369.0, 378826.0, 58729.0, 58856.41143588595, 59009.70495147497, 59021.704891476125, 59201.0, 61910.38089619136, 62175.6891215541, 62409.0, 62492.68753656294, 62836.0, 14295.57113286603, 14671.266436678123, 14744.336504857307, 14781.556553303419, 14809.777853332209, 12086.637400877988, 12134.96852767507, 12153.696157595954, 12182.147249692614, 12231.633051008484, 15262.618434539003, 16329.591760206089, 16390.754138687927, 16391.754123688155, 16430.260638271306, 6644.4020038196795, 6665.566738162004, 6765.627890466071, 6781.677572873243, 6788.151481064867, 869.4609342207872, 876.2245412809582, 963.7397902566408, 1035.3735989726258, 1059.86751656043, 29801.254968625522, 29950.400991980037, 30025.0, 30167.64245608953, 30403.0, 869.6825658634552, 988.9208863290919, 1016.7356487313352, 1035.4460363705532, 1048.2819268800895, 11471.197016208785, 14306.785398219035, 14994.175320357484, 15401.614959626098, 15820.971636843567, 655128.0, 657909.0, 662381.0, 665099.0, 667418.0, 57130.0, 57218.0, 57431.42568574261, 57528.84942301131, 57538.84922301614, 1355.7695191817218, 1361.700425906299, 1387.861213878604, 1389.4164450930432, 1393.4217299820461, 23721.0, 28784.136475905758, 29534.704652954057, 30200.395992079597, 30264.5460318091, 49760.0, 50035.499645004544, 50119.74940125276, 50192.24711629401, 50367.0, 595398.0, 596646.0, 596841.0, 597474.0, 599534.0, 38708.61291387163, 39083.609163908, 40468.0, 40510.59489405068, 40550.39174412445, 44741.32888006748, 45327.093458129995, 46072.308915365626, 46540.83647908829, 46678.0, 37379.658636439126, 37929.86210413692, 38251.80874095612, 39056.0, 39487.407688884115, 37843.864684059525, 37916.58750712339, 38374.84875453741, 38878.41682374823, 38999.0, 27850.0, 36269.63730362768, 36428.63571364252, 37238.8138059308, 37434.62565374311, 30707.07878763641, 33538.0, 34575.65424345724, 34600.82699586486, 34612.65387346094, 20493.79506204919, 24886.50226995486, 25313.746862531138, 25314.0, 25561.105361312264], "value": 19555.52245929824, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.93155437358155, 349.6608289958739, 373.033842348318, 375.2495009980039, 401.1055346577133, 18420.90789546052, 18761.624767504658, 19515.0, 19992.60014799705, 20237.797622023776, 19078.618427631438, 19647.21411143556, 19683.81897086172, 19913.203471861107, 20058.29795957138, 5620.91568626471, 5751.079827227644, 5875.676837773921, 5896.351401345849, 5914.585978981473, 5031.547160755531, 5682.687452190128, 5834.620749651281, 5869.8239052828485, 5881.14723365114, 5518.834434966958, 5757.136429535562, 5761.395053519383, 5799.7680092796445, 5801.216835727186, 5620.156976453523, 5729.369769325371, 5734.741936612862, 5734.885302293973, 5747.971260143693, 4836.51634836517, 5035.018171456568, 5059.822906198266, 5074.797008119671, 5084.51697088776, 24032.0, 24102.638460423106, 24294.27117186487, 24578.385540361454, 24602.277840551167, 2410.614301711718, 2432.91484798033, 2486.9751302487107, 2927.5608658701153, 3039.1946134274435, 6831.28271531487, 12947.87052129476, 13071.673208169777, 14669.92665036668, 15057.515509940957, 1737.7132773092424, 1780.975938835175, 1789.3647755046902, 1825.4797382745853, 1910.0927059646556, 83986.16013839905, 121930.78069219283, 124510.0, 129915.0, 131595.0, 11566.595169169039, 11703.414829258503, 11730.70673233159, 11749.706257343463, 11883.405829708652, 14587.708245835023, 14598.41606335756, 14612.0, 14613.488527901472, 14679.972401931762, 8115.229053239933, 8138.104808471064, 8153.510789352657, 8224.83550329002, 8426.820245165682, 8070.2333278338465, 8085.191480851985, 8103.027636683636, 8213.425060245725, 8257.876131858027, 4495.325701144854, 4523.0, 4523.11799199153, 4530.207213737583, 4592.402987611589, 1913.339897735279, 1952.7656681198064, 1960.6372821028156, 1961.9705704414446, 1962.8037196280259, 830.6220669595319, 911.2573252798948, 935.5228833295068, 941.6280569175137, 1034.8240799064176, 21060.89469552673, 21461.0, 21605.89197054036, 21627.0, 21648.675269870964, 955.254901177083, 959.9664011759555, 962.1629182611033, 965.584798536636, 968.4721826604517, 8567.614457349435, 9271.536423178813, 11359.318440893407, 11404.315741055563, 11440.0, 482418.0, 602011.9597608023, 620663.0, 636823.0, 642539.0, 87874.0, 99455.00544994602, 110080.0, 115876.42061789805, 137408.9388659171, 856.5246288309924, 996.6262651505685, 1022.447878145793, 1024.2522958240527, 1025.3284098915221, 19232.326868559532, 19667.311644092388, 19771.604567908274, 19789.406317810488, 19851.404457866287, 45147.0, 53602.0, 54961.0, 59327.703361484644, 59918.50203744941, 70490.0, 74566.2543374581, 78972.81540777007, 80563.0, 81340.18659813327, 7193.892091618734, 7525.397968162424, 8235.929329187167, 8261.586920654063, 8322.50064996102, 12313.56902508408, 12899.613011609667, 12925.676858078623, 13011.934940325238, 13107.344632768132, 8916.955415222883, 11199.21605487608, 11430.885691142983, 11476.368799716096, 11547.711307217387, 10945.398003109905, 11048.779024419628, 11068.612598559012, 11075.889241107487, 11520.53917843277, 11114.333140011626, 11778.646640600795, 11805.645830625095, 11832.171747977904, 11855.932966033099, 8067.0, 8084.231997960184, 8223.794405139919, 8307.501549907025, 8317.41778075529, 8186.918130818615, 10890.183236257153, 10979.945100274448, 11369.859597496132, 11567.190296679151], "value": 9533.415279844232, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1457.3369117051745, 1544.2047345617007, 1659.0294677613595, 1733.9739903901436, 1778.466460061981, 6521.0, 7482.438817088713, 7731.652075656609, 8420.452670576416, 8447.450915690484, 7972.0, 8239.794005149874, 8284.751457456287, 8380.203880631345, 8387.874181887277, 2291.8854057297153, 2307.1002309099463, 2307.5615633029756, 2321.9535609287805, 2325.2094287942095, 2295.644175152854, 2336.626139817621, 2350.518143780521, 2358.127492827656, 2358.1864256831363, 2283.668868014145, 2297.3911913342977, 2299.275728145637, 2317.860928344296, 2332.125452955142, 2257.221258665758, 2262.5588010337824, 2267.2858049714287, 2276.032686108414, 2282.3267136194863, 2229.052652622629, 2310.7342655594484, 2311.214187176368, 2330.1262026739973, 2337.661039149331, 8287.668493260262, 8317.251447369705, 8326.625301861432, 8358.498490090615, 8358.582070896491, 4995.175795993657, 5129.358830146231, 5131.1277082895995, 5136.563392111675, 5142.922856157161, 3246.4481038223557, 3313.3207692423234, 3520.559930008749, 3524.259905419865, 3538.221591249921, 801.6633014134078, 1051.6529545250048, 1069.8769641491251, 1090.1605763562013, 1102.4542851288584, 10679.0, 10789.028987391173, 10882.183836212316, 10893.291936024134, 10909.781804363869, 4527.569880861313, 4597.195490789099, 4709.740964246999, 4734.242521196661, 4748.121597504472, 7682.846343073107, 9338.159565638989, 9434.622615095457, 9439.52802359893, 9443.291753118407, 5122.692638441706, 5160.638755287169, 5164.819231326961, 5170.560502357266, 5177.404598471148, 4205.243056249843, 4222.894427639272, 4619.099275641285, 4659.207934651117, 4732.266498692639, 3814.5231846019246, 3841.2125514270065, 3901.122247494291, 3928.862489812843, 3931.0368959604507, 1265.7721610110084, 1268.7843066678686, 1276.7893297605976, 1288.2141893444991, 1290.748304080714, 805.5046146619903, 878.1394233651058, 885.0618344554792, 894.088030209183, 902.2691619787862, 5770.307563092456, 5771.624844385102, 5821.7089145543405, 5825.475707186373, 5837.591368604242, 867.3711559119714, 877.5875338590852, 881.2025117268882, 895.7223260789176, 922.8292765838341, 3752.5309336332957, 3817.0838998640124, 3858.7684738915755, 3865.1496670732395, 3867.2265546890735, 9861.950690246646, 10239.744006399751, 10243.617111689975, 10257.692269231935, 10262.973702629677, 10113.747156321151, 10141.391516508886, 10180.185585153169, 10238.692839214835, 10243.7439064024, 6165.691715414211, 7715.305622494003, 7795.532268063935, 7806.297433231037, 7822.491538050009, 4474.395956545824, 5490.917636235539, 5640.746166422438, 5774.653520788766, 5843.883122337615, 9002.594883230402, 9314.394564353295, 9318.673846415342, 9435.52822358893, 9463.479508627091, 9212.723618291462, 9465.432074075303, 9506.809863802824, 9561.187299079656, 9599.232061434928, 7088.964555177397, 7172.139343278631, 7188.28117188277, 7212.74755383559, 7274.454415918722, 7800.570968596555, 7855.567943763148, 7869.960650196941, 7883.802904927194, 7897.723579674684, 7087.078679771596, 7174.838866775008, 7204.855902881808, 7308.96345518269, 7348.0, 6992.405645520188, 7068.363847253566, 7074.469414794014, 7147.5711457310645, 7204.9639751800905, 6662.866742665216, 6692.297308782508, 6736.865262694817, 6758.222804377559, 6834.453243740588, 4807.110684523374, 4850.636202284913, 4869.415670119608, 4871.732054737024, 4906.2640603909695, 3569.8572057117426, 4393.75834329115, 4810.903781924412, 4823.3729615149805, 4916.532929371704], "value": 4400.253837956609, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [246.6657678845165, 298.2186670922183, 307.52794460503105, 310.93781243751255, 314.9873157797681, 4769.618430525558, 5306.92039619406, 5460.5358544523715, 5599.468050535198, 5611.494965453108, 4052.7973601319964, 4763.904721905568, 4908.484609116045, 5166.689998600086, 5408.728334099677, 3616.5840928293187, 4132.938005929906, 4141.565135660758, 4155.792210389483, 4171.478565179353, 4036.475258216428, 4156.2726522858375, 4158.812853421588, 4159.563245859186, 4179.749215047107, 4080.245154646385, 4098.9590104099025, 4099.7745124018165, 4114.465119534471, 4120.464339635843, 3597.6582224688614, 3918.392649139388, 4092.242935057009, 4109.671226301919, 4112.527059388179, 3336.6663333666684, 3442.431998720208, 3449.275652113059, 3589.210373717804, 3617.475466057433, 4436.134953684033, 5040.6471546991725, 5230.686158830483, 5274.551663108602, 5296.549793267577, 4011.0, 4468.575485328888, 4634.841289677547, 4722.1736196165875, 4726.432828060655, 5425.37608175057, 5636.689982050985, 5714.457126572969, 5764.654120752768, 5770.527840923276, 3014.0709945634812, 3440.690337869604, 3628.4920111184456, 3772.43413487979, 3865.1833638190005, 4836.685615434985, 6019.969900150471, 6056.636601803906, 6063.695795597689, 6109.694515274218, 3918.7844668543503, 3961.3265744823448, 3973.8807835764396, 3975.642192202658, 3990.3615421532418, 3861.189150278417, 3979.7015223858325, 4004.2191772604647, 4029.395590661424, 4032.2540330039037, 4109.8972525686495, 4652.604528615106, 4672.696274742131, 4684.236300582673, 4686.320483529869, 4502.842400515966, 5238.476152384731, 5786.334571524244, 5846.766129354864, 5866.70666466675, 1145.0724912820642, 1153.4463457540428, 1223.889849913512, 1231.9630411087683, 1303.7718399280088, 854.6837670061992, 872.7905302727299, 877.3376101043672, 882.3779235638859, 885.9335549833788, 4573.696496498482, 4861.489543597942, 4989.700617962933, 5164.535191832678, 5274.498922602346, 4612.215923293048, 4790.640701947368, 5697.316322041382, 5699.497397056279, 5713.885722285614, 4722.813114819597, 5055.368078990126, 5123.641345105882, 5154.458781827854, 5180.958852095372, 2618.0574993002506, 2821.9858900705776, 2854.8287102773484, 2958.6745457999605, 2964.5256758918467, 5758.913616295759, 5836.795712150054, 5896.292444906639, 6233.501319894398, 6275.246970363494, 5014.868680810641, 5885.058390657474, 6235.971064774261, 6248.50011999039, 6265.342139075331, 5158.819441319536, 5228.477152284741, 5353.0, 5490.203920431515, 6272.498200143887, 5547.833564993057, 5667.829965101054, 5789.2763404574425, 6020.578559500793, 6059.484943779652, 5289.444608316226, 5583.246261754611, 5960.672163031075, 6012.218411606463, 6053.727582258897, 5568.081266590967, 6067.908981365194, 6213.254409470893, 6264.498840092872, 6332.873342533031, 3579.3557159710463, 3986.60133986599, 3987.4218238355857, 3995.9810248386475, 4002.459667944907, 6044.909326360196, 6063.724559633148, 6064.363241859542, 6078.270607527125, 6085.330613632408, 4717.5940270463, 4835.758212089452, 4925.433575138905, 5570.6657600544095, 5792.652440853562, 5780.0, 5816.214811000461, 5828.912566311424, 5830.591858569859, 5860.706964651836, 4964.379452568429, 5169.922451163311, 5372.435894231049, 5580.3727577073605, 5723.7996670116345, 5788.739506722123, 5795.160371418059, 5800.216970709069, 5804.796832110856, 5828.388019257917, 5485.314335708036, 5665.745041473061, 5690.374058853606, 5702.914456283242, 5712.914306285492], "value": 4133.8312979217835, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.9858802823944, 714.2857142857142, 721.6824597177242, 761.0866959648422, 775.4339332287426, 13687.452501899925, 14328.856711432882, 14433.422663093465, 14445.566633001014, 14524.854751452482, 12937.41781619827, 14106.083104598194, 14599.927000364984, 14617.634559136028, 14956.32696528656, 666.167290886392, 689.6172624193571, 820.1675299570941, 832.2717622080664, 854.5470900422786, 786.7490689803212, 840.3109450250797, 852.4160949749411, 857.0401150711212, 862.1507814802414, 893.8301722672704, 901.900790912999, 905.1491597897988, 905.7645012296817, 907.918287354138, 880.2122100719852, 886.3042511628373, 891.5052146058968, 892.6340200517757, 894.2220268366563, 660.54092405778, 664.3522565498648, 664.9468042556584, 666.540087339734, 672.1564436632044, 14149.858501414958, 14461.20463374514, 14478.56564303062, 14534.491292804702, 14567.708645827024, 13115.147515411467, 13537.39081741324, 13617.931910340385, 13746.93126534371, 13870.653233669138, 10129.594816207344, 10782.892171078345, 11185.328880267129, 11341.659750207507, 11636.418179091012, 3590.4434812604086, 3649.5255616769914, 3675.209829886587, 3677.42999835026, 3687.649673281034, 88239.0, 90935.63596546058, 91753.08246917579, 93332.53333733288, 95838.9999999993, 8760.386772925962, 10207.591696332212, 10380.688579342632, 10393.740156496147, 10523.631672891412, 18992.240310387708, 19348.903255483634, 20238.89880550588, 20241.291554795513, 20324.898375508026, 7582.772516824504, 7631.885521717179, 7738.264864837832, 7819.413543984224, 7877.4091943104495, 4127.545969943304, 4173.1862286854375, 4323.567643235651, 4352.82588696455, 4388.692791504564, 3229.6931791479774, 3330.250693593922, 3330.733541316689, 3331.7168040716324, 3340.1983523954564, 633.5818359882547, 637.4549759955264, 638.1831255992337, 647.7052940911874, 666.8032930285577, 554.6505701408087, 556.7327682712321, 602.9155918171459, 633.0621154991052, 662.5991275278484, 7603.771886843404, 8666.393352465393, 8706.825863482694, 9029.006809250977, 9145.0, 553.720371212535, 641.5893827950116, 650.1418128070995, 660.9438197753245, 672.0691841799144, 4275.5724427557, 4483.574060464251, 4485.327200919823, 4488.820447182142, 4535.7732113394195, 63450.36549634537, 93589.0, 107685.4615726932, 111637.44181279204, 112327.43836280766, 80575.59712201518, 81017.59491202506, 85917.0, 87157.0, 88657.0, 53697.1945420819, 56881.72473101113, 62911.370886291465, 80766.0, 80886.0, 8030.277275045274, 9044.638214471348, 9356.95321523388, 9490.620375185192, 9522.761930951781, 46506.83732906704, 47025.025855221465, 47336.0, 51875.74062129665, 54182.18726719181, 71625.6418717924, 72931.63534182508, 72963.27036729564, 73991.0, 74509.0, 12446.875531244572, 12468.563600273947, 12495.562655307021, 12677.492900283885, 13018.869811301765, 13689.520866769615, 13696.589102326947, 13929.651758705708, 14114.435422583392, 14539.491117810825, 10841.349519028883, 12373.319467429017, 12970.156939798882, 13518.932405338304, 13779.448822047007, 13023.934880325538, 13178.802317965046, 13291.069625126149, 13555.186688798703, 13919.025668203129, 11081.83377249358, 12640.312347486462, 12739.235645861278, 12860.292683902475, 13364.732705346034, 9214.769630759016, 10713.303635263683, 10763.677089687322, 10954.452277385939, 11139.0, 10446.791064178826, 11182.66452006441, 11306.208565400342, 11377.43112844371, 11411.885881141414], "value": 7172.1570065580845, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1528.8999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 170.0, 144.0, 116.0, 113.0, 117.0, 118.0, 130.0, 172.0, 156.0], "value": 137.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 117.0, 112.0, 145.0, 116.0, 129.0, 145.0, 143.0, 128.0, 148.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 192.0, 196.0, 197.0, 197.0, 195.0, 194.0, 195.0, 191.0, 197.0], "value": 194.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 59.0, 88.0, 70.0, 84.0, 86.0, 61.0, 75.0, 70.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 70.0, 89.0, 72.0, 70.0, 70.0, 86.0, 69.0, 73.0, 114.0], "value": 79.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 88.0, 85.0, 102.0, 85.0, 91.0, 87.0, 90.0, 131.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 95.0, 96.0, 106.0, 95.0, 97.0, 95.0, 94.0, 96.0], "value": 107.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 94.0, 86.0, 90.0, 89.0, 113.0, 90.0, 140.0, 139.0], "value": 102.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 60.0, 60.0, 60.0, 61.0, 58.0, 60.0, 59.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 107.0, 124.0, 117.0, 74.0, 69.0, 73.0, 72.0, 72.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 81.0, 82.0, 88.0, 86.0, 84.0, 85.0, 87.0, 84.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 137.0, 152.0, 128.0, 144.0, 144.0, 144.0, 190.0, 140.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 165.0, 164.0, 166.0, 163.0, 164.0, 167.0, 163.0, 170.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 66.0, 69.0, 68.0, 65.0, 67.0, 68.0, 68.0, 67.0], "value": 67.5, "unit": "score"}]}]} 19:28:20 INFO - Return code: 0 19:28:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:28:20 ERROR - # TBPL SUCCESS # 19:28:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:28:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:28:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:28:20 INFO - 2016-12-14 19:28:20.634 screenresolution[1663:11006] starting screenresolution argv=screenresolution get 19:28:20 INFO - 2016-12-14 19:28:20.637 screenresolution[1663:11006] Display 0: 1600x1200x32@60 19:28:20 INFO - 2016-12-14 19:28:20.643 screenresolution[1664:11009] starting screenresolution argv=screenresolution list 19:28:20 INFO - Available Modes on Display 0 19:28:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:28:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:28:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:28:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:28:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:28:20 INFO - Intel Iris: 19:28:20 INFO - Chipset Model: Intel Iris 19:28:20 INFO - Type: GPU 19:28:20 INFO - Bus: Built-In 19:28:20 INFO - VRAM (Dynamic, Max): 1536 MB 19:28:20 INFO - Vendor: Intel (0x8086) 19:28:20 INFO - Device ID: 0x0a2e 19:28:20 INFO - Revision ID: 0x0009 19:28:20 INFO - Displays: 19:28:20 INFO - Display: 19:28:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:28:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:28:20 INFO - Display Serial Number: 1600x1200 60 19:28:20 INFO - Main Display: Yes 19:28:20 INFO - Mirror: Off 19:28:20 INFO - Online: Yes 19:28:20 INFO - Rotation: Supported 19:28:20 INFO - Return code: 0 19:28:20 INFO - Running post-action listener: _resource_record_post_action 19:28:20 INFO - [mozharness: 2016-12-15 03:28:20.895228Z] Finished run-tests step (success) 19:28:20 INFO - Running post-run listener: _resource_record_post_run 19:28:20 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 38590976; Write bytes: 660013056; Read time: 267; Write time: 1919 19:28:20 INFO - TinderboxPrint: CPU usage
54.5% 19:28:20 INFO - TinderboxPrint: I/O read bytes / time
38,590,976 / 267 19:28:20 INFO - TinderboxPrint: I/O write bytes / time
660,013,056 / 1,919 19:28:20 INFO - TinderboxPrint: CPU idle
2,203.0 (45.5%) 19:28:20 INFO - TinderboxPrint: CPU system
980.0 (20.3%) 19:28:20 INFO - TinderboxPrint: CPU user
1,654.7 (34.2%) 19:28:20 INFO - TinderboxPrint: Swap in / out
387,465,216 / 0 19:28:20 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349704704; Write bytes: 350369792; Read time: 27052; Write time: 729 19:28:20 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35256832; Write bytes: 308168704; Read time: 231; Write time: 1183 19:28:21 INFO - Running post-run listener: _upload_blobber_files 19:28:21 INFO - Blob upload gear active. 19:28:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:28:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:28:21 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:28:21 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:28:21 INFO - (blobuploader) - INFO - Open directory for files ... 19:28:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:28:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:28:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:23 INFO - (blobuploader) - INFO - Done attempting. 19:28:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:28:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:28:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:23 INFO - (blobuploader) - INFO - Done attempting. 19:28:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:28:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:28:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:26 INFO - (blobuploader) - INFO - Done attempting. 19:28:26 INFO - (blobuploader) - INFO - Iteration through files over. 19:28:26 INFO - Return code: 0 19:28:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:28:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:28:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a29d442c19efdcf295c1e354152e64f414ebf4f41ad32cdebfd0b4f08fb38d31dbc509cc64b2d98700ab749a214811bdaa09e574c7eee593272d6d658d3c15b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf75345fe3571506ca9e8188c8c728bc989e5a22ec8ea11078388bc583f0c3cf3ec3fbf3456fe42bce2edb5d7033bbed15b756a1a6e711fe2c5b68113afce10c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fec6193eedf2ac985bd962d92f228a740d33fea383d21c228a5e32929a1c4c3c44f85bb5e25d94531f176d3772c284f8a7e8e6820c849eead1240281e8a91b2e"} 19:28:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:28:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:28:26 INFO - Contents: 19:28:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a29d442c19efdcf295c1e354152e64f414ebf4f41ad32cdebfd0b4f08fb38d31dbc509cc64b2d98700ab749a214811bdaa09e574c7eee593272d6d658d3c15b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf75345fe3571506ca9e8188c8c728bc989e5a22ec8ea11078388bc583f0c3cf3ec3fbf3456fe42bce2edb5d7033bbed15b756a1a6e711fe2c5b68113afce10c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fec6193eedf2ac985bd962d92f228a740d33fea383d21c228a5e32929a1c4c3c44f85bb5e25d94531f176d3772c284f8a7e8e6820c849eead1240281e8a91b2e"} 19:28:26 INFO - Running post-run listener: copy_logs_to_upload_dir 19:28:26 INFO - Copying logs to upload dir... 19:28:26 INFO - mkdir: /builds/slave/test/build/upload/logs 19:28:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.506651 ========= master_lag: 3.26 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-14 19:28:29.650767) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 19:28:29.654486) ========= 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.COoLMMy84R/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.C8DxWpmPTU/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a29d442c19efdcf295c1e354152e64f414ebf4f41ad32cdebfd0b4f08fb38d31dbc509cc64b2d98700ab749a214811bdaa09e574c7eee593272d6d658d3c15b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf75345fe3571506ca9e8188c8c728bc989e5a22ec8ea11078388bc583f0c3cf3ec3fbf3456fe42bce2edb5d7033bbed15b756a1a6e711fe2c5b68113afce10c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fec6193eedf2ac985bd962d92f228a740d33fea383d21c228a5e32929a1c4c3c44f85bb5e25d94531f176d3772c284f8a7e8e6820c849eead1240281e8a91b2e"} build_url:https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011141 build_url: 'https://queue.taskcluster.net/v1/task/Iqrh7065TPeK_wXUJuG6SA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a29d442c19efdcf295c1e354152e64f414ebf4f41ad32cdebfd0b4f08fb38d31dbc509cc64b2d98700ab749a214811bdaa09e574c7eee593272d6d658d3c15b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf75345fe3571506ca9e8188c8c728bc989e5a22ec8ea11078388bc583f0c3cf3ec3fbf3456fe42bce2edb5d7033bbed15b756a1a6e711fe2c5b68113afce10c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fec6193eedf2ac985bd962d92f228a740d33fea383d21c228a5e32929a1c4c3c44f85bb5e25d94531f176d3772c284f8a7e8e6820c849eead1240281e8a91b2e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 19:28:29.693490) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 19:28:29.694294) ========= 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.COoLMMy84R/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.C8DxWpmPTU/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.005291 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 19:28:29.748377) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 19:28:29.748670) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 19:28:29.748990) ========= ========= Total master_lag: 3.41 =========