builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0228 starttime: 1483458117.37 results: success (0) buildid: 20170103062620 builduid: c1f3c525bc1e47ea96f72640c6edbd77 revision: e5e607abb3b91048542e7d49334461d86277300f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:57.366781) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:57.367234) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:57.367523) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:57.391888) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:57.392154) ========= 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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 --2017-01-03 07:41:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.1M=0.001s 2017-01-03 07:41:57 (10.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120830 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:57.536052) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:57.536388) ========= 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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.017779 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:57.585271) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-03 07:41:57.585663) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev e5e607abb3b91048542e7d49334461d86277300f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev e5e607abb3b91048542e7d49334461d86277300f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0yEOuxFGCd/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.rI5cSHDU5w/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 2017-01-03 07:41:57,660 truncating revision to first 12 chars 2017-01-03 07:41:57,660 Setting DEBUG logging. 2017-01-03 07:41:57,660 attempt 1/10 2017-01-03 07:41:57,660 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/e5e607abb3b9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-03 07:41:59,396 unpacking tar archive at: graphics-e5e607abb3b9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.992013 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-03 07:41:59.600932) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:59.601252) ========= script_repo_revision: e5e607abb3b91048542e7d49334461d86277300f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:59.601679) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:59.601943) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 07:41:59.621792) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-03 07:41:59.622136) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0yEOuxFGCd/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.rI5cSHDU5w/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 07:41:59 INFO - MultiFileLogger online at 20170103 07:41:59 in /builds/slave/test 07:41:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 07:41:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:41:59 INFO - {'append_to_log': False, 07:41:59 INFO - 'base_work_dir': '/builds/slave/test', 07:41:59 INFO - 'blob_upload_branch': 'Graphics', 07:41:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:41:59 INFO - 'branch': 'Graphics', 07:41:59 INFO - 'buildbot_json_path': 'buildprops.json', 07:41:59 INFO - 'config_files': ('talos/mac_config.py',), 07:41:59 INFO - 'default_actions': ('clobber', 07:41:59 INFO - 'read-buildbot-config', 07:41:59 INFO - 'download-and-extract', 07:41:59 INFO - 'populate-webroot', 07:41:59 INFO - 'create-virtualenv', 07:41:59 INFO - 'install', 07:41:59 INFO - 'run-tests'), 07:41:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:41:59 INFO - 'download_minidump_stackwalk': True, 07:41:59 INFO - 'download_symbols': 'ondemand', 07:41:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:41:59 INFO - 'tooltool.py': '/tools/tooltool.py', 07:41:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:41:59 INFO - '/tools/misc-python/virtualenv.py')}, 07:41:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:41:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:41:59 INFO - 'installer_path': 'installer.exe', 07:41:59 INFO - 'log_level': 'info', 07:41:59 INFO - 'log_name': 'talos', 07:41:59 INFO - 'log_to_console': True, 07:41:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:41:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:41:59 INFO - 'opt_config_files': (), 07:41:59 INFO - 'pip_index': False, 07:41:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:41:59 INFO - 'cmd': ('bash', 07:41:59 INFO - '-c', 07:41:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:41:59 INFO - 'enabled': True, 07:41:59 INFO - 'halt_on_failure': False, 07:41:59 INFO - 'name': 'check_screen_resolution'},), 07:41:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:41:59 INFO - 'cmd': ('bash', 07:41:59 INFO - '-c', 07:41:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:41:59 INFO - 'enabled': True, 07:41:59 INFO - 'halt_on_failure': False, 07:41:59 INFO - 'name': 'check_screen_resolution'},), 07:41:59 INFO - 'run_cmd_checks_enabled': True, 07:41:59 INFO - 'sps_profile': False, 07:41:59 INFO - 'sps_profile_interval': 0, 07:41:59 INFO - 'suite': 'dromaeojs', 07:41:59 INFO - 'system_bits': '32', 07:41:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:41:59 INFO - 'title': 't-yosemite-r7-0228', 07:41:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:41:59 INFO - 'use_talos_json': True, 07:41:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:41:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:41:59 INFO - 'work_dir': 'build'} 07:41:59 INFO - [mozharness: 2017-01-03 15:41:59.826825Z] Running clobber step. 07:41:59 INFO - Running pre-action listener: _resource_record_pre_action 07:41:59 INFO - Running main action method: clobber 07:41:59 INFO - rmtree: /builds/slave/test/build 07:41:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:42:00 INFO - Running post-action listener: _resource_record_post_action 07:42:00 INFO - [mozharness: 2017-01-03 15:42:00.722637Z] Finished clobber step (success) 07:42:00 INFO - [mozharness: 2017-01-03 15:42:00.722811Z] Running read-buildbot-config step. 07:42:00 INFO - Running pre-action listener: _resource_record_pre_action 07:42:00 INFO - Running main action method: read_buildbot_config 07:42:00 INFO - Using buildbot properties: 07:42:00 INFO - { 07:42:00 INFO - "project": "", 07:42:00 INFO - "product": "firefox", 07:42:00 INFO - "script_repo_revision": "default", 07:42:00 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 07:42:00 INFO - "repository": "", 07:42:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 07:42:00 INFO - "buildid": "20170103062620", 07:42:00 INFO - "pgo_build": "False", 07:42:00 INFO - "basedir": "/builds/slave/test", 07:42:00 INFO - "buildnumber": 18, 07:42:00 INFO - "slavename": "t-yosemite-r7-0228", 07:42:00 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:42:00 INFO - "platform": "yosemite_r7", 07:42:00 INFO - "scheduler": "tests-graphics-macosx64-talos", 07:42:00 INFO - "branch": "graphics", 07:42:00 INFO - "repo_path": "projects/graphics", 07:42:00 INFO - "revision": "e5e607abb3b91048542e7d49334461d86277300f", 07:42:00 INFO - "stage_platform": "macosx64", 07:42:00 INFO - "builduid": "c1f3c525bc1e47ea96f72640c6edbd77", 07:42:00 INFO - "slavebuilddir": "test" 07:42:00 INFO - } 07:42:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:42:00 INFO - Running post-action listener: _resource_record_post_action 07:42:00 INFO - [mozharness: 2017-01-03 15:42:00.727704Z] Finished read-buildbot-config step (success) 07:42:00 INFO - [mozharness: 2017-01-03 15:42:00.727884Z] Running download-and-extract step. 07:42:00 INFO - Running pre-action listener: _resource_record_pre_action 07:42:00 INFO - Running main action method: download_and_extract 07:42:00 INFO - mkdir: /builds/slave/test/build/tests 07:42:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:00 INFO - trying https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:42:00 INFO - Downloading https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:42:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 07:42:01 INFO - Downloaded 1199 bytes. 07:42:01 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:42:01 INFO - Using the following test package requirements: 07:42:01 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:42:01 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:42:01 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:42:01 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:42:01 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:42:01 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:42:01 INFO - u'jsshell-mac.zip'], 07:42:01 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:42:01 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:42:01 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:42:01 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:42:01 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:42:01 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:42:01 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:42:01 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:42:01 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:42:01 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:42:01 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:42:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:42:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:42:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:42:01 INFO - Fetch https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:42:03 INFO - Expected file size: 16300300 07:42:03 INFO - Obtained file size: 16300300 07:42:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:42:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:42:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:42:09 INFO - Fetch https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:42:10 INFO - Expected file size: 16300300 07:42:10 INFO - Obtained file size: 16300300 07:42:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:42:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:42:17 INFO - Fetch https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:42:18 INFO - Expected file size: 14409776 07:42:18 INFO - Obtained file size: 14409776 07:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:18 INFO - trying https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:42:18 INFO - Downloading https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:42:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 07:42:21 INFO - Downloaded 69682069 bytes. 07:42:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:42:21 INFO - mkdir: /builds/slave/test/properties 07:42:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:42:21 INFO - Writing to file /builds/slave/test/properties/build_url 07:42:21 INFO - Contents: 07:42:21 INFO - build_url:https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:42:22 INFO - Running post-action listener: _resource_record_post_action 07:42:22 INFO - Running post-action listener: set_extra_try_arguments 07:42:22 INFO - [mozharness: 2017-01-03 15:42:22.629273Z] Finished download-and-extract step (success) 07:42:22 INFO - [mozharness: 2017-01-03 15:42:22.629398Z] Running populate-webroot step. 07:42:22 INFO - Running pre-action listener: _resource_record_pre_action 07:42:22 INFO - Running main action method: populate_webroot 07:42:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:42:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:42:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:42:22 INFO - '--tppagecycles', 07:42:22 INFO - '7'], 07:42:22 INFO - 'tests': ['tsvgm']}}, 07:42:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:42:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:42:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:42:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:42:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:42:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:42:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:42:22 INFO - 'talos_options': ['--disable-e10s'], 07:42:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:42:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:42:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:42:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:42:22 INFO - 'talos_options': ['--disable-e10s'], 07:42:22 INFO - 'tests': ['damp', 'tps']}, 07:42:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:42:22 INFO - 'tests': ['damp', 'tps']}, 07:42:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:42:22 INFO - 'tests': ['dromaeo_dom']}, 07:42:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:42:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:42:22 INFO - 'tests': ['basic_compositor_video']}, 07:42:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:42:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:42:22 INFO - 'tests': ['a11yr', 07:42:22 INFO - 'ts_paint', 07:42:22 INFO - 'tpaint', 07:42:22 INFO - 'sessionrestore', 07:42:22 INFO - 'sessionrestore_no_auto_restore', 07:42:22 INFO - 'tabpaint']}, 07:42:22 INFO - 'other-e10s': {'tests': ['a11yr', 07:42:22 INFO - 'ts_paint', 07:42:22 INFO - 'tpaint', 07:42:22 INFO - 'sessionrestore', 07:42:22 INFO - 'sessionrestore_no_auto_restore', 07:42:22 INFO - 'tabpaint']}, 07:42:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:42:22 INFO - 'ts_paint', 07:42:22 INFO - 'tpaint', 07:42:22 INFO - 'sessionrestore', 07:42:22 INFO - 'sessionrestore_no_auto_restore', 07:42:22 INFO - 'tabpaint']}, 07:42:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:42:22 INFO - 'ts_paint', 07:42:22 INFO - 'tpaint', 07:42:22 INFO - 'sessionrestore', 07:42:22 INFO - 'sessionrestore_no_auto_restore', 07:42:22 INFO - 'tabpaint']}, 07:42:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:42:22 INFO - 'tests': ['a11yr', 07:42:22 INFO - 'ts_paint', 07:42:22 INFO - 'tpaint', 07:42:22 INFO - 'sessionrestore', 07:42:22 INFO - 'sessionrestore_no_auto_restore', 07:42:22 INFO - 'tabpaint']}, 07:42:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:42:22 INFO - 'tests': ['a11yr', 07:42:22 INFO - 'ts_paint', 07:42:22 INFO - 'tpaint', 07:42:22 INFO - 'sessionrestore', 07:42:22 INFO - 'sessionrestore_no_auto_restore', 07:42:22 INFO - 'tabpaint']}, 07:42:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:42:22 INFO - 'tests': ['tsvgx', 07:42:22 INFO - 'tsvgr_opacity', 07:42:22 INFO - 'tart', 07:42:22 INFO - 'tscrollx', 07:42:22 INFO - 'cart', 07:42:22 INFO - 'tsvg_static']}, 07:42:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:42:22 INFO - 'tsvgr_opacity', 07:42:22 INFO - 'tart', 07:42:22 INFO - 'tscrollx', 07:42:22 INFO - 'cart', 07:42:22 INFO - 'tsvg_static']}, 07:42:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:42:22 INFO - 'talos_options': ['--disable-e10s'], 07:42:22 INFO - 'tests': ['tp5o']}, 07:42:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:42:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:42:22 INFO - 'talos_options': ['--disable-e10s', 07:42:22 INFO - '--xperf_path', 07:42:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:42:22 INFO - 'tests': ['tp5n']}, 07:42:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:42:22 INFO - 'talos_options': ['--xperf_path', 07:42:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:42:22 INFO - 'tests': ['tp5n']}}, 07:42:22 INFO - 'talos.zip': {'path': '', 07:42:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:42:22 INFO - Running post-action listener: _resource_record_post_action 07:42:22 INFO - [mozharness: 2017-01-03 15:42:22.648099Z] Finished populate-webroot step (success) 07:42:22 INFO - [mozharness: 2017-01-03 15:42:22.648232Z] Running create-virtualenv step. 07:42:22 INFO - Running pre-action listener: _resource_record_pre_action 07:42:22 INFO - Running main action method: create_virtualenv 07:42:22 INFO - Creating virtualenv /builds/slave/test/build/venv 07:42:22 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 07:42:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:42:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:42:22 INFO - Using real prefix '/tools/python27' 07:42:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:42:23 INFO - Installing distribute.............................................................................................................................................................................................done. 07:42:26 INFO - Installing pip.................done. 07:42:26 INFO - Return code: 0 07:42:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:42:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:42:26 INFO - Reading from file tmpfile_stdout 07:42:26 INFO - Output received: 07:42:26 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) 07:42:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:42:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103ca7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103caa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5adedc50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a85d30>, '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 0x1039acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a7abe8>, '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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 07:42:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:42:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:42:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0yEOuxFGCd/Render', 07:42:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:26 INFO - 'HOME': '/Users/cltbld', 07:42:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:26 INFO - 'LOGNAME': 'cltbld', 07:42:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:26 INFO - 'MOZ_NO_REMOTE': '1', 07:42:26 INFO - 'NO_EM_RESTART': '1', 07:42:26 INFO - 'PAGER': '/bin/cat', 07:42:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:26 INFO - 'PWD': '/builds/slave/test', 07:42:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:26 INFO - 'SHELL': '/bin/bash', 07:42:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rI5cSHDU5w/Listeners', 07:42:26 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 07:42:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:26 INFO - 'USER': 'cltbld', 07:42:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:26 INFO - 'XPC_FLAGS': '0x0', 07:42:26 INFO - 'XPC_SERVICE_NAME': '0', 07:42:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:26 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 07:42:26 INFO - Cleaning up... 07:42:26 INFO - Return code: 0 07:42:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:42:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ca7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103caa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5adedc50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a85d30>, '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 0x1039acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a7abe8>, '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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 07:42:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:42:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:42:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0yEOuxFGCd/Render', 07:42:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:26 INFO - 'HOME': '/Users/cltbld', 07:42:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:26 INFO - 'LOGNAME': 'cltbld', 07:42:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:26 INFO - 'MOZ_NO_REMOTE': '1', 07:42:26 INFO - 'NO_EM_RESTART': '1', 07:42:26 INFO - 'PAGER': '/bin/cat', 07:42:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:26 INFO - 'PWD': '/builds/slave/test', 07:42:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:26 INFO - 'SHELL': '/bin/bash', 07:42:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rI5cSHDU5w/Listeners', 07:42:26 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 07:42:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:26 INFO - 'USER': 'cltbld', 07:42:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:26 INFO - 'XPC_FLAGS': '0x0', 07:42:26 INFO - 'XPC_SERVICE_NAME': '0', 07:42:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:27 INFO - Downloading/unpacking psutil>=3.1.1 07:42:27 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 07:42:27 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 07:42:27 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 07:42:27 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 07:42:27 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 07:42:27 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 07:42:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:42:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:42:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:42:30 INFO - Installing collected packages: psutil 07:42:30 INFO - Running setup.py install for psutil 07:42:30 INFO - building 'psutil._psutil_osx' extension 07:42:30 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 07:42:31 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 07:42:31 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 07:42:31 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 07:42:31 INFO - building 'psutil._psutil_posix' extension 07:42:31 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 07:42:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:42:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:42:31 INFO - ^ 07:42:31 INFO - 1 warning generated. 07:42:31 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 07:42:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:42:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:42:31 INFO - Successfully installed psutil 07:42:31 INFO - Cleaning up... 07:42:31 INFO - Return code: 0 07:42:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:31 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 0x103ca7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103caa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5adedc50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a85d30>, '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 0x1039acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a7abe8>, '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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 07:42:31 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 07:42:31 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 07:42:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0yEOuxFGCd/Render', 07:42:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:31 INFO - 'HOME': '/Users/cltbld', 07:42:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:31 INFO - 'LOGNAME': 'cltbld', 07:42:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:31 INFO - 'MOZ_NO_REMOTE': '1', 07:42:31 INFO - 'NO_EM_RESTART': '1', 07:42:31 INFO - 'PAGER': '/bin/cat', 07:42:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:31 INFO - 'PWD': '/builds/slave/test', 07:42:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:31 INFO - 'SHELL': '/bin/bash', 07:42:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rI5cSHDU5w/Listeners', 07:42:31 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 07:42:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:31 INFO - 'USER': 'cltbld', 07:42:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:31 INFO - 'XPC_FLAGS': '0x0', 07:42:31 INFO - 'XPC_SERVICE_NAME': '0', 07:42:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:42:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:42:34 INFO - Installing collected packages: mozsystemmonitor 07:42:34 INFO - Running setup.py install for mozsystemmonitor 07:42:34 INFO - Successfully installed mozsystemmonitor 07:42:34 INFO - Cleaning up... 07:42:34 INFO - Return code: 0 07:42:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:42: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')]} 07:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42: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')]} 07:42:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ca7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103caa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5adedc50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a85d30>, '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 0x1039acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a7abe8>, '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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 07:42:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:42:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:42:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0yEOuxFGCd/Render', 07:42:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:34 INFO - 'HOME': '/Users/cltbld', 07:42:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:34 INFO - 'LOGNAME': 'cltbld', 07:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:34 INFO - 'MOZ_NO_REMOTE': '1', 07:42:34 INFO - 'NO_EM_RESTART': '1', 07:42:34 INFO - 'PAGER': '/bin/cat', 07:42:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:34 INFO - 'PWD': '/builds/slave/test', 07:42:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:34 INFO - 'SHELL': '/bin/bash', 07:42:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rI5cSHDU5w/Listeners', 07:42:34 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 07:42:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:34 INFO - 'USER': 'cltbld', 07:42:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:34 INFO - 'XPC_FLAGS': '0x0', 07:42:34 INFO - 'XPC_SERVICE_NAME': '0', 07:42:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:35 INFO - Downloading/unpacking jsonschema==2.5.1 07:42:35 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 07:42:35 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 07:42:35 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 07:42:35 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 07:42:35 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 07:42:35 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 07:42:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:42:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:42:37 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 07:42:37 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 07:42:37 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 07:42:37 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 07:42:37 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 07:42:37 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 07:42:38 INFO - Downloading functools32-3.2.3-2.tar.gz 07:42:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:42:38 INFO - warning: no files found matching '*.txt' 07:42:38 INFO - no previously-included directories found matching 'build' 07:42:38 INFO - no previously-included directories found matching 'dist' 07:42:38 INFO - no previously-included directories found matching '.git*' 07:42:38 INFO - Installing collected packages: jsonschema, functools32 07:42:38 INFO - Running setup.py install for functools32 07:42:38 INFO - warning: no files found matching '*.txt' 07:42:38 INFO - no previously-included directories found matching 'build' 07:42:38 INFO - no previously-included directories found matching 'dist' 07:42:38 INFO - no previously-included directories found matching '.git*' 07:42:38 INFO - Successfully installed jsonschema functools32 07:42:38 INFO - Cleaning up... 07:42:38 INFO - Return code: 0 07:42:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:38 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 0x103ca7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103caa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5adedc50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a85d30>, '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 0x1039acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a7abe8>, '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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 07:42:38 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 07:42:38 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 07:42:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0yEOuxFGCd/Render', 07:42:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:38 INFO - 'HOME': '/Users/cltbld', 07:42:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:38 INFO - 'LOGNAME': 'cltbld', 07:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:38 INFO - 'MOZ_NO_REMOTE': '1', 07:42:38 INFO - 'NO_EM_RESTART': '1', 07:42:38 INFO - 'PAGER': '/bin/cat', 07:42:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:38 INFO - 'PWD': '/builds/slave/test', 07:42:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:38 INFO - 'SHELL': '/bin/bash', 07:42:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rI5cSHDU5w/Listeners', 07:42:38 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 07:42:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:38 INFO - 'USER': 'cltbld', 07:42:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:38 INFO - 'XPC_FLAGS': '0x0', 07:42:38 INFO - 'XPC_SERVICE_NAME': '0', 07:42:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:42:38 INFO - Cleaning up... 07:42:38 INFO - Return code: 0 07:42:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:38 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 0x103ca7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103caa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5adedc50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a85d30>, '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 0x1039acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a7abe8>, '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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 07:42:38 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 07:42:38 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 07:42:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0yEOuxFGCd/Render', 07:42:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:38 INFO - 'HOME': '/Users/cltbld', 07:42:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:38 INFO - 'LOGNAME': 'cltbld', 07:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:38 INFO - 'MOZ_NO_REMOTE': '1', 07:42:38 INFO - 'NO_EM_RESTART': '1', 07:42:38 INFO - 'PAGER': '/bin/cat', 07:42:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:38 INFO - 'PWD': '/builds/slave/test', 07:42:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:38 INFO - 'SHELL': '/bin/bash', 07:42:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rI5cSHDU5w/Listeners', 07:42:38 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 07:42:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:38 INFO - 'USER': 'cltbld', 07:42:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:38 INFO - 'XPC_FLAGS': '0x0', 07:42:38 INFO - 'XPC_SERVICE_NAME': '0', 07:42:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:39 INFO - Downloading/unpacking blobuploader==1.2.4 07:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:41 INFO - Downloading blobuploader-1.2.4.tar.gz 07:42:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:42:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:42:41 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 07:42:41 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 07:42:41 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 07:42:41 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 07:42:41 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 07:42:41 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 07:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:42:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:42: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 07:42: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 07:42: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 07:42: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 07:42: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 07:42: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 07:42:43 INFO - Downloading docopt-0.6.1.tar.gz 07:42:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:42:43 INFO - Installing collected packages: blobuploader, requests, docopt 07:42:43 INFO - Running setup.py install for blobuploader 07:42:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:42:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:42:43 INFO - Running setup.py install for requests 07:42:43 INFO - Running setup.py install for docopt 07:42:43 INFO - Successfully installed blobuploader requests docopt 07:42:43 INFO - Cleaning up... 07:42:43 INFO - Return code: 0 07:42:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42:43 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')]} 07:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:43 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')]} 07:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:43 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 0x103ca7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103caa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5adedc50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a85d30>, '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 0x1039acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a7abe8>, '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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 07:42:43 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 07:42:43 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 07:42:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0yEOuxFGCd/Render', 07:42:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:44 INFO - 'HOME': '/Users/cltbld', 07:42:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:44 INFO - 'LOGNAME': 'cltbld', 07:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:44 INFO - 'MOZ_NO_REMOTE': '1', 07:42:44 INFO - 'NO_EM_RESTART': '1', 07:42:44 INFO - 'PAGER': '/bin/cat', 07:42:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:44 INFO - 'PWD': '/builds/slave/test', 07:42:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:44 INFO - 'SHELL': '/bin/bash', 07:42:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rI5cSHDU5w/Listeners', 07:42:44 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 07:42:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:44 INFO - 'USER': 'cltbld', 07:42:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:44 INFO - 'XPC_FLAGS': '0x0', 07:42:44 INFO - 'XPC_SERVICE_NAME': '0', 07:42:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:42:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ebBn1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:42:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-bAVARe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:42:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-57nsja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:42:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-kPLbcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:42:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-QjUkQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:42:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-VtB3oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:42:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-7TzxXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:42:44 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-OdUGO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:42:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-UBHR_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:42:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-RiAN0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:42:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-tTIp2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:42:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-aI0iSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:42:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-D0nZFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:42:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-5wAf2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-MN46Wj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:42:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-iM0Czw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:42:45 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-qA84Mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:42:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:42:46 INFO - Running setup.py install for manifestparser 07:42:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:42:46 INFO - Running setup.py install for mozcrash 07:42:46 INFO - Running setup.py install for mozdebug 07:42:46 INFO - Running setup.py install for mozdevice 07:42:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:42:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:42:46 INFO - Running setup.py install for mozfile 07:42:46 INFO - Running setup.py install for mozhttpd 07:42:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:42:46 INFO - Running setup.py install for mozinfo 07:42:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:42:47 INFO - Running setup.py install for mozInstall 07:42:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:42:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:42:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:42:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:42:47 INFO - Running setup.py install for mozleak 07:42:47 INFO - Running setup.py install for mozlog 07:42:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:42:47 INFO - Running setup.py install for moznetwork 07:42:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:42:47 INFO - Running setup.py install for mozprocess 07:42:47 INFO - Running setup.py install for mozprofile 07:42:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:42:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:42:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:42:47 INFO - Running setup.py install for mozrunner 07:42:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:42:48 INFO - Running setup.py install for mozscreenshot 07:42:48 INFO - Running setup.py install for moztest 07:42:48 INFO - Running setup.py install for mozversion 07:42:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:42:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:42:48 INFO - Cleaning up... 07:42:48 INFO - Return code: 0 07:42:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:48 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 0x103ca7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103caa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5adedc50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a85d30>, '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 0x1039acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a7abe8>, '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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 07:42:48 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 07:42:48 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 07:42:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0yEOuxFGCd/Render', 07:42:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:48 INFO - 'HOME': '/Users/cltbld', 07:42:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:48 INFO - 'LOGNAME': 'cltbld', 07:42:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:48 INFO - 'MOZ_NO_REMOTE': '1', 07:42:48 INFO - 'NO_EM_RESTART': '1', 07:42:48 INFO - 'PAGER': '/bin/cat', 07:42:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:48 INFO - 'PWD': '/builds/slave/test', 07:42:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:48 INFO - 'SHELL': '/bin/bash', 07:42:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rI5cSHDU5w/Listeners', 07:42:48 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 07:42:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:48 INFO - 'USER': 'cltbld', 07:42:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:48 INFO - 'XPC_FLAGS': '0x0', 07:42:48 INFO - 'XPC_SERVICE_NAME': '0', 07:42:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:42:48 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-5Tu_5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:42:49 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)) 07:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:42:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-oUes_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:42:49 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)) 07:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:42:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-m_Hhp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:42:49 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)) 07:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:42:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-LvsUOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:42:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 07:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:42:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-dVw0OE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:42:49 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)) 07:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:42:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-FPUBZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:42:49 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)) 07:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:42:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-1s9qrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:42:49 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)) 07:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:42:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-4pbKQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:42:49 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)) 07:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:42:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-hhscBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:42:49 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)) 07:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:42:49 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-CqqvT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:42:50 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)) 07:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:42:50 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-HyzJme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:42:50 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)) 07:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:42:50 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-53kiNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:42:50 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)) 07:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:42:50 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-KxtNXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:42:50 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)) 07:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:42:50 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-5DNWSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:42:50 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)) 07:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:50 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-L0ohRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:50 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)) 07:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:42:50 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-PTkf7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 07:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:42:50 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-1kFXKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:42:50 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)) 07:42:50 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)) 07:42:50 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)) 07:42:50 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)) 07:42:50 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:42:50 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:42:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:42: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 07:42: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 07:42: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 07:42: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 07:42: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 07:42: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 07:42:53 INFO - Downloading blessings-1.6.tar.gz 07:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:42:53 INFO - Installing collected packages: blessings 07:42:53 INFO - Running setup.py install for blessings 07:42:53 INFO - Successfully installed blessings 07:42:53 INFO - Cleaning up... 07:42:53 INFO - Return code: 0 07:42:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:42:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:42:54 INFO - Reading from file tmpfile_stdout 07:42:54 INFO - Current package versions: 07:42:54 INFO - blessings == 1.6 07:42:54 INFO - blobuploader == 1.2.4 07:42:54 INFO - docopt == 0.6.1 07:42:54 INFO - functools32 == 3.2.3-2 07:42:54 INFO - jsonschema == 2.5.1 07:42:54 INFO - manifestparser == 1.1 07:42:54 INFO - mozInstall == 1.12 07:42:54 INFO - mozcrash == 1.0 07:42:54 INFO - mozdebug == 0.1 07:42:54 INFO - mozdevice == 0.49 07:42:54 INFO - mozfile == 1.2 07:42:54 INFO - mozhttpd == 0.7 07:42:54 INFO - mozinfo == 0.9 07:42:54 INFO - mozleak == 0.1 07:42:54 INFO - mozlog == 3.4 07:42:54 INFO - moznetwork == 0.27 07:42:54 INFO - mozprocess == 0.23 07:42:54 INFO - mozprofile == 0.28 07:42:54 INFO - mozrunner == 6.13 07:42:54 INFO - mozscreenshot == 0.1 07:42:54 INFO - mozsystemmonitor == 0.3 07:42:54 INFO - moztest == 0.8 07:42:54 INFO - mozversion == 1.4 07:42:54 INFO - psutil == 3.1.1 07:42:54 INFO - requests == 1.2.3 07:42:54 INFO - wsgiref == 0.1.2 07:42:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42: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')]} 07:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42: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')]} 07:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:54 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 0x103ca7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103caa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5adedc50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a85d30>, '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 0x1039acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a7abe8>, '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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 07:42:54 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 07:42:54 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 07:42:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0yEOuxFGCd/Render', 07:42:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:54 INFO - 'HOME': '/Users/cltbld', 07:42:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:54 INFO - 'LOGNAME': 'cltbld', 07:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:54 INFO - 'MOZ_NO_REMOTE': '1', 07:42:54 INFO - 'NO_EM_RESTART': '1', 07:42:54 INFO - 'PAGER': '/bin/cat', 07:42:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:54 INFO - 'PWD': '/builds/slave/test', 07:42:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:54 INFO - 'SHELL': '/bin/bash', 07:42:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rI5cSHDU5w/Listeners', 07:42:54 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 07:42:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:54 INFO - 'USER': 'cltbld', 07:42:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:54 INFO - 'XPC_FLAGS': '0x0', 07:42:54 INFO - 'XPC_SERVICE_NAME': '0', 07:42:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:55 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)) 07:42:55 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)) 07:42:55 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)) 07:42:55 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)) 07:42:55 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)) 07:42:55 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)) 07:42:55 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)) 07:42:55 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)) 07:42:55 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)) 07:42:55 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)) 07:42:55 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)) 07:42:55 INFO - Cleaning up... 07:42:55 INFO - Return code: 0 07:42:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:42:55 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')]} 07:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:55 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')]} 07:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:55 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 0x103ca7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103caa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e5adedc50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a85d30>, '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 0x1039acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a7abe8>, '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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 07:42:55 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 07:42:55 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 07:42:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0yEOuxFGCd/Render', 07:42:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:42:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:42:55 INFO - 'HOME': '/Users/cltbld', 07:42:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:42:55 INFO - 'LOGNAME': 'cltbld', 07:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:55 INFO - 'MOZ_NO_REMOTE': '1', 07:42:55 INFO - 'NO_EM_RESTART': '1', 07:42:55 INFO - 'PAGER': '/bin/cat', 07:42:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:55 INFO - 'PWD': '/builds/slave/test', 07:42:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:42:55 INFO - 'SHELL': '/bin/bash', 07:42:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rI5cSHDU5w/Listeners', 07:42:55 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 07:42:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:42:55 INFO - 'USER': 'cltbld', 07:42:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:42:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:55 INFO - 'XPC_FLAGS': '0x0', 07:42:55 INFO - 'XPC_SERVICE_NAME': '0', 07:42:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:42:55 INFO - Cleaning up... 07:42:55 INFO - Return code: 0 07:42:55 INFO - Running post-action listener: _resource_record_post_action 07:42:55 INFO - Running post-action listener: _start_resource_monitoring 07:42:55 INFO - Starting resource monitoring. 07:42:55 INFO - [mozharness: 2017-01-03 15:42:55.779442Z] Finished create-virtualenv step (success) 07:42:55 INFO - [mozharness: 2017-01-03 15:42:55.780443Z] Running install step. 07:42:55 INFO - Running pre-action listener: _resource_record_pre_action 07:42:55 INFO - Running main action method: install 07:42:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:42:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:42:56 INFO - Reading from file tmpfile_stdout 07:42:56 INFO - Detecting whether we're running mozinstall >=1.0... 07:42:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:42:56 INFO - Reading from file tmpfile_stdout 07:42:56 INFO - Output received: 07:42:56 INFO - Usage: mozinstall [options] installer 07:42:56 INFO - Options: 07:42:56 INFO - -h, --help show this help message and exit 07:42:56 INFO - -d DEST, --destination=DEST 07:42:56 INFO - Directory to install application into. [default: 07:42:56 INFO - "/builds/slave/test"] 07:42:56 INFO - --app=APP Application being installed. [default: firefox] 07:42:56 INFO - mkdir: /builds/slave/test/build/application 07:42:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:43:17 INFO - Reading from file tmpfile_stdout 07:43:17 INFO - Output received: 07:43:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:43:17 INFO - Running post-action listener: _resource_record_post_action 07:43:17 INFO - [mozharness: 2017-01-03 15:43:17.894496Z] Finished install step (success) 07:43:17 INFO - [mozharness: 2017-01-03 15:43:17.894639Z] Running run-tests step. 07:43:17 INFO - Running pre-action listener: _resource_record_pre_action 07:43:17 INFO - Running main action method: run_tests 07:43:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:43:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:43:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:43:17 INFO - 2017-01-03 07:43:17.909 screenresolution[2186:24659] starting screenresolution argv=screenresolution get 07:43:17 INFO - 2017-01-03 07:43:17.912 screenresolution[2186:24659] Display 0: 1600x1200x32@60 07:43:17 INFO - 2017-01-03 07:43:17.917 screenresolution[2187:24661] starting screenresolution argv=screenresolution list 07:43:17 INFO - Available Modes on Display 0 07:43:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:43:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:43:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:43:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:43:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:43:18 INFO - Intel Iris: 07:43:18 INFO - Chipset Model: Intel Iris 07:43:18 INFO - Type: GPU 07:43:18 INFO - Bus: Built-In 07:43:18 INFO - VRAM (Dynamic, Max): 1536 MB 07:43:18 INFO - Vendor: Intel (0x8086) 07:43:18 INFO - Device ID: 0x0a2e 07:43:18 INFO - Revision ID: 0x0009 07:43:18 INFO - Displays: 07:43:18 INFO - Display: 07:43:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:43:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:43:18 INFO - Display Serial Number: 1600x1200 60 07:43:18 INFO - Main Display: Yes 07:43:18 INFO - Mirror: Off 07:43:18 INFO - Online: Yes 07:43:18 INFO - Rotation: Supported 07:43:18 INFO - Return code: 0 07:43:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:43:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:43:18 INFO - Python 2.7.3 07:43:18 INFO - Return code: 0 07:43:18 INFO - grabbing minidump binary from tooltool 07:43:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:18 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 0x103a85d30>, '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 0x1039acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a7abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:43:18 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 07:43:18 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 07:43:18 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 07:43:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:43:18 INFO - Return code: 0 07:43:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:43:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:43:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:43:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:43:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:43:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:43:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0228 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:43:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0yEOuxFGCd/Render', 07:43:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:43:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:43:18 INFO - 'HOME': '/Users/cltbld', 07:43:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:43:18 INFO - 'LOGNAME': 'cltbld', 07:43:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:43:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:43:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:18 INFO - 'MOZ_NO_REMOTE': '1', 07:43:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:43:18 INFO - 'NO_EM_RESTART': '1', 07:43:18 INFO - 'PAGER': '/bin/cat', 07:43:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:43:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:18 INFO - 'PWD': '/builds/slave/test', 07:43:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:43:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:43:18 INFO - 'SHELL': '/bin/bash', 07:43:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rI5cSHDU5w/Listeners', 07:43:18 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 07:43:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:43:18 INFO - 'USER': 'cltbld', 07:43:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:43:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:43:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:18 INFO - 'XPC_FLAGS': '0x0', 07:43:18 INFO - 'XPC_SERVICE_NAME': '0', 07:43:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:43:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:43:18 INFO - mozversion application_buildid: 20170103062620 07:43:18 INFO - mozversion application_changeset: e5e607abb3b91048542e7d49334461d86277300f 07:43:18 INFO - mozversion application_display_name: Nightly 07:43:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:43:18 INFO - mozversion application_name: Firefox 07:43:18 INFO - mozversion application_remotingname: firefox 07:43:18 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 07:43:18 INFO - mozversion application_vendor: Mozilla 07:43:18 INFO - mozversion application_version: 53.0a1 07:43:18 INFO - mozversion platform_buildid: 20170103062620 07:43:18 INFO - mozversion platform_changeset: e5e607abb3b91048542e7d49334461d86277300f 07:43:18 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 07:43:18 INFO - mozversion platform_version: 53.0a1 07:43:18 INFO - using testdate: 1483458198 07:43:18 INFO - actual date: 1483458198 07:43:18 INFO - starting webserver on 'localhost:60295' 07:43:18 INFO - SUITE-START | Running 2 tests 07:43:18 INFO - TEST-START | dromaeo_css 07:43:18 INFO - operating with platform_type : mac_ 07:43:18 INFO - Initialising browser for dromaeo_css test... 07:43:18 INFO - TEST-INFO | started process 2197 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpehDxOp/profile http://localhost:60295/getInfo.html) 07:43:19 INFO - PROCESS | 2197 | 1483458199023 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:43:20 INFO - PROCESS | 2197 | __metrics Screen width/height:1600/1200 07:43:20 INFO - PROCESS | 2197 | colorDepth:24 07:43:20 INFO - PROCESS | 2197 | Browser inner width/height: 1024/689 07:43:20 INFO - PROCESS | 2197 | __metrics 07:43:20 INFO - PROCESS | 2197 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:43:20 INFO - PROCESS | 2197 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:43:21 INFO - TEST-INFO | 2197: exit 0 07:43:21 INFO - Browser initialized. 07:43:21 INFO - Running cycle 1/1 for dromaeo_css test... 07:43:21 INFO - TEST-INFO | started process 2199 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpehDxOp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:43:33 INFO - PROCESS | 2199 | RSS: Main: 233291776 07:43:33 INFO - PROCESS | 2199 | 07:44:31 INFO - PROCESS | 2199 | 1483458271282 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] 07:46:38 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60295/tests/dromaeo/cssquery-ext.html) 07:46:40 INFO - PROCESS | 2199 | RSS: Main: 347791360 07:46:40 INFO - PROCESS | 2199 | 07:48:22 INFO - PROCESS | 2199 | ************************* 07:48:22 INFO - PROCESS | 2199 | A coding exception was thrown and uncaught in a Task. 07:48:22 INFO - PROCESS | 2199 | 07:48:22 INFO - PROCESS | 2199 | Full message: ReferenceError: fetch is not defined 07:48:22 INFO - PROCESS | 2199 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:48:22 INFO - PROCESS | 2199 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:48:22 INFO - PROCESS | 2199 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:48:22 INFO - PROCESS | 2199 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:48:22 INFO - PROCESS | 2199 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:48:22 INFO - PROCESS | 2199 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:48:22 INFO - PROCESS | 2199 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:48:22 INFO - PROCESS | 2199 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:48:22 INFO - PROCESS | 2199 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:48:22 INFO - PROCESS | 2199 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:48:22 INFO - PROCESS | 2199 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:48:22 INFO - PROCESS | 2199 | 07:48:22 INFO - PROCESS | 2199 | ************************* 07:49:43 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-ext.html (next: http://localhost:60295/tests/dromaeo/cssquery-jquery.html) 07:49:43 INFO - PROCESS | 2199 | RSS: Main: 370335744 07:49:43 INFO - PROCESS | 2199 | 07:52:46 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60295/tests/dromaeo/cssquery-mootools.html) 07:52:46 INFO - PROCESS | 2199 | RSS: Main: 329109504 07:52:46 INFO - PROCESS | 2199 | 07:55:49 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60295/tests/dromaeo/cssquery-prototype.html) 07:55:49 INFO - PROCESS | 2199 | RSS: Main: 331784192 07:55:49 INFO - PROCESS | 2199 | 07:58:53 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60295/tests/dromaeo/cssquery-yui.html) 07:58:53 INFO - PROCESS | 2199 | RSS: Main: 331329536 07:58:53 INFO - PROCESS | 2199 | 08:01:57 INFO - PROCESS | 2199 | Cycle 1(1): loaded http://localhost:60295/tests/dromaeo/cssquery-yui.html (next: http://localhost:60295/tests/dromaeo/cssquery-dojo.html) 08:01:57 INFO - PROCESS | 2199 | RSS: Main: 423104512 08:01:57 INFO - PROCESS | 2199 | 08:01:57 INFO - PROCESS | 2199 | __start_tp_report 08:01:57 INFO - PROCESS | 2199 | _x_x_mozilla_page_load 08:01:57 INFO - PROCESS | 2199 | _x_x_mozilla_page_load_details 08:01:57 INFO - PROCESS | 2199 | |i|pagename|runs| 08:01:57 INFO - PROCESS | 2199 | |0;dojo.html;3210.5826242588464;3292.390907682079;3310.702036816686;3337.9332413351717;3363.915902102447;5578.86052848678;5632.887342253162;5749.310082790066;5768.19245305657;5797.304323481183;5625.1843482694985;5671.06427439473;5685.516731077853;5691.7723291068305;5711.371749107605;5139.7430128493515;5176.741162941857;5201.661891977009;5203.297554830087;5240;5097.006083813658;5312.2828418163435;5328.706921119337;5340.439253878326;5347.438518955492;4529.682922195448;5305.204219367087;5316.282301889253;5321.494458026501;5327.334083239595;5306.787728490855;5308.336457942757;5318.228856815779;5326.387465441447;5337.5196232338885;4274.5939135782055;5203.089459344599;5263.31576895005;5280.709560974145;5282.943411317713;5753.769849206027;5783.392743761887;5823.621464604829;5903.409659034105;5911.615744976562;6283.183186185813;6324.652144132071;6337.809865704036;6341.778037768702;6349.8412539686415;6174.228221472316;6197.6901154942525;6251.062340648894;6277;6293.30773614902;5907.497862681635;5919.881602367928;5920.703964801743;5924.8815023700145;5926.851828704317;6352;6356.014817703263;6356.3325850785;6358.650274234869;6365.96817015912;6115.908261376083;6136.171616831671;6145.170401995673;6152.969235153795;6173.588659537429;5926.088671623431;6256.061590761331;6336.809895703136;6343.9365606343345;6356.173697419306;5486.341639003345;5681.971590142023;5842.480954951743;6312.873742525124;6313.810585682437;6099.188726523911;6313.684315784192;6324.3991820776955;6325.9051114233325;6335.68321583919;4886.096072226578;4903.117438861039;4937;4937.987712518925;4945.184044632667;4445.622122119656;4460.174867649431;4461.7992190351515;4494.393256910341;4495.370648109268;5823.2429784128635;5829.446202610745;5832.066869300891;5835.649861008353;5854.443827836349;6170.7223174957235;6208.906866397007;6248.187735594434;6286.779962701284;6288.276848162428;5748.942510574924;5781.82654520365;5830.562707796932;5841.123831425235;5856.531477481792;5320.76056577455;5354.518093371616;5355.464453554691;5392.218128371364;5466.753996070186;6294.181756371734;6316.14732011191;6352.047192921099;6374;6378.489720822416;6203.348648391854;6239.126522286792;6260.40526150015;6372.0760489729655;6397.8720425592155;6211.65835879031;6344.873102537831;6357.4596159326975;6374.394432528902;6383.808485745435;6197.938020619917;6207.410296021871;6220.191375121205;6227.346128656426;6233.252009758677;6189.659568723763;6248.781292654735;6335.081413194969;6359.713812878525;6362.745490180526;4723.929141062818;6069.0289553672965;6130.386961303834;6140.19751335002;6161.353057928853;5941.37615550361;6152.938470615237;6170.136180934763;6176.6911654418;6178.938210617836;5791.420857914175;5888.970555147197;5997.760089596367;6007.189029481139;6012.939870601413;6100.481459076028;6163.137160797402;6189.907151392643;6242.469390101892;6253.437190652863;5028.421731500778;6076.936536106075;6077.453029227391;6081.612732940851;6109.313572000287 08:01:57 INFO - PROCESS | 2199 | |1;ext.html;459.41195270054334;464.6027646362362;477.193542912478;497.3633748801533;499.02938784064986;30443.63003664835;31960.840195799046;31989.880354187637;32028.87898923538;32030.519542206857;37677.81161094198;40510.7974460128;42293.57706422935;42330;43433.56566434335;9258.21305189058;9373.953130234338;9429.198518125933;9448.66929657462;9459.810803783954;9327.39371940825;9457;9461.574229159703;9474.763130921714;9482.857757133614;8408;8605.61274742635;11347.546098156065;11596.362200079035;11601;11387.259828111188;11460.770784584345;11481.483333250024;11486.770264594661;11504.482298296596;10149.543270552844;10697.823239443655;11351.26216795914;11352.943235283854;11356.205065645408;30639.540406893913;31145;38998.805005975075;39340.705487122206;39506.40740388897;3771.5146406786953;3813.1229817141934;4032.8185231664647;4039.596040395937;4140.896477588054;26044.479110417684;28453.430931381463;29041.56437653437;30535.694643053506;30810.84594577013;2778.9444211115665;3288.96711032889;3308.3052558962645;3327.7005069543857;3335.0661814691925;490660;490959.5452022753;492189;493016;493815;62758;62797.05804412937;62884;62895;63209;66383.33616663772;66427;66465;66490;66512.66743666347;15245.466408675644;15679.05925644465;15785.842141578667;15845.603859903595;15861.52415427539;12113.454894529767;12170.330631815157;12176.75646487083;12183.329916854658;12191.634250972485;14397.496087637091;15607.531774046796;18135.546611334557;18173.818261817214;18217;5890.970545147246;6295.937040629627;7074;7135.179454362711;7193.388561972291;698.8148140742708;850.2815121222557;878.885744853165;888.6889588643922;889.9199072083414;29145.708542914723;29374.706252937624;29793.851030744707;29875.701242987292;29955.25111872177;1019.4240254256322;1063.4310643805527;1067.797118547489;1067.9412632305143;1139.464451707696;13273.402696878664;14354.354054067591;14955.551333460013;15289.464868729541;15435.536933892;506526.1516002896;508635;509112;510929;515274;57107.71446142826;57166.42833571611;57383;57413.712931435075;57486.7125664369;1307.3397934043437;1316.3813007886279;1347.083982891643;1385.3142694366354;1410.9365078571489;28311.43377132487;28430.857845710638;28517.572236416887;29371.265718357212;29872.25319367033;49328.50671493383;49614.75192624014;49764.502354977434;49775.75112124416;49795;383692;385046.5701101344;385759;386263;387028;38501.42247866228;38981.27131100796;39318;39565.40651890166;39647.2070558593;39230;45633.31550026686;46912.29631555461;47438;49622;37938.241235175694;38362.6163738359;38369.42445863259;39593.406098907966;39752.60247397489;31039.53440698346;34541.963741087806;37932;37964.24071518499;39077.80461097676;37141.25717485581;37685.434718479795;38136.809315953244;38401.271942763204;38420.231595368496;32067.518987215677;33509.64903509629;34081.65918340785;34263.82868085644;34594.82702586471;24554.754452455247;24637.753622464264;24745.752542474343;25201.117960872016;25327.366815829013 08:01:57 INFO - PROCESS | 2199 | |2;jquery.html;371.9888403347899;372.93846515324975;376.4203127184136;392.81930312056454;400.95589485156626;15584.92207538962;16266.105364204965;18948.526286842836;19824.60350792985;19968.60062798743;11902.2263552869;14070.718585628281;16817.32730690774;19570.902145489254;19587.314443994426;5050.585835965926;5305.867353316179;5306.814261500848;5309.256704061435;5310.07073762092;3825.5791862895057;3995.5205375355;4051.412545180947;4057.2899742544937;6469.8706025879455;5852.736626851802;6081.722838203982;6491.22105347359;6498.740050397979;6501.154849869534;4946.703197808107;5905.438983296602;5932.881342373172;5944.940550594482;6469.4177524022825;5722.942770572325;5725.742341594639;5728.341240757324;5728.398518155575;5743.655380677173;12920.160189587647;13033.934830325883;13174.209547427185;13203.151558781778;13206.226692394608;2565.115035312814;2572.8323986302125;2573.4724381501933;2583.7028741694758;2590.248827839924;9061;9722.513874306256;9889.060539248832;10178.083972442515;10289.794204115951;1496.8428315026983;1565.2721484509643;1834.5872178759805;1846.6675998320297;1847.4272975377542;81109.59445202796;119165;119529;119894;120727;11102.666919992413;11252;11306.491207895664;11308.717282068013;11499.597514086967;14436.422543098159;14450.060746051473;14464;14473.421063157355;14526.85473145255;8100.3519718422385;8251.174882511701;8313.501189928624;8379.581020948928;8390.790230244293;8038.45040476739;8071.838563228703;8134.145914678874;8293.709720159766;8298.543580103153;4409.977950110229;4497.370368148462;4512.300593408027;4587.931181032287;4600.60894823937;1875.8593105516868;1933.1977229449622;1938.4378530226359;1941.79611140831;1945.4358236111368;709.7622296530617;818.3126174013738;850.6172222499899;861.6165806216299;867.509856930832;14618.853811461962;17529;18801.717974230396;19339.8066019338;19541.108795498014;866.8829707989466;922.7231830450838;923.2244914272044;955.6894009446958;959.9808003839884;5235.528802407802;5944.6730429826775;6284.497240220863;6480.092787009728;6643.302453242437;494318;503057;507421;512052;531793;122152.38923805323;122796;123903.08708876741;127359.36320318338;129465.05802412971;996.7159359582485;1012.6303899076784;1039.1219419590434;1039.2673165418362;1041.770810421706;11581.82627260575;11679.416029198675;11688.47401866935;11730.296182229093;11814.232074915102;38664;38804.53747818924;42162.78918605387;46933.3573324932;50817;62210.37789622046;77512;78018;78130;80881.59559202167;7951.84096318082;8093.87859182101;8161.347092232489;8190.877136843072;8418.831623367621;10445.373277603368;12292.631221063382;12530.74938501243;12577.685557861127;13025.609231722685;10985.143862595623;11101.278416902875;11171.486415556436;11187.832182517432;11239.04468120219;8924.464532128093;10845.457727113771;11149.554017839195;11361.750207477337;11484.253523520945;10351.741206469898;11169.441527923735;11280.661580152608;11450.484728187084;11874.16880818369;6997.930020699728;7235.963820180866;7274.199838017919;7459.142198647225;8209.630566624397;9530.377766782965;9818.410895346302;9977.054474377426;10677.199210059121;11241.381724005258 08:01:57 INFO - PROCESS | 2199 | |3;mootools.html;1384.4877395363715;1389.270632917718;1405.873471387575;1406.978895316571;1412.0892024644102;7961.16407777183;8232.958835205824;8244.628991695366;8324.334053275723;8375.329973602113;7384.630768461582;7576;7605.8478830423355;7996.760097197079;8163.795905102375;2258.390234636647;2321.674965504822;2323.6979192704925;2324.883755812211;2325.8604483730946;2245.348848833836;2274.7384050834116;2285.5086156476355;2289.1186893046215;2289.9656505152434;2022.8280596149345;2102.484891201658;2239.496113374486;2299.5515874404496;2328.441174118216;1957.725918371415;2280.8061314788265;2301.4476525633895;2321.86228747914;2330.976690233093;2172.717546718932;2183.224955140934;2193.429708275844;2199.9010044548036;2206.7572567017614;7183.6408179591335;7322.1945585985495;8035.276825085712;8132.146124656885;8157.796055098611;4977.8257760978195;5133.204353325241;5133.897322053538;5141.691498510101;5147.562457191143;3138.3880143372053;3310.3710295043866;3363.276895467486;3369.1913940654313;3377.6115746689197;1145.7422080032009;1167.1479819731558;1200.2318516149671;1226.7853125702966;1230.5693007447412;10696.358218506837;10696.946515267375;10697.786044279072;10713.357198568034;10745.462726863703;3816.3512202925012;4466.330050492387;4492.213862574036;4518.249265547936;4538.183127037165;9184.311176661777;9215.44707317563;9220.170184683277;9220.216281616138;9225.261979041661;5091.974540127276;5116.667416617908;5200.791968321225;5201.713905735145;5203.271541984125;4011.498562679665;4170.395292682605;4717.268823332389;4738.099761045391;4757.09615173116;3667.9633203668154;3729.310077635646;3744.213715119801;3786.545614526219;3808.7333886628226;1049.7113293844102;1064.414571985405;1235.5799028330232;1236.0852968803022;1241.3979220078254;714.2536049827942;719.8524302517972;742.0983505041401;782.4679218131726;872.3806097670725;5143;5273.655217919406;5396.082665946798;5427.9185812212845;5492.231087647732;758.525921299188;761.6534476813036;850.7022542110336;885.8892638420198;901.7565257380602;3486.1981744198974;3495.598006229265;3534.1871369585137;3600.5499312585926;3624.4744512045604;9270.44377337362;9813;9848.162906152915;9951.054649808402;9955.601775928882;9607.183389411979;9873.160781333667;9906.752331191634;9977.551010204414;10016.54925528353;7482.401407887356;7645.961770191114;7659.961700191463;7839;7856.607169641609;4512.0750246198795;5088.287639730367;5271.420143784104;5390.946090539044;5433.728313584384;8844.734657960014;8991.190792828676;9121.133492318218;9146.359754817098;9254.676086336945;9116.179543841086;9288.62845486173;9295;9349;9399.248060155034;6513.804585862432;6718.664066796543;6719.630420326929;6945.201301850352;7098.041764361745;7101.928980710127;7462.701491940262;7822.530648161129;7922.762317130495;7939.84120317602;6936.167659880846;7010.474214433836;7033.296670332926;7065.894011589728;7156.463265255231;6893.931060689329;6900.102986611709;6917.342852429011;6952.930470695431;6960.37356637905;6087.786927457518;6267.4986001120715;6369.044643303541;6397.680115994089;6402.327755585597;4057.837686492507;4665.5101214372;4671.672982891165;4722.173619616622;4826.8069277228515;4438.667099967451;4678.555537223959;4763.214069678603;4800.183968725255;4850.417949846041 08:01:57 INFO - PROCESS | 2199 | |4;prototype.html;342.0610424385062;346.04664150266024;351.99648003519974;354.5444104325941;364.087959661049;4213.3469312256575;4826.396700412449;4839.943024214708;5211.45279745627;5215.973920130399;4098.9590104099025;4828.46886842447;5170.870728231796;5181.559567436772;5190.9740451297785;3423.4864770284407;3663.212409331993;4031.475908131946;4033.112715202665;4034.616711412411;3310.337932413515;3751.2990646734443;3947.3486874665646;4089.8568550100754;4093.2222877653153;3561;4052.946233979157;4099.528554216258;4125.546189919107;4128.256913755523;3440.7935523868523;3684.7420680552373;3850.403187505941;3994.460747799039;4141.668666506687;3348.296857659894;3544.4302880411497;3610.4403817408343;3645.343838109139;3648.6168952259895;4700.673166782431;4765.785539650689;5129.487051294878;5157.30376399185;5157.4068982067165;4138.79306034697;4560.407147070892;4678.789454474523;4699.882502937419;4715.41057367829;4845.4185497740145;5050.823221187277;5124.282600435942;5463.672179669193;5469.316335458067;3353.748468864845;3462.342154990569;3649.708023358125;3973.304671682444;3980.8805735827973;4686.73507108533;4801.279808028754;4803.78382972767;5329.0674132027125;5567.109262517978;3306.966930330714;3960.4455376247347;3963.6432721055244;4060.5990933128023;4067.4508941292547;3414.2147306119236;3573.8927832165077;3946.625070618287;4057.736247143926;4182.895427614334;3706.8887933361502;4269.87190384289;4332.1119170569955;4359.869203923888;4753.643476739189;4655.837045703384;4918.360413294136;4980.302757613937;5087.97456012725;5541.445855414427;1230.5262473947416;1287.46570173377;1338.070041321292;1345.219772531929;1346.9897576817386;851.8211175653058;882.5587206396801;946.5456580841235;967.3277072434731;969.132626299458;4402.581754733295;4499.345386222145;4506.121306345297;4614.700044497097;4875.853724388274;4780.01249219238;4999.250112483084;5230.2666087191155;5384.623076384693;5401.7299135043095;4802.231642937113;4880.389951256093;4962.429320628102;4981.363604568613;5028.572571331478;2455.742147074567;2502.398464982413;2718.3340081680117;2899.289419242657;2916.314666053476;5633.605647604711;5646.774129034875;5658.066419040812;5695.772169113189;5765.567582431335;5087.016063735146;5176.456472070378;5239.973800130975;5634;5743.971280143656;4768.189407800752;5593.776248950077;5645.661260324394;5645.745941432645;5683.54531637468;4973.751312434291;5082.119615742199;5472.233887255867;5487.807926722546;5506.229127922014;5016.774245158904;5232.5552328052545;5454.754536045967;5471.534919531725;5506.366767821752;5195.960807838523;5330.706811125426;5377.736231985558;5600.523955463831;5860.472557469848;3591.8147011485626;3687.539057617798;3823.6558709715146;3882.650561449483;3913.6673382762783;4848.927266091082;5697.54419646419;5775.7400916957995;5801;5821.912671310019;4742.5731684148595;4905.386826646669;5338.839834804962;5597.244372009726;5617.382087970238;4506.549345065468;4745.691530050535;5037.042961837239;5618.606697531133;5619.466150715675;4533.773311334486;5135.666181698178;5217.104506998652;5356.116240820221;5500.312460942382;4522.683412161117;4642.76966603857;4741.691790033637;5411.269478620493;5635.478420826272;5404.108322126805;5412.404635490014;5414.648047876875;5415.296011518478;5462.9726851365485 08:01:57 INFO - PROCESS | 2199 | |5;yui.html;695.0399872359947;708.3589351636764;744.9478536502447;779.212994875176;790.8932294140291;14799.33402996865;15707.685846283068;15726.056436613782;15744.448944286953;15748.448804291853;14075.507357242499;14222;14229.075110117861;14246;14249.71500569988;663.8041777675597;685.2496516314652;688.8484533402643;837.8198687282234;863.6459051788768;859.3683642522741;865.9350548708841;881.920627143557;882.7175303902753;885.2652298592153;939.8355287824603;941.750436134425;942.1238248428958;943.3585162089777;945.0785484152965;706.0926709178681;728.7922941961516;933.9959543490775;936.8782058332441;936.9672061477816;658;678.2573082474682;683.5215349255541;685.4310921934782;686.9690863911136;14901.776473352907;14948.62628434287;14949.925250373788;14954.850451495455;14979.400823967137;12442.440090195963;12771.552995645108;12785.936070319682;12889.484420623165;12923.289219092947;12513.061520385916;12672.23966562;12692.048096392808;12889.935550322281;13228.074034817566;4340.804663790137;4372.322290045048;4392.209402307583;4453.4433195850515;4508.819647214107;99736.00263997339;103445.44831827529;103720.96279037188;104063.55300916893;104725;9856.457894815854;10341.74145646365;10453.425061621683;10480.947595261974;10772.892271077346;15422.46021389246;19941.800581994285;19954.501137471678;19992.800071999092;20069.498262543555;7327.413806895435;7437.256274372519;7523.247675232433;7566.010084701514;7608.657610407545;4098.139390727921;4125.66994640434;4242.85150019754;4321.13577284538;4334.414853994734;3326.7172290355593;3353.279045005336;3422.828858557112;3427.7771944823508;3428.3828910795796;564.4524810933389;604.6674329118968;620.5314987184647;622.9626222426668;634.058423241486;492.90141971605823;578.8581797459565;654.2835595023441;672.9360710732473;676.1345477788439;7525.548467091992;8790.505614045627;9113.270938325051;9195.678151264674;9490.857637135448;531.3305235403419;541.5667466027242;622.9938649081791;653.1182903080803;659.7525927777084;4384.956150438455;4416.470023597189;4422.646188304992;4537.205988951986;4563.881848946963;98855;107117.464412679;110636;111043;112054;95697.56453653201;96223.51888240654;96635;97398.5130074345;97670.02329976752;64323.678381607795;82838.51484455475;84129.57935210285;85146;85248;8690.869636955576;8754.649814007369;8758.124187581447;8844.955775221082;8901.287896968097;36223.63776362308;49676.25485617791;50061;51763;51783;65754;65882.34117658954;67708.6614566924;68217.63564728579;68829.07200519697;12474.58169082337;12901.741965160832;12925.741485170432;12951;13113.147645403018;14373.209473479059;14723.558293251219;14922.305639388682;15064.6987060256;15299.847001529843;11893.870082342164;13361.39873705705;13571.660708482366;13860.930695346458;14107.624253436928;10489.370637761758;12594.87405125937;13483.460661573426;13540.458381664623;14058.226797526235;13091.214527128403;13133.277669727875;13146.539871104847;13265.535706250621;13812.585622431343;11122.833157502806;11142.331460112418;11234.943825280821;11413.486393112496;11617.244879082833;9928.269725825456;11071.391073491035;11246.437678116225;11401.828972565252;11571.537138514364 08:01:57 INFO - PROCESS | 2199 | __end_tp_report 08:01:57 INFO - PROCESS | 2199 | __start_cc_report 08:01:57 INFO - PROCESS | 2199 | _x_x_mozilla_cycle_collect,1665 08:01:57 INFO - PROCESS | 2199 | __end_cc_report 08:01:57 INFO - PROCESS | 2199 | __startTimestamp1483459317557__endTimestamp 08:01:57 INFO - PROCESS | 2199 | 08:01:57 INFO - PROCESS | 2199 | ------- Summary: start ------- 08:01:57 INFO - PROCESS | 2199 | Number of tests: 6 08:01:57 INFO - PROCESS | 2199 | 08:01:57 INFO - PROCESS | 2199 | [#0] dojo.html Cycles:165 Average:5760.56 Median:5926.85 stddev:671.64 (11.3%) stddev-sans-first:643.22 08:01:57 INFO - PROCESS | 2199 | Values: 3210.6 3292.4 3310.7 3337.9 3363.9 5578.9 5632.9 5749.3 5768.2 5797.3 5625.2 5671.1 5685.5 5691.8 5711.4 5139.7 5176.7 5201.7 5203.3 5240.0 5097.0 5312.3 5328.7 5340.4 5347.4 4529.7 5305.2 5316.3 5321.5 5327.3 5306.8 5308.3 5318.2 5326.4 5337.5 4274.6 5203.1 5263.3 5280.7 5282.9 5753.8 5783.4 5823.6 5903.4 5911.6 6283.2 6324.7 6337.8 6341.8 6349.8 6174.2 6197.7 6251.1 6277.0 6293.3 5907.5 5919.9 5920.7 5924.9 5926.9 6352.0 6356.0 6356.3 6358.7 6366.0 6115.9 6136.2 6145.2 6153.0 6173.6 5926.1 6256.1 6336.8 6343.9 6356.2 5486.3 5682.0 5842.5 6312.9 6313.8 6099.2 6313.7 6324.4 6325.9 6335.7 4886.1 4903.1 4937.0 4938.0 4945.2 4445.6 4460.2 4461.8 4494.4 4495.4 5823.2 5829.4 5832.1 5835.6 5854.4 6170.7 6208.9 6248.2 6286.8 6288.3 5748.9 5781.8 5830.6 5841.1 5856.5 5320.8 5354.5 5355.5 5392.2 5466.8 6294.2 6316.1 6352.0 6374.0 6378.5 6203.3 6239.1 6260.4 6372.1 6397.9 6211.7 6344.9 6357.5 6374.4 6383.8 6197.9 6207.4 6220.2 6227.3 6233.3 6189.7 6248.8 6335.1 6359.7 6362.7 4723.9 6069.0 6130.4 6140.2 6161.4 5941.4 6152.9 6170.1 6176.7 6178.9 5791.4 5889.0 5997.8 6007.2 6012.9 6100.5 6163.1 6189.9 6242.5 6253.4 5028.4 6076.9 6077.5 6081.6 6109.3 08:01:57 INFO - PROCESS | 2199 | 08:01:57 INFO - PROCESS | 2199 | [#1] ext.html Cycles:180 Average:61265.69 Median:38249.71 stddev:123699.72 (323.4%) stddev-sans-first:123962.48 08:01:57 INFO - PROCESS | 2199 | Values: 459.4 464.6 477.2 497.4 499.0 30443.6 31960.8 31989.9 32028.9 32030.5 37677.8 40510.8 42293.6 42330.0 43433.6 9258.2 9374.0 9429.2 9448.7 9459.8 9327.4 9457.0 9461.6 9474.8 9482.9 8408.0 8605.6 11347.5 11596.4 11601.0 11387.3 11460.8 11481.5 11486.8 11504.5 10149.5 10697.8 11351.3 11352.9 11356.2 30639.5 31145.0 38998.8 39340.7 39506.4 3771.5 3813.1 4032.8 4039.6 4140.9 26044.5 28453.4 29041.6 30535.7 30810.8 2778.9 3289.0 3308.3 3327.7 3335.1 490660.0 490959.5 492189.0 493016.0 493815.0 62758.0 62797.1 62884.0 62895.0 63209.0 66383.3 66427.0 66465.0 66490.0 66512.7 15245.5 15679.1 15785.8 15845.6 15861.5 12113.5 12170.3 12176.8 12183.3 12191.6 14397.5 15607.5 18135.5 18173.8 18217.0 5891.0 6295.9 7074.0 7135.2 7193.4 698.8 850.3 878.9 888.7 889.9 29145.7 29374.7 29793.9 29875.7 29955.3 1019.4 1063.4 1067.8 1067.9 1139.5 13273.4 14354.4 14955.6 15289.5 15435.5 506526.2 508635.0 509112.0 510929.0 515274.0 57107.7 57166.4 57383.0 57413.7 57486.7 1307.3 1316.4 1347.1 1385.3 1410.9 28311.4 28430.9 28517.6 29371.3 29872.3 49328.5 49614.8 49764.5 49775.8 49795.0 383692.0 385046.6 385759.0 386263.0 387028.0 38501.4 38981.3 39318.0 39565.4 39647.2 39230.0 45633.3 46912.3 47438.0 49622.0 37938.2 38362.6 38369.4 39593.4 39752.6 31039.5 34542.0 37932.0 37964.2 39077.8 37141.3 37685.4 38136.8 38401.3 38420.2 32067.5 33509.6 34081.7 34263.8 34594.8 24554.8 24637.8 24745.8 25201.1 25327.4 08:01:57 INFO - PROCESS | 2199 | 08:01:57 INFO - PROCESS | 2199 | [#2] jquery.html Cycles:180 Average:30940.24 Median:38734.27 stddev:85939.96 (221.9%) stddev-sans-first:86150.40 08:01:57 INFO - PROCESS | 2199 | Values: 372.0 372.9 376.4 392.8 401.0 15584.9 16266.1 18948.5 19824.6 19968.6 11902.2 14070.7 16817.3 19570.9 19587.3 5050.6 5305.9 5306.8 5309.3 5310.1 3825.6 3995.5 4051.4 4057.3 6469.9 5852.7 6081.7 6491.2 6498.7 6501.2 4946.7 5905.4 5932.9 5944.9 6469.4 5722.9 5725.7 5728.3 5728.4 5743.7 12920.2 13033.9 13174.2 13203.2 13206.2 2565.1 2572.8 2573.5 2583.7 2590.2 9061.0 9722.5 9889.1 10178.1 10289.8 1496.8 1565.3 1834.6 1846.7 1847.4 81109.6 119165.0 119529.0 119894.0 120727.0 11102.7 11252.0 11306.5 11308.7 11499.6 14436.4 14450.1 14464.0 14473.4 14526.9 8100.4 8251.2 8313.5 8379.6 8390.8 8038.5 8071.8 8134.1 8293.7 8298.5 4410.0 4497.4 4512.3 4587.9 4600.6 1875.9 1933.2 1938.4 1941.8 1945.4 709.8 818.3 850.6 861.6 867.5 14618.9 17529.0 18801.7 19339.8 19541.1 866.9 922.7 923.2 955.7 960.0 5235.5 5944.7 6284.5 6480.1 6643.3 494318.0 503057.0 507421.0 512052.0 531793.0 122152.4 122796.0 123903.1 127359.4 129465.1 996.7 1012.6 1039.1 1039.3 1041.8 11581.8 11679.4 11688.5 11730.3 11814.2 38664.0 38804.5 42162.8 46933.4 50817.0 62210.4 77512.0 78018.0 78130.0 80881.6 7951.8 8093.9 8161.3 8190.9 8418.8 10445.4 12292.6 12530.7 12577.7 13025.6 10985.1 11101.3 11171.5 11187.8 11239.0 8924.5 10845.5 11149.6 11361.8 11484.3 10351.7 11169.4 11280.7 11450.5 11874.2 6997.9 7236.0 7274.2 7459.1 8209.6 9530.4 9818.4 9977.1 10677.2 11241.4 08:01:57 INFO - PROCESS | 2199 | 08:01:57 INFO - PROCESS | 2199 | [#3] mootools.html Cycles:180 Average:5282.22 Median:5174.18 stddev:2922.81 (56.5%) stddev-sans-first:2916.33 08:01:57 INFO - PROCESS | 2199 | Values: 1384.5 1389.3 1405.9 1407.0 1412.1 7961.2 8233.0 8244.6 8324.3 8375.3 7384.6 7576.0 7605.8 7996.8 8163.8 2258.4 2321.7 2323.7 2324.9 2325.9 2245.3 2274.7 2285.5 2289.1 2290.0 2022.8 2102.5 2239.5 2299.6 2328.4 1957.7 2280.8 2301.4 2321.9 2331.0 2172.7 2183.2 2193.4 2199.9 2206.8 7183.6 7322.2 8035.3 8132.1 8157.8 4977.8 5133.2 5133.9 5141.7 5147.6 3138.4 3310.4 3363.3 3369.2 3377.6 1145.7 1167.1 1200.2 1226.8 1230.6 10696.4 10696.9 10697.8 10713.4 10745.5 3816.4 4466.3 4492.2 4518.2 4538.2 9184.3 9215.4 9220.2 9220.2 9225.3 5092.0 5116.7 5200.8 5201.7 5203.3 4011.5 4170.4 4717.3 4738.1 4757.1 3668.0 3729.3 3744.2 3786.5 3808.7 1049.7 1064.4 1235.6 1236.1 1241.4 714.3 719.9 742.1 782.5 872.4 5143.0 5273.7 5396.1 5427.9 5492.2 758.5 761.7 850.7 885.9 901.8 3486.2 3495.6 3534.2 3600.5 3624.5 9270.4 9813.0 9848.2 9951.1 9955.6 9607.2 9873.2 9906.8 9977.6 10016.5 7482.4 7646.0 7660.0 7839.0 7856.6 4512.1 5088.3 5271.4 5390.9 5433.7 8844.7 8991.2 9121.1 9146.4 9254.7 9116.2 9288.6 9295.0 9349.0 9399.2 6513.8 6718.7 6719.6 6945.2 7098.0 7101.9 7462.7 7822.5 7922.8 7939.8 6936.2 7010.5 7033.3 7065.9 7156.5 6893.9 6900.1 6917.3 6952.9 6960.4 6087.8 6267.5 6369.0 6397.7 6402.3 4057.8 4665.5 4671.7 4722.2 4826.8 4438.7 4678.6 4763.2 4800.2 4850.4 08:01:57 INFO - PROCESS | 2199 | 08:01:57 INFO - PROCESS | 2199 | [#4] prototype.html Cycles:180 Average:4365.40 Median:4803.01 stddev:1305.97 (27.2%) stddev-sans-first:1274.24 08:01:57 INFO - PROCESS | 2199 | Values: 342.1 346.0 352.0 354.5 364.1 4213.3 4826.4 4839.9 5211.5 5216.0 4099.0 4828.5 5170.9 5181.6 5191.0 3423.5 3663.2 4031.5 4033.1 4034.6 3310.3 3751.3 3947.3 4089.9 4093.2 3561.0 4052.9 4099.5 4125.5 4128.3 3440.8 3684.7 3850.4 3994.5 4141.7 3348.3 3544.4 3610.4 3645.3 3648.6 4700.7 4765.8 5129.5 5157.3 5157.4 4138.8 4560.4 4678.8 4699.9 4715.4 4845.4 5050.8 5124.3 5463.7 5469.3 3353.7 3462.3 3649.7 3973.3 3980.9 4686.7 4801.3 4803.8 5329.1 5567.1 3307.0 3960.4 3963.6 4060.6 4067.5 3414.2 3573.9 3946.6 4057.7 4182.9 3706.9 4269.9 4332.1 4359.9 4753.6 4655.8 4918.4 4980.3 5088.0 5541.4 1230.5 1287.5 1338.1 1345.2 1347.0 851.8 882.6 946.5 967.3 969.1 4402.6 4499.3 4506.1 4614.7 4875.9 4780.0 4999.3 5230.3 5384.6 5401.7 4802.2 4880.4 4962.4 4981.4 5028.6 2455.7 2502.4 2718.3 2899.3 2916.3 5633.6 5646.8 5658.1 5695.8 5765.6 5087.0 5176.5 5240.0 5634.0 5744.0 4768.2 5593.8 5645.7 5645.7 5683.5 4973.8 5082.1 5472.2 5487.8 5506.2 5016.8 5232.6 5454.8 5471.5 5506.4 5196.0 5330.7 5377.7 5600.5 5860.5 3591.8 3687.5 3823.7 3882.7 3913.7 4848.9 5697.5 5775.7 5801.0 5821.9 4742.6 4905.4 5338.8 5597.2 5617.4 4506.5 4745.7 5037.0 5618.6 5619.5 4533.8 5135.7 5217.1 5356.1 5500.3 4522.7 4642.8 4741.7 5411.3 5635.5 5404.1 5412.4 5414.6 5415.3 5463.0 08:01:57 INFO - PROCESS | 2199 | 08:01:57 INFO - PROCESS | 2199 | [#5] yui.html Cycles:180 Average:20580.04 Median:4404.34 stddev:30229.20 (686.4%) stddev-sans-first:30277.13 08:01:57 INFO - PROCESS | 2199 | Values: 695.0 708.4 744.9 779.2 790.9 14799.3 15707.7 15726.1 15744.4 15748.4 14075.5 14222.0 14229.1 14246.0 14249.7 663.8 685.2 688.8 837.8 863.6 859.4 865.9 881.9 882.7 885.3 939.8 941.8 942.1 943.4 945.1 706.1 728.8 934.0 936.9 937.0 658.0 678.3 683.5 685.4 687.0 14901.8 14948.6 14949.9 14954.9 14979.4 12442.4 12771.6 12785.9 12889.5 12923.3 12513.1 12672.2 12692.0 12889.9 13228.1 4340.8 4372.3 4392.2 4453.4 4508.8 99736.0 103445.4 103721.0 104063.6 104725.0 9856.5 10341.7 10453.4 10480.9 10772.9 15422.5 19941.8 19954.5 19992.8 20069.5 7327.4 7437.3 7523.2 7566.0 7608.7 4098.1 4125.7 4242.9 4321.1 4334.4 3326.7 3353.3 3422.8 3427.8 3428.4 564.5 604.7 620.5 623.0 634.1 492.9 578.9 654.3 672.9 676.1 7525.5 8790.5 9113.3 9195.7 9490.9 531.3 541.6 623.0 653.1 659.8 4385.0 4416.5 4422.6 4537.2 4563.9 98855.0 107117.5 110636.0 111043.0 112054.0 95697.6 96223.5 96635.0 97398.5 97670.0 64323.7 82838.5 84129.6 85146.0 85248.0 8690.9 8754.6 8758.1 8845.0 8901.3 36223.6 49676.3 50061.0 51763.0 51783.0 65754.0 65882.3 67708.7 68217.6 68829.1 12474.6 12901.7 12925.7 12951.0 13113.1 14373.2 14723.6 14922.3 15064.7 15299.8 11893.9 13361.4 13571.7 13860.9 14107.6 10489.4 12594.9 13483.5 13540.5 14058.2 13091.2 13133.3 13146.5 13265.5 13812.6 11122.8 11142.3 11234.9 11413.5 11617.2 9928.3 11071.4 11246.4 11401.8 11571.5 08:01:57 INFO - PROCESS | 2199 | -------- Summary: end -------- 08:01:57 INFO - PROCESS | 2199 | 08:01:58 INFO - TEST-INFO | 2199: exit 0 08:01:58 INFO - TEST-OK | dromaeo_css | took 1119726ms 08:01:58 INFO - TEST-START | kraken 08:01:58 INFO - operating with platform_type : mac_ 08:01:58 INFO - Initialising browser for kraken test... 08:01:58 INFO - TEST-INFO | started process 2201 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpAbq_Q0/profile http://localhost:60295/getInfo.html) 08:01:58 INFO - PROCESS | 2201 | 1483459318739 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:02:00 INFO - PROCESS | 2201 | __metrics Screen width/height:1600/1200 08:02:00 INFO - PROCESS | 2201 | colorDepth:24 08:02:00 INFO - PROCESS | 2201 | Browser inner width/height: 1024/689 08:02:00 INFO - PROCESS | 2201 | __metrics 08:02:00 INFO - PROCESS | 2201 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:02:00 INFO - PROCESS | 2201 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:02:01 INFO - TEST-INFO | 2201: exit 0 08:02:01 INFO - Browser initialized. 08:02:01 INFO - Running cycle 1/1 for kraken test... 08:02:01 INFO - TEST-INFO | started process 2202 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpAbq_Q0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:02:13 INFO - PROCESS | 2202 | RSS: Main: 225452032 08:02:13 INFO - PROCESS | 2202 | 08:02:16 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60295/tests/kraken/driver.html?testName=audio-beat-detection) 08:02:16 INFO - PROCESS | 2202 | RSS: Main: 286015488 08:02:16 INFO - PROCESS | 2202 | 08:02:19 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60295/tests/kraken/driver.html?testName=audio-dft) 08:02:20 INFO - PROCESS | 2202 | RSS: Main: 289550336 08:02:20 INFO - PROCESS | 2202 | 08:02:23 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60295/tests/kraken/driver.html?testName=audio-fft) 08:02:24 INFO - PROCESS | 2202 | RSS: Main: 382214144 08:02:24 INFO - PROCESS | 2202 | 08:02:26 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60295/tests/kraken/driver.html?testName=audio-oscillator) 08:02:26 INFO - PROCESS | 2202 | RSS: Main: 283869184 08:02:26 INFO - PROCESS | 2202 | 08:02:28 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60295/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:02:29 INFO - PROCESS | 2202 | RSS: Main: 286851072 08:02:29 INFO - PROCESS | 2202 | 08:02:32 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60295/tests/kraken/driver.html?testName=imaging-darkroom) 08:02:32 INFO - PROCESS | 2202 | RSS: Main: 328880128 08:02:32 INFO - PROCESS | 2202 | 08:02:36 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60295/tests/kraken/driver.html?testName=imaging-desaturate) 08:02:36 INFO - PROCESS | 2202 | RSS: Main: 330264576 08:02:36 INFO - PROCESS | 2202 | 08:02:39 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60295/tests/kraken/driver.html?testName=json-parse-financial) 08:02:40 INFO - PROCESS | 2202 | RSS: Main: 326537216 08:02:40 INFO - PROCESS | 2202 | 08:02:41 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60295/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:02:42 INFO - PROCESS | 2202 | RSS: Main: 303804416 08:02:42 INFO - PROCESS | 2202 | 08:02:44 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:02:44 INFO - PROCESS | 2202 | RSS: Main: 306401280 08:02:44 INFO - PROCESS | 2202 | 08:02:47 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:02:47 INFO - PROCESS | 2202 | RSS: Main: 312012800 08:02:47 INFO - PROCESS | 2202 | 08:02:50 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:02:51 INFO - PROCESS | 2202 | RSS: Main: 322342912 08:02:51 INFO - PROCESS | 2202 | 08:02:54 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:02:55 INFO - PROCESS | 2202 | RSS: Main: 321077248 08:02:55 INFO - PROCESS | 2202 | 08:02:56 INFO - PROCESS | 2202 | Cycle 1(1): loaded http://localhost:60295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60295/tests/kraken/driver.html?testName=ai-astar) 08:02:57 INFO - PROCESS | 2202 | RSS: Main: 365154304 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | __start_tp_report 08:02:57 INFO - PROCESS | 2202 | _x_x_mozilla_page_load 08:02:57 INFO - PROCESS | 2202 | _x_x_mozilla_page_load_details 08:02:57 INFO - PROCESS | 2202 | |i|pagename|runs| 08:02:57 INFO - PROCESS | 2202 | |0;ai-astar;103;114;114;114;114;115;115;114;114;114 08:02:57 INFO - PROCESS | 2202 | |1;audio-beat-detection;144;114;132;183;109;110;110;108;130;109 08:02:57 INFO - PROCESS | 2202 | |2;audio-dft;184;183;184;187;184;182;180;184;184;183 08:02:57 INFO - PROCESS | 2202 | |3;audio-fft;80;59;58;88;62;60;59;58;73;58 08:02:57 INFO - PROCESS | 2202 | |4;audio-oscillator;90;70;84;69;87;80;77;55;90;112 08:02:57 INFO - PROCESS | 2202 | |5;imaging-gaussian-blur;93;133;122;86;87;84;89;85;88;85 08:02:57 INFO - PROCESS | 2202 | |6;imaging-darkroom;96;112;168;119;96;98;97;96;96;118 08:02:57 INFO - PROCESS | 2202 | |7;imaging-desaturate;88;85;85;87;85;85;84;87;94;87 08:02:57 INFO - PROCESS | 2202 | |8;json-parse-financial;57;57;56;56;54;57;55;55;56;55 08:02:57 INFO - PROCESS | 2202 | |9;json-stringify-tinderbox;68;71;74;78;75;71;70;72;72;73 08:02:57 INFO - PROCESS | 2202 | |10;stanford-crypto-aes;88;83;91;86;90;87;90;90;92;86 08:02:57 INFO - PROCESS | 2202 | |11;stanford-crypto-ccm;140;141;140;139;137;138;146;153;268;161 08:02:57 INFO - PROCESS | 2202 | |12;stanford-crypto-pbkdf2;174;172;173;172;174;170;173;183;241;188 08:02:57 INFO - PROCESS | 2202 | |13;stanford-crypto-sha256-iterative;67;68;67;68;67;67;68;70;83;86 08:02:57 INFO - PROCESS | 2202 | __end_tp_report 08:02:57 INFO - PROCESS | 2202 | __start_cc_report 08:02:57 INFO - PROCESS | 2202 | _x_x_mozilla_cycle_collect,1994 08:02:57 INFO - PROCESS | 2202 | __end_cc_report 08:02:57 INFO - PROCESS | 2202 | __startTimestamp1483459377113__endTimestamp 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | ------- Summary: start ------- 08:02:57 INFO - PROCESS | 2202 | Number of tests: 14 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:3.57 (3.1%) stddev-sans-first:0.44 08:02:57 INFO - PROCESS | 2202 | Values: 103.0 114.0 114.0 114.0 114.0 115.0 115.0 114.0 114.0 114.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:122.00 stddev:23.97 (19.6%) stddev-sans-first:24.41 08:02:57 INFO - PROCESS | 2202 | Values: 144.0 114.0 132.0 183.0 109.0 110.0 110.0 108.0 130.0 109.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.78 (1.0%) stddev-sans-first:1.88 08:02:57 INFO - PROCESS | 2202 | Values: 184.0 183.0 184.0 187.0 184.0 182.0 180.0 184.0 184.0 183.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#3] audio-fft Cycles:10 Average:65.50 Median:61.00 stddev:10.90 (17.9%) stddev-sans-first:10.22 08:02:57 INFO - PROCESS | 2202 | Values: 80.0 59.0 58.0 88.0 62.0 60.0 59.0 58.0 73.0 58.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:82.00 stddev:15.36 (18.7%) stddev-sans-first:15.98 08:02:57 INFO - PROCESS | 2202 | Values: 90.0 70.0 84.0 69.0 87.0 80.0 77.0 55.0 90.0 112.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#5] imaging-gaussian-blur Cycles:10 Average:95.20 Median:86.50 stddev:17.41 (20.1%) stddev-sans-first:18.45 08:02:57 INFO - PROCESS | 2202 | Values: 93.0 133.0 122.0 86.0 87.0 84.0 89.0 85.0 88.0 85.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#6] imaging-darkroom Cycles:10 Average:109.60 Median:96.00 stddev:22.63 (23.6%) stddev-sans-first:23.46 08:02:57 INFO - PROCESS | 2202 | Values: 96.0 112.0 168.0 119.0 96.0 98.0 97.0 96.0 96.0 118.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#7] imaging-desaturate Cycles:10 Average:86.70 Median:87.00 stddev:2.87 (3.3%) stddev-sans-first:3.00 08:02:57 INFO - PROCESS | 2202 | Values: 88.0 85.0 85.0 87.0 85.0 85.0 84.0 87.0 94.0 87.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.03 (1.8%) stddev-sans-first:1.00 08:02:57 INFO - PROCESS | 2202 | Values: 57.0 57.0 56.0 56.0 54.0 57.0 55.0 55.0 56.0 55.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:72.50 stddev:2.80 (3.9%) stddev-sans-first:2.47 08:02:57 INFO - PROCESS | 2202 | Values: 68.0 71.0 74.0 78.0 75.0 71.0 70.0 72.0 72.0 73.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:90.00 stddev:2.79 (3.1%) stddev-sans-first:2.96 08:02:57 INFO - PROCESS | 2202 | Values: 88.0 83.0 91.0 86.0 90.0 87.0 90.0 90.0 92.0 86.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#11] stanford-crypto-ccm Cycles:10 Average:156.30 Median:143.50 stddev:39.98 (27.9%) stddev-sans-first:41.97 08:02:57 INFO - PROCESS | 2202 | Values: 140.0 141.0 140.0 139.0 137.0 138.0 146.0 153.0 268.0 161.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:174.00 stddev:21.48 (12.3%) stddev-sans-first:22.59 08:02:57 INFO - PROCESS | 2202 | Values: 174.0 172.0 173.0 172.0 174.0 170.0 173.0 183.0 241.0 188.0 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - PROCESS | 2202 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:68.00 stddev:7.16 (10.5%) stddev-sans-first:7.43 08:02:57 INFO - PROCESS | 2202 | Values: 67.0 68.0 67.0 68.0 67.0 67.0 68.0 70.0 83.0 86.0 08:02:57 INFO - PROCESS | 2202 | -------- Summary: end -------- 08:02:57 INFO - PROCESS | 2202 | 08:02:57 INFO - TEST-INFO | 2202: exit 0 08:02:58 INFO - TEST-OK | kraken | took 59775ms 08:02:58 INFO - SUITE-END | took 1179s 08:02:58 INFO - Completed test suite (00:19:39) 08:02:58 INFO - javascript benchmark 08:02:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3210.5826242588464, 3292.390907682079, 3310.702036816686, 3337.9332413351717, 3363.915902102447, 5578.86052848678, 5632.887342253162, 5749.310082790066, 5768.19245305657, 5797.304323481183, 5625.1843482694985, 5671.06427439473, 5685.516731077853, 5691.7723291068305, 5711.371749107605, 5139.7430128493515, 5176.741162941857, 5201.661891977009, 5203.297554830087, 5240.0, 5097.006083813658, 5312.2828418163435, 5328.706921119337, 5340.439253878326, 5347.438518955492, 4529.682922195448, 5305.204219367087, 5316.282301889253, 5321.494458026501, 5327.334083239595, 5306.787728490855, 5308.336457942757, 5318.228856815779, 5326.387465441447, 5337.5196232338885, 4274.5939135782055, 5203.089459344599, 5263.31576895005, 5280.709560974145, 5282.943411317713, 5753.769849206027, 5783.392743761887, 5823.621464604829, 5903.409659034105, 5911.615744976562, 6283.183186185813, 6324.652144132071, 6337.809865704036, 6341.778037768702, 6349.8412539686415, 6174.228221472316, 6197.6901154942525, 6251.062340648894, 6277.0, 6293.30773614902, 5907.497862681635, 5919.881602367928, 5920.703964801743, 5924.8815023700145, 5926.851828704317, 6352.0, 6356.014817703263, 6356.3325850785, 6358.650274234869, 6365.96817015912, 6115.908261376083, 6136.171616831671, 6145.170401995673, 6152.969235153795, 6173.588659537429, 5926.088671623431, 6256.061590761331, 6336.809895703136, 6343.9365606343345, 6356.173697419306, 5486.341639003345, 5681.971590142023, 5842.480954951743, 6312.873742525124, 6313.810585682437, 6099.188726523911, 6313.684315784192, 6324.3991820776955, 6325.9051114233325, 6335.68321583919, 4886.096072226578, 4903.117438861039, 4937.0, 4937.987712518925, 4945.184044632667, 4445.622122119656, 4460.174867649431, 4461.7992190351515, 4494.393256910341, 4495.370648109268, 5823.2429784128635, 5829.446202610745, 5832.066869300891, 5835.649861008353, 5854.443827836349, 6170.7223174957235, 6208.906866397007, 6248.187735594434, 6286.779962701284, 6288.276848162428, 5748.942510574924, 5781.82654520365, 5830.562707796932, 5841.123831425235, 5856.531477481792, 5320.76056577455, 5354.518093371616, 5355.464453554691, 5392.218128371364, 5466.753996070186, 6294.181756371734, 6316.14732011191, 6352.047192921099, 6374.0, 6378.489720822416, 6203.348648391854, 6239.126522286792, 6260.40526150015, 6372.0760489729655, 6397.8720425592155, 6211.65835879031, 6344.873102537831, 6357.4596159326975, 6374.394432528902, 6383.808485745435, 6197.938020619917, 6207.410296021871, 6220.191375121205, 6227.346128656426, 6233.252009758677, 6189.659568723763, 6248.781292654735, 6335.081413194969, 6359.713812878525, 6362.745490180526, 4723.929141062818, 6069.0289553672965, 6130.386961303834, 6140.19751335002, 6161.353057928853, 5941.37615550361, 6152.938470615237, 6170.136180934763, 6176.6911654418, 6178.938210617836, 5791.420857914175, 5888.970555147197, 5997.760089596367, 6007.189029481139, 6012.939870601413, 6100.481459076028, 6163.137160797402, 6189.907151392643, 6242.469390101892, 6253.437190652863, 5028.421731500778, 6076.936536106075, 6077.453029227391, 6081.612732940851, 6109.313572000287], "value": 5716.839863205297, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.41195270054334, 464.6027646362362, 477.193542912478, 497.3633748801533, 499.02938784064986, 30443.63003664835, 31960.840195799046, 31989.880354187637, 32028.87898923538, 32030.519542206857, 37677.81161094198, 40510.7974460128, 42293.57706422935, 42330.0, 43433.56566434335, 9258.21305189058, 9373.953130234338, 9429.198518125933, 9448.66929657462, 9459.810803783954, 9327.39371940825, 9457.0, 9461.574229159703, 9474.763130921714, 9482.857757133614, 8408.0, 8605.61274742635, 11347.546098156065, 11596.362200079035, 11601.0, 11387.259828111188, 11460.770784584345, 11481.483333250024, 11486.770264594661, 11504.482298296596, 10149.543270552844, 10697.823239443655, 11351.26216795914, 11352.943235283854, 11356.205065645408, 30639.540406893913, 31145.0, 38998.805005975075, 39340.705487122206, 39506.40740388897, 3771.5146406786953, 3813.1229817141934, 4032.8185231664647, 4039.596040395937, 4140.896477588054, 26044.479110417684, 28453.430931381463, 29041.56437653437, 30535.694643053506, 30810.84594577013, 2778.9444211115665, 3288.96711032889, 3308.3052558962645, 3327.7005069543857, 3335.0661814691925, 490660.0, 490959.5452022753, 492189.0, 493016.0, 493815.0, 62758.0, 62797.05804412937, 62884.0, 62895.0, 63209.0, 66383.33616663772, 66427.0, 66465.0, 66490.0, 66512.66743666347, 15245.466408675644, 15679.05925644465, 15785.842141578667, 15845.603859903595, 15861.52415427539, 12113.454894529767, 12170.330631815157, 12176.75646487083, 12183.329916854658, 12191.634250972485, 14397.496087637091, 15607.531774046796, 18135.546611334557, 18173.818261817214, 18217.0, 5890.970545147246, 6295.937040629627, 7074.0, 7135.179454362711, 7193.388561972291, 698.8148140742708, 850.2815121222557, 878.885744853165, 888.6889588643922, 889.9199072083414, 29145.708542914723, 29374.706252937624, 29793.851030744707, 29875.701242987292, 29955.25111872177, 1019.4240254256322, 1063.4310643805527, 1067.797118547489, 1067.9412632305143, 1139.464451707696, 13273.402696878664, 14354.354054067591, 14955.551333460013, 15289.464868729541, 15435.536933892, 506526.1516002896, 508635.0, 509112.0, 510929.0, 515274.0, 57107.71446142826, 57166.42833571611, 57383.0, 57413.712931435075, 57486.7125664369, 1307.3397934043437, 1316.3813007886279, 1347.083982891643, 1385.3142694366354, 1410.9365078571489, 28311.43377132487, 28430.857845710638, 28517.572236416887, 29371.265718357212, 29872.25319367033, 49328.50671493383, 49614.75192624014, 49764.502354977434, 49775.75112124416, 49795.0, 383692.0, 385046.5701101344, 385759.0, 386263.0, 387028.0, 38501.42247866228, 38981.27131100796, 39318.0, 39565.40651890166, 39647.2070558593, 39230.0, 45633.31550026686, 46912.29631555461, 47438.0, 49622.0, 37938.241235175694, 38362.6163738359, 38369.42445863259, 39593.406098907966, 39752.60247397489, 31039.53440698346, 34541.963741087806, 37932.0, 37964.24071518499, 39077.80461097676, 37141.25717485581, 37685.434718479795, 38136.809315953244, 38401.271942763204, 38420.231595368496, 32067.518987215677, 33509.64903509629, 34081.65918340785, 34263.82868085644, 34594.82702586471, 24554.754452455247, 24637.753622464264, 24745.752542474343, 25201.117960872016, 25327.366815829013], "value": 19533.023533771786, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.9888403347899, 372.93846515324975, 376.4203127184136, 392.81930312056454, 400.95589485156626, 15584.92207538962, 16266.105364204965, 18948.526286842836, 19824.60350792985, 19968.60062798743, 11902.2263552869, 14070.718585628281, 16817.32730690774, 19570.902145489254, 19587.314443994426, 5050.585835965926, 5305.867353316179, 5306.814261500848, 5309.256704061435, 5310.07073762092, 3825.5791862895057, 3995.5205375355, 4051.412545180947, 4057.2899742544937, 6469.8706025879455, 5852.736626851802, 6081.722838203982, 6491.22105347359, 6498.740050397979, 6501.154849869534, 4946.703197808107, 5905.438983296602, 5932.881342373172, 5944.940550594482, 6469.4177524022825, 5722.942770572325, 5725.742341594639, 5728.341240757324, 5728.398518155575, 5743.655380677173, 12920.160189587647, 13033.934830325883, 13174.209547427185, 13203.151558781778, 13206.226692394608, 2565.115035312814, 2572.8323986302125, 2573.4724381501933, 2583.7028741694758, 2590.248827839924, 9061.0, 9722.513874306256, 9889.060539248832, 10178.083972442515, 10289.794204115951, 1496.8428315026983, 1565.2721484509643, 1834.5872178759805, 1846.6675998320297, 1847.4272975377542, 81109.59445202796, 119165.0, 119529.0, 119894.0, 120727.0, 11102.666919992413, 11252.0, 11306.491207895664, 11308.717282068013, 11499.597514086967, 14436.422543098159, 14450.060746051473, 14464.0, 14473.421063157355, 14526.85473145255, 8100.3519718422385, 8251.174882511701, 8313.501189928624, 8379.581020948928, 8390.790230244293, 8038.45040476739, 8071.838563228703, 8134.145914678874, 8293.709720159766, 8298.543580103153, 4409.977950110229, 4497.370368148462, 4512.300593408027, 4587.931181032287, 4600.60894823937, 1875.8593105516868, 1933.1977229449622, 1938.4378530226359, 1941.79611140831, 1945.4358236111368, 709.7622296530617, 818.3126174013738, 850.6172222499899, 861.6165806216299, 867.509856930832, 14618.853811461962, 17529.0, 18801.717974230396, 19339.8066019338, 19541.108795498014, 866.8829707989466, 922.7231830450838, 923.2244914272044, 955.6894009446958, 959.9808003839884, 5235.528802407802, 5944.6730429826775, 6284.497240220863, 6480.092787009728, 6643.302453242437, 494318.0, 503057.0, 507421.0, 512052.0, 531793.0, 122152.38923805323, 122796.0, 123903.08708876741, 127359.36320318338, 129465.05802412971, 996.7159359582485, 1012.6303899076784, 1039.1219419590434, 1039.2673165418362, 1041.770810421706, 11581.82627260575, 11679.416029198675, 11688.47401866935, 11730.296182229093, 11814.232074915102, 38664.0, 38804.53747818924, 42162.78918605387, 46933.3573324932, 50817.0, 62210.37789622046, 77512.0, 78018.0, 78130.0, 80881.59559202167, 7951.84096318082, 8093.87859182101, 8161.347092232489, 8190.877136843072, 8418.831623367621, 10445.373277603368, 12292.631221063382, 12530.74938501243, 12577.685557861127, 13025.609231722685, 10985.143862595623, 11101.278416902875, 11171.486415556436, 11187.832182517432, 11239.04468120219, 8924.464532128093, 10845.457727113771, 11149.554017839195, 11361.750207477337, 11484.253523520945, 10351.741206469898, 11169.441527923735, 11280.661580152608, 11450.484728187084, 11874.16880818369, 6997.930020699728, 7235.963820180866, 7274.199838017919, 7459.142198647225, 8209.630566624397, 9530.377766782965, 9818.410895346302, 9977.054474377426, 10677.199210059121, 11241.381724005258], "value": 8727.67919693665, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.4877395363715, 1389.270632917718, 1405.873471387575, 1406.978895316571, 1412.0892024644102, 7961.16407777183, 8232.958835205824, 8244.628991695366, 8324.334053275723, 8375.329973602113, 7384.630768461582, 7576.0, 7605.8478830423355, 7996.760097197079, 8163.795905102375, 2258.390234636647, 2321.674965504822, 2323.6979192704925, 2324.883755812211, 2325.8604483730946, 2245.348848833836, 2274.7384050834116, 2285.5086156476355, 2289.1186893046215, 2289.9656505152434, 2022.8280596149345, 2102.484891201658, 2239.496113374486, 2299.5515874404496, 2328.441174118216, 1957.725918371415, 2280.8061314788265, 2301.4476525633895, 2321.86228747914, 2330.976690233093, 2172.717546718932, 2183.224955140934, 2193.429708275844, 2199.9010044548036, 2206.7572567017614, 7183.6408179591335, 7322.1945585985495, 8035.276825085712, 8132.146124656885, 8157.796055098611, 4977.8257760978195, 5133.204353325241, 5133.897322053538, 5141.691498510101, 5147.562457191143, 3138.3880143372053, 3310.3710295043866, 3363.276895467486, 3369.1913940654313, 3377.6115746689197, 1145.7422080032009, 1167.1479819731558, 1200.2318516149671, 1226.7853125702966, 1230.5693007447412, 10696.358218506837, 10696.946515267375, 10697.786044279072, 10713.357198568034, 10745.462726863703, 3816.3512202925012, 4466.330050492387, 4492.213862574036, 4518.249265547936, 4538.183127037165, 9184.311176661777, 9215.44707317563, 9220.170184683277, 9220.216281616138, 9225.261979041661, 5091.974540127276, 5116.667416617908, 5200.791968321225, 5201.713905735145, 5203.271541984125, 4011.498562679665, 4170.395292682605, 4717.268823332389, 4738.099761045391, 4757.09615173116, 3667.9633203668154, 3729.310077635646, 3744.213715119801, 3786.545614526219, 3808.7333886628226, 1049.7113293844102, 1064.414571985405, 1235.5799028330232, 1236.0852968803022, 1241.3979220078254, 714.2536049827942, 719.8524302517972, 742.0983505041401, 782.4679218131726, 872.3806097670725, 5143.0, 5273.655217919406, 5396.082665946798, 5427.9185812212845, 5492.231087647732, 758.525921299188, 761.6534476813036, 850.7022542110336, 885.8892638420198, 901.7565257380602, 3486.1981744198974, 3495.598006229265, 3534.1871369585137, 3600.5499312585926, 3624.4744512045604, 9270.44377337362, 9813.0, 9848.162906152915, 9951.054649808402, 9955.601775928882, 9607.183389411979, 9873.160781333667, 9906.752331191634, 9977.551010204414, 10016.54925528353, 7482.401407887356, 7645.961770191114, 7659.961700191463, 7839.0, 7856.607169641609, 4512.0750246198795, 5088.287639730367, 5271.420143784104, 5390.946090539044, 5433.728313584384, 8844.734657960014, 8991.190792828676, 9121.133492318218, 9146.359754817098, 9254.676086336945, 9116.179543841086, 9288.62845486173, 9295.0, 9349.0, 9399.248060155034, 6513.804585862432, 6718.664066796543, 6719.630420326929, 6945.201301850352, 7098.041764361745, 7101.928980710127, 7462.701491940262, 7822.530648161129, 7922.762317130495, 7939.84120317602, 6936.167659880846, 7010.474214433836, 7033.296670332926, 7065.894011589728, 7156.463265255231, 6893.931060689329, 6900.102986611709, 6917.342852429011, 6952.930470695431, 6960.37356637905, 6087.786927457518, 6267.4986001120715, 6369.044643303541, 6397.680115994089, 6402.327755585597, 4057.837686492507, 4665.5101214372, 4671.672982891165, 4722.173619616622, 4826.8069277228515, 4438.667099967451, 4678.555537223959, 4763.214069678603, 4800.183968725255, 4850.417949846041], "value": 4262.388549705811, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.0610424385062, 346.04664150266024, 351.99648003519974, 354.5444104325941, 364.087959661049, 4213.3469312256575, 4826.396700412449, 4839.943024214708, 5211.45279745627, 5215.973920130399, 4098.9590104099025, 4828.46886842447, 5170.870728231796, 5181.559567436772, 5190.9740451297785, 3423.4864770284407, 3663.212409331993, 4031.475908131946, 4033.112715202665, 4034.616711412411, 3310.337932413515, 3751.2990646734443, 3947.3486874665646, 4089.8568550100754, 4093.2222877653153, 3561.0, 4052.946233979157, 4099.528554216258, 4125.546189919107, 4128.256913755523, 3440.7935523868523, 3684.7420680552373, 3850.403187505941, 3994.460747799039, 4141.668666506687, 3348.296857659894, 3544.4302880411497, 3610.4403817408343, 3645.343838109139, 3648.6168952259895, 4700.673166782431, 4765.785539650689, 5129.487051294878, 5157.30376399185, 5157.4068982067165, 4138.79306034697, 4560.407147070892, 4678.789454474523, 4699.882502937419, 4715.41057367829, 4845.4185497740145, 5050.823221187277, 5124.282600435942, 5463.672179669193, 5469.316335458067, 3353.748468864845, 3462.342154990569, 3649.708023358125, 3973.304671682444, 3980.8805735827973, 4686.73507108533, 4801.279808028754, 4803.78382972767, 5329.0674132027125, 5567.109262517978, 3306.966930330714, 3960.4455376247347, 3963.6432721055244, 4060.5990933128023, 4067.4508941292547, 3414.2147306119236, 3573.8927832165077, 3946.625070618287, 4057.736247143926, 4182.895427614334, 3706.8887933361502, 4269.87190384289, 4332.1119170569955, 4359.869203923888, 4753.643476739189, 4655.837045703384, 4918.360413294136, 4980.302757613937, 5087.97456012725, 5541.445855414427, 1230.5262473947416, 1287.46570173377, 1338.070041321292, 1345.219772531929, 1346.9897576817386, 851.8211175653058, 882.5587206396801, 946.5456580841235, 967.3277072434731, 969.132626299458, 4402.581754733295, 4499.345386222145, 4506.121306345297, 4614.700044497097, 4875.853724388274, 4780.01249219238, 4999.250112483084, 5230.2666087191155, 5384.623076384693, 5401.7299135043095, 4802.231642937113, 4880.389951256093, 4962.429320628102, 4981.363604568613, 5028.572571331478, 2455.742147074567, 2502.398464982413, 2718.3340081680117, 2899.289419242657, 2916.314666053476, 5633.605647604711, 5646.774129034875, 5658.066419040812, 5695.772169113189, 5765.567582431335, 5087.016063735146, 5176.456472070378, 5239.973800130975, 5634.0, 5743.971280143656, 4768.189407800752, 5593.776248950077, 5645.661260324394, 5645.745941432645, 5683.54531637468, 4973.751312434291, 5082.119615742199, 5472.233887255867, 5487.807926722546, 5506.229127922014, 5016.774245158904, 5232.5552328052545, 5454.754536045967, 5471.534919531725, 5506.366767821752, 5195.960807838523, 5330.706811125426, 5377.736231985558, 5600.523955463831, 5860.472557469848, 3591.8147011485626, 3687.539057617798, 3823.6558709715146, 3882.650561449483, 3913.6673382762783, 4848.927266091082, 5697.54419646419, 5775.7400916957995, 5801.0, 5821.912671310019, 4742.5731684148595, 4905.386826646669, 5338.839834804962, 5597.244372009726, 5617.382087970238, 4506.549345065468, 4745.691530050535, 5037.042961837239, 5618.606697531133, 5619.466150715675, 4533.773311334486, 5135.666181698178, 5217.104506998652, 5356.116240820221, 5500.312460942382, 4522.683412161117, 4642.76966603857, 4741.691790033637, 5411.269478620493, 5635.478420826272, 5404.108322126805, 5412.404635490014, 5414.648047876875, 5415.296011518478, 5462.9726851365485], "value": 3971.3807118984278, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.0399872359947, 708.3589351636764, 744.9478536502447, 779.212994875176, 790.8932294140291, 14799.33402996865, 15707.685846283068, 15726.056436613782, 15744.448944286953, 15748.448804291853, 14075.507357242499, 14222.0, 14229.075110117861, 14246.0, 14249.71500569988, 663.8041777675597, 685.2496516314652, 688.8484533402643, 837.8198687282234, 863.6459051788768, 859.3683642522741, 865.9350548708841, 881.920627143557, 882.7175303902753, 885.2652298592153, 939.8355287824603, 941.750436134425, 942.1238248428958, 943.3585162089777, 945.0785484152965, 706.0926709178681, 728.7922941961516, 933.9959543490775, 936.8782058332441, 936.9672061477816, 658.0, 678.2573082474682, 683.5215349255541, 685.4310921934782, 686.9690863911136, 14901.776473352907, 14948.62628434287, 14949.925250373788, 14954.850451495455, 14979.400823967137, 12442.440090195963, 12771.552995645108, 12785.936070319682, 12889.484420623165, 12923.289219092947, 12513.061520385916, 12672.23966562, 12692.048096392808, 12889.935550322281, 13228.074034817566, 4340.804663790137, 4372.322290045048, 4392.209402307583, 4453.4433195850515, 4508.819647214107, 99736.00263997339, 103445.44831827529, 103720.96279037188, 104063.55300916893, 104725.0, 9856.457894815854, 10341.74145646365, 10453.425061621683, 10480.947595261974, 10772.892271077346, 15422.46021389246, 19941.800581994285, 19954.501137471678, 19992.800071999092, 20069.498262543555, 7327.413806895435, 7437.256274372519, 7523.247675232433, 7566.010084701514, 7608.657610407545, 4098.139390727921, 4125.66994640434, 4242.85150019754, 4321.13577284538, 4334.414853994734, 3326.7172290355593, 3353.279045005336, 3422.828858557112, 3427.7771944823508, 3428.3828910795796, 564.4524810933389, 604.6674329118968, 620.5314987184647, 622.9626222426668, 634.058423241486, 492.90141971605823, 578.8581797459565, 654.2835595023441, 672.9360710732473, 676.1345477788439, 7525.548467091992, 8790.505614045627, 9113.270938325051, 9195.678151264674, 9490.857637135448, 531.3305235403419, 541.5667466027242, 622.9938649081791, 653.1182903080803, 659.7525927777084, 4384.956150438455, 4416.470023597189, 4422.646188304992, 4537.205988951986, 4563.881848946963, 98855.0, 107117.464412679, 110636.0, 111043.0, 112054.0, 95697.56453653201, 96223.51888240654, 96635.0, 97398.5130074345, 97670.02329976752, 64323.678381607795, 82838.51484455475, 84129.57935210285, 85146.0, 85248.0, 8690.869636955576, 8754.649814007369, 8758.124187581447, 8844.955775221082, 8901.287896968097, 36223.63776362308, 49676.25485617791, 50061.0, 51763.0, 51783.0, 65754.0, 65882.34117658954, 67708.6614566924, 68217.63564728579, 68829.07200519697, 12474.58169082337, 12901.741965160832, 12925.741485170432, 12951.0, 13113.147645403018, 14373.209473479059, 14723.558293251219, 14922.305639388682, 15064.6987060256, 15299.847001529843, 11893.870082342164, 13361.39873705705, 13571.660708482366, 13860.930695346458, 14107.624253436928, 10489.370637761758, 12594.87405125937, 13483.460661573426, 13540.458381664623, 14058.226797526235, 13091.214527128403, 13133.277669727875, 13146.539871104847, 13265.535706250621, 13812.585622431343, 11122.833157502806, 11142.331460112418, 11234.943825280821, 11413.486393112496, 11617.244879082833, 9928.269725825456, 11071.391073491035, 11246.437678116225, 11401.828972565252, 11571.537138514364], "value": 7358.414463688438, "unit": "score"}], "name": "dromaeo_css", "value": 7036.799304485986, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 114.0, 114.0, 115.0, 115.0, 114.0, 114.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 132.0, 183.0, 109.0, 110.0, 110.0, 108.0, 130.0, 109.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 184.0, 187.0, 184.0, 182.0, 180.0, 184.0, 184.0, 183.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 88.0, 62.0, 60.0, 59.0, 58.0, 73.0, 58.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 84.0, 69.0, 87.0, 80.0, 77.0, 55.0, 90.0, 112.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 133.0, 122.0, 86.0, 87.0, 84.0, 89.0, 85.0, 88.0, 85.0], "value": 95.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 112.0, 168.0, 119.0, 96.0, 98.0, 97.0, 96.0, 96.0, 118.0], "value": 109.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 85.0, 87.0, 85.0, 85.0, 84.0, 87.0, 94.0, 87.0], "value": 86.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 56.0, 54.0, 57.0, 55.0, 55.0, 56.0, 55.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 74.0, 78.0, 75.0, 71.0, 70.0, 72.0, 72.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 91.0, 86.0, 90.0, 87.0, 90.0, 90.0, 92.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 140.0, 139.0, 137.0, 138.0, 146.0, 153.0, 268.0, 161.0], "value": 156.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 172.0, 173.0, 172.0, 174.0, 170.0, 173.0, 183.0, 241.0, 188.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 68.0, 67.0, 67.0, 68.0, 70.0, 83.0, 86.0], "value": 71.1, "unit": "score"}], "name": "kraken", "value": 1485.8, "alertThreshold": 2.0}]} 08:02:58 INFO - Return code: 0 08:02:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:02:58 INFO - # TBPL SUCCESS # 08:02:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:02:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:02:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:02:58 INFO - 2017-01-03 08:02:58.379 screenresolution[2206:26690] starting screenresolution argv=screenresolution get 08:02:58 INFO - 2017-01-03 08:02:58.381 screenresolution[2206:26690] Display 0: 1600x1200x32@60 08:02:58 INFO - 2017-01-03 08:02:58.387 screenresolution[2207:26693] starting screenresolution argv=screenresolution list 08:02:58 INFO - Available Modes on Display 0 08:02:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:02:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:02:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:02:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:02:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:02:58 INFO - Intel Iris: 08:02:58 INFO - Chipset Model: Intel Iris 08:02:58 INFO - Type: GPU 08:02:58 INFO - Bus: Built-In 08:02:58 INFO - VRAM (Dynamic, Max): 1536 MB 08:02:58 INFO - Vendor: Intel (0x8086) 08:02:58 INFO - Device ID: 0x0a2e 08:02:58 INFO - Revision ID: 0x0009 08:02:58 INFO - Displays: 08:02:58 INFO - Display: 08:02:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:02:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:02:58 INFO - Display Serial Number: 1600x1200 60 08:02:58 INFO - Main Display: Yes 08:02:58 INFO - Mirror: Off 08:02:58 INFO - Online: Yes 08:02:58 INFO - Rotation: Supported 08:02:58 INFO - Return code: 0 08:02:58 INFO - Running post-action listener: _resource_record_post_action 08:02:58 INFO - [mozharness: 2017-01-03 16:02:58.631172Z] Finished run-tests step (success) 08:02:58 INFO - Running post-run listener: _resource_record_post_run 08:02:58 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 0; Write bytes: 553220608; Read time: 0; Write time: 1678 08:02:58 INFO - TinderboxPrint: CPU usage
53.8% 08:02:58 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:02:58 INFO - TinderboxPrint: I/O write bytes / time
553,220,608 / 1,678 08:02:58 INFO - TinderboxPrint: CPU idle
2,222.0 (46.2%) 08:02:58 INFO - TinderboxPrint: CPU system
968.1 (20.1%) 08:02:58 INFO - TinderboxPrint: CPU user
1,619.7 (33.7%) 08:02:58 INFO - TinderboxPrint: Swap in / out
249,143,296 / 0 08:02:58 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250577408; Write bytes: 254269440; Read time: 18383; Write time: 563 08:02:58 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 0; Write bytes: 297546240; Read time: 0; Write time: 1104 08:02:59 INFO - Running post-run listener: _upload_blobber_files 08:02:59 INFO - Blob upload gear active. 08:02:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:02:59 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 08:02:59 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:02:59 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:02:59 INFO - (blobuploader) - INFO - Open directory for files ... 08:02:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:02:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:03:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:00 INFO - (blobuploader) - INFO - Done attempting. 08:03:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:03:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:03:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:01 INFO - (blobuploader) - INFO - Done attempting. 08:03:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:03:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:03:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:02 INFO - (blobuploader) - INFO - Done attempting. 08:03:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:03:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:03:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:04 INFO - (blobuploader) - INFO - Done attempting. 08:03:04 INFO - (blobuploader) - INFO - Iteration through files over. 08:03:04 INFO - Return code: 0 08:03:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:03:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:03:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/40dfb9f63d8cc3c8bac31053a0ea5ab4467361575681b324f0b7a496e144bc313a246321e8668fcf19a63b590058e603bec9d25104b763b011192d1c1fa1fa86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/79a8957c20d18196b10e3843e756c0967ff11a842a1191a5240678e4f22df322affc21c7798ba1bd96dd8bcfd62b5e04309e63f9af15e9193241b42554de0219", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/697142ac5107a9f5cd2a586e2fd7c85c52a9466ceb1541256e6b75d6bdc7e0d2227001fd3c6dc26990adf5e07f940700d962aa77676319f1f36ad2114ecdc240", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4a22eff7df3d5b81f34ba629496624b80581a264f5412afafc955e8904d1e737a28fd6c27a27333776ef60feecbbd3f90808f01abf225af07b309e939f2cff6d"} 08:03:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:03:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:03:04 INFO - Contents: 08:03:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/40dfb9f63d8cc3c8bac31053a0ea5ab4467361575681b324f0b7a496e144bc313a246321e8668fcf19a63b590058e603bec9d25104b763b011192d1c1fa1fa86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/79a8957c20d18196b10e3843e756c0967ff11a842a1191a5240678e4f22df322affc21c7798ba1bd96dd8bcfd62b5e04309e63f9af15e9193241b42554de0219", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/697142ac5107a9f5cd2a586e2fd7c85c52a9466ceb1541256e6b75d6bdc7e0d2227001fd3c6dc26990adf5e07f940700d962aa77676319f1f36ad2114ecdc240", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4a22eff7df3d5b81f34ba629496624b80581a264f5412afafc955e8904d1e737a28fd6c27a27333776ef60feecbbd3f90808f01abf225af07b309e939f2cff6d"} 08:03:04 INFO - Running post-run listener: copy_logs_to_upload_dir 08:03:04 INFO - Copying logs to upload dir... 08:03:04 INFO - mkdir: /builds/slave/test/build/upload/logs 08:03:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.837766 ========= master_lag: 3.10 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-03 08:03:07.560221) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 08:03:07.563722) ========= 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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/40dfb9f63d8cc3c8bac31053a0ea5ab4467361575681b324f0b7a496e144bc313a246321e8668fcf19a63b590058e603bec9d25104b763b011192d1c1fa1fa86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/79a8957c20d18196b10e3843e756c0967ff11a842a1191a5240678e4f22df322affc21c7798ba1bd96dd8bcfd62b5e04309e63f9af15e9193241b42554de0219", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/697142ac5107a9f5cd2a586e2fd7c85c52a9466ceb1541256e6b75d6bdc7e0d2227001fd3c6dc26990adf5e07f940700d962aa77676319f1f36ad2114ecdc240", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4a22eff7df3d5b81f34ba629496624b80581a264f5412afafc955e8904d1e737a28fd6c27a27333776ef60feecbbd3f90808f01abf225af07b309e939f2cff6d"} build_url:https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011457 build_url: 'https://queue.taskcluster.net/v1/task/DKkm-Gk_RVuPHk2t330y4A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/40dfb9f63d8cc3c8bac31053a0ea5ab4467361575681b324f0b7a496e144bc313a246321e8668fcf19a63b590058e603bec9d25104b763b011192d1c1fa1fa86", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/79a8957c20d18196b10e3843e756c0967ff11a842a1191a5240678e4f22df322affc21c7798ba1bd96dd8bcfd62b5e04309e63f9af15e9193241b42554de0219", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/697142ac5107a9f5cd2a586e2fd7c85c52a9466ceb1541256e6b75d6bdc7e0d2227001fd3c6dc26990adf5e07f940700d962aa77676319f1f36ad2114ecdc240", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4a22eff7df3d5b81f34ba629496624b80581a264f5412afafc955e8904d1e737a28fd6c27a27333776ef60feecbbd3f90808f01abf225af07b309e939f2cff6d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 08:03:07.599129) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 08:03:07.599431) ========= 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.0yEOuxFGCd/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.rI5cSHDU5w/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.004589 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 08:03:07.646763) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 08:03:07.647061) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 08:03:07.647339) ========= ========= Total master_lag: 3.24 =========