builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0325 starttime: 1483980238.94 results: success (0) buildid: 20170109072309 builduid: f1e3ca26bcc546f6a337ddc30d53ba7f revision: eefd48f36d82304bd1a4bb8626b29ef5ee54bb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 08:43:58.938853) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 08:43:58.939268) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 08:43:58.939583) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 08:43:58.965845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:43:58.966131) ========= 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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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-09 08:43:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2017-01-09 08:43:59 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109565 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:43:59.111273) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:43:59.111599) ========= 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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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.025627 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:43:59.161833) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 08:43:59.162180) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev eefd48f36d82304bd1a4bb8626b29ef5ee54bb80 --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 eefd48f36d82304bd1a4bb8626b29ef5ee54bb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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-09 08:43:59,258 truncating revision to first 12 chars 2017-01-09 08:43:59,258 Setting DEBUG logging. 2017-01-09 08:43:59,258 attempt 1/10 2017-01-09 08:43:59,258 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/eefd48f36d82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 08:44:00,355 unpacking tar archive at: graphics-eefd48f36d82/testing/mozharness/ program finished with exit code 0 elapsedTime=1.386061 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 08:44:00.574535) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 08:44:00.574955) ========= script_repo_revision: eefd48f36d82304bd1a4bb8626b29ef5ee54bb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 08:44:00.575397) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 08:44:00.575670) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 08:44:00.597195) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 50 secs) (at 2017-01-09 08:44:00.597573) ========= /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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 08:44:00 INFO - MultiFileLogger online at 20170109 08:44:00 in /builds/slave/test 08:44:00 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 08:44:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:44:00 INFO - {'append_to_log': False, 08:44:00 INFO - 'base_work_dir': '/builds/slave/test', 08:44:00 INFO - 'blob_upload_branch': 'Graphics', 08:44:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:44:00 INFO - 'branch': 'Graphics', 08:44:00 INFO - 'buildbot_json_path': 'buildprops.json', 08:44:00 INFO - 'config_files': ('talos/mac_config.py',), 08:44:00 INFO - 'default_actions': ('clobber', 08:44:00 INFO - 'read-buildbot-config', 08:44:00 INFO - 'download-and-extract', 08:44:00 INFO - 'populate-webroot', 08:44:00 INFO - 'create-virtualenv', 08:44:00 INFO - 'install', 08:44:00 INFO - 'run-tests'), 08:44:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:44:00 INFO - 'download_minidump_stackwalk': True, 08:44:00 INFO - 'download_symbols': 'ondemand', 08:44:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:44:00 INFO - 'tooltool.py': '/tools/tooltool.py', 08:44:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:44:00 INFO - '/tools/misc-python/virtualenv.py')}, 08:44:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:44:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:44:00 INFO - 'installer_path': 'installer.exe', 08:44:00 INFO - 'log_level': 'info', 08:44:00 INFO - 'log_name': 'talos', 08:44:00 INFO - 'log_to_console': True, 08:44:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:44:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:44:00 INFO - 'opt_config_files': (), 08:44:00 INFO - 'pip_index': False, 08:44:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:44:00 INFO - 'cmd': ('bash', 08:44:00 INFO - '-c', 08:44:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:44:00 INFO - 'enabled': True, 08:44:00 INFO - 'halt_on_failure': False, 08:44:00 INFO - 'name': 'check_screen_resolution'},), 08:44:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:44:00 INFO - 'cmd': ('bash', 08:44:00 INFO - '-c', 08:44:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:44:00 INFO - 'enabled': True, 08:44:00 INFO - 'halt_on_failure': False, 08:44:00 INFO - 'name': 'check_screen_resolution'},), 08:44:00 INFO - 'run_cmd_checks_enabled': True, 08:44:00 INFO - 'sps_profile': False, 08:44:00 INFO - 'sps_profile_interval': 0, 08:44:00 INFO - 'suite': 'dromaeojs', 08:44:00 INFO - 'system_bits': '32', 08:44:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:44:00 INFO - 'title': 't-yosemite-r7-0325', 08:44:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:44:00 INFO - 'use_talos_json': True, 08:44:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:44:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:44:00 INFO - 'work_dir': 'build'} 08:44:00 INFO - [mozharness: 2017-01-09 16:44:00.803696Z] Running clobber step. 08:44:00 INFO - Running pre-action listener: _resource_record_pre_action 08:44:00 INFO - Running main action method: clobber 08:44:00 INFO - rmtree: /builds/slave/test/build 08:44:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:44:03 INFO - Running post-action listener: _resource_record_post_action 08:44:03 INFO - [mozharness: 2017-01-09 16:44:03.189087Z] Finished clobber step (success) 08:44:03 INFO - [mozharness: 2017-01-09 16:44:03.189252Z] Running read-buildbot-config step. 08:44:03 INFO - Running pre-action listener: _resource_record_pre_action 08:44:03 INFO - Running main action method: read_buildbot_config 08:44:03 INFO - Using buildbot properties: 08:44:03 INFO - { 08:44:03 INFO - "project": "", 08:44:03 INFO - "product": "firefox", 08:44:03 INFO - "script_repo_revision": "default", 08:44:03 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 08:44:03 INFO - "repository": "", 08:44:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 08:44:03 INFO - "buildid": "20170109072309", 08:44:03 INFO - "pgo_build": "False", 08:44:03 INFO - "basedir": "/builds/slave/test", 08:44:03 INFO - "buildnumber": 0, 08:44:03 INFO - "slavename": "t-yosemite-r7-0325", 08:44:03 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:44:03 INFO - "platform": "yosemite_r7", 08:44:03 INFO - "scheduler": "tests-graphics-macosx64-talos", 08:44:03 INFO - "branch": "graphics", 08:44:03 INFO - "repo_path": "projects/graphics", 08:44:03 INFO - "revision": "eefd48f36d82304bd1a4bb8626b29ef5ee54bb80", 08:44:03 INFO - "stage_platform": "macosx64", 08:44:03 INFO - "builduid": "f1e3ca26bcc546f6a337ddc30d53ba7f", 08:44:03 INFO - "slavebuilddir": "test" 08:44:03 INFO - } 08:44:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:44:03 INFO - Running post-action listener: _resource_record_post_action 08:44:03 INFO - [mozharness: 2017-01-09 16:44:03.192598Z] Finished read-buildbot-config step (success) 08:44:03 INFO - [mozharness: 2017-01-09 16:44:03.192707Z] Running download-and-extract step. 08:44:03 INFO - Running pre-action listener: _resource_record_pre_action 08:44:03 INFO - Running main action method: download_and_extract 08:44:03 INFO - mkdir: /builds/slave/test/build/tests 08:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:03 INFO - trying https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:44:03 INFO - Downloading https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/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 08:44:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/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 08:44:04 INFO - Downloaded 1199 bytes. 08:44:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:44:04 INFO - Using the following test package requirements: 08:44:04 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:44:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:44:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:44:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:04 INFO - u'jsshell-mac.zip'], 08:44:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:44:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:44:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:44:04 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:44:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:44:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:44:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:44:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:44:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:44:04 INFO - Fetch https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:44:05 INFO - Expected file size: 16304854 08:44:05 INFO - Obtained file size: 16304854 08:44:11 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 08:44:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:44:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:44:11 INFO - Fetch https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:44:12 INFO - Expected file size: 16304854 08:44:12 INFO - Obtained file size: 16304854 08:44:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:44:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:44:18 INFO - Fetch https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:45:02 INFO - retry: attempt #1 caught exception: The read operation timed out 08:45:02 INFO - retry: Failed, sleeping 60 seconds before retrying 08:46:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #2 08:46:02 INFO - Fetch https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:46:04 INFO - Expected file size: 14403537 08:46:04 INFO - Obtained file size: 14403537 08:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:04 INFO - trying https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:46:04 INFO - Downloading https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:46:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/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 08:46:06 INFO - Downloaded 69917870 bytes. 08:46:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:46:06 INFO - mkdir: /builds/slave/test/properties 08:46:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:46:06 INFO - Writing to file /builds/slave/test/properties/build_url 08:46:06 INFO - Contents: 08:46:06 INFO - build_url:https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:46:07 INFO - Running post-action listener: _resource_record_post_action 08:46:07 INFO - Running post-action listener: set_extra_try_arguments 08:46:07 INFO - [mozharness: 2017-01-09 16:46:07.148951Z] Finished download-and-extract step (success) 08:46:07 INFO - [mozharness: 2017-01-09 16:46:07.149116Z] Running populate-webroot step. 08:46:07 INFO - Running pre-action listener: _resource_record_pre_action 08:46:07 INFO - Running main action method: populate_webroot 08:46:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:46:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:46:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:46:07 INFO - '--tppagecycles', 08:46:07 INFO - '7'], 08:46:07 INFO - 'tests': ['tsvgm']}}, 08:46:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:46:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:46:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:46:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:46:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:46:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:46:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:46:07 INFO - 'talos_options': ['--disable-e10s'], 08:46:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:46:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:46:07 INFO - 'talos_options': ['--disable-e10s'], 08:46:07 INFO - 'tests': ['damp', 'tps']}, 08:46:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:46:07 INFO - 'tests': ['damp', 'tps']}, 08:46:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:46:07 INFO - 'tests': ['dromaeo_dom']}, 08:46:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:46:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:46:07 INFO - 'tests': ['basic_compositor_video']}, 08:46:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:46:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:46:07 INFO - 'tests': ['a11yr', 08:46:07 INFO - 'ts_paint', 08:46:07 INFO - 'tpaint', 08:46:07 INFO - 'sessionrestore', 08:46:07 INFO - 'sessionrestore_no_auto_restore', 08:46:07 INFO - 'tabpaint']}, 08:46:07 INFO - 'other-e10s': {'tests': ['a11yr', 08:46:07 INFO - 'ts_paint', 08:46:07 INFO - 'tpaint', 08:46:07 INFO - 'sessionrestore', 08:46:07 INFO - 'sessionrestore_no_auto_restore', 08:46:07 INFO - 'tabpaint']}, 08:46:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:46:07 INFO - 'ts_paint', 08:46:07 INFO - 'tpaint', 08:46:07 INFO - 'sessionrestore', 08:46:07 INFO - 'sessionrestore_no_auto_restore', 08:46:07 INFO - 'tabpaint']}, 08:46:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:46:07 INFO - 'ts_paint', 08:46:07 INFO - 'tpaint', 08:46:07 INFO - 'sessionrestore', 08:46:07 INFO - 'sessionrestore_no_auto_restore', 08:46:07 INFO - 'tabpaint']}, 08:46:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:46:07 INFO - 'tests': ['a11yr', 08:46:07 INFO - 'ts_paint', 08:46:07 INFO - 'tpaint', 08:46:07 INFO - 'sessionrestore', 08:46:07 INFO - 'sessionrestore_no_auto_restore', 08:46:07 INFO - 'tabpaint']}, 08:46:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:46:07 INFO - 'tests': ['a11yr', 08:46:07 INFO - 'ts_paint', 08:46:07 INFO - 'tpaint', 08:46:07 INFO - 'sessionrestore', 08:46:07 INFO - 'sessionrestore_no_auto_restore', 08:46:07 INFO - 'tabpaint']}, 08:46:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:46:07 INFO - 'tests': ['tsvgx', 08:46:07 INFO - 'tsvgr_opacity', 08:46:07 INFO - 'tart', 08:46:07 INFO - 'tscrollx', 08:46:07 INFO - 'cart', 08:46:07 INFO - 'tsvg_static']}, 08:46:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:46:07 INFO - 'tsvgr_opacity', 08:46:07 INFO - 'tart', 08:46:07 INFO - 'tscrollx', 08:46:07 INFO - 'cart', 08:46:07 INFO - 'tsvg_static']}, 08:46:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:46:07 INFO - 'talos_options': ['--disable-e10s'], 08:46:07 INFO - 'tests': ['tp5o']}, 08:46:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:46:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:46:07 INFO - 'talos_options': ['--disable-e10s', 08:46:07 INFO - '--xperf_path', 08:46:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:07 INFO - 'tests': ['tp5n']}, 08:46:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:46:07 INFO - 'talos_options': ['--xperf_path', 08:46:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:07 INFO - 'tests': ['tp5n']}}, 08:46:07 INFO - 'talos.zip': {'path': '', 08:46:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:46:07 INFO - Running post-action listener: _resource_record_post_action 08:46:07 INFO - [mozharness: 2017-01-09 16:46:07.167320Z] Finished populate-webroot step (success) 08:46:07 INFO - [mozharness: 2017-01-09 16:46:07.167429Z] Running create-virtualenv step. 08:46:07 INFO - Running pre-action listener: _resource_record_pre_action 08:46:07 INFO - Running main action method: create_virtualenv 08:46:07 INFO - Creating virtualenv /builds/slave/test/build/venv 08:46:07 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 08:46:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:46:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:46:07 INFO - Using real prefix '/tools/python27' 08:46:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:46:08 INFO - Installing distribute.............................................................................................................................................................................................done. 08:46:10 INFO - Installing pip.................done. 08:46:10 INFO - Return code: 0 08:46:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:46:11 INFO - Reading from file tmpfile_stdout 08:46:11 INFO - Output received: 08:46:11 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) 08:46:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9170474f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fa3d30>, '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 0x108ecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f98be8>, '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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 08:46:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:46:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJ2NvsJz1U/Render', 08:46:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:11 INFO - 'HOME': '/Users/cltbld', 08:46:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:11 INFO - 'LOGNAME': 'cltbld', 08:46:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:11 INFO - 'MOZ_NO_REMOTE': '1', 08:46:11 INFO - 'NO_EM_RESTART': '1', 08:46:11 INFO - 'PAGER': '/bin/cat', 08:46:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:11 INFO - 'PWD': '/builds/slave/test', 08:46:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:11 INFO - 'SHELL': '/bin/bash', 08:46:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bjRWB3c7h8/Listeners', 08:46:11 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 08:46:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:11 INFO - 'USER': 'cltbld', 08:46:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:11 INFO - 'XPC_FLAGS': '0x0', 08:46:11 INFO - 'XPC_SERVICE_NAME': '0', 08:46:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:11 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 08:46:11 INFO - Cleaning up... 08:46:11 INFO - Return code: 0 08:46:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1091c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9170474f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fa3d30>, '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 0x108ecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f98be8>, '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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 08:46:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:46:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJ2NvsJz1U/Render', 08:46:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:11 INFO - 'HOME': '/Users/cltbld', 08:46:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:11 INFO - 'LOGNAME': 'cltbld', 08:46:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:11 INFO - 'MOZ_NO_REMOTE': '1', 08:46:11 INFO - 'NO_EM_RESTART': '1', 08:46:11 INFO - 'PAGER': '/bin/cat', 08:46:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:11 INFO - 'PWD': '/builds/slave/test', 08:46:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:11 INFO - 'SHELL': '/bin/bash', 08:46:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bjRWB3c7h8/Listeners', 08:46:11 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 08:46:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:11 INFO - 'USER': 'cltbld', 08:46:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:11 INFO - 'XPC_FLAGS': '0x0', 08:46:11 INFO - 'XPC_SERVICE_NAME': '0', 08:46:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:11 INFO - Downloading/unpacking psutil>=3.1.1 08:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:46:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:15 INFO - Installing collected packages: psutil 08:46:15 INFO - Running setup.py install for psutil 08:46:15 INFO - building 'psutil._psutil_osx' extension 08:46:15 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 08:46:16 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 08:46:16 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 08:46:16 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 08:46:16 INFO - building 'psutil._psutil_posix' extension 08:46:16 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 08:46:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:46:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:46:16 INFO - ^ 08:46:16 INFO - 1 warning generated. 08:46:16 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 08:46:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:16 INFO - Successfully installed psutil 08:46:16 INFO - Cleaning up... 08:46:16 INFO - Return code: 0 08:46:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:16 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 0x1091c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9170474f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fa3d30>, '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 0x108ecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f98be8>, '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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 08:46:16 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 08:46:16 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 08:46:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJ2NvsJz1U/Render', 08:46:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:16 INFO - 'HOME': '/Users/cltbld', 08:46:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:16 INFO - 'LOGNAME': 'cltbld', 08:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:16 INFO - 'MOZ_NO_REMOTE': '1', 08:46:16 INFO - 'NO_EM_RESTART': '1', 08:46:16 INFO - 'PAGER': '/bin/cat', 08:46:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:16 INFO - 'PWD': '/builds/slave/test', 08:46:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:16 INFO - 'SHELL': '/bin/bash', 08:46:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bjRWB3c7h8/Listeners', 08:46:16 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 08:46:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:16 INFO - 'USER': 'cltbld', 08:46:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:16 INFO - 'XPC_FLAGS': '0x0', 08:46:16 INFO - 'XPC_SERVICE_NAME': '0', 08:46:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:46:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:46:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:46:19 INFO - Installing collected packages: mozsystemmonitor 08:46:19 INFO - Running setup.py install for mozsystemmonitor 08:46:19 INFO - Successfully installed mozsystemmonitor 08:46:19 INFO - Cleaning up... 08:46:20 INFO - Return code: 0 08:46:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:20 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 0x1091c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9170474f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fa3d30>, '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 0x108ecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f98be8>, '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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 08:46:20 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 08:46:20 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 08:46:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJ2NvsJz1U/Render', 08:46:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:20 INFO - 'HOME': '/Users/cltbld', 08:46:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:20 INFO - 'LOGNAME': 'cltbld', 08:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:20 INFO - 'MOZ_NO_REMOTE': '1', 08:46:20 INFO - 'NO_EM_RESTART': '1', 08:46:20 INFO - 'PAGER': '/bin/cat', 08:46:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:20 INFO - 'PWD': '/builds/slave/test', 08:46:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:20 INFO - 'SHELL': '/bin/bash', 08:46:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bjRWB3c7h8/Listeners', 08:46:20 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 08:46:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:20 INFO - 'USER': 'cltbld', 08:46:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:20 INFO - 'XPC_FLAGS': '0x0', 08:46:20 INFO - 'XPC_SERVICE_NAME': '0', 08:46:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:20 INFO - Downloading/unpacking jsonschema==2.5.1 08:46:20 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 08:46:20 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 08:46:20 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 08:46:20 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 08:46:20 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 08:46:20 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 08:46:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:46:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:23 INFO - Downloading functools32-3.2.3-2.tar.gz 08:46:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:46:23 INFO - warning: no files found matching '*.txt' 08:46:23 INFO - no previously-included directories found matching 'build' 08:46:23 INFO - no previously-included directories found matching 'dist' 08:46:23 INFO - no previously-included directories found matching '.git*' 08:46:23 INFO - Installing collected packages: jsonschema, functools32 08:46:23 INFO - Running setup.py install for functools32 08:46:23 INFO - warning: no files found matching '*.txt' 08:46:23 INFO - no previously-included directories found matching 'build' 08:46:23 INFO - no previously-included directories found matching 'dist' 08:46:23 INFO - no previously-included directories found matching '.git*' 08:46:23 INFO - Successfully installed jsonschema functools32 08:46:23 INFO - Cleaning up... 08:46:23 INFO - Return code: 0 08:46:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:46:23 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')]} 08:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:23 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')]} 08:46:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:23 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 0x1091c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9170474f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fa3d30>, '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 0x108ecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f98be8>, '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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 08:46:23 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 08:46:23 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 08:46:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJ2NvsJz1U/Render', 08:46:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:23 INFO - 'HOME': '/Users/cltbld', 08:46:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:23 INFO - 'LOGNAME': 'cltbld', 08:46:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:23 INFO - 'MOZ_NO_REMOTE': '1', 08:46:23 INFO - 'NO_EM_RESTART': '1', 08:46:23 INFO - 'PAGER': '/bin/cat', 08:46:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:23 INFO - 'PWD': '/builds/slave/test', 08:46:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:23 INFO - 'SHELL': '/bin/bash', 08:46:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bjRWB3c7h8/Listeners', 08:46:23 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 08:46:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:23 INFO - 'USER': 'cltbld', 08:46:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:23 INFO - 'XPC_FLAGS': '0x0', 08:46:23 INFO - 'XPC_SERVICE_NAME': '0', 08:46:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:46:24 INFO - Cleaning up... 08:46:24 INFO - Return code: 0 08:46:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:24 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 0x1091c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9170474f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fa3d30>, '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 0x108ecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f98be8>, '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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 08:46:24 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 08:46:24 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 08:46:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJ2NvsJz1U/Render', 08:46:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:24 INFO - 'HOME': '/Users/cltbld', 08:46:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:24 INFO - 'LOGNAME': 'cltbld', 08:46:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:24 INFO - 'MOZ_NO_REMOTE': '1', 08:46:24 INFO - 'NO_EM_RESTART': '1', 08:46:24 INFO - 'PAGER': '/bin/cat', 08:46:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:24 INFO - 'PWD': '/builds/slave/test', 08:46:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:24 INFO - 'SHELL': '/bin/bash', 08:46:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bjRWB3c7h8/Listeners', 08:46:24 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 08:46:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:24 INFO - 'USER': 'cltbld', 08:46:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:24 INFO - 'XPC_FLAGS': '0x0', 08:46:24 INFO - 'XPC_SERVICE_NAME': '0', 08:46:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:24 INFO - Downloading/unpacking blobuploader==1.2.4 08:46:24 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 08:46:24 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 08:46:24 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 08:46:24 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 08:46:24 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 08:46:24 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 08:46:26 INFO - Downloading blobuploader-1.2.4.tar.gz 08:46:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:46:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:46:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46:28 INFO - Downloading docopt-0.6.1.tar.gz 08:46:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:46:28 INFO - Installing collected packages: blobuploader, requests, docopt 08:46:28 INFO - Running setup.py install for blobuploader 08:46:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:46:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:46:28 INFO - Running setup.py install for requests 08:46:28 INFO - Running setup.py install for docopt 08:46:29 INFO - Successfully installed blobuploader requests docopt 08:46:29 INFO - Cleaning up... 08:46:29 INFO - Return code: 0 08:46:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:29 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')]} 08:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:29 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')]} 08:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:29 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 0x1091c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9170474f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fa3d30>, '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 0x108ecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f98be8>, '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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 08:46:29 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 08:46:29 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 08:46:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJ2NvsJz1U/Render', 08:46:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:29 INFO - 'HOME': '/Users/cltbld', 08:46:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:29 INFO - 'LOGNAME': 'cltbld', 08:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:29 INFO - 'MOZ_NO_REMOTE': '1', 08:46:29 INFO - 'NO_EM_RESTART': '1', 08:46:29 INFO - 'PAGER': '/bin/cat', 08:46:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:29 INFO - 'PWD': '/builds/slave/test', 08:46:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:29 INFO - 'SHELL': '/bin/bash', 08:46:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bjRWB3c7h8/Listeners', 08:46:29 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 08:46:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:29 INFO - 'USER': 'cltbld', 08:46:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:29 INFO - 'XPC_FLAGS': '0x0', 08:46:29 INFO - 'XPC_SERVICE_NAME': '0', 08:46:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:29 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-EuOLao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:29 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-1ahxEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:29 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-6JNqy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:29 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-di0_2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:29 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-c6VwLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:29 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-vdQDMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:30 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-te2YRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:30 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-NNEN6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:30 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-_xEj4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:30 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-PMweyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:30 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-mIKNbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:30 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-QisFwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:30 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-8nfYCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:30 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-OLbaJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:30 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-YROOGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:31 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-6VZP7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:31 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-u4GDpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:46:31 INFO - Running setup.py install for manifestparser 08:46:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:46:31 INFO - Running setup.py install for mozcrash 08:46:31 INFO - Running setup.py install for mozdebug 08:46:31 INFO - Running setup.py install for mozdevice 08:46:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:46:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:46:31 INFO - Running setup.py install for mozfile 08:46:31 INFO - Running setup.py install for mozhttpd 08:46:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:46:32 INFO - Running setup.py install for mozinfo 08:46:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:46:32 INFO - Running setup.py install for mozInstall 08:46:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:46:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:46:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:46:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:46:32 INFO - Running setup.py install for mozleak 08:46:32 INFO - Running setup.py install for mozlog 08:46:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:46:32 INFO - Running setup.py install for moznetwork 08:46:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:46:32 INFO - Running setup.py install for mozprocess 08:46:33 INFO - Running setup.py install for mozprofile 08:46:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:46:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:46:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:46:33 INFO - Running setup.py install for mozrunner 08:46:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:46:33 INFO - Running setup.py install for mozscreenshot 08:46:33 INFO - Running setup.py install for moztest 08:46:33 INFO - Running setup.py install for mozversion 08:46:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:46:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:46:33 INFO - Cleaning up... 08:46:33 INFO - Return code: 0 08:46:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:33 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')]} 08:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:33 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')]} 08:46:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:33 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 0x1091c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9170474f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fa3d30>, '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 0x108ecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f98be8>, '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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 08:46:33 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 08:46:33 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 08:46:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJ2NvsJz1U/Render', 08:46:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:33 INFO - 'HOME': '/Users/cltbld', 08:46:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:33 INFO - 'LOGNAME': 'cltbld', 08:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:33 INFO - 'MOZ_NO_REMOTE': '1', 08:46:33 INFO - 'NO_EM_RESTART': '1', 08:46:33 INFO - 'PAGER': '/bin/cat', 08:46:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:33 INFO - 'PWD': '/builds/slave/test', 08:46:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:33 INFO - 'SHELL': '/bin/bash', 08:46:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bjRWB3c7h8/Listeners', 08:46:33 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 08:46:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:33 INFO - 'USER': 'cltbld', 08:46:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:33 INFO - 'XPC_FLAGS': '0x0', 08:46:33 INFO - 'XPC_SERVICE_NAME': '0', 08:46:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-8z8zgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:34 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)) 08:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-5dYniC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:34 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)) 08:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-6uA9xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:34 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)) 08:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-T60uob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:34 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)) 08:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-CGZ6BS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:34 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)) 08:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-OzFAn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:34 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)) 08:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-b5abyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:34 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)) 08:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-F6sub7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:34 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)) 08:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-RleSny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:35 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)) 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-ANY9Dg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:35 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)) 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-7W0N69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:35 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)) 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-G5x5Dg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-RCRDYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:35 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)) 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-J4d02x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:35 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)) 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-KzubaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:35 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)) 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-IbgUZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:35 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)) 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-LnoFUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:35 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)) 08:46:35 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)) 08:46:35 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)) 08:46:35 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)) 08:46:35 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)) 08:46:35 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)) 08:46:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46:39 INFO - Downloading blessings-1.6.tar.gz 08:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:46:39 INFO - Installing collected packages: blessings 08:46:39 INFO - Running setup.py install for blessings 08:46:39 INFO - Successfully installed blessings 08:46:39 INFO - Cleaning up... 08:46:39 INFO - Return code: 0 08:46:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:46:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:40 INFO - Reading from file tmpfile_stdout 08:46:40 INFO - Current package versions: 08:46:40 INFO - blessings == 1.6 08:46:40 INFO - blobuploader == 1.2.4 08:46:40 INFO - docopt == 0.6.1 08:46:40 INFO - functools32 == 3.2.3-2 08:46:40 INFO - jsonschema == 2.5.1 08:46:40 INFO - manifestparser == 1.1 08:46:40 INFO - mozInstall == 1.12 08:46:40 INFO - mozcrash == 1.0 08:46:40 INFO - mozdebug == 0.1 08:46:40 INFO - mozdevice == 0.49 08:46:40 INFO - mozfile == 1.2 08:46:40 INFO - mozhttpd == 0.7 08:46:40 INFO - mozinfo == 0.9 08:46:40 INFO - mozleak == 0.1 08:46:40 INFO - mozlog == 3.4 08:46:40 INFO - moznetwork == 0.27 08:46:40 INFO - mozprocess == 0.24 08:46:40 INFO - mozprofile == 0.28 08:46:40 INFO - mozrunner == 6.13 08:46:40 INFO - mozscreenshot == 0.1 08:46:40 INFO - mozsystemmonitor == 0.3 08:46:40 INFO - moztest == 0.8 08:46:40 INFO - mozversion == 1.4 08:46:40 INFO - psutil == 3.1.1 08:46:40 INFO - requests == 1.2.3 08:46:40 INFO - wsgiref == 0.1.2 08:46:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:40 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')]} 08:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:40 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')]} 08:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:40 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 0x1091c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9170474f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fa3d30>, '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 0x108ecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f98be8>, '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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 08:46:40 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 08:46:40 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 08:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJ2NvsJz1U/Render', 08:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:40 INFO - 'HOME': '/Users/cltbld', 08:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:40 INFO - 'LOGNAME': 'cltbld', 08:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:40 INFO - 'MOZ_NO_REMOTE': '1', 08:46:40 INFO - 'NO_EM_RESTART': '1', 08:46:40 INFO - 'PAGER': '/bin/cat', 08:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:40 INFO - 'PWD': '/builds/slave/test', 08:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:40 INFO - 'SHELL': '/bin/bash', 08:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bjRWB3c7h8/Listeners', 08:46:40 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 08:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:40 INFO - 'USER': 'cltbld', 08:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:40 INFO - 'XPC_FLAGS': '0x0', 08:46:40 INFO - 'XPC_SERVICE_NAME': '0', 08:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:40 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)) 08:46:40 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)) 08:46:40 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)) 08:46:40 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)) 08:46:40 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)) 08:46:40 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)) 08:46:40 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)) 08:46:40 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)) 08:46:40 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)) 08:46:40 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)) 08:46:40 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)) 08:46:40 INFO - Cleaning up... 08:46:40 INFO - Return code: 0 08:46:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:46:40 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')]} 08:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:40 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')]} 08:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:40 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 0x1091c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9170474f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fa3d30>, '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 0x108ecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f98be8>, '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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 08:46:40 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 08:46:40 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 08:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJ2NvsJz1U/Render', 08:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:40 INFO - 'HOME': '/Users/cltbld', 08:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:40 INFO - 'LOGNAME': 'cltbld', 08:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:40 INFO - 'MOZ_NO_REMOTE': '1', 08:46:40 INFO - 'NO_EM_RESTART': '1', 08:46:40 INFO - 'PAGER': '/bin/cat', 08:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:40 INFO - 'PWD': '/builds/slave/test', 08:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:40 INFO - 'SHELL': '/bin/bash', 08:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bjRWB3c7h8/Listeners', 08:46:40 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 08:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:40 INFO - 'USER': 'cltbld', 08:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:40 INFO - 'XPC_FLAGS': '0x0', 08:46:40 INFO - 'XPC_SERVICE_NAME': '0', 08:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:46:40 INFO - Cleaning up... 08:46:40 INFO - Return code: 0 08:46:40 INFO - Running post-action listener: _resource_record_post_action 08:46:40 INFO - Running post-action listener: _start_resource_monitoring 08:46:40 INFO - Starting resource monitoring. 08:46:40 INFO - [mozharness: 2017-01-09 16:46:40.652601Z] Finished create-virtualenv step (success) 08:46:40 INFO - [mozharness: 2017-01-09 16:46:40.653504Z] Running install step. 08:46:40 INFO - Running pre-action listener: _resource_record_pre_action 08:46:40 INFO - Running main action method: install 08:46:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:40 INFO - Reading from file tmpfile_stdout 08:46:40 INFO - Detecting whether we're running mozinstall >=1.0... 08:46:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:46:41 INFO - Reading from file tmpfile_stdout 08:46:41 INFO - Output received: 08:46:41 INFO - Usage: mozinstall [options] installer 08:46:41 INFO - Options: 08:46:41 INFO - -h, --help show this help message and exit 08:46:41 INFO - -d DEST, --destination=DEST 08:46:41 INFO - Directory to install application into. [default: 08:46:41 INFO - "/builds/slave/test"] 08:46:41 INFO - --app=APP Application being installed. [default: firefox] 08:46:41 INFO - mkdir: /builds/slave/test/build/application 08:46:41 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'] 08:46:41 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 08:47:03 INFO - Reading from file tmpfile_stdout 08:47:03 INFO - Output received: 08:47:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:47:03 INFO - Running post-action listener: _resource_record_post_action 08:47:03 INFO - [mozharness: 2017-01-09 16:47:03.062575Z] Finished install step (success) 08:47:03 INFO - [mozharness: 2017-01-09 16:47:03.062739Z] Running run-tests step. 08:47:03 INFO - Running pre-action listener: _resource_record_pre_action 08:47:03 INFO - Running main action method: run_tests 08:47:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:47:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:47:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:47:03 INFO - 2017-01-09 08:47:03.077 screenresolution[1633:7716] starting screenresolution argv=screenresolution get 08:47:03 INFO - 2017-01-09 08:47:03.079 screenresolution[1633:7716] Display 0: 1600x1200x32@60 08:47:03 INFO - 2017-01-09 08:47:03.085 screenresolution[1634:7718] starting screenresolution argv=screenresolution list 08:47:03 INFO - Available Modes on Display 0 08:47:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:47:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:47:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:47:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:47:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:47:03 INFO - Intel Iris: 08:47:03 INFO - Chipset Model: Intel Iris 08:47:03 INFO - Type: GPU 08:47:03 INFO - Bus: Built-In 08:47:03 INFO - VRAM (Dynamic, Max): 1536 MB 08:47:03 INFO - Vendor: Intel (0x8086) 08:47:03 INFO - Device ID: 0x0a2e 08:47:03 INFO - Revision ID: 0x0009 08:47:03 INFO - Displays: 08:47:03 INFO - Display: 08:47:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:47:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:47:03 INFO - Display Serial Number: 1600x1200 60 08:47:03 INFO - Main Display: Yes 08:47:03 INFO - Mirror: Off 08:47:03 INFO - Online: Yes 08:47:03 INFO - Rotation: Supported 08:47:03 INFO - Return code: 0 08:47:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:47:03 INFO - Python 2.7.3 08:47:03 INFO - Return code: 0 08:47:03 INFO - grabbing minidump binary from tooltool 08:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:03 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 0x108fa3d30>, '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 0x108ecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f98be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:47:03 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 08:47:03 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 08:47:03 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 08:47:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:47:03 INFO - Return code: 0 08:47:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:47:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:47:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:47:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:47:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:47:03 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/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 08:47:03 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/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0325 --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 08:47:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dJ2NvsJz1U/Render', 08:47:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:03 INFO - 'HOME': '/Users/cltbld', 08:47:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:03 INFO - 'LOGNAME': 'cltbld', 08:47:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:47:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:03 INFO - 'MOZ_NO_REMOTE': '1', 08:47:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:47:03 INFO - 'NO_EM_RESTART': '1', 08:47:03 INFO - 'PAGER': '/bin/cat', 08:47:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:03 INFO - 'PWD': '/builds/slave/test', 08:47:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:47:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:03 INFO - 'SHELL': '/bin/bash', 08:47:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bjRWB3c7h8/Listeners', 08:47:03 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 08:47:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:03 INFO - 'USER': 'cltbld', 08:47:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:03 INFO - 'XPC_FLAGS': '0x0', 08:47:03 INFO - 'XPC_SERVICE_NAME': '0', 08:47:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:03 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/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 08:47:03 INFO - mozversion application_buildid: 20170109072309 08:47:03 INFO - mozversion application_changeset: eefd48f36d82304bd1a4bb8626b29ef5ee54bb80 08:47:03 INFO - mozversion application_display_name: Nightly 08:47:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:47:03 INFO - mozversion application_name: Firefox 08:47:03 INFO - mozversion application_remotingname: firefox 08:47:03 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 08:47:03 INFO - mozversion application_vendor: Mozilla 08:47:03 INFO - mozversion application_version: 53.0a1 08:47:03 INFO - mozversion platform_buildid: 20170109072309 08:47:03 INFO - mozversion platform_changeset: eefd48f36d82304bd1a4bb8626b29ef5ee54bb80 08:47:03 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 08:47:03 INFO - mozversion platform_version: 53.0a1 08:47:03 INFO - using testdate: 1483980423 08:47:03 INFO - actual date: 1483980423 08:47:03 INFO - starting webserver on 'localhost:49255' 08:47:03 INFO - SUITE-START | Running 2 tests 08:47:03 INFO - TEST-START | dromaeo_css 08:47:03 INFO - operating with platform_type : mac_ 08:47:03 INFO - Initialising browser for dromaeo_css test... 08:47:03 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpXawgKd/profile http://localhost:49255/getInfo.html) 08:47:04 INFO - PROCESS | 1644 | 1483980424213 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:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 08:47:06 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 08:47:06 INFO - PROCESS | 1644 | colorDepth:24 08:47:06 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 08:47:06 INFO - PROCESS | 1644 | __metrics 08:47:06 INFO - PROCESS | 1644 | 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:47:06 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:47:07 INFO - TEST-INFO | 1644: exit 0 08:47:07 INFO - Browser initialized. 08:47:07 INFO - Running cycle 1/1 for dromaeo_css test... 08:47:07 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpXawgKd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:19 INFO - PROCESS | 1647 | RSS: Main: 234631168 08:47:19 INFO - PROCESS | 1647 | 08:50:24 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49255/tests/dromaeo/cssquery-ext.html) 08:50:25 INFO - PROCESS | 1647 | RSS: Main: 360378368 08:50:25 INFO - PROCESS | 1647 | 08:52:08 INFO - PROCESS | 1647 | ************************* 08:52:08 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 08:52:08 INFO - PROCESS | 1647 | 08:52:08 INFO - PROCESS | 1647 | Full message: ReferenceError: fetch is not defined 08:52:08 INFO - PROCESS | 1647 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:52:08 INFO - PROCESS | 1647 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:52:08 INFO - PROCESS | 1647 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:52:08 INFO - PROCESS | 1647 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:52:08 INFO - PROCESS | 1647 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:52:08 INFO - PROCESS | 1647 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:52:08 INFO - PROCESS | 1647 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:52:08 INFO - PROCESS | 1647 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:52:08 INFO - PROCESS | 1647 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:52:08 INFO - PROCESS | 1647 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:52:08 INFO - PROCESS | 1647 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:52:08 INFO - PROCESS | 1647 | 08:52:08 INFO - PROCESS | 1647 | ************************* 08:53:28 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-ext.html (next: http://localhost:49255/tests/dromaeo/cssquery-jquery.html) 08:53:28 INFO - PROCESS | 1647 | RSS: Main: 369889280 08:53:28 INFO - PROCESS | 1647 | 08:56:31 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49255/tests/dromaeo/cssquery-mootools.html) 08:56:31 INFO - PROCESS | 1647 | RSS: Main: 336596992 08:56:31 INFO - PROCESS | 1647 | 08:59:34 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49255/tests/dromaeo/cssquery-prototype.html) 08:59:35 INFO - PROCESS | 1647 | RSS: Main: 342331392 08:59:35 INFO - PROCESS | 1647 | 09:02:38 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49255/tests/dromaeo/cssquery-yui.html) 09:02:39 INFO - PROCESS | 1647 | RSS: Main: 332881920 09:02:39 INFO - PROCESS | 1647 | 09:05:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49255/tests/dromaeo/cssquery-yui.html (next: http://localhost:49255/tests/dromaeo/cssquery-dojo.html) 09:05:42 INFO - PROCESS | 1647 | RSS: Main: 381218816 09:05:42 INFO - PROCESS | 1647 | 09:05:42 INFO - PROCESS | 1647 | __start_tp_report 09:05:42 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 09:05:42 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 09:05:42 INFO - PROCESS | 1647 | |i|pagename|runs| 09:05:42 INFO - PROCESS | 1647 | |0;dojo.html;3237.1745204972726;3263.1515805890467;3267.7549183811207;3293.3248684019777;3321.7342612590996;5389.865253368668;5417.052015897212;5458.290422245108;5476.835694929154;5496.5053145216925;5455;5456.536194423469;5540.473655002778;5540.806071787489;5671.744771485282;4932.4574296827495;4954.3063971044085;4958.702477851322;4983.72589507577;5020.59835213182;4996.575291100261;5030.144875371196;5063.215201643751;5085.5423011928915;5113.539781419671;4940.98709764501;5022.773975171108;5039.874003149914;5058.4688607696025;5080.110980578403;4982.5017498250245;4996.300517927493;5026.019926114409;5040.521150490697;5043.470435604246;4962.87592810179;4968.776405061781;4997.600191984632;5022;5050.747462626854;5485.177223416479;5488.780448782084;5497.615166938316;5501.202325662756;5513.448655134494;4831.661783675144;5781.739821707991;5786.826395208151;5921.703914804286;6018.879622407528;6056.909146362808;6059;6100.298465676459;6105.267367915834;6143.87712245757;5578.525825304894;5578.860528486739;5584;5588;5589.133684278902;5994;6104.890867003831;6139.723712432951;6148.692565371803;6158.753649853956;5792.536597072224;5805.477507024388;5834.416558344216;5874.530037596982;5943.435373639497;5932.0508718604815;6054.788082417094;6068.302145253264;6073.240844894388;6087.29996050451;5824.475797178275;5853.560982926298;5854.092615644582;5915.585908986416;5922.8815423691285;5966.642001479925;5972.372900845436;5981.282246130492;5987.550933679991;5991.400859914061;4685.625149987993;4711.670183087151;4720.834770783074;4749.453812811571;4765.737884416321;4232.32282834745;4266.488021437447;4268.765217913045;4291.935620965688;4307.913841723211;5629.352624448159;5637.069883469264;5645.266115404971;5649.717514124277;5694.544436445074;5836.503897168704;5941.168236446814;6082;6089.482393996472;6096.69516524172;5568.053430916754;5574.804881829197;5602.13166959122;5603.691796951125;5675.943240567624;5216.947830521646;5221.869453263623;5223.268742376071;5229.581633469314;5246.895062098737;5193.76628051731;6184.288806787277;6193.783217587453;6205.813825585149;6267.749290028348;5896.557758168069;5952.136940143741;5952.434518720715;5955.225820643462;6024.849378765566;6212.720427580679;6216.937830621636;6225.937740622536;6246.750129994931;6247.437730604267;5899.908516924383;5918.348981611933;5929.229200203946;5933.9109913352195;5982.760689572368;5848.736806843617;5901.704914754159;5911.822645320647;6016.217891674054;6042.788502402394;5759.9136012959;5776.508996735324;5807.332156801853;5812.854678632899;5883.382244864399;5635.577331700221;5680.943190568207;5728.770849166153;5833.79581714638;5845.649261044351;5617.831465056055;5629.352624448241;5654.575906806924;5660.688662123458;5718.285214348207;5811.854703632443;5821.592488525763;5829.76680932758;5840.678762668094;5842.415758424294;5511.22842802016;5561.332640083216;5568;5610.326760788568;5633.746481408429 09:05:42 INFO - PROCESS | 1647 | |1;ext.html;476.4354240225332;499.27604972789453;505.11352576228734;519.4104691175517;544.4365082139985;30615.081547553582;38701.225975480505;40317.798411007905;40377.78866634002;42474;41313.58686413135;41886.58113418865;42328.36507452383;42364.57635423645;42439.575604244026;8372.581370931459;9318.25453963683;9518.476483793389;9524.666636667718;9524.8571271431;8450.408471407003;11101.83347249792;11342.773144537105;11363.943180284086;11566.768664626745;11290.379029153393;11367.602133925315;11370.488328025218;11377.065898797224;11390.430478476042;11249.493772780244;11305.830412543819;11381.886181138165;11403.201775875692;11422.600208992686;11256.155788315828;11283.37941413222;11308.264962777394;11321.773564528663;11332.036776873976;40130.799346003085;40319;40390.59609403898;40392.78821635326;40480;3947.1513624570857;4044.1507283470505;4054.4323794668476;4059.167870586552;4144.3990621360035;29829.850850745825;30745.538816917764;30997.38005239905;31261.531077033862;31445;3085.52330554836;3176.698213669698;3184.7738620630994;3189.100015900786;3215.2122729931084;454682;455169;456213;457380;461547;58113;58365.416345836;58412.12381814276;58472.70763646154;58499.4150058494;62897.685511573065;63101.684491577245;63400.682996585645;63592;63680;15829.050256984618;15829.525114246591;16014.359425623077;16016.919915400349;16038.919805400898;11093.223474356717;11356.012697777856;11374.20380573352;12478.251304921734;12590.24458532491;14740.631484212981;16241.350345986028;16622.667546648998;16926.238319275417;16955.237014334383;5520.66875987442;5834.416558344216;6188.824123416536;6486.189226346707;6576.539642225094;801.4149670740392;837.9245867871921;856.8971723393108;859.8366310401004;874.3516880949013;29793;29809.85095074511;29887.16071973159;30211.546826797614;30244.395112097634;996.6412091647002;1038.6105210546045;1042.2287507244585;1063.5692544519482;1082.621082621092;14925.552233433014;15421.537353879401;15458;15527.844721552865;15857.762133567774;580702.0964895233;581953;588167;588644;588983;53297.667558311354;56228.5942851432;59293;59583.80832383415;59688;1394.2053029773135;1425.6150839273546;1448.9420423182955;1450.5068276785732;1453.6874571967078;28940.540054095636;29059.128226152403;29221.85389073041;29906.80372785151;30050.54924176183;50407.747961259956;50768;50780.238296424846;50818.74590627023;50980.235296469844;507482;508248.9276394045;509976;510668;511471;33557.496637549964;34350.82824585861;38341.616583833806;39120.21759564736;39220.41169382405;36694.633053670186;46228.844278893295;46442.76778616085;46522.30216546687;47424.7628761854;37855.864324070324;38480.03799905025;38681.806590966866;39024.60805715465;39443.605563943995;33338.66661333453;38089.809550952064;38091.42862857115;39389.6061039386;39421;36769;36990.43333698351;37146.62853371432;37212.25575488529;37759.678411255474;31773.682263177998;33378.16554586155;34016.82991585026;34203.97388078362;34233;21800.800955947576;24547;24580.877095614407;24701.50596988086;25145.119920802685 09:05:42 INFO - PROCESS | 1647 | |2;jquery.html;371.13710622801995;373.3335995248482;375.81243271262815;393.74209892520395;401.2216300377268;14925.477608283712;15289.235538223085;17577.3847915323;20120;20612.48468788281;21982.670259946113;22230.666540001912;22261.888690556523;22300.553988920212;22334.553308933813;5818;5822.563307751914;5972.343042265369;6001.06983417571;6055.545834062441;5922.792702255422;5956.821295361146;5973.970130149343;5981.551383646222;5982.192404025466;3981.3032719274156;4324.502682191557;4328.177646247219;5930.110483427498;6587.440067594261;6616.239132499776;6627.502937279699;6630.502712296597;6639.3692599203;6650.401463868251;5465.808696695636;5579.246801681762;5706.1440783882335;5716.171155182476;5805.651660900359;26476.735232647814;26561;26645.73354266452;26860.194194174204;28374.716252837414;2918;3042.574039634453;3402.5916889973114;3404.608470025954;3467.6185619581825;17390.956542607357;17394.478165654924;18120.90939545307;18160.63911691485;18355.35756248525;1621.116491512117;1886.9056547172586;1998.3305592626486;2011.386527109231;2034.338839877031;150766.24616876955;157535.2123239388;160099;163322.18338908348;163372;23496;23530.64704029441;23582.7641723584;23596.764032359457;23902;39851.00372490711;39908.204130814185;40193.59806401957;40201;40216.79349619516;8333;8482.491050536988;8642.3950323478;8802.60388282516;11325.660230193107;7772.106207786177;8473.3644976627;8507.744767656981;8530.061693213742;8691.002139016124;4470.955290447054;4711;4790.808367665254;4803.759812009385;4890.462049174588;2014.21445636202;2039.1945181653164;2042.0606521000507;2045.233037610896;2052.209899188823;378.49470956273683;384.25454618040914;1013.3717095400899;1036.6630844975414;1067.0343339277965;10113.342632728849;11219.719507012227;11285.661430157108;11930.04559635227;12029.857163569446;347.8191340502924;416.2777580897141;417.06993404707674;426.3881330291042;441.18160811694486;5207.3230480037355;5959.970200148971;5990.73041713124;6040.607360521552;6124.5100391968535;697761;705928;731474;755073;760233;106724.51626770121;139837;141048;152401;156704;448.8855341887798;464.5981226239346;465.3183086777819;465.51121322611124;465.86722784006395;11402.771944560896;11514.194006419806;11527.942360288145;11706.180567360203;11746.353950532803;55035.44964550303;55236.72381638066;56000.719996399755;56406.43593564012;59458;74123;81524;83126;83764;86031;7268.38218751412;7489.962550187214;7625.847483050197;7722.6138693063995;7848.097468791162;13176.80234796498;13250.337483125611;13267.336633168496;13640.522581709592;13888.652783680085;9667;11527.308361498337;11804.40977951082;11866.881331186923;11948.522059117538;11451.828222576834;11523.366214857928;11708.180427370344;11825.231359961575;11853.881461185623;9473.431594104375;11684.298942063504;11884.405779711153;11954.043676506033;12088.972437342924;6420.518461115342;7422.14645315774;7597.734079307419;7822.6479808409895;8042.879356809535;10192.949035254776;11249.550017999189;11457.656270311903;11962.641120766391;12049.457774400349 09:05:42 INFO - PROCESS | 1647 | |3;mootools.html;1345.9798103028454;1434.8449498153984;1454.585443148702;1477.062065588351;1487.776833474978;8010.1589333120055;8415.747527574174;8420.789480262996;8439.113893041234;8441.198086181803;7489.138749043862;7714.190010048948;7731.961340193306;8047.4769140006;8242.91757082429;2262.5361800830833;2318.9304320870315;2321.5240875620457;2328.860268383894;2331.778481044298;2300.735415427222;2332.6034574122355;2348.4481146930457;2357.6463530470396;2359.3629719975597;2052.168871606994;2137.6472881974532;2224.8442609017375;2301.4591570980806;2324.9070037198494;1988.6122206169803;2310.607196776535;2313.2597568777996;2317.8493397929083;2321.895514701834;2306.1467257114805;2320.953580928372;2324.2097686786406;2329.2895666821614;2331.2889568681544;8289.502629842229;8312.584370781437;8416.410851240416;8420.410571260014;8444.957775211145;5033.672811267256;5188.92216616751;5189.558887494568;5204.401493828221;5204.76578553966;3223.468127758917;3399.42209824328;3468.94796578049;3472.947905781415;3483.6168021517615;1161.97095072623;1210.5520957245787;1232.7041510037527;1253.931033793141;1258.987410125896;10352.5341359639;10504;10743.731406714816;10744.14046876248;10748.570057197703;4309.676774241944;4367.694261401735;4465.37484752135;4478.193925093449;4489.6408287336935;8661.177188167114;8666.653333866701;8673.132686731276;8680.045195028542;8689.261412779984;5088.643794934393;5092.465291144451;5097;5098.923516147261;5109.131447653907;3852.287326844543;4244.925461130289;4727.976360118178;4737.360456338351;4780.259059845729;3017.6227971503563;3544.2379888324135;3660;3682.1531047859144;3713.665770080706;983.4296108257194;1118.625260537729;1130.6099395708468;1131.9830202546968;1137.9601713940137;763.2443880558208;777.1412589089089;876.9824603507894;891.5364010714521;920.8434566123775;5348.090824559834;5573.219749235111;5749.453801888814;5830.416958304136;6033.939660603338;966.6568368229177;984.5864736810556;986.8519722041751;1012.1851909213154;1044.8746150461993;3654.689351405107;3716.4425336199247;3721.7953012584567;3814.961850381461;3898.6686131679126;9492.715218543455;9939.950300248596;9949.651762188289;9954.502274886227;9965.651202207888;9874.160696340892;9887.653932112486;9918.15695665862;9952.950235248778;9966.103050725469;7297.671604777904;7683.6926522939575;7849.646765895548;7859.685612575548;7867.095284042294;5548.389677135431;5622.100463925753;5735.225744524602;5757.740901659352;5837.503812176013;9132.589033493641;9151.816963660822;9152.679656212;9200.309976751636;9299.581518831534;8776.429532080394;9055.36612437123;9058.592363343534;9118.452892826452;9143;6520.510961677988;6747.392734653905;6749.763758268437;7013.754518591825;7193.460490463341;6277.529185311028;7410.333070023724;7457.179710231761;7469.029026226556;7760.611969401394;6835.316468353125;7058.576485410892;7070.15158181001;7115.039469671528;7221.386182174574;6980.05769221169;6983.615901125284;7012.403945664676;7024.088257470388;7092.326229008236;6286.214223222098;6323.36766323364;6400.839979000562;6408.935910640834;6416.422521973045;4869.732164730974;4916.950830491649;4926.926096108489;4957.578605818546;5056.494350564914;4935.457099718957;4940.258961155855;4980.551750342486;5032.370953630796;5064.949350506447 09:05:42 INFO - PROCESS | 1647 | |4;prototype.html;347.1321695760598;350.86316336628704;359.13090321422146;366.42654246104826;369.51962448816533;4259.212045771531;4844.249141383091;5008.39899212095;5015.874603134924;5075.350511083899;4536.977315113428;4800.975995120019;4903.239997800347;4948.802047918088;4973.278874563186;3176.3647270545866;3991.600839916014;3995.98002009991;4003.219372222418;4020.2160578687167;2614.5032443835707;3653.725970552219;3864.6666899919624;4001.5998400160042;4030.858919937803;3542.256126213498;3807.600201978794;3825.2158307547024;3841.8463261469506;3850.3839385698293;3268.428025095611;3805.543334799814;3806.0969515242377;3822.847086116552;3853.190829925719;3318.3197444523817;3327.4177019021577;3333.849976751052;3342.699157075875;3345.849436775351;3981.8009099544906;4404.581564751344;4640.886187315054;4932.630052746058;4949.307097006422;3894.279558281727;4296.892577685551;4331.177076355482;4342.002678767771;4373.9125217495475;5226.921596176022;5235.806158243468;5238.79044838206;5257.921131183035;5260.29095016067;3628.0385697790107;3658.1951970566433;3661.139632186434;3666.578343490506;3669.7614655047605;5164.664296820694;5509.669419834822;5510.256115424407;5542.251796007487;5542.533572560213;3184.9044528664176;3693.7045036397026;3694.4827724118645;3704.3147017801793;3737.7009839212797;3769.9434508482395;3790.223004284115;3793.7344385893275;3794.886153415402;3805.219929914361;4164.646005089601;4316.330968699856;4351.891202719894;4375.906023494126;4409.228385032607;5244.449332820075;5274.3143391359645;5295.511109778398;5339.5728341732565;5349.010433069817;1063.7181146996052;1146.696125526736;1176.0356507663805;1202.4108186988487;1218.421249906305;716.6201912986126;750.512167091395;811.2820154163608;832.7626626411444;839.8656215005569;4423.955760442419;4556.703814252063;4638.0751987925305;4649.349091127245;4654.836290927268;4245.702800803914;4608.654350923694;4808.0624278266105;5106.591472682177;5142.537171654569;3774.7546409483843;4061.2080644274674;4411.977940110279;4502.482214545304;4543.886402839889;2415.251272105653;2444.53102366611;2556.1595651131156;2665.3469899874653;2671.584060447966;4616.961183733633;4906.901861962812;5178.559822415137;5235.633505654644;5269.394019687708;4650.558196971282;4743.928841067387;4968.751562421864;5130.743462826844;5296.867578310496;4447.6219521341045;4862.9756851216225;5057.367829021372;5184.7926082956255;5223.686578805284;4907.975460122677;4912.631552633638;5045.837184323022;5049.419316778471;5056.57019153376;4504.842330518416;4673.779954521575;4826.7827947743135;4960.635825147969;5013.348264725708;4559;4564.549564175036;5267.864726189922;5292.761825717929;5330.307060082319;3032.1358412852233;3127.6090488688915;3135.7300293381218;3336.6830151135605;3356.4965255211914;4432.29083346663;4709.512683248966;5002.224655178453;5044.39467263916;5045.520675535819;3957.782321972204;4872.780724867319;4886.633502487399;4902.338184345211;4968.801247950041;4602.401687780567;4606.355110284496;4951.183054795918;4986.4319875263545;5022.422421421437;4591.461860276845;4698.077105616711;4765.7140571565815;4824.815621689668;4853.587445067209;4370.606645401929;4822.397200349957;4883.95116048835;4919.483454237254;4927.408710954708;4191.580841915784;4789.425268967746;4805.567498925114;4806.831760888352;4832.323474713613 09:05:42 INFO - PROCESS | 1647 | |5;yui.html;721.282324087533;725.7604990353184;766.7738017284901;803.220875750522;807.2613558593889;14934.477293294736;15926.601834954132;15926.681466370666;15977.121258330802;15992.600184995366;14536.491222807204;14660.853391466082;14662.266886655678;14666.63333416665;14697.191654459;670.2392784189955;694.7464175575903;696.7352406085706;822.7038266224155;845.2435070611799;717.8456631824157;840.4999025579771;845.9703910363138;850.3112478892086;852.778277647813;759.5518644000039;904.9864252036225;905.8233644439337;906.2387594420685;906.3429013964889;705.5061456980135;900.6577500549814;900.6937641202023;902.255639097747;903.421810041574;535.8151437753969;652.4128284543901;667.6761770541284;669.4376723552191;673.1921693967209;12797.296148711817;15207;15308.693826123415;15322;15330.693386132214;13574.93212533931;13666.453341866314;13671;13781.586552403443;13786.379612917342;10567.86161746717;12470.75058498834;12662.733726627355;12770.23378597287;12870.67823304425;4433.113377324516;4468.396766436522;4502.099580083996;4570.405847239871;4665.183592871268;99307.50346248223;100922;103047.48476257645;103643;104143.95856041419;10857;10927.45362731876;10929.56281748721;11047;11065.944670276758;19970.10134543949;19976.200951962048;20001.899990500246;20038.298659546846;20058.799412005985;6749.291324410964;6959.791206263821;7166.820829479305;7267.273272672691;7709.9614501927135;4212.599803018708;4254.489461264668;4265.189613973335;4283.764392958355;4410.647148228134;3360.8487618057247;3453.4474484082425;3454.602720687103;3456.7407444441264;3464.826758662057;628.4595248086641;637.6014990630856;640.1262276991965;644.0886146103212;656.7077650445506;521.4733119549206;540.675594643211;542.2706459811548;546.0013575714341;549.719642982074;8409.537475438787;8633.913660863436;8667.219950204386;8881.73354799357;9129.269658427309;523.8585581892868;530.7638101045075;559.706154269012;577.4716134736669;591.9585629005928;4211.7262377945335;4345.847895323648;4358.128374325084;4362.912741745147;4504.752238626907;84505;99052.5047374773;102555.4872225634;104119.95880041254;105091;73005;77397.61301193458;77837.221627783;79436.60281698554;83380;72035.63982180055;77268.22731772835;77565.61217193991;77966;79377;9765.658201962897;9892.604295828087;9978.950105249427;10049.145822604867;10086.949565252127;45333.77333113313;47453.52546474491;47851;48137.75931120322;51837.48162518327;62493.68753156205;65112.37219069561;65814;66136.67726645536;67273;11147;11521;11560.42197890119;11709.180357374902;12250;12742;12941.28822914711;13334.666633334244;13583.117097388638;13889.722205556034;12015.219010764273;12030.218035827644;12364.62906112818;12486.500539978299;12869.297447536122;10286.845697314697;11777.764444710887;11840.585579505021;12103.152779305365;12436.440360183995;11854.348010859485;11856.347900865536;11951.521939122338;12150;12498.625041248777;9971.202303815822;10010.599576016877;10211.897881021392;10514.369137851754;10598;8121.67513299485;9303.395279306824;9758.804823903623;9787.559559819683;9794.559244834141 09:05:42 INFO - PROCESS | 1647 | __end_tp_report 09:05:42 INFO - PROCESS | 1647 | __start_cc_report 09:05:42 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1318 09:05:42 INFO - PROCESS | 1647 | __end_cc_report 09:05:42 INFO - PROCESS | 1647 | __startTimestamp1483981542644__endTimestamp 09:05:42 INFO - PROCESS | 1647 | 09:05:42 INFO - PROCESS | 1647 | ------- Summary: start ------- 09:05:42 INFO - PROCESS | 1647 | Number of tests: 6 09:05:42 INFO - PROCESS | 1647 | 09:05:42 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5521.55 Median:5654.58 stddev:610.34 (10.8%) stddev-sans-first:585.31 09:05:42 INFO - PROCESS | 1647 | Values: 3237.2 3263.2 3267.8 3293.3 3321.7 5389.9 5417.1 5458.3 5476.8 5496.5 5455.0 5456.5 5540.5 5540.8 5671.7 4932.5 4954.3 4958.7 4983.7 5020.6 4996.6 5030.1 5063.2 5085.5 5113.5 4941.0 5022.8 5039.9 5058.5 5080.1 4982.5 4996.3 5026.0 5040.5 5043.5 4962.9 4968.8 4997.6 5022.0 5050.7 5485.2 5488.8 5497.6 5501.2 5513.4 4831.7 5781.7 5786.8 5921.7 6018.9 6056.9 6059.0 6100.3 6105.3 6143.9 5578.5 5578.9 5584.0 5588.0 5589.1 5994.0 6104.9 6139.7 6148.7 6158.8 5792.5 5805.5 5834.4 5874.5 5943.4 5932.1 6054.8 6068.3 6073.2 6087.3 5824.5 5853.6 5854.1 5915.6 5922.9 5966.6 5972.4 5981.3 5987.6 5991.4 4685.6 4711.7 4720.8 4749.5 4765.7 4232.3 4266.5 4268.8 4291.9 4307.9 5629.4 5637.1 5645.3 5649.7 5694.5 5836.5 5941.2 6082.0 6089.5 6096.7 5568.1 5574.8 5602.1 5603.7 5675.9 5216.9 5221.9 5223.3 5229.6 5246.9 5193.8 6184.3 6193.8 6205.8 6267.7 5896.6 5952.1 5952.4 5955.2 6024.8 6212.7 6216.9 6225.9 6246.8 6247.4 5899.9 5918.3 5929.2 5933.9 5982.8 5848.7 5901.7 5911.8 6016.2 6042.8 5759.9 5776.5 5807.3 5812.9 5883.4 5635.6 5680.9 5728.8 5833.8 5845.6 5617.8 5629.4 5654.6 5660.7 5718.3 5811.9 5821.6 5829.8 5840.7 5842.4 5511.2 5561.3 5568.0 5610.3 5633.7 09:05:42 INFO - PROCESS | 1647 | 09:05:42 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:66027.24 Median:38216.52 stddev:138477.30 (362.3%) stddev-sans-first:138778.31 09:05:42 INFO - PROCESS | 1647 | Values: 476.4 499.3 505.1 519.4 544.4 30615.1 38701.2 40317.8 40377.8 42474.0 41313.6 41886.6 42328.4 42364.6 42439.6 8372.6 9318.3 9518.5 9524.7 9524.9 8450.4 11101.8 11342.8 11363.9 11566.8 11290.4 11367.6 11370.5 11377.1 11390.4 11249.5 11305.8 11381.9 11403.2 11422.6 11256.2 11283.4 11308.3 11321.8 11332.0 40130.8 40319.0 40390.6 40392.8 40480.0 3947.2 4044.2 4054.4 4059.2 4144.4 29829.9 30745.5 30997.4 31261.5 31445.0 3085.5 3176.7 3184.8 3189.1 3215.2 454682.0 455169.0 456213.0 457380.0 461547.0 58113.0 58365.4 58412.1 58472.7 58499.4 62897.7 63101.7 63400.7 63592.0 63680.0 15829.1 15829.5 16014.4 16016.9 16038.9 11093.2 11356.0 11374.2 12478.3 12590.2 14740.6 16241.4 16622.7 16926.2 16955.2 5520.7 5834.4 6188.8 6486.2 6576.5 801.4 837.9 856.9 859.8 874.4 29793.0 29809.9 29887.2 30211.5 30244.4 996.6 1038.6 1042.2 1063.6 1082.6 14925.6 15421.5 15458.0 15527.8 15857.8 580702.1 581953.0 588167.0 588644.0 588983.0 53297.7 56228.6 59293.0 59583.8 59688.0 1394.2 1425.6 1448.9 1450.5 1453.7 28940.5 29059.1 29221.9 29906.8 30050.5 50407.7 50768.0 50780.2 50818.7 50980.2 507482.0 508248.9 509976.0 510668.0 511471.0 33557.5 34350.8 38341.6 39120.2 39220.4 36694.6 46228.8 46442.8 46522.3 47424.8 37855.9 38480.0 38681.8 39024.6 39443.6 33338.7 38089.8 38091.4 39389.6 39421.0 36769.0 36990.4 37146.6 37212.3 37759.7 31773.7 33378.2 34016.8 34204.0 34233.0 21800.8 24547.0 24580.9 24701.5 25145.1 09:05:42 INFO - PROCESS | 1647 | 09:05:42 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:41079.63 Median:377.15 stddev:122057.13 (32362.7%) stddev-sans-first:122361.26 09:05:42 INFO - PROCESS | 1647 | Values: 371.1 373.3 375.8 393.7 401.2 14925.5 15289.2 17577.4 20120.0 20612.5 21982.7 22230.7 22261.9 22300.6 22334.6 5818.0 5822.6 5972.3 6001.1 6055.5 5922.8 5956.8 5974.0 5981.6 5982.2 3981.3 4324.5 4328.2 5930.1 6587.4 6616.2 6627.5 6630.5 6639.4 6650.4 5465.8 5579.2 5706.1 5716.2 5805.7 26476.7 26561.0 26645.7 26860.2 28374.7 2918.0 3042.6 3402.6 3404.6 3467.6 17391.0 17394.5 18120.9 18160.6 18355.4 1621.1 1886.9 1998.3 2011.4 2034.3 150766.2 157535.2 160099.0 163322.2 163372.0 23496.0 23530.6 23582.8 23596.8 23902.0 39851.0 39908.2 40193.6 40201.0 40216.8 8333.0 8482.5 8642.4 8802.6 11325.7 7772.1 8473.4 8507.7 8530.1 8691.0 4471.0 4711.0 4790.8 4803.8 4890.5 2014.2 2039.2 2042.1 2045.2 2052.2 378.5 384.3 1013.4 1036.7 1067.0 10113.3 11219.7 11285.7 11930.0 12029.9 347.8 416.3 417.1 426.4 441.2 5207.3 5960.0 5990.7 6040.6 6124.5 697761.0 705928.0 731474.0 755073.0 760233.0 106724.5 139837.0 141048.0 152401.0 156704.0 448.9 464.6 465.3 465.5 465.9 11402.8 11514.2 11527.9 11706.2 11746.4 55035.4 55236.7 56000.7 56406.4 59458.0 74123.0 81524.0 83126.0 83764.0 86031.0 7268.4 7490.0 7625.8 7722.6 7848.1 13176.8 13250.3 13267.3 13640.5 13888.7 9667.0 11527.3 11804.4 11866.9 11948.5 11451.8 11523.4 11708.2 11825.2 11853.9 9473.4 11684.3 11884.4 11954.0 12089.0 6420.5 7422.1 7597.7 7822.6 8042.9 10192.9 11249.6 11457.7 11962.6 12049.5 09:05:42 INFO - PROCESS | 1647 | 09:05:42 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5343.56 Median:5276.43 stddev:2896.06 (54.9%) stddev-sans-first:2888.60 09:05:42 INFO - PROCESS | 1647 | Values: 1346.0 1434.8 1454.6 1477.1 1487.8 8010.2 8415.7 8420.8 8439.1 8441.2 7489.1 7714.2 7732.0 8047.5 8242.9 2262.5 2318.9 2321.5 2328.9 2331.8 2300.7 2332.6 2348.4 2357.6 2359.4 2052.2 2137.6 2224.8 2301.5 2324.9 1988.6 2310.6 2313.3 2317.8 2321.9 2306.1 2321.0 2324.2 2329.3 2331.3 8289.5 8312.6 8416.4 8420.4 8445.0 5033.7 5188.9 5189.6 5204.4 5204.8 3223.5 3399.4 3468.9 3472.9 3483.6 1162.0 1210.6 1232.7 1253.9 1259.0 10352.5 10504.0 10743.7 10744.1 10748.6 4309.7 4367.7 4465.4 4478.2 4489.6 8661.2 8666.7 8673.1 8680.0 8689.3 5088.6 5092.5 5097.0 5098.9 5109.1 3852.3 4244.9 4728.0 4737.4 4780.3 3017.6 3544.2 3660.0 3682.2 3713.7 983.4 1118.6 1130.6 1132.0 1138.0 763.2 777.1 877.0 891.5 920.8 5348.1 5573.2 5749.5 5830.4 6033.9 966.7 984.6 986.9 1012.2 1044.9 3654.7 3716.4 3721.8 3815.0 3898.7 9492.7 9940.0 9949.7 9954.5 9965.7 9874.2 9887.7 9918.2 9953.0 9966.1 7297.7 7683.7 7849.6 7859.7 7867.1 5548.4 5622.1 5735.2 5757.7 5837.5 9132.6 9151.8 9152.7 9200.3 9299.6 8776.4 9055.4 9058.6 9118.5 9143.0 6520.5 6747.4 6749.8 7013.8 7193.5 6277.5 7410.3 7457.2 7469.0 7760.6 6835.3 7058.6 7070.2 7115.0 7221.4 6980.1 6983.6 7012.4 7024.1 7092.3 6286.2 6323.4 6400.8 6408.9 6416.4 4869.7 4917.0 4926.9 4957.6 5056.5 4935.5 4940.3 4980.6 5032.4 5064.9 09:05:42 INFO - PROCESS | 1647 | 09:05:42 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4116.99 Median:4596.93 stddev:1229.13 (26.7%) stddev-sans-first:1199.57 09:05:42 INFO - PROCESS | 1647 | Values: 347.1 350.9 359.1 366.4 369.5 4259.2 4844.2 5008.4 5015.9 5075.4 4537.0 4801.0 4903.2 4948.8 4973.3 3176.4 3991.6 3996.0 4003.2 4020.2 2614.5 3653.7 3864.7 4001.6 4030.9 3542.3 3807.6 3825.2 3841.8 3850.4 3268.4 3805.5 3806.1 3822.8 3853.2 3318.3 3327.4 3333.8 3342.7 3345.8 3981.8 4404.6 4640.9 4932.6 4949.3 3894.3 4296.9 4331.2 4342.0 4373.9 5226.9 5235.8 5238.8 5257.9 5260.3 3628.0 3658.2 3661.1 3666.6 3669.8 5164.7 5509.7 5510.3 5542.3 5542.5 3184.9 3693.7 3694.5 3704.3 3737.7 3769.9 3790.2 3793.7 3794.9 3805.2 4164.6 4316.3 4351.9 4375.9 4409.2 5244.4 5274.3 5295.5 5339.6 5349.0 1063.7 1146.7 1176.0 1202.4 1218.4 716.6 750.5 811.3 832.8 839.9 4424.0 4556.7 4638.1 4649.3 4654.8 4245.7 4608.7 4808.1 5106.6 5142.5 3774.8 4061.2 4412.0 4502.5 4543.9 2415.3 2444.5 2556.2 2665.3 2671.6 4617.0 4906.9 5178.6 5235.6 5269.4 4650.6 4743.9 4968.8 5130.7 5296.9 4447.6 4863.0 5057.4 5184.8 5223.7 4908.0 4912.6 5045.8 5049.4 5056.6 4504.8 4673.8 4826.8 4960.6 5013.3 4559.0 4564.5 5267.9 5292.8 5330.3 3032.1 3127.6 3135.7 3336.7 3356.5 4432.3 4709.5 5002.2 5044.4 5045.5 3957.8 4872.8 4886.6 4902.3 4968.8 4602.4 4606.4 4951.2 4986.4 5022.4 4591.5 4698.1 4765.7 4824.8 4853.6 4370.6 4822.4 4884.0 4919.5 4927.4 4191.6 4789.4 4805.6 4806.8 4832.3 09:05:42 INFO - PROCESS | 1647 | 09:05:42 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:19486.93 Median:24919.26 stddev:28001.81 (112.4%) stddev-sans-first:28044.91 09:05:42 INFO - PROCESS | 1647 | Values: 721.3 725.8 766.8 803.2 807.3 14934.5 15926.6 15926.7 15977.1 15992.6 14536.5 14660.9 14662.3 14666.6 14697.2 670.2 694.7 696.7 822.7 845.2 717.8 840.5 846.0 850.3 852.8 759.6 905.0 905.8 906.2 906.3 705.5 900.7 900.7 902.3 903.4 535.8 652.4 667.7 669.4 673.2 12797.3 15207.0 15308.7 15322.0 15330.7 13574.9 13666.5 13671.0 13781.6 13786.4 10567.9 12470.8 12662.7 12770.2 12870.7 4433.1 4468.4 4502.1 4570.4 4665.2 99307.5 100922.0 103047.5 103643.0 104144.0 10857.0 10927.5 10929.6 11047.0 11065.9 19970.1 19976.2 20001.9 20038.3 20058.8 6749.3 6959.8 7166.8 7267.3 7710.0 4212.6 4254.5 4265.2 4283.8 4410.6 3360.8 3453.4 3454.6 3456.7 3464.8 628.5 637.6 640.1 644.1 656.7 521.5 540.7 542.3 546.0 549.7 8409.5 8633.9 8667.2 8881.7 9129.3 523.9 530.8 559.7 577.5 592.0 4211.7 4345.8 4358.1 4362.9 4504.8 84505.0 99052.5 102555.5 104120.0 105091.0 73005.0 77397.6 77837.2 79436.6 83380.0 72035.6 77268.2 77565.6 77966.0 79377.0 9765.7 9892.6 9979.0 10049.1 10086.9 45333.8 47453.5 47851.0 48137.8 51837.5 62493.7 65112.4 65814.0 66136.7 67273.0 11147.0 11521.0 11560.4 11709.2 12250.0 12742.0 12941.3 13334.7 13583.1 13889.7 12015.2 12030.2 12364.6 12486.5 12869.3 10286.8 11777.8 11840.6 12103.2 12436.4 11854.3 11856.3 11951.5 12150.0 12498.6 9971.2 10010.6 10211.9 10514.4 10598.0 8121.7 9303.4 9758.8 9787.6 9794.6 09:05:42 INFO - PROCESS | 1647 | -------- Summary: end -------- 09:05:42 INFO - PROCESS | 1647 | 09:05:43 INFO - TEST-INFO | 1647: exit 0 09:05:43 INFO - TEST-OK | dromaeo_css | took 1119629ms 09:05:43 INFO - TEST-START | kraken 09:05:43 INFO - operating with platform_type : mac_ 09:05:43 INFO - Initialising browser for kraken test... 09:05:43 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmprptZel/profile http://localhost:49255/getInfo.html) 09:05:43 INFO - PROCESS | 1652 | 1483981543784 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:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 09:05:45 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:05:45 INFO - PROCESS | 1652 | colorDepth:24 09:05:45 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:05:45 INFO - PROCESS | 1652 | __metrics 09:05:45 INFO - PROCESS | 1652 | 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" 09:05:45 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:05:46 INFO - TEST-INFO | 1652: exit 0 09:05:46 INFO - Browser initialized. 09:05:46 INFO - Running cycle 1/1 for kraken test... 09:05:46 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmprptZel/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:05:58 INFO - PROCESS | 1653 | RSS: Main: 234164224 09:05:58 INFO - PROCESS | 1653 | 09:06:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49255/tests/kraken/driver.html?testName=audio-beat-detection) 09:06:02 INFO - PROCESS | 1653 | RSS: Main: 286842880 09:06:02 INFO - PROCESS | 1653 | 09:06:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49255/tests/kraken/driver.html?testName=audio-dft) 09:06:05 INFO - PROCESS | 1653 | RSS: Main: 286547968 09:06:05 INFO - PROCESS | 1653 | 09:06:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49255/tests/kraken/driver.html?testName=audio-fft) 09:06:09 INFO - PROCESS | 1653 | RSS: Main: 380465152 09:06:09 INFO - PROCESS | 1653 | 09:06:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49255/tests/kraken/driver.html?testName=audio-oscillator) 09:06:12 INFO - PROCESS | 1653 | RSS: Main: 282472448 09:06:12 INFO - PROCESS | 1653 | 09:06:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49255/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:06:14 INFO - PROCESS | 1653 | RSS: Main: 287723520 09:06:14 INFO - PROCESS | 1653 | 09:06:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49255/tests/kraken/driver.html?testName=imaging-darkroom) 09:06:18 INFO - PROCESS | 1653 | RSS: Main: 331059200 09:06:18 INFO - PROCESS | 1653 | 09:06:21 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49255/tests/kraken/driver.html?testName=imaging-desaturate) 09:06:21 INFO - PROCESS | 1653 | RSS: Main: 330272768 09:06:21 INFO - PROCESS | 1653 | 09:06:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49255/tests/kraken/driver.html?testName=json-parse-financial) 09:06:25 INFO - PROCESS | 1653 | RSS: Main: 330883072 09:06:25 INFO - PROCESS | 1653 | 09:06:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49255/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:06:27 INFO - PROCESS | 1653 | RSS: Main: 304148480 09:06:27 INFO - PROCESS | 1653 | 09:06:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:06:30 INFO - PROCESS | 1653 | RSS: Main: 307720192 09:06:30 INFO - PROCESS | 1653 | 09:06:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:06:33 INFO - PROCESS | 1653 | RSS: Main: 315052032 09:06:33 INFO - PROCESS | 1653 | 09:06:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:06:36 INFO - PROCESS | 1653 | RSS: Main: 327778304 09:06:36 INFO - PROCESS | 1653 | 09:06:39 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:06:40 INFO - PROCESS | 1653 | RSS: Main: 321789952 09:06:40 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49255/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49255/tests/kraken/driver.html?testName=ai-astar) 09:06:42 INFO - PROCESS | 1653 | RSS: Main: 366358528 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | __start_tp_report 09:06:42 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 09:06:42 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 09:06:42 INFO - PROCESS | 1653 | |i|pagename|runs| 09:06:42 INFO - PROCESS | 1653 | |0;ai-astar;113;115;114;115;115;115;113;114;113;114 09:06:42 INFO - PROCESS | 1653 | |1;audio-beat-detection;148;110;108;147;112;112;111;111;157;140 09:06:42 INFO - PROCESS | 1653 | |2;audio-dft;187;183;184;180;186;185;181;186;183;185 09:06:42 INFO - PROCESS | 1653 | |3;audio-fft;80;59;58;88;63;78;49;60;90;83 09:06:42 INFO - PROCESS | 1653 | |4;audio-oscillator;90;69;87;68;69;68;83;69;68;83 09:06:42 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;89;85;89;85;87;84;90;86;89;86 09:06:42 INFO - PROCESS | 1653 | |6;imaging-darkroom;102;125;95;98;98;138;149;116;96;94 09:06:42 INFO - PROCESS | 1653 | |7;imaging-desaturate;85;84;88;85;91;86;70;131;133;129 09:06:42 INFO - PROCESS | 1653 | |8;json-parse-financial;56;56;55;56;53;56;74;84;84;85 09:06:42 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;65;72;79;77;72;69;70;70;71;71 09:06:42 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;85;87;90;89;85;90;90;90;93;90 09:06:42 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;140;140;158;155;144;147;151;151;183;156 09:06:42 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;174;182;236;191;173;173;174;176;187;174 09:06:42 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;68;67;66;67;67;67;65;66;68;69 09:06:42 INFO - PROCESS | 1653 | __end_tp_report 09:06:42 INFO - PROCESS | 1653 | __start_cc_report 09:06:42 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,2001 09:06:42 INFO - PROCESS | 1653 | __end_cc_report 09:06:42 INFO - PROCESS | 1653 | __startTimestamp1483981602429__endTimestamp 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | ------- Summary: start ------- 09:06:42 INFO - PROCESS | 1653 | Number of tests: 14 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.50 stddev:0.88 (0.8%) stddev-sans-first:0.83 09:06:42 INFO - PROCESS | 1653 | Values: 113.0 115.0 114.0 115.0 115.0 115.0 113.0 114.0 113.0 114.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:126.00 stddev:19.73 (15.7%) stddev-sans-first:19.19 09:06:42 INFO - PROCESS | 1653 | Values: 148.0 110.0 108.0 147.0 112.0 112.0 111.0 111.0 157.0 140.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:184.00 Median:185.00 stddev:2.26 (1.2%) stddev-sans-first:2.12 09:06:42 INFO - PROCESS | 1653 | Values: 187.0 183.0 184.0 180.0 186.0 185.0 181.0 186.0 183.0 185.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:70.80 Median:79.00 stddev:14.55 (18.4%) stddev-sans-first:15.05 09:06:42 INFO - PROCESS | 1653 | Values: 80.0 59.0 58.0 88.0 63.0 78.0 49.0 60.0 90.0 83.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:76.00 stddev:9.13 (12.0%) stddev-sans-first:8.01 09:06:42 INFO - PROCESS | 1653 | Values: 90.0 69.0 87.0 68.0 69.0 68.0 83.0 69.0 68.0 83.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:2.11 (2.4%) stddev-sans-first:2.11 09:06:42 INFO - PROCESS | 1653 | Values: 89.0 85.0 89.0 85.0 87.0 84.0 90.0 86.0 89.0 86.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:111.10 Median:94.50 stddev:19.95 (21.1%) stddev-sans-first:20.89 09:06:42 INFO - PROCESS | 1653 | Values: 102.0 125.0 95.0 98.0 98.0 138.0 149.0 116.0 96.0 94.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:98.20 Median:85.00 stddev:23.30 (27.4%) stddev-sans-first:24.22 09:06:42 INFO - PROCESS | 1653 | Values: 85.0 84.0 88.0 85.0 91.0 86.0 70.0 131.0 133.0 129.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:65.00 stddev:14.00 (21.5%) stddev-sans-first:14.38 09:06:42 INFO - PROCESS | 1653 | Values: 56.0 56.0 55.0 56.0 53.0 56.0 74.0 84.0 84.0 85.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.50 stddev:3.95 (5.5%) stddev-sans-first:3.39 09:06:42 INFO - PROCESS | 1653 | Values: 65.0 72.0 79.0 77.0 72.0 69.0 70.0 70.0 71.0 71.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:90.00 stddev:2.51 (2.8%) stddev-sans-first:2.24 09:06:42 INFO - PROCESS | 1653 | Values: 85.0 87.0 90.0 89.0 85.0 90.0 90.0 90.0 93.0 90.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:153.00 stddev:12.47 (8.1%) stddev-sans-first:12.37 09:06:42 INFO - PROCESS | 1653 | Values: 140.0 140.0 158.0 155.0 144.0 147.0 151.0 151.0 183.0 156.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.00 Median:179.00 stddev:19.36 (10.8%) stddev-sans-first:20.19 09:06:42 INFO - PROCESS | 1653 | Values: 174.0 182.0 236.0 191.0 173.0 173.0 174.0 176.0 187.0 174.0 09:06:42 INFO - PROCESS | 1653 | 09:06:42 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.15 (1.7%) stddev-sans-first:1.17 09:06:42 INFO - PROCESS | 1653 | Values: 68.0 67.0 66.0 67.0 67.0 67.0 65.0 66.0 68.0 69.0 09:06:42 INFO - PROCESS | 1653 | -------- Summary: end -------- 09:06:42 INFO - PROCESS | 1653 | 09:06:43 INFO - TEST-INFO | 1653: exit 0 09:06:43 INFO - TEST-OK | kraken | took 60234ms 09:06:43 INFO - SUITE-END | took 1179s 09:06:43 INFO - Completed test suite (00:19:39) 09:06:43 INFO - javascript benchmark 09:06:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3237.1745204972726, 3263.1515805890467, 3267.7549183811207, 3293.3248684019777, 3321.7342612590996, 5389.865253368668, 5417.052015897212, 5458.290422245108, 5476.835694929154, 5496.5053145216925, 5455.0, 5456.536194423469, 5540.473655002778, 5540.806071787489, 5671.744771485282, 4932.4574296827495, 4954.3063971044085, 4958.702477851322, 4983.72589507577, 5020.59835213182, 4996.575291100261, 5030.144875371196, 5063.215201643751, 5085.5423011928915, 5113.539781419671, 4940.98709764501, 5022.773975171108, 5039.874003149914, 5058.4688607696025, 5080.110980578403, 4982.5017498250245, 4996.300517927493, 5026.019926114409, 5040.521150490697, 5043.470435604246, 4962.87592810179, 4968.776405061781, 4997.600191984632, 5022.0, 5050.747462626854, 5485.177223416479, 5488.780448782084, 5497.615166938316, 5501.202325662756, 5513.448655134494, 4831.661783675144, 5781.739821707991, 5786.826395208151, 5921.703914804286, 6018.879622407528, 6056.909146362808, 6059.0, 6100.298465676459, 6105.267367915834, 6143.87712245757, 5578.525825304894, 5578.860528486739, 5584.0, 5588.0, 5589.133684278902, 5994.0, 6104.890867003831, 6139.723712432951, 6148.692565371803, 6158.753649853956, 5792.536597072224, 5805.477507024388, 5834.416558344216, 5874.530037596982, 5943.435373639497, 5932.0508718604815, 6054.788082417094, 6068.302145253264, 6073.240844894388, 6087.29996050451, 5824.475797178275, 5853.560982926298, 5854.092615644582, 5915.585908986416, 5922.8815423691285, 5966.642001479925, 5972.372900845436, 5981.282246130492, 5987.550933679991, 5991.400859914061, 4685.625149987993, 4711.670183087151, 4720.834770783074, 4749.453812811571, 4765.737884416321, 4232.32282834745, 4266.488021437447, 4268.765217913045, 4291.935620965688, 4307.913841723211, 5629.352624448159, 5637.069883469264, 5645.266115404971, 5649.717514124277, 5694.544436445074, 5836.503897168704, 5941.168236446814, 6082.0, 6089.482393996472, 6096.69516524172, 5568.053430916754, 5574.804881829197, 5602.13166959122, 5603.691796951125, 5675.943240567624, 5216.947830521646, 5221.869453263623, 5223.268742376071, 5229.581633469314, 5246.895062098737, 5193.76628051731, 6184.288806787277, 6193.783217587453, 6205.813825585149, 6267.749290028348, 5896.557758168069, 5952.136940143741, 5952.434518720715, 5955.225820643462, 6024.849378765566, 6212.720427580679, 6216.937830621636, 6225.937740622536, 6246.750129994931, 6247.437730604267, 5899.908516924383, 5918.348981611933, 5929.229200203946, 5933.9109913352195, 5982.760689572368, 5848.736806843617, 5901.704914754159, 5911.822645320647, 6016.217891674054, 6042.788502402394, 5759.9136012959, 5776.508996735324, 5807.332156801853, 5812.854678632899, 5883.382244864399, 5635.577331700221, 5680.943190568207, 5728.770849166153, 5833.79581714638, 5845.649261044351, 5617.831465056055, 5629.352624448241, 5654.575906806924, 5660.688662123458, 5718.285214348207, 5811.854703632443, 5821.592488525763, 5829.76680932758, 5840.678762668094, 5842.415758424294, 5511.22842802016, 5561.332640083216, 5568.0, 5610.326760788568, 5633.746481408429], "value": 5483.116963475996, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.4354240225332, 499.27604972789453, 505.11352576228734, 519.4104691175517, 544.4365082139985, 30615.081547553582, 38701.225975480505, 40317.798411007905, 40377.78866634002, 42474.0, 41313.58686413135, 41886.58113418865, 42328.36507452383, 42364.57635423645, 42439.575604244026, 8372.581370931459, 9318.25453963683, 9518.476483793389, 9524.666636667718, 9524.8571271431, 8450.408471407003, 11101.83347249792, 11342.773144537105, 11363.943180284086, 11566.768664626745, 11290.379029153393, 11367.602133925315, 11370.488328025218, 11377.065898797224, 11390.430478476042, 11249.493772780244, 11305.830412543819, 11381.886181138165, 11403.201775875692, 11422.600208992686, 11256.155788315828, 11283.37941413222, 11308.264962777394, 11321.773564528663, 11332.036776873976, 40130.799346003085, 40319.0, 40390.59609403898, 40392.78821635326, 40480.0, 3947.1513624570857, 4044.1507283470505, 4054.4323794668476, 4059.167870586552, 4144.3990621360035, 29829.850850745825, 30745.538816917764, 30997.38005239905, 31261.531077033862, 31445.0, 3085.52330554836, 3176.698213669698, 3184.7738620630994, 3189.100015900786, 3215.2122729931084, 454682.0, 455169.0, 456213.0, 457380.0, 461547.0, 58113.0, 58365.416345836, 58412.12381814276, 58472.70763646154, 58499.4150058494, 62897.685511573065, 63101.684491577245, 63400.682996585645, 63592.0, 63680.0, 15829.050256984618, 15829.525114246591, 16014.359425623077, 16016.919915400349, 16038.919805400898, 11093.223474356717, 11356.012697777856, 11374.20380573352, 12478.251304921734, 12590.24458532491, 14740.631484212981, 16241.350345986028, 16622.667546648998, 16926.238319275417, 16955.237014334383, 5520.66875987442, 5834.416558344216, 6188.824123416536, 6486.189226346707, 6576.539642225094, 801.4149670740392, 837.9245867871921, 856.8971723393108, 859.8366310401004, 874.3516880949013, 29793.0, 29809.85095074511, 29887.16071973159, 30211.546826797614, 30244.395112097634, 996.6412091647002, 1038.6105210546045, 1042.2287507244585, 1063.5692544519482, 1082.621082621092, 14925.552233433014, 15421.537353879401, 15458.0, 15527.844721552865, 15857.762133567774, 580702.0964895233, 581953.0, 588167.0, 588644.0, 588983.0, 53297.667558311354, 56228.5942851432, 59293.0, 59583.80832383415, 59688.0, 1394.2053029773135, 1425.6150839273546, 1448.9420423182955, 1450.5068276785732, 1453.6874571967078, 28940.540054095636, 29059.128226152403, 29221.85389073041, 29906.80372785151, 30050.54924176183, 50407.747961259956, 50768.0, 50780.238296424846, 50818.74590627023, 50980.235296469844, 507482.0, 508248.9276394045, 509976.0, 510668.0, 511471.0, 33557.496637549964, 34350.82824585861, 38341.616583833806, 39120.21759564736, 39220.41169382405, 36694.633053670186, 46228.844278893295, 46442.76778616085, 46522.30216546687, 47424.7628761854, 37855.864324070324, 38480.03799905025, 38681.806590966866, 39024.60805715465, 39443.605563943995, 33338.66661333453, 38089.809550952064, 38091.42862857115, 39389.6061039386, 39421.0, 36769.0, 36990.43333698351, 37146.62853371432, 37212.25575488529, 37759.678411255474, 31773.682263177998, 33378.16554586155, 34016.82991585026, 34203.97388078362, 34233.0, 21800.800955947576, 24547.0, 24580.877095614407, 24701.50596988086, 25145.119920802685], "value": 19980.573199944516, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.13710622801995, 373.3335995248482, 375.81243271262815, 393.74209892520395, 401.2216300377268, 14925.477608283712, 15289.235538223085, 17577.3847915323, 20120.0, 20612.48468788281, 21982.670259946113, 22230.666540001912, 22261.888690556523, 22300.553988920212, 22334.553308933813, 5818.0, 5822.563307751914, 5972.343042265369, 6001.06983417571, 6055.545834062441, 5922.792702255422, 5956.821295361146, 5973.970130149343, 5981.551383646222, 5982.192404025466, 3981.3032719274156, 4324.502682191557, 4328.177646247219, 5930.110483427498, 6587.440067594261, 6616.239132499776, 6627.502937279699, 6630.502712296597, 6639.3692599203, 6650.401463868251, 5465.808696695636, 5579.246801681762, 5706.1440783882335, 5716.171155182476, 5805.651660900359, 26476.735232647814, 26561.0, 26645.73354266452, 26860.194194174204, 28374.716252837414, 2918.0, 3042.574039634453, 3402.5916889973114, 3404.608470025954, 3467.6185619581825, 17390.956542607357, 17394.478165654924, 18120.90939545307, 18160.63911691485, 18355.35756248525, 1621.116491512117, 1886.9056547172586, 1998.3305592626486, 2011.386527109231, 2034.338839877031, 150766.24616876955, 157535.2123239388, 160099.0, 163322.18338908348, 163372.0, 23496.0, 23530.64704029441, 23582.7641723584, 23596.764032359457, 23902.0, 39851.00372490711, 39908.204130814185, 40193.59806401957, 40201.0, 40216.79349619516, 8333.0, 8482.491050536988, 8642.3950323478, 8802.60388282516, 11325.660230193107, 7772.106207786177, 8473.3644976627, 8507.744767656981, 8530.061693213742, 8691.002139016124, 4470.955290447054, 4711.0, 4790.808367665254, 4803.759812009385, 4890.462049174588, 2014.21445636202, 2039.1945181653164, 2042.0606521000507, 2045.233037610896, 2052.209899188823, 378.49470956273683, 384.25454618040914, 1013.3717095400899, 1036.6630844975414, 1067.0343339277965, 10113.342632728849, 11219.719507012227, 11285.661430157108, 11930.04559635227, 12029.857163569446, 347.8191340502924, 416.2777580897141, 417.06993404707674, 426.3881330291042, 441.18160811694486, 5207.3230480037355, 5959.970200148971, 5990.73041713124, 6040.607360521552, 6124.5100391968535, 697761.0, 705928.0, 731474.0, 755073.0, 760233.0, 106724.51626770121, 139837.0, 141048.0, 152401.0, 156704.0, 448.8855341887798, 464.5981226239346, 465.3183086777819, 465.51121322611124, 465.86722784006395, 11402.771944560896, 11514.194006419806, 11527.942360288145, 11706.180567360203, 11746.353950532803, 55035.44964550303, 55236.72381638066, 56000.719996399755, 56406.43593564012, 59458.0, 74123.0, 81524.0, 83126.0, 83764.0, 86031.0, 7268.38218751412, 7489.962550187214, 7625.847483050197, 7722.6138693063995, 7848.097468791162, 13176.80234796498, 13250.337483125611, 13267.336633168496, 13640.522581709592, 13888.652783680085, 9667.0, 11527.308361498337, 11804.40977951082, 11866.881331186923, 11948.522059117538, 11451.828222576834, 11523.366214857928, 11708.180427370344, 11825.231359961575, 11853.881461185623, 9473.431594104375, 11684.298942063504, 11884.405779711153, 11954.043676506033, 12088.972437342924, 6420.518461115342, 7422.14645315774, 7597.734079307419, 7822.6479808409895, 8042.879356809535, 10192.949035254776, 11249.550017999189, 11457.656270311903, 11962.641120766391, 12049.457774400349], "value": 9605.450559943301, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.9798103028454, 1434.8449498153984, 1454.585443148702, 1477.062065588351, 1487.776833474978, 8010.1589333120055, 8415.747527574174, 8420.789480262996, 8439.113893041234, 8441.198086181803, 7489.138749043862, 7714.190010048948, 7731.961340193306, 8047.4769140006, 8242.91757082429, 2262.5361800830833, 2318.9304320870315, 2321.5240875620457, 2328.860268383894, 2331.778481044298, 2300.735415427222, 2332.6034574122355, 2348.4481146930457, 2357.6463530470396, 2359.3629719975597, 2052.168871606994, 2137.6472881974532, 2224.8442609017375, 2301.4591570980806, 2324.9070037198494, 1988.6122206169803, 2310.607196776535, 2313.2597568777996, 2317.8493397929083, 2321.895514701834, 2306.1467257114805, 2320.953580928372, 2324.2097686786406, 2329.2895666821614, 2331.2889568681544, 8289.502629842229, 8312.584370781437, 8416.410851240416, 8420.410571260014, 8444.957775211145, 5033.672811267256, 5188.92216616751, 5189.558887494568, 5204.401493828221, 5204.76578553966, 3223.468127758917, 3399.42209824328, 3468.94796578049, 3472.947905781415, 3483.6168021517615, 1161.97095072623, 1210.5520957245787, 1232.7041510037527, 1253.931033793141, 1258.987410125896, 10352.5341359639, 10504.0, 10743.731406714816, 10744.14046876248, 10748.570057197703, 4309.676774241944, 4367.694261401735, 4465.37484752135, 4478.193925093449, 4489.6408287336935, 8661.177188167114, 8666.653333866701, 8673.132686731276, 8680.045195028542, 8689.261412779984, 5088.643794934393, 5092.465291144451, 5097.0, 5098.923516147261, 5109.131447653907, 3852.287326844543, 4244.925461130289, 4727.976360118178, 4737.360456338351, 4780.259059845729, 3017.6227971503563, 3544.2379888324135, 3660.0, 3682.1531047859144, 3713.665770080706, 983.4296108257194, 1118.625260537729, 1130.6099395708468, 1131.9830202546968, 1137.9601713940137, 763.2443880558208, 777.1412589089089, 876.9824603507894, 891.5364010714521, 920.8434566123775, 5348.090824559834, 5573.219749235111, 5749.453801888814, 5830.416958304136, 6033.939660603338, 966.6568368229177, 984.5864736810556, 986.8519722041751, 1012.1851909213154, 1044.8746150461993, 3654.689351405107, 3716.4425336199247, 3721.7953012584567, 3814.961850381461, 3898.6686131679126, 9492.715218543455, 9939.950300248596, 9949.651762188289, 9954.502274886227, 9965.651202207888, 9874.160696340892, 9887.653932112486, 9918.15695665862, 9952.950235248778, 9966.103050725469, 7297.671604777904, 7683.6926522939575, 7849.646765895548, 7859.685612575548, 7867.095284042294, 5548.389677135431, 5622.100463925753, 5735.225744524602, 5757.740901659352, 5837.503812176013, 9132.589033493641, 9151.816963660822, 9152.679656212, 9200.309976751636, 9299.581518831534, 8776.429532080394, 9055.36612437123, 9058.592363343534, 9118.452892826452, 9143.0, 6520.510961677988, 6747.392734653905, 6749.763758268437, 7013.754518591825, 7193.460490463341, 6277.529185311028, 7410.333070023724, 7457.179710231761, 7469.029026226556, 7760.611969401394, 6835.316468353125, 7058.576485410892, 7070.15158181001, 7115.039469671528, 7221.386182174574, 6980.05769221169, 6983.615901125284, 7012.403945664676, 7024.088257470388, 7092.326229008236, 6286.214223222098, 6323.36766323364, 6400.839979000562, 6408.935910640834, 6416.422521973045, 4869.732164730974, 4916.950830491649, 4926.926096108489, 4957.578605818546, 5056.494350564914, 4935.457099718957, 4940.258961155855, 4980.551750342486, 5032.370953630796, 5064.949350506447], "value": 4349.330407250941, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.1321695760598, 350.86316336628704, 359.13090321422146, 366.42654246104826, 369.51962448816533, 4259.212045771531, 4844.249141383091, 5008.39899212095, 5015.874603134924, 5075.350511083899, 4536.977315113428, 4800.975995120019, 4903.239997800347, 4948.802047918088, 4973.278874563186, 3176.3647270545866, 3991.600839916014, 3995.98002009991, 4003.219372222418, 4020.2160578687167, 2614.5032443835707, 3653.725970552219, 3864.6666899919624, 4001.5998400160042, 4030.858919937803, 3542.256126213498, 3807.600201978794, 3825.2158307547024, 3841.8463261469506, 3850.3839385698293, 3268.428025095611, 3805.543334799814, 3806.0969515242377, 3822.847086116552, 3853.190829925719, 3318.3197444523817, 3327.4177019021577, 3333.849976751052, 3342.699157075875, 3345.849436775351, 3981.8009099544906, 4404.581564751344, 4640.886187315054, 4932.630052746058, 4949.307097006422, 3894.279558281727, 4296.892577685551, 4331.177076355482, 4342.002678767771, 4373.9125217495475, 5226.921596176022, 5235.806158243468, 5238.79044838206, 5257.921131183035, 5260.29095016067, 3628.0385697790107, 3658.1951970566433, 3661.139632186434, 3666.578343490506, 3669.7614655047605, 5164.664296820694, 5509.669419834822, 5510.256115424407, 5542.251796007487, 5542.533572560213, 3184.9044528664176, 3693.7045036397026, 3694.4827724118645, 3704.3147017801793, 3737.7009839212797, 3769.9434508482395, 3790.223004284115, 3793.7344385893275, 3794.886153415402, 3805.219929914361, 4164.646005089601, 4316.330968699856, 4351.891202719894, 4375.906023494126, 4409.228385032607, 5244.449332820075, 5274.3143391359645, 5295.511109778398, 5339.5728341732565, 5349.010433069817, 1063.7181146996052, 1146.696125526736, 1176.0356507663805, 1202.4108186988487, 1218.421249906305, 716.6201912986126, 750.512167091395, 811.2820154163608, 832.7626626411444, 839.8656215005569, 4423.955760442419, 4556.703814252063, 4638.0751987925305, 4649.349091127245, 4654.836290927268, 4245.702800803914, 4608.654350923694, 4808.0624278266105, 5106.591472682177, 5142.537171654569, 3774.7546409483843, 4061.2080644274674, 4411.977940110279, 4502.482214545304, 4543.886402839889, 2415.251272105653, 2444.53102366611, 2556.1595651131156, 2665.3469899874653, 2671.584060447966, 4616.961183733633, 4906.901861962812, 5178.559822415137, 5235.633505654644, 5269.394019687708, 4650.558196971282, 4743.928841067387, 4968.751562421864, 5130.743462826844, 5296.867578310496, 4447.6219521341045, 4862.9756851216225, 5057.367829021372, 5184.7926082956255, 5223.686578805284, 4907.975460122677, 4912.631552633638, 5045.837184323022, 5049.419316778471, 5056.57019153376, 4504.842330518416, 4673.779954521575, 4826.7827947743135, 4960.635825147969, 5013.348264725708, 4559.0, 4564.549564175036, 5267.864726189922, 5292.761825717929, 5330.307060082319, 3032.1358412852233, 3127.6090488688915, 3135.7300293381218, 3336.6830151135605, 3356.4965255211914, 4432.29083346663, 4709.512683248966, 5002.224655178453, 5044.39467263916, 5045.520675535819, 3957.782321972204, 4872.780724867319, 4886.633502487399, 4902.338184345211, 4968.801247950041, 4602.401687780567, 4606.355110284496, 4951.183054795918, 4986.4319875263545, 5022.422421421437, 4591.461860276845, 4698.077105616711, 4765.7140571565815, 4824.815621689668, 4853.587445067209, 4370.606645401929, 4822.397200349957, 4883.95116048835, 4919.483454237254, 4927.408710954708, 4191.580841915784, 4789.425268967746, 4805.567498925114, 4806.831760888352, 4832.323474713613], "value": 3741.378944837029, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.282324087533, 725.7604990353184, 766.7738017284901, 803.220875750522, 807.2613558593889, 14934.477293294736, 15926.601834954132, 15926.681466370666, 15977.121258330802, 15992.600184995366, 14536.491222807204, 14660.853391466082, 14662.266886655678, 14666.63333416665, 14697.191654459, 670.2392784189955, 694.7464175575903, 696.7352406085706, 822.7038266224155, 845.2435070611799, 717.8456631824157, 840.4999025579771, 845.9703910363138, 850.3112478892086, 852.778277647813, 759.5518644000039, 904.9864252036225, 905.8233644439337, 906.2387594420685, 906.3429013964889, 705.5061456980135, 900.6577500549814, 900.6937641202023, 902.255639097747, 903.421810041574, 535.8151437753969, 652.4128284543901, 667.6761770541284, 669.4376723552191, 673.1921693967209, 12797.296148711817, 15207.0, 15308.693826123415, 15322.0, 15330.693386132214, 13574.93212533931, 13666.453341866314, 13671.0, 13781.586552403443, 13786.379612917342, 10567.86161746717, 12470.75058498834, 12662.733726627355, 12770.23378597287, 12870.67823304425, 4433.113377324516, 4468.396766436522, 4502.099580083996, 4570.405847239871, 4665.183592871268, 99307.50346248223, 100922.0, 103047.48476257645, 103643.0, 104143.95856041419, 10857.0, 10927.45362731876, 10929.56281748721, 11047.0, 11065.944670276758, 19970.10134543949, 19976.200951962048, 20001.899990500246, 20038.298659546846, 20058.799412005985, 6749.291324410964, 6959.791206263821, 7166.820829479305, 7267.273272672691, 7709.9614501927135, 4212.599803018708, 4254.489461264668, 4265.189613973335, 4283.764392958355, 4410.647148228134, 3360.8487618057247, 3453.4474484082425, 3454.602720687103, 3456.7407444441264, 3464.826758662057, 628.4595248086641, 637.6014990630856, 640.1262276991965, 644.0886146103212, 656.7077650445506, 521.4733119549206, 540.675594643211, 542.2706459811548, 546.0013575714341, 549.719642982074, 8409.537475438787, 8633.913660863436, 8667.219950204386, 8881.73354799357, 9129.269658427309, 523.8585581892868, 530.7638101045075, 559.706154269012, 577.4716134736669, 591.9585629005928, 4211.7262377945335, 4345.847895323648, 4358.128374325084, 4362.912741745147, 4504.752238626907, 84505.0, 99052.5047374773, 102555.4872225634, 104119.95880041254, 105091.0, 73005.0, 77397.61301193458, 77837.221627783, 79436.60281698554, 83380.0, 72035.63982180055, 77268.22731772835, 77565.61217193991, 77966.0, 79377.0, 9765.658201962897, 9892.604295828087, 9978.950105249427, 10049.145822604867, 10086.949565252127, 45333.77333113313, 47453.52546474491, 47851.0, 48137.75931120322, 51837.48162518327, 62493.68753156205, 65112.37219069561, 65814.0, 66136.67726645536, 67273.0, 11147.0, 11521.0, 11560.42197890119, 11709.180357374902, 12250.0, 12742.0, 12941.28822914711, 13334.666633334244, 13583.117097388638, 13889.722205556034, 12015.219010764273, 12030.218035827644, 12364.62906112818, 12486.500539978299, 12869.297447536122, 10286.845697314697, 11777.764444710887, 11840.585579505021, 12103.152779305365, 12436.440360183995, 11854.348010859485, 11856.347900865536, 11951.521939122338, 12150.0, 12498.625041248777, 9971.202303815822, 10010.599576016877, 10211.897881021392, 10514.369137851754, 10598.0, 8121.67513299485, 9303.395279306824, 9758.804823903623, 9787.559559819683, 9794.559244834141], "value": 7124.101414361172, "unit": "score"}], "name": "dromaeo_css", "value": 7042.579144239987, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 114.0, 115.0, 115.0, 115.0, 113.0, 114.0, 113.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 110.0, 108.0, 147.0, 112.0, 112.0, 111.0, 111.0, 157.0, 140.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 184.0, 180.0, 186.0, 185.0, 181.0, 186.0, 183.0, 185.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 88.0, 63.0, 78.0, 49.0, 60.0, 90.0, 83.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 87.0, 68.0, 69.0, 68.0, 83.0, 69.0, 68.0, 83.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 85.0, 87.0, 84.0, 90.0, 86.0, 89.0, 86.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 125.0, 95.0, 98.0, 98.0, 138.0, 149.0, 116.0, 96.0, 94.0], "value": 111.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 88.0, 85.0, 91.0, 86.0, 70.0, 131.0, 133.0, 129.0], "value": 98.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 56.0, 53.0, 56.0, 74.0, 84.0, 84.0, 85.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 79.0, 77.0, 72.0, 69.0, 70.0, 70.0, 71.0, 71.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 90.0, 89.0, 85.0, 90.0, 90.0, 90.0, 93.0, 90.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 158.0, 155.0, 144.0, 147.0, 151.0, 151.0, 183.0, 156.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 182.0, 236.0, 191.0, 173.0, 173.0, 174.0, 176.0, 187.0, 174.0], "value": 184.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 67.0, 67.0, 67.0, 65.0, 66.0, 68.0, 69.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1496.1000000000001, "alertThreshold": 2.0}]} 09:06:43 INFO - Return code: 0 09:06:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:06:43 INFO - # TBPL SUCCESS # 09:06:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:06:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:06:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:06:43 INFO - 2017-01-09 09:06:43.739 screenresolution[1657:9927] starting screenresolution argv=screenresolution get 09:06:43 INFO - 2017-01-09 09:06:43.742 screenresolution[1657:9927] Display 0: 1600x1200x32@60 09:06:43 INFO - 2017-01-09 09:06:43.749 screenresolution[1658:9930] starting screenresolution argv=screenresolution list 09:06:43 INFO - Available Modes on Display 0 09:06:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:06:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:06:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:06:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:06:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:06:43 INFO - Intel Iris: 09:06:43 INFO - Chipset Model: Intel Iris 09:06:43 INFO - Type: GPU 09:06:43 INFO - Bus: Built-In 09:06:43 INFO - VRAM (Dynamic, Max): 1536 MB 09:06:43 INFO - Vendor: Intel (0x8086) 09:06:43 INFO - Device ID: 0x0a2e 09:06:43 INFO - Revision ID: 0x0009 09:06:43 INFO - Displays: 09:06:43 INFO - Display: 09:06:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:06:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:06:43 INFO - Display Serial Number: 1600x1200 60 09:06:43 INFO - Main Display: Yes 09:06:43 INFO - Mirror: Off 09:06:43 INFO - Online: Yes 09:06:43 INFO - Rotation: Supported 09:06:43 INFO - Return code: 0 09:06:43 INFO - Running post-action listener: _resource_record_post_action 09:06:43 INFO - [mozharness: 2017-01-09 17:06:43.993097Z] Finished run-tests step (success) 09:06:43 INFO - Running post-run listener: _resource_record_post_run 09:06:44 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32185344; Write bytes: 548067328; Read time: 190; Write time: 1658 09:06:44 INFO - TinderboxPrint: CPU usage
53.8% 09:06:44 INFO - TinderboxPrint: I/O read bytes / time
32,185,344 / 190 09:06:44 INFO - TinderboxPrint: I/O write bytes / time
548,067,328 / 1,658 09:06:44 INFO - TinderboxPrint: CPU idle
2,222.2 (46.2%) 09:06:44 INFO - TinderboxPrint: CPU system
961.7 (20.0%) 09:06:44 INFO - TinderboxPrint: CPU user
1,625.8 (33.8%) 09:06:44 INFO - TinderboxPrint: Swap in / out
278,032,384 / 0 09:06:44 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 244195840; Write bytes: 236511232; Read time: 17723; Write time: 504 09:06:44 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30157824; Write bytes: 296134656; Read time: 171; Write time: 1108 09:06:44 INFO - Running post-run listener: _upload_blobber_files 09:06:44 INFO - Blob upload gear active. 09:06:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:06:44 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 09:06:44 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'] 09:06:44 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 09:06:44 INFO - (blobuploader) - INFO - Open directory for files ... 09:06:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:06:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:06:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:45 INFO - (blobuploader) - INFO - Done attempting. 09:06:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:06:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:06:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:46 INFO - (blobuploader) - INFO - Done attempting. 09:06:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:06:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:06:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:47 INFO - (blobuploader) - INFO - Done attempting. 09:06:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:06:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:06:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:50 INFO - (blobuploader) - INFO - Done attempting. 09:06:50 INFO - (blobuploader) - INFO - Iteration through files over. 09:06:50 INFO - Return code: 0 09:06:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:06:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:06:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/103af5ab8d6bee3a814b656dd6f6a8ef2f822bfa2430b2735d35ca5624e3227a7a277a3173b2bf292387de43cd8e5f90ce9b445b34abfe6d5f5dac9a836a22fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6ae950083e1bf207655c00c4849235938ba6806d0de069626ec2ce777249cc5c5ecf66b82c7c0106b0888b39aa27cbcef158914f5c341db462ff696fa973f791", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/980a138ce8e6b6b877eaafe81317c2d5f1ab5314d8fb271eb560c0f4d710d4742678384e37fd1ce1e08a9211aeb5fc9486d3aed57fd15633c2de4a26c3cf17fc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/025d777e584c491ae5312ed7cf5a7d9ac064a77c8566ad1ab83bda5041e19bea5400085c0b60797fdf4771ba2c51617c76551950c69628b07ec1800130537974"} 09:06:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:06:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:06:50 INFO - Contents: 09:06:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/103af5ab8d6bee3a814b656dd6f6a8ef2f822bfa2430b2735d35ca5624e3227a7a277a3173b2bf292387de43cd8e5f90ce9b445b34abfe6d5f5dac9a836a22fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6ae950083e1bf207655c00c4849235938ba6806d0de069626ec2ce777249cc5c5ecf66b82c7c0106b0888b39aa27cbcef158914f5c341db462ff696fa973f791", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/980a138ce8e6b6b877eaafe81317c2d5f1ab5314d8fb271eb560c0f4d710d4742678384e37fd1ce1e08a9211aeb5fc9486d3aed57fd15633c2de4a26c3cf17fc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/025d777e584c491ae5312ed7cf5a7d9ac064a77c8566ad1ab83bda5041e19bea5400085c0b60797fdf4771ba2c51617c76551950c69628b07ec1800130537974"} 09:06:50 INFO - Running post-run listener: copy_logs_to_upload_dir 09:06:50 INFO - Copying logs to upload dir... 09:06:50 INFO - mkdir: /builds/slave/test/build/upload/logs 09:06:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1369.760376 ========= master_lag: 0.86 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 50 secs) (at 2017-01-09 09:06:51.219950) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 09:06:51.227848) ========= 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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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/103af5ab8d6bee3a814b656dd6f6a8ef2f822bfa2430b2735d35ca5624e3227a7a277a3173b2bf292387de43cd8e5f90ce9b445b34abfe6d5f5dac9a836a22fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6ae950083e1bf207655c00c4849235938ba6806d0de069626ec2ce777249cc5c5ecf66b82c7c0106b0888b39aa27cbcef158914f5c341db462ff696fa973f791", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/980a138ce8e6b6b877eaafe81317c2d5f1ab5314d8fb271eb560c0f4d710d4742678384e37fd1ce1e08a9211aeb5fc9486d3aed57fd15633c2de4a26c3cf17fc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/025d777e584c491ae5312ed7cf5a7d9ac064a77c8566ad1ab83bda5041e19bea5400085c0b60797fdf4771ba2c51617c76551950c69628b07ec1800130537974"} build_url:https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011112 build_url: 'https://queue.taskcluster.net/v1/task/PMGiFmpFTluoWPrklLO7hg/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/103af5ab8d6bee3a814b656dd6f6a8ef2f822bfa2430b2735d35ca5624e3227a7a277a3173b2bf292387de43cd8e5f90ce9b445b34abfe6d5f5dac9a836a22fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6ae950083e1bf207655c00c4849235938ba6806d0de069626ec2ce777249cc5c5ecf66b82c7c0106b0888b39aa27cbcef158914f5c341db462ff696fa973f791", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/980a138ce8e6b6b877eaafe81317c2d5f1ab5314d8fb271eb560c0f4d710d4742678384e37fd1ce1e08a9211aeb5fc9486d3aed57fd15633c2de4a26c3cf17fc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/025d777e584c491ae5312ed7cf5a7d9ac064a77c8566ad1ab83bda5041e19bea5400085c0b60797fdf4771ba2c51617c76551950c69628b07ec1800130537974"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 09:06:51.264936) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:06:51.265310) ========= 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.dJ2NvsJz1U/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.bjRWB3c7h8/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005028 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:06:51.314239) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 09:06:51.317664) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 09:06:51.318043) ========= ========= Total master_lag: 1.02 =========