builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0228 starttime: 1483705963.53 results: success (0) buildid: 20170106030204 builduid: 10a3e528a73148bc94f9bbb9f997d5e1 revision: a14094edbad78fc1d16e8d4c57902537cf286fd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:43.530521) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:43.531003) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:43.531750) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:43.559206) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:43.559570) ========= 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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-06 04:32:43-- 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% 157M=0s 2017-01-06 04:32:44 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.640103 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:44.225767) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:44.226151) ========= 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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.024757 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:44.276904) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-06 04:32:44.277292) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a14094edbad78fc1d16e8d4c57902537cf286fd1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a14094edbad78fc1d16e8d4c57902537cf286fd1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hvkLZ4cosS/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.5GiQdWVgNh/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-06 04:32:44,491 truncating revision to first 12 chars 2017-01-06 04:32:44,491 Setting DEBUG logging. 2017-01-06 04:32:44,491 attempt 1/10 2017-01-06 04:32:44,491 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a14094edbad7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-06 04:32:46,302 unpacking tar archive at: mozilla-central-a14094edbad7/testing/mozharness/ program finished with exit code 0 elapsedTime=2.092508 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-06 04:32:46.398238) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:46.398888) ========= script_repo_revision: a14094edbad78fc1d16e8d4c57902537cf286fd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:46.399806) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:46.400493) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 04:32:46.421747) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-06 04:32:46.422294) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hvkLZ4cosS/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.5GiQdWVgNh/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:32:46 INFO - MultiFileLogger online at 20170106 04:32:46 in /builds/slave/test 04:32:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:32:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:32:46 INFO - {'append_to_log': False, 04:32:46 INFO - 'base_work_dir': '/builds/slave/test', 04:32:46 INFO - 'blob_upload_branch': 'Firefox', 04:32:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:32:46 INFO - 'branch': 'Firefox', 04:32:46 INFO - 'buildbot_json_path': 'buildprops.json', 04:32:46 INFO - 'config_files': ('talos/mac_config.py',), 04:32:46 INFO - 'default_actions': ('clobber', 04:32:46 INFO - 'read-buildbot-config', 04:32:46 INFO - 'download-and-extract', 04:32:46 INFO - 'populate-webroot', 04:32:46 INFO - 'create-virtualenv', 04:32:46 INFO - 'install', 04:32:46 INFO - 'run-tests'), 04:32:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:32:46 INFO - 'download_minidump_stackwalk': True, 04:32:46 INFO - 'download_symbols': 'ondemand', 04:32:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:32:46 INFO - 'tooltool.py': '/tools/tooltool.py', 04:32:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:32:46 INFO - '/tools/misc-python/virtualenv.py')}, 04:32:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:32:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:32:46 INFO - 'installer_path': 'installer.exe', 04:32:46 INFO - 'log_level': 'info', 04:32:46 INFO - 'log_name': 'talos', 04:32:46 INFO - 'log_to_console': True, 04:32:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:32:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:32:46 INFO - 'opt_config_files': (), 04:32:46 INFO - 'pip_index': False, 04:32:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:32:46 INFO - 'cmd': ('bash', 04:32:46 INFO - '-c', 04:32:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:32:46 INFO - 'enabled': True, 04:32:46 INFO - 'halt_on_failure': False, 04:32:46 INFO - 'name': 'check_screen_resolution'},), 04:32:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:32:46 INFO - 'cmd': ('bash', 04:32:46 INFO - '-c', 04:32:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:32:46 INFO - 'enabled': True, 04:32:46 INFO - 'halt_on_failure': False, 04:32:46 INFO - 'name': 'check_screen_resolution'},), 04:32:46 INFO - 'run_cmd_checks_enabled': True, 04:32:46 INFO - 'sps_profile': False, 04:32:46 INFO - 'sps_profile_interval': 0, 04:32:46 INFO - 'suite': 'dromaeojs', 04:32:46 INFO - 'system_bits': '32', 04:32:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:32:46 INFO - 'title': 't-yosemite-r7-0228', 04:32:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:32:46 INFO - 'use_talos_json': True, 04:32:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:32:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:32:46 INFO - 'work_dir': 'build'} 04:32:46 INFO - [mozharness: 2017-01-06 12:32:46.739617Z] Running clobber step. 04:32:46 INFO - Running pre-action listener: _resource_record_pre_action 04:32:46 INFO - Running main action method: clobber 04:32:46 INFO - rmtree: /builds/slave/test/build 04:32:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:32:49 INFO - Running post-action listener: _resource_record_post_action 04:32:49 INFO - [mozharness: 2017-01-06 12:32:49.250358Z] Finished clobber step (success) 04:32:49 INFO - [mozharness: 2017-01-06 12:32:49.250521Z] Running read-buildbot-config step. 04:32:49 INFO - Running pre-action listener: _resource_record_pre_action 04:32:49 INFO - Running main action method: read_buildbot_config 04:32:49 INFO - Using buildbot properties: 04:32:49 INFO - { 04:32:49 INFO - "project": "", 04:32:49 INFO - "product": "firefox", 04:32:49 INFO - "script_repo_revision": "production", 04:32:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:32:49 INFO - "repository": "", 04:32:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:32:49 INFO - "buildid": "20170106030204", 04:32:49 INFO - "stage_platform": "macosx64", 04:32:49 INFO - "basedir": "/builds/slave/test", 04:32:49 INFO - "nightly_build": "True", 04:32:49 INFO - "buildnumber": 106, 04:32:49 INFO - "slavename": "t-yosemite-r7-0228", 04:32:49 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:32:49 INFO - "platform": "yosemite_r7", 04:32:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:32:49 INFO - "branch": "mozilla-central", 04:32:49 INFO - "repo_path": "mozilla-central", 04:32:49 INFO - "revision": "a14094edbad78fc1d16e8d4c57902537cf286fd1", 04:32:49 INFO - "pgo_build": "False", 04:32:49 INFO - "builduid": "10a3e528a73148bc94f9bbb9f997d5e1", 04:32:49 INFO - "slavebuilddir": "test" 04:32:49 INFO - } 04:32:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:32:49 INFO - Running post-action listener: _resource_record_post_action 04:32:49 INFO - [mozharness: 2017-01-06 12:32:49.253935Z] Finished read-buildbot-config step (success) 04:32:49 INFO - [mozharness: 2017-01-06 12:32:49.254072Z] Running download-and-extract step. 04:32:49 INFO - Running pre-action listener: _resource_record_pre_action 04:32:49 INFO - Running main action method: download_and_extract 04:32:49 INFO - mkdir: /builds/slave/test/build/tests 04:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:32:49 INFO - trying https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:32:49 INFO - Downloading https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/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 04:32:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/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 04:32:50 INFO - Downloaded 1199 bytes. 04:32:50 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:32:50 INFO - Using the following test package requirements: 04:32:50 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:32:50 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:32:50 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:32:50 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:32:50 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:32:50 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:32:50 INFO - u'jsshell-mac.zip'], 04:32:50 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:32:50 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:32:50 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:32:50 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:32:50 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:32:50 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:32:50 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:32:50 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:32:50 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:32:50 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:32:50 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:32:50 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:32:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:32:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:32:50 INFO - Fetch https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:32:51 INFO - Expected file size: 16300236 04:32:51 INFO - Obtained file size: 16300236 04:32:57 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 04:32:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:32:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:32:57 INFO - Fetch https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:32:58 INFO - Expected file size: 16300236 04:32:58 INFO - Obtained file size: 16300236 04:33:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:33:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:33:05 INFO - Fetch https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:33:06 INFO - Expected file size: 14403730 04:33:06 INFO - Obtained file size: 14403730 04:33:07 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')]} 04:33:07 INFO - trying https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:33:07 INFO - Downloading https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:33:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/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 04:33:09 INFO - Downloaded 69780964 bytes. 04:33:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:33:09 INFO - mkdir: /builds/slave/test/properties 04:33:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:33:09 INFO - Writing to file /builds/slave/test/properties/build_url 04:33:09 INFO - Contents: 04:33:09 INFO - build_url:https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:33:09 INFO - Running post-action listener: _resource_record_post_action 04:33:09 INFO - Running post-action listener: set_extra_try_arguments 04:33:09 INFO - [mozharness: 2017-01-06 12:33:09.786075Z] Finished download-and-extract step (success) 04:33:09 INFO - [mozharness: 2017-01-06 12:33:09.786211Z] Running populate-webroot step. 04:33:09 INFO - Running pre-action listener: _resource_record_pre_action 04:33:09 INFO - Running main action method: populate_webroot 04:33:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:33:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:33:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:33:09 INFO - '--tppagecycles', 04:33:09 INFO - '7'], 04:33:09 INFO - 'tests': ['tsvgm']}}, 04:33:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:33:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:33:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:33:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:33:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:33:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:33:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:33:09 INFO - 'talos_options': ['--disable-e10s'], 04:33:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:33:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:33:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:33:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:33:09 INFO - 'talos_options': ['--disable-e10s'], 04:33:09 INFO - 'tests': ['damp', 'tps']}, 04:33:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:33:09 INFO - 'tests': ['damp', 'tps']}, 04:33:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:33:09 INFO - 'tests': ['dromaeo_dom']}, 04:33:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:33:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:33:09 INFO - 'tests': ['basic_compositor_video']}, 04:33:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:33:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:33:09 INFO - 'tests': ['a11yr', 04:33:09 INFO - 'ts_paint', 04:33:09 INFO - 'tpaint', 04:33:09 INFO - 'sessionrestore', 04:33:09 INFO - 'sessionrestore_no_auto_restore', 04:33:09 INFO - 'tabpaint']}, 04:33:09 INFO - 'other-e10s': {'tests': ['a11yr', 04:33:09 INFO - 'ts_paint', 04:33:09 INFO - 'tpaint', 04:33:09 INFO - 'sessionrestore', 04:33:09 INFO - 'sessionrestore_no_auto_restore', 04:33:09 INFO - 'tabpaint']}, 04:33:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:33:09 INFO - 'ts_paint', 04:33:09 INFO - 'tpaint', 04:33:09 INFO - 'sessionrestore', 04:33:09 INFO - 'sessionrestore_no_auto_restore', 04:33:09 INFO - 'tabpaint']}, 04:33:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:33:09 INFO - 'ts_paint', 04:33:09 INFO - 'tpaint', 04:33:09 INFO - 'sessionrestore', 04:33:09 INFO - 'sessionrestore_no_auto_restore', 04:33:09 INFO - 'tabpaint']}, 04:33:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:33:09 INFO - 'tests': ['a11yr', 04:33:09 INFO - 'ts_paint', 04:33:09 INFO - 'tpaint', 04:33:09 INFO - 'sessionrestore', 04:33:09 INFO - 'sessionrestore_no_auto_restore', 04:33:09 INFO - 'tabpaint']}, 04:33:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:33:09 INFO - 'tests': ['a11yr', 04:33:09 INFO - 'ts_paint', 04:33:09 INFO - 'tpaint', 04:33:09 INFO - 'sessionrestore', 04:33:09 INFO - 'sessionrestore_no_auto_restore', 04:33:09 INFO - 'tabpaint']}, 04:33:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:33:09 INFO - 'tests': ['tsvgx', 04:33:09 INFO - 'tsvgr_opacity', 04:33:09 INFO - 'tart', 04:33:09 INFO - 'tscrollx', 04:33:09 INFO - 'cart', 04:33:09 INFO - 'tsvg_static']}, 04:33:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:33:09 INFO - 'tsvgr_opacity', 04:33:09 INFO - 'tart', 04:33:09 INFO - 'tscrollx', 04:33:09 INFO - 'cart', 04:33:09 INFO - 'tsvg_static']}, 04:33:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:33:09 INFO - 'talos_options': ['--disable-e10s'], 04:33:09 INFO - 'tests': ['tp5o']}, 04:33:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:33:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:33:09 INFO - 'talos_options': ['--disable-e10s', 04:33:09 INFO - '--xperf_path', 04:33:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:33:09 INFO - 'tests': ['tp5n']}, 04:33:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:33:09 INFO - 'talos_options': ['--xperf_path', 04:33:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:33:09 INFO - 'tests': ['tp5n']}}, 04:33:09 INFO - 'talos.zip': {'path': '', 04:33:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:33:09 INFO - Running post-action listener: _resource_record_post_action 04:33:09 INFO - [mozharness: 2017-01-06 12:33:09.804511Z] Finished populate-webroot step (success) 04:33:09 INFO - [mozharness: 2017-01-06 12:33:09.804641Z] Running create-virtualenv step. 04:33:09 INFO - Running pre-action listener: _resource_record_pre_action 04:33:09 INFO - Running main action method: create_virtualenv 04:33:09 INFO - Creating virtualenv /builds/slave/test/build/venv 04:33:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:33:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:33:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:33:09 INFO - Using real prefix '/tools/python27' 04:33:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:33:10 INFO - Installing distribute.............................................................................................................................................................................................done. 04:33:13 INFO - Installing pip.................done. 04:33:13 INFO - Return code: 0 04:33:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:33:13 INFO - Reading from file tmpfile_stdout 04:33:13 INFO - Output received: 04:33:13 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) 04:33:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:33:13 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')]} 04:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:13 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')]} 04:33:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:13 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 0x1026fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82a9c7e380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d8d30>, '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 0x1023ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024cdbe8>, '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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:33:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hvkLZ4cosS/Render', 04:33:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:13 INFO - 'HOME': '/Users/cltbld', 04:33:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:13 INFO - 'LOGNAME': 'cltbld', 04:33:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:13 INFO - 'MOZ_NO_REMOTE': '1', 04:33:13 INFO - 'NO_EM_RESTART': '1', 04:33:13 INFO - 'PAGER': '/bin/cat', 04:33:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:13 INFO - 'PWD': '/builds/slave/test', 04:33:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:13 INFO - 'SHELL': '/bin/bash', 04:33:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5GiQdWVgNh/Listeners', 04:33:13 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:33:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:13 INFO - 'USER': 'cltbld', 04:33:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:13 INFO - 'XPC_FLAGS': '0x0', 04:33:13 INFO - 'XPC_SERVICE_NAME': '0', 04:33:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:33:14 INFO - Cleaning up... 04:33:14 INFO - Return code: 0 04:33:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:14 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 0x1026fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82a9c7e380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d8d30>, '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 0x1023ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024cdbe8>, '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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:14 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 04:33:14 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 04:33:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hvkLZ4cosS/Render', 04:33:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:14 INFO - 'HOME': '/Users/cltbld', 04:33:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:14 INFO - 'LOGNAME': 'cltbld', 04:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:14 INFO - 'MOZ_NO_REMOTE': '1', 04:33:14 INFO - 'NO_EM_RESTART': '1', 04:33:14 INFO - 'PAGER': '/bin/cat', 04:33:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:14 INFO - 'PWD': '/builds/slave/test', 04:33:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:14 INFO - 'SHELL': '/bin/bash', 04:33:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5GiQdWVgNh/Listeners', 04:33:14 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:33:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:14 INFO - 'USER': 'cltbld', 04:33:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:14 INFO - 'XPC_FLAGS': '0x0', 04:33:14 INFO - 'XPC_SERVICE_NAME': '0', 04:33:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:14 INFO - Downloading/unpacking psutil>=3.1.1 04:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:33:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:33:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:33:18 INFO - Installing collected packages: psutil 04:33:18 INFO - Running setup.py install for psutil 04:33:18 INFO - building 'psutil._psutil_osx' extension 04:33:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:33:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:33:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:33:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:33:19 INFO - building 'psutil._psutil_posix' extension 04:33:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:33:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:33:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:33:19 INFO - ^ 04:33:19 INFO - 1 warning generated. 04:33:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:33:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:33:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:33:19 INFO - Successfully installed psutil 04:33:19 INFO - Cleaning up... 04:33:19 INFO - Return code: 0 04:33:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:33:19 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')]} 04:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:19 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')]} 04:33:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:19 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 0x1026fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82a9c7e380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d8d30>, '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 0x1023ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024cdbe8>, '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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:19 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 04:33:19 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 04:33:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hvkLZ4cosS/Render', 04:33:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:19 INFO - 'HOME': '/Users/cltbld', 04:33:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:19 INFO - 'LOGNAME': 'cltbld', 04:33:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:19 INFO - 'MOZ_NO_REMOTE': '1', 04:33:19 INFO - 'NO_EM_RESTART': '1', 04:33:19 INFO - 'PAGER': '/bin/cat', 04:33:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:19 INFO - 'PWD': '/builds/slave/test', 04:33:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:19 INFO - 'SHELL': '/bin/bash', 04:33:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5GiQdWVgNh/Listeners', 04:33:19 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:33:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:19 INFO - 'USER': 'cltbld', 04:33:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:19 INFO - 'XPC_FLAGS': '0x0', 04:33:19 INFO - 'XPC_SERVICE_NAME': '0', 04:33:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:33:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:33:22 INFO - Installing collected packages: mozsystemmonitor 04:33:22 INFO - Running setup.py install for mozsystemmonitor 04:33:22 INFO - Successfully installed mozsystemmonitor 04:33:22 INFO - Cleaning up... 04:33:22 INFO - Return code: 0 04:33:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:33:22 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')]} 04:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:22 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')]} 04:33:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:22 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 0x1026fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82a9c7e380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d8d30>, '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 0x1023ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024cdbe8>, '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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:22 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 04:33:22 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 04:33:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hvkLZ4cosS/Render', 04:33:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:22 INFO - 'HOME': '/Users/cltbld', 04:33:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:22 INFO - 'LOGNAME': 'cltbld', 04:33:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:22 INFO - 'MOZ_NO_REMOTE': '1', 04:33:22 INFO - 'NO_EM_RESTART': '1', 04:33:22 INFO - 'PAGER': '/bin/cat', 04:33:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:22 INFO - 'PWD': '/builds/slave/test', 04:33:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:22 INFO - 'SHELL': '/bin/bash', 04:33:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5GiQdWVgNh/Listeners', 04:33:22 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:33:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:22 INFO - 'USER': 'cltbld', 04:33:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:22 INFO - 'XPC_FLAGS': '0x0', 04:33:22 INFO - 'XPC_SERVICE_NAME': '0', 04:33:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:23 INFO - Downloading/unpacking jsonschema==2.5.1 04:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:33:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:26 INFO - Downloading functools32-3.2.3-2.tar.gz 04:33:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:33:26 INFO - warning: no files found matching '*.txt' 04:33:26 INFO - no previously-included directories found matching 'build' 04:33:26 INFO - no previously-included directories found matching 'dist' 04:33:26 INFO - no previously-included directories found matching '.git*' 04:33:26 INFO - Installing collected packages: jsonschema, functools32 04:33:26 INFO - Running setup.py install for functools32 04:33:26 INFO - warning: no files found matching '*.txt' 04:33:26 INFO - no previously-included directories found matching 'build' 04:33:26 INFO - no previously-included directories found matching 'dist' 04:33:26 INFO - no previously-included directories found matching '.git*' 04:33:26 INFO - Successfully installed jsonschema functools32 04:33:26 INFO - Cleaning up... 04:33:26 INFO - Return code: 0 04:33:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1026fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82a9c7e380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d8d30>, '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 0x1023ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024cdbe8>, '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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:33:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hvkLZ4cosS/Render', 04:33:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:26 INFO - 'HOME': '/Users/cltbld', 04:33:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:26 INFO - 'LOGNAME': 'cltbld', 04:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:26 INFO - 'MOZ_NO_REMOTE': '1', 04:33:26 INFO - 'NO_EM_RESTART': '1', 04:33:26 INFO - 'PAGER': '/bin/cat', 04:33:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:26 INFO - 'PWD': '/builds/slave/test', 04:33:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:26 INFO - 'SHELL': '/bin/bash', 04:33:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5GiQdWVgNh/Listeners', 04:33:26 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:33:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:26 INFO - 'USER': 'cltbld', 04:33:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:26 INFO - 'XPC_FLAGS': '0x0', 04:33:26 INFO - 'XPC_SERVICE_NAME': '0', 04:33:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:33:26 INFO - Cleaning up... 04:33:26 INFO - Return code: 0 04:33:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1026fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82a9c7e380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d8d30>, '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 0x1023ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024cdbe8>, '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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:33:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hvkLZ4cosS/Render', 04:33:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:26 INFO - 'HOME': '/Users/cltbld', 04:33:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:26 INFO - 'LOGNAME': 'cltbld', 04:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:26 INFO - 'MOZ_NO_REMOTE': '1', 04:33:26 INFO - 'NO_EM_RESTART': '1', 04:33:26 INFO - 'PAGER': '/bin/cat', 04:33:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:26 INFO - 'PWD': '/builds/slave/test', 04:33:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:26 INFO - 'SHELL': '/bin/bash', 04:33:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5GiQdWVgNh/Listeners', 04:33:26 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:33:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:26 INFO - 'USER': 'cltbld', 04:33:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:26 INFO - 'XPC_FLAGS': '0x0', 04:33:26 INFO - 'XPC_SERVICE_NAME': '0', 04:33:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:26 INFO - Downloading/unpacking blobuploader==1.2.4 04:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:29 INFO - Downloading blobuploader-1.2.4.tar.gz 04:33:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:33:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:33:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:30 INFO - Downloading docopt-0.6.1.tar.gz 04:33:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:33:31 INFO - Installing collected packages: blobuploader, requests, docopt 04:33:31 INFO - Running setup.py install for blobuploader 04:33:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:33:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:33:31 INFO - Running setup.py install for requests 04:33:31 INFO - Running setup.py install for docopt 04:33:31 INFO - Successfully installed blobuploader requests docopt 04:33:31 INFO - Cleaning up... 04:33:31 INFO - Return code: 0 04:33:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:31 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 0x1026fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82a9c7e380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d8d30>, '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 0x1023ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024cdbe8>, '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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:33:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:33:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hvkLZ4cosS/Render', 04:33:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:31 INFO - 'HOME': '/Users/cltbld', 04:33:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:31 INFO - 'LOGNAME': 'cltbld', 04:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:31 INFO - 'MOZ_NO_REMOTE': '1', 04:33:31 INFO - 'NO_EM_RESTART': '1', 04:33:31 INFO - 'PAGER': '/bin/cat', 04:33:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:31 INFO - 'PWD': '/builds/slave/test', 04:33:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:31 INFO - 'SHELL': '/bin/bash', 04:33:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5GiQdWVgNh/Listeners', 04:33:31 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:33:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:31 INFO - 'USER': 'cltbld', 04:33:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:31 INFO - 'XPC_FLAGS': '0x0', 04:33:31 INFO - 'XPC_SERVICE_NAME': '0', 04:33:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:33:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-gK2fxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:33:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-zN9Z2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:33:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-8of4VQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:33:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-caYGXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:33:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-2bmG9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:33:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-gFWhjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:33:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-VLbT_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:33:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-WAnjYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:33:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:33:32 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-LmRJjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:33:33 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Mea6pA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:33:33 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-9gNmq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:33:33 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-XQ9kbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:33:33 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-4HCw0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:33:33 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-m6xXPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:33:33 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-l9SFUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:33:33 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-boRLBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:33:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:33:33 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ItO3a7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:33:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:33:33 INFO - Running setup.py install for manifestparser 04:33:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:33:34 INFO - Running setup.py install for mozcrash 04:33:34 INFO - Running setup.py install for mozdebug 04:33:34 INFO - Running setup.py install for mozdevice 04:33:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:33:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:33:34 INFO - Running setup.py install for mozfile 04:33:34 INFO - Running setup.py install for mozhttpd 04:33:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:33:34 INFO - Running setup.py install for mozinfo 04:33:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:33:34 INFO - Running setup.py install for mozInstall 04:33:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:33:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:33:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:33:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:33:35 INFO - Running setup.py install for mozleak 04:33:35 INFO - Running setup.py install for mozlog 04:33:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:33:35 INFO - Running setup.py install for moznetwork 04:33:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:33:35 INFO - Running setup.py install for mozprocess 04:33:35 INFO - Running setup.py install for mozprofile 04:33:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:33:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:33:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:33:35 INFO - Running setup.py install for mozrunner 04:33:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:33:36 INFO - Running setup.py install for mozscreenshot 04:33:36 INFO - Running setup.py install for moztest 04:33:36 INFO - Running setup.py install for mozversion 04:33:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:33:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:33:36 INFO - Cleaning up... 04:33:36 INFO - Return code: 0 04:33:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:33:36 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')]} 04:33:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:36 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')]} 04:33:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:36 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 0x1026fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82a9c7e380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d8d30>, '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 0x1023ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024cdbe8>, '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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:33:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:33:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hvkLZ4cosS/Render', 04:33:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:36 INFO - 'HOME': '/Users/cltbld', 04:33:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:36 INFO - 'LOGNAME': 'cltbld', 04:33:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:36 INFO - 'MOZ_NO_REMOTE': '1', 04:33:36 INFO - 'NO_EM_RESTART': '1', 04:33:36 INFO - 'PAGER': '/bin/cat', 04:33:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:36 INFO - 'PWD': '/builds/slave/test', 04:33:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:36 INFO - 'SHELL': '/bin/bash', 04:33:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5GiQdWVgNh/Listeners', 04:33:36 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:33:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:36 INFO - 'USER': 'cltbld', 04:33:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:36 INFO - 'XPC_FLAGS': '0x0', 04:33:36 INFO - 'XPC_SERVICE_NAME': '0', 04:33:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:33:36 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Uxb3DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:33:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:33:36 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-iKgT14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:33:36 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)) 04:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:33:36 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-mRX5q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:33:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Pe4ccj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:33:37 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)) 04:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:33:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-41sZNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:33:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-yMMnM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:33:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-pRIN6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:33:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Qw2YOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:33:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-5BXY7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:33:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-KL8ss9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:33:37 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)) 04:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:33:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-aiQ7Rh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:33:37 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-TQ2g6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:33:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:33:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-lmhY_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:33:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:33:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-tE_tPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:33:38 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)) 04:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:33:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-aXzRdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:33:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:33:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Jqj6mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:33:38 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)) 04:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:33:38 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-xbVOFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:33:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:33:38 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)) 04:33:38 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)) 04:33:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:33:38 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)) 04:33:38 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)) 04:33:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:41 INFO - Downloading blessings-1.6.tar.gz 04:33:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:33:41 INFO - Installing collected packages: blessings 04:33:41 INFO - Running setup.py install for blessings 04:33:41 INFO - Successfully installed blessings 04:33:41 INFO - Cleaning up... 04:33:41 INFO - Return code: 0 04:33:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:33:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:33:41 INFO - Reading from file tmpfile_stdout 04:33:41 INFO - Current package versions: 04:33:41 INFO - blessings == 1.6 04:33:41 INFO - blobuploader == 1.2.4 04:33:41 INFO - docopt == 0.6.1 04:33:41 INFO - functools32 == 3.2.3-2 04:33:41 INFO - jsonschema == 2.5.1 04:33:41 INFO - manifestparser == 1.1 04:33:41 INFO - mozInstall == 1.12 04:33:41 INFO - mozcrash == 1.0 04:33:41 INFO - mozdebug == 0.1 04:33:41 INFO - mozdevice == 0.49 04:33:41 INFO - mozfile == 1.2 04:33:41 INFO - mozhttpd == 0.7 04:33:41 INFO - mozinfo == 0.9 04:33:41 INFO - mozleak == 0.1 04:33:41 INFO - mozlog == 3.4 04:33:41 INFO - moznetwork == 0.27 04:33:41 INFO - mozprocess == 0.23 04:33:41 INFO - mozprofile == 0.28 04:33:41 INFO - mozrunner == 6.13 04:33:41 INFO - mozscreenshot == 0.1 04:33:41 INFO - mozsystemmonitor == 0.3 04:33:41 INFO - moztest == 0.8 04:33:41 INFO - mozversion == 1.4 04:33:41 INFO - psutil == 3.1.1 04:33:41 INFO - requests == 1.2.3 04:33:41 INFO - wsgiref == 0.1.2 04:33:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:41 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 0x1026fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82a9c7e380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d8d30>, '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 0x1023ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024cdbe8>, '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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:33:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hvkLZ4cosS/Render', 04:33:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:41 INFO - 'HOME': '/Users/cltbld', 04:33:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:41 INFO - 'LOGNAME': 'cltbld', 04:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:41 INFO - 'MOZ_NO_REMOTE': '1', 04:33:41 INFO - 'NO_EM_RESTART': '1', 04:33:41 INFO - 'PAGER': '/bin/cat', 04:33:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:41 INFO - 'PWD': '/builds/slave/test', 04:33:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:41 INFO - 'SHELL': '/bin/bash', 04:33:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5GiQdWVgNh/Listeners', 04:33:41 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:33:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:41 INFO - 'USER': 'cltbld', 04:33:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:41 INFO - 'XPC_FLAGS': '0x0', 04:33:41 INFO - 'XPC_SERVICE_NAME': '0', 04:33:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:33:42 INFO - Cleaning up... 04:33:42 INFO - Return code: 0 04:33:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:33:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1026fab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026fd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82a9c7e380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d8d30>, '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 0x1023ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024cdbe8>, '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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:33:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hvkLZ4cosS/Render', 04:33:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:42 INFO - 'HOME': '/Users/cltbld', 04:33:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:42 INFO - 'LOGNAME': 'cltbld', 04:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:42 INFO - 'MOZ_NO_REMOTE': '1', 04:33:42 INFO - 'NO_EM_RESTART': '1', 04:33:42 INFO - 'PAGER': '/bin/cat', 04:33:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:42 INFO - 'PWD': '/builds/slave/test', 04:33:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:42 INFO - 'SHELL': '/bin/bash', 04:33:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5GiQdWVgNh/Listeners', 04:33:42 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:33:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:42 INFO - 'USER': 'cltbld', 04:33:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:42 INFO - 'XPC_FLAGS': '0x0', 04:33:42 INFO - 'XPC_SERVICE_NAME': '0', 04:33:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:33:42 INFO - Cleaning up... 04:33:42 INFO - Return code: 0 04:33:42 INFO - Running post-action listener: _resource_record_post_action 04:33:42 INFO - Running post-action listener: _start_resource_monitoring 04:33:42 INFO - Starting resource monitoring. 04:33:42 INFO - [mozharness: 2017-01-06 12:33:42.394103Z] Finished create-virtualenv step (success) 04:33:42 INFO - [mozharness: 2017-01-06 12:33:42.395045Z] Running install step. 04:33:42 INFO - Running pre-action listener: _resource_record_pre_action 04:33:42 INFO - Running main action method: install 04:33:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:33:42 INFO - Reading from file tmpfile_stdout 04:33:42 INFO - Detecting whether we're running mozinstall >=1.0... 04:33:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:33:42 INFO - Reading from file tmpfile_stdout 04:33:42 INFO - Output received: 04:33:42 INFO - Usage: mozinstall [options] installer 04:33:42 INFO - Options: 04:33:42 INFO - -h, --help show this help message and exit 04:33:42 INFO - -d DEST, --destination=DEST 04:33:42 INFO - Directory to install application into. [default: 04:33:42 INFO - "/builds/slave/test"] 04:33:42 INFO - --app=APP Application being installed. [default: firefox] 04:33:42 INFO - mkdir: /builds/slave/test/build/application 04:33:42 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'] 04:33:42 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 04:34:04 INFO - Reading from file tmpfile_stdout 04:34:04 INFO - Output received: 04:34:04 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:34:04 INFO - Running post-action listener: _resource_record_post_action 04:34:04 INFO - [mozharness: 2017-01-06 12:34:04.451102Z] Finished install step (success) 04:34:04 INFO - [mozharness: 2017-01-06 12:34:04.451246Z] Running run-tests step. 04:34:04 INFO - Running pre-action listener: _resource_record_pre_action 04:34:04 INFO - Running main action method: run_tests 04:34:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:34:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:34:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:34:04 INFO - 2017-01-06 04:34:04.465 screenresolution[1631:8413] starting screenresolution argv=screenresolution get 04:34:04 INFO - 2017-01-06 04:34:04.468 screenresolution[1631:8413] Display 0: 1600x1200x32@60 04:34:04 INFO - 2017-01-06 04:34:04.474 screenresolution[1632:8415] starting screenresolution argv=screenresolution list 04:34:04 INFO - Available Modes on Display 0 04:34:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:34:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:34:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:34:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:34:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:34:04 INFO - Intel Iris: 04:34:04 INFO - Chipset Model: Intel Iris 04:34:04 INFO - Type: GPU 04:34:04 INFO - Bus: Built-In 04:34:04 INFO - VRAM (Dynamic, Max): 1536 MB 04:34:04 INFO - Vendor: Intel (0x8086) 04:34:04 INFO - Device ID: 0x0a2e 04:34:04 INFO - Revision ID: 0x0009 04:34:04 INFO - Displays: 04:34:04 INFO - Display: 04:34:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:34:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:34:04 INFO - Display Serial Number: 1600x1200 60 04:34:04 INFO - Main Display: Yes 04:34:04 INFO - Mirror: Off 04:34:04 INFO - Online: Yes 04:34:04 INFO - Rotation: Supported 04:34:04 INFO - Return code: 0 04:34:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:34:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:34:04 INFO - Python 2.7.3 04:34:04 INFO - Return code: 0 04:34:04 INFO - grabbing minidump binary from tooltool 04:34: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')]} 04:34:04 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 0x1024d8d30>, '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 0x1023ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024cdbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:34:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:34:04 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:34:04 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:34:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:34:04 INFO - Return code: 0 04:34:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:34:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:34:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:34:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:34:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:34:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:34:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0228 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:34:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hvkLZ4cosS/Render', 04:34:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:04 INFO - 'HOME': '/Users/cltbld', 04:34:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:04 INFO - 'LOGNAME': 'cltbld', 04:34:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:34:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:04 INFO - 'MOZ_NO_REMOTE': '1', 04:34:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:34:04 INFO - 'NO_EM_RESTART': '1', 04:34:04 INFO - 'PAGER': '/bin/cat', 04:34:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:04 INFO - 'PWD': '/builds/slave/test', 04:34:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:34:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:04 INFO - 'SHELL': '/bin/bash', 04:34:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5GiQdWVgNh/Listeners', 04:34:04 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 04:34:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:04 INFO - 'USER': 'cltbld', 04:34:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:04 INFO - 'XPC_FLAGS': '0x0', 04:34:04 INFO - 'XPC_SERVICE_NAME': '0', 04:34:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:34:05 INFO - mozversion application_buildid: 20170106030204 04:34:05 INFO - mozversion application_changeset: a14094edbad78fc1d16e8d4c57902537cf286fd1 04:34:05 INFO - mozversion application_display_name: Nightly 04:34:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:34:05 INFO - mozversion application_name: Firefox 04:34:05 INFO - mozversion application_remotingname: firefox 04:34:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:34:05 INFO - mozversion application_vendor: Mozilla 04:34:05 INFO - mozversion application_version: 53.0a1 04:34:05 INFO - mozversion platform_buildid: 20170106030204 04:34:05 INFO - mozversion platform_changeset: a14094edbad78fc1d16e8d4c57902537cf286fd1 04:34:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:34:05 INFO - mozversion platform_version: 53.0a1 04:34:05 INFO - using testdate: 1483706045 04:34:05 INFO - actual date: 1483706045 04:34:05 INFO - starting webserver on 'localhost:49241' 04:34:05 INFO - SUITE-START | Running 2 tests 04:34:05 INFO - TEST-START | dromaeo_css 04:34:05 INFO - operating with platform_type : mac_ 04:34:05 INFO - Initialising browser for dromaeo_css test... 04:34:05 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpZxHcwV/profile http://localhost:49241/getInfo.html) 04:34:05 INFO - PROCESS | 1642 | 1483706045723 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/FirefoxNightly.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/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < 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 04:34:07 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 04:34:07 INFO - PROCESS | 1642 | colorDepth:24 04:34:07 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 04:34:07 INFO - PROCESS | 1642 | __metrics 04:34:07 INFO - PROCESS | 1642 | 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" 04:34:07 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:34:08 INFO - TEST-INFO | 1642: exit 0 04:34:08 INFO - Browser initialized. 04:34:08 INFO - Running cycle 1/1 for dromaeo_css test... 04:34:08 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpZxHcwV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:34:20 INFO - PROCESS | 1646 | RSS: Main: 234156032 04:34:20 INFO - PROCESS | 1646 | 04:37:24 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 04:37:26 INFO - PROCESS | 1646 | RSS: Main: 371843072 04:37:26 INFO - PROCESS | 1646 | 04:39:09 INFO - PROCESS | 1646 | ************************* 04:39:09 INFO - PROCESS | 1646 | A coding exception was thrown and uncaught in a Task. 04:39:09 INFO - PROCESS | 1646 | 04:39:09 INFO - PROCESS | 1646 | Full message: ReferenceError: fetch is not defined 04:39:09 INFO - PROCESS | 1646 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:39:09 INFO - PROCESS | 1646 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:39:09 INFO - PROCESS | 1646 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:39:09 INFO - PROCESS | 1646 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:39:09 INFO - PROCESS | 1646 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:39:09 INFO - PROCESS | 1646 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:39:09 INFO - PROCESS | 1646 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:39:09 INFO - PROCESS | 1646 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:39:09 INFO - PROCESS | 1646 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:39:09 INFO - PROCESS | 1646 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:39:09 INFO - PROCESS | 1646 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:39:09 INFO - PROCESS | 1646 | 04:39:09 INFO - PROCESS | 1646 | ************************* 04:40:29 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 04:40:29 INFO - PROCESS | 1646 | RSS: Main: 365744128 04:40:29 INFO - PROCESS | 1646 | 04:43:32 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 04:43:32 INFO - PROCESS | 1646 | RSS: Main: 330551296 04:43:32 INFO - PROCESS | 1646 | 04:46:35 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 04:46:35 INFO - PROCESS | 1646 | RSS: Main: 358518784 04:46:35 INFO - PROCESS | 1646 | 04:49:39 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 04:49:40 INFO - PROCESS | 1646 | RSS: Main: 328679424 04:49:40 INFO - PROCESS | 1646 | 04:52:43 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 04:52:43 INFO - PROCESS | 1646 | RSS: Main: 489926656 04:52:43 INFO - PROCESS | 1646 | 04:52:43 INFO - PROCESS | 1646 | __start_tp_report 04:52:43 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 04:52:43 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 04:52:43 INFO - PROCESS | 1646 | |i|pagename|runs| 04:52:43 INFO - PROCESS | 1646 | |0;dojo.html;3047.4209900118963;3315.071779901627;3333.9166520836984;3373.0386839750677;3394.4059789536823;5495.313085864267;5554.527865131458;5680.261565996419;5706.77172913084;5725.599208055438;4759.286107083948;5540.612157149001;5650.350209725882;5749.396313387097;5758.280214973129;5082.23766435034;5134.948650513485;5135.614828887829;5185.144451165572;5198.3761948566225;4593.8268752463455;5202.739863006854;5279.973600131994;5301.20481927712;5362.705051222181;4613.446386433633;5082.771275292584;5242.475752424765;5263.736813159365;5273.024490469237;4262.232798096342;5123.359580052494;5152.92270615941;5183.041137389558;5185;5132.897342053176;5176.8964620707375;5177.870553236199;5191.195364718475;5203.60972927032;5602.2717046783655;5651.8304450866535;5665.405132461074;5691.459311365414;5719;6035.3059398169335;6087.2086628738425;6115.4190351916495;6195.110491300152;6232.750689972396;5007.749612519359;6174.259088909314;6203.968980155116;6282.120503129566;6326.145970294044;5735.799247026417;5790.739416726257;5804.622699524517;5813.331466881321;5822.767089316422;6193.4425901669065;6204.193454850841;6271;6278.152449419361;6292.653904035322;5969.253843269591;6047.697615119227;6095.329513753483;6114.510839132859;6137.62214577108;6117.480014198843;6233.065040244;6233.34549872266;6240.438360547481;6241.126242326078;6094;6117.471378795421;6127.417895299939;6134.2332208473945;6134.693265336716;6217.689115544295;6221.191245138103;6228;6231.065340199007;6233;4821.156297647968;4856.902861942742;4882.365292512021;4882.438519570295;4893.02139572087;4299.419578356882;4361.280388735823;4366.6506679465565;4368;4384.802683879233;5670.858228544319;5680.147977803363;5692.829215123553;5694.4590263924865;5709.885802283931;6168.321484636686;6292.685365731695;6307.905381419282;6311.873762524724;6318.147050148263;5524.475174858382;5559.416261292587;5567.638103523258;5637.915431268534;5719.628224165416;5357.357117145967;5379.973100134553;5385.892282154335;5393.811216607478;5393.892122157535;6181.134641150243;6273.466755325849;6279.963805972055;6289.465395441439;6323.430891219812;6142.692865356804;6277.780277690259;6303.590266632654;6310.7791227306825;6320.747170113144;6266.530010249341;6273.121762953099;6275.466585340298;6284.4344009039405;6286.622802631857;4890.923996720716;5711.200431939615;6091.847703807263;6134.938650613437;6142.232220972379;6172.22847144107;6200.224971878515;6227.190465239671;6241.500679945502;6257.906131408124;6042.063480160582;6048.4556389923355;6052.90920636182;6053.031514957762;6080.635161890301;5494.5054945055135;5529.253550770594;5881.353051164283;5937.139114828412;6018.428249316308;5855.473007429352;5895.263092113486;5924.674142922008;5942.6731529766275;5976.61152025117;6007.459328660441;6047.667378294236;6074.119252708421;6075.665838378932;6077.604955677867;5879.559033072451;5897.587168898136;5928.584999050197;5937;5943.58394912352 04:52:43 INFO - PROCESS | 1646 | |1;ext.html;445.34756581607945;471.59678474903944;488.3456168733896;503.2928735127148;508.82954210339534;30441.543376849364;39455;41472;41566.79216603913;41649.58350416495;40096.99757506064;43046.41721496712;43176.352354714705;43185;43192.352114718306;9285.025072367405;9492.952535237315;9504.619815207383;9507.429554226734;9516.80966380672;8467.915320846774;8680.956595217014;11279.37963412012;11510.71223219422;11521.481533330978;11395.373254471;11497.94251028748;11575.652730418102;11584.536618535249;11598.420078996058;8453.408261421702;11449.484773185184;11496.310221386744;11519.654410367702;11555.075593952464;8426.452280601741;8568.271696905771;8575.399722019461;8583.270422014137;11019.889801101966;39120.60879391198;40641.98395040118;40660.39009414861;40673.593264067276;40745;3601.693856022241;3604.1920256509293;3886.5724770275247;3888.1640447303685;3926.8821935341984;24742.752572474226;30276.394472110434;30654.693453065407;30856;31359.686403136133;3159.9052028439182;3194.105650417887;3205.5512228288057;3265.1673823175174;3301.9174520636934;297789;301821;301891;302108;302392;60417.395826041175;61203.6939815307;61596.69201653963;61675;62929;64563.67718161379;64623;64804.02793958094;64865.67567162134;64877;15683.686326273639;16168.595785105466;16197.595060123356;16202.189890505664;16232.350705971628;10097.949510252402;11626.186166968231;12129;12146.939265303618;12147.939260303641;15590.844091559165;15988.840111598967;18098.276068957093;18201.7269740954;18486.445406637824;6692.52764391833;6749.161950045275;6803.65981700913;6828.863422731518;6846.5892046477375;830.3440282177133;845.8012367093728;846.2806614377853;866.4974314897345;884.9247813935888;29983.850080749457;30046.54930176049;30349;30632.693673062982;30776.69223307738;1024.2164743970868;1034.2243317511866;1079.611339917629;1083.6369816111533;1138.914581406398;15388.692226155414;15406;15470.071795692069;15594.220288985505;15802.446914357943;553134;554336;554687;555269;556610;47446.5255347449;57863.710681446326;58054.4194558049;58200;58236;1404.6558593144541;1426.9357878895476;1431.4918204037624;1438.2880474165356;1444.9638759031109;21581.568368632226;22618.208362707883;29089.981850635126;29101.85449072741;29974.85012574923;50624.49375506198;51130.977380452925;51145.23282150845;51193.7440312796;51237.48762512327;432373;433541;434625;435586;436066;39278.3788511034;39836.601633983286;39926.00739925984;40079.59920400759;40200.39699404453;41247;41302;42790.144197116504;44799.10401792011;45325.773371132935;29967.850160749058;35129.82435087808;35617.64382356143;36818.815905920295;38251.426228607146;35274.47088293729;35328.823355883054;36195.276094477435;36339.091522712144;36371.81814090913;34507.13732156716;34560;35506.934791956286;35738.28523429569;35804.82097589495;31222.21944451407;31653.8417307912;32336.35327293488;32352.83823580867;33575.16062098467;23654.408639784142;24050.39874003164;24083.88400415026;24288.271351859472;24349.51300974006 04:52:43 INFO - PROCESS | 1646 | |2;jquery.html;334.21126142304166;364.937960546707;385.37761515153016;389.7116134060795;410.5053410640179;15043.398264069438;15344.693106137871;16899.91550042248;20466.386008419733;21038.948052597385;16985.74521382177;20954.580908381824;21347.57304853902;21563.353099407002;21754.12983480659;5468.535174510172;5691;5895.322037965646;5931.614445061079;5948.375420580842;5821.446962538552;6007.369226231248;6015.0676645120075;6018.368071352511;6018.729157187937;3850.4224366344993;3965.8611948581806;4056.472658554384;4058.5129784425762;5994.190784244115;4037.394390841368;6499.18760154983;6552.344765523457;6566.573172743804;6572.5399222054475;5667.943320566824;5851.561132915049;5874.588778785486;5881.323647780475;5930.911036334458;23365.299041028797;24740.38149046288;25750;25873.353166170808;26138.73861261382;2530.2029860593852;2573.2537564106387;2634.249238966885;2641.220839852248;2643.722409147031;9715.951420242924;10320.690379288633;10442.895571044268;10607.939206079407;14163.433462661482;1798.2896755781524;1834.5321942904475;1834.596388794463;1840.2638944422204;1848.7689038870142;132529;138312;140292;140988.29505852406;142563.28718356343;9884.703458896245;11330.490127944098;11640;11757.235779674294;12027.699307517381;15342.386304547694;15395.461158859385;15653.373865045498;15709.921450392674;15751.44869929547;8395.538245396561;8549.230569248797;8552.144785521523;8729.476231426135;8731.126887311219;8289.8342033159;8346.874796878052;8352.248297653241;8428.030776460662;8438.90294261742;4177.5047638641845;4662.813487460531;4754.429468463737;4764.928526072112;4847.466778654345;1927.9036048197534;2021.3329601231558;2021.7978202179959;2029.3607513633158;2032.400441869652;999.7200783780553;1004.8141093897652;1006.9295149339476;1023.2785885950387;1032.3238278927315;21047.8947605261;21529.354119376443;21544.89227553852;21553.35339939804;21601.891990539945;1027.8715160604925;1028.5937054863286;1063.6649455421518;1067.989320106789;1078.2667785905503;8560.657573696983;11159.72867403928;11343.03584195336;11347.602833900773;11412.258203216765;722365;725372;733473;751722;752242;113041.43479282552;119681.20478192835;142387;150543.24728376287;153805;994.1996692662332;1006.2352612014921;1030.340582027503;1035.9482025898674;1037.1339931157568;19963.60072798565;20116.49708757234;20293.69559456639;20330.288439904532;20396;58743.706281468316;59075;59330.703346482995;59671.70164149151;59906.70046649913;84674;85834.57082714546;85847.57076214578;86149;87916.5604171975;7942.761717148494;8191.83616327682;8428.199321064734;8467.79837242309;8548.700795472128;9280.628774849201;12614.432350544432;13067.607971760863;13470.797938031134;13651.590452286448;10961.56153753841;11580.189386742848;11616.767664646828;11707.414629268673;11829.467673954865;11357;11477.827832582685;11484;11894.821577676168;11930.926216640544;11623.302601843916;11682.591109311134;11956.76086478283;12085.758284834079;12096.185572164246;8462.534560599226;8483.74548763538;8505.74482765518;8604.397692161489;8614.052454229904;11333.716657083638;11439.828402574134;11646.650600481998;11855;12025.63923082309 04:52:43 INFO - PROCESS | 1646 | |3;mootools.html;1312.4881296294445;1439.5969128643983;1447.5295528953086;1456.5484699743074;1461.305879707139;8008.599570021504;8412.747617571467;8441.62012709428;8444.4511106778;8447.873281900776;7368.5210461319975;7668.616569171547;7671.84656306875;7747.496412733182;7754.961225193881;1911.980880191201;2187.070495039614;2259.039908039085;2266.0709109265204;2268.206127855254;1972.8618996670239;2016.3346095788354;2291.0835665733675;2365.4441206316587;2371.8221133414977;2041.8876961767096;2112.176251262009;2305.3199306204624;2325.697659304288;2328.429534763977;1765.6380442009358;2231.520223152022;2240.4958884250914;2245.438640339915;2255.3459496745922;2040.4082815983347;2233.050953344822;2289.427643089228;2309.191782875997;2315.733690625566;8256.215659512336;8375.539345336005;8386.203310685476;8388.790280243044;8421.663133474654;4444.31113177458;5062.519060689228;5097.49025097491;5229.503197196298;5238.135707608279;3290.3583801158784;3473.9826300868585;3525.8589656413715;3531.5938667053106;3540.2565461253166;1148.6496618531344;1203.7231436769505;1222.4926655437982;1240.6216104088248;1268.8413948256468;10857.565697372096;10858.72853178669;10861.782764344747;10921.563137474572;10925.672229833117;3823.6176382362096;4471.5081341052455;4669.976650116728;4692.788824502905;4710.646701497333;9503.28725345602;9576;9599.088086631758;9631.181349585224;9654.662086826927;5126.6923984561045;5151.124308867502;5216.37403511581;5248.422673505911;5266.183741520071;4587.288970209623;4695.95304046962;4779.569838714532;4797.7601119943865;4817.9036419272115;3224.3873664003763;3725.2921944830396;3744.1949980753725;3805.238952209569;3898.922021559553;1065.2543219746208;1250.0686988193854;1250.662321173278;1253.3044160490833;1262.955796547135;862.3055695558259;877.1929824561419;884.8539990901429;910.0853642089658;920.447731361178;5306.25809107634;5323.7410071942295;5695.857603559862;5743.368229494752;5779.479846813807;558.904986152355;568.914662800575;574.067140896044;585.689584520205;594;3681.3189559931498;3684.5578530576504;3790.8483660653264;3792.6965842732516;3805.162864169878;9169.587368568431;9946.45294508809;10101.69694909154;10108.797824043624;10128.949355253324;9318.114779095975;9678.032196780267;10043.4476103815;10068.949655251823;10092.293539452168;7607.543547387064;7647.273509016746;7828.960855195687;7858.842823143619;7860.292573668397;5400;5421.186821976735;5472.452754724496;5495.945040549544;5542.307211598551;8870.955645221733;9157.862632060658;9270.490123043297;9274.118958698913;9290.442573445614;6683.732650693918;7964.20357964199;9198.586063627286;9206.493642849708;9266.536673166449;7226.205117437183;7371.262873712586;7387.852242955219;7394.55632662042;7406;7673.34776544;7831.138574756658;7858.371330293448;7908.6045697713735;7925.484843485155;7036.331548502884;7133.144022717307;7133.643317833984;7171.99592057102;7224.855502889807;6960.23437421873;7004.509684322253;7066.152061752623;7105.857882842418;7133.5719856808755;6215.875682486416;6299.370062993664;6369.45859601939;6411.262704789009;6505.349465053457;4936.24845117706;4976.950230497649;4989.750512474435;4994.300797888372;5138.049460849755;4929.285253638274;5046.293518907282;5065.164247899203;5131.230315452712;5178.223266510054 04:52:43 INFO - PROCESS | 1646 | |4;prototype.html;333.464789013633;355.307151055442;356.62554317966135;368.03391098366785;380.0347118319469;4072.0430698785835;4516.645006498054;4751.9762401187945;4904.705717656939;5067.6959382437035;4637.65217608679;4920.376275828977;5059.026979478006;5130.692158470494;5140;3612.981935090334;3994.4208089826957;4031.4355990161403;4048.068944142849;4065.9911665367713;3812;3848.8845334640005;4006.4190692349594;4036.9798151009204;4091.1204091120403;4011.2150093208534;4056.1482088761395;4077.3272410052305;4078.9592104078874;4117.855875044374;3587.587427445825;4063.674906007512;4084.775337356459;4086.836526538935;4110.568390319033;3408.386490431721;3460.8961731148106;3546.946795798065;3656.8171591420323;3657.8294945617413;4780.796866099721;4854.466008739036;4988.401391833003;5099.3370861787735;5103.489651034867;4657.347971284023;4668.603168730628;4678.929816052762;4685.969086800898;4704.647151463654;4979.90040199194;5004.174311238678;5168.9224661630105;5262.92105618416;5486.643368181056;3476.5654293213347;3588.8743893963588;3605.8281058656044;4001.4397984282223;4001.8799436016966;4404;5525.060739674265;5621.80323688673;5714.571407144481;5753.252077229975;3337.8331083445732;3549.6272891346553;3733.3839916413485;3761.247750449921;4019.376996565537;3825.5218097737784;3887.630675085862;4021.2761702893176;4096.754194748325;4103.425520427142;3688.317661232627;4233.364995250737;4285.550017248206;4536.115457485825;4671.065786842645;5479.643823151482;5498.95519851227;5507.366652834975;5511.649645836796;5581.079121944915;1250.9804509324872;1332.8000799880097;1338.7188690374935;1347.905646604748;1348.8313960754906;798.9440739148204;816.8978877640295;816.9223923727236;823.1603764160642;854.6538651845888;3867.864624738177;4126.855560055444;4861.885079990216;4882.26765985098;4925.5074492551175;4782.880427989328;5103.770330335144;5161.045206636784;5329.003247564327;5347.572194224453;4235.703500754979;4641.651876109306;4672.836450724209;4859.975700121548;4882.511748825089;2677.0362669438987;2795.175423250146;2805.3547684032787;2838.3177896887655;2840.821059260425;5239.004589128053;5415.292353823089;5452.6455780374945;5565.860853478695;5595.720213989284;4612.492625811158;5121.539061484485;5225.947740522623;5493.368262649767;5552.805651802168;4563.589276965089;4633.374494443207;4970.90058198834;5454.390954668383;5578.330600327987;4402.647788176874;5298.417174110922;5313.495217954288;5317.601179911445;5335.573154147582;4935.728534930613;5014.423341315795;5257.211418287287;5277.920831187458;5357.303550538405;4952.2819191217795;5266.499047771366;5411.83764487066;5448.782048718007;5602.915956260578;2865.9856700716364;3143.9214019649694;3435.3301106284034;3596.1549035977046;3651.3062518121474;4817.518248175154;4841.636877234292;5121.795128194831;5194.402643696023;5273;4809.3026511154985;5197.792088316576;5455.199784071167;5504.339479262514;5511.22842802;4335.956640433556;4745.603470247503;5209.53114219722;5293.703042754396;5492.313460817398;4454.643628509776;5069.529836347418;5349;5389.784408623611;5400.891982160256;4346.087321662486;4997.276484315984;5236.659617125027;5494.697791621499;5505.972470137784;5143.9712057587885;5238.703393578546;5331.333583302087;5336.813211537733;5386.569074474111 04:52:43 INFO - PROCESS | 1646 | |5;yui.html;643.7296335539073;678.233596036479;698.8462538241585;768.3008462299309;796.2793671727087;11997.760044799099;15210.467633632825;15562;15562.37750489979;15592.610184745387;14064.437422503088;14131.717365652707;14315.212663303515;14388.640283992905;14403.855961440382;671.9630420326904;853.9658413663461;865.6191275838643;876.3952872517974;876.4916348517844;690.3614156904868;841.7264389073575;869.8738682890977;872.1191596487562;872.2847265242503;928.7306681062483;930.9945259120134;931.6133804471136;932.9160375566198;933.3093510802025;925.3013974449248;925.6436272035281;929.1173385284008;930.0188301342081;930.906909309067;523.2413001148312;537.5054949446519;657.1424291299852;670.7518218259227;675.4528831646383;15276;15293;15367.385304587802;15369.615759606098;15403.92172547911;13539.187648741008;13584.388702508411;13591.320433978262;13635.795463068062;13689.31553422325;12348.814767778491;12432.440540175714;12434.564790232298;12506.249625022529;12651.683707907285;4416.757078360689;4499.325101234808;4555.681102322838;4585.220512512881;4595.747233902134;100039;101560;102475.48762256141;103778;105248.4737576315;10811.405372704578;10866.239363244655;10905.509252083675;11101;11398.316101033799;21153.471163220736;21461.46346341354;21563.89218053931;21608.783912160678;21610.56778864414;7541.43439242048;7601.7719468416035;7603.163651998276;7655.846883062308;7831.138574756772;4150.5641907599875;4193.308104162779;4247.0444150066605;4249.511306199826;4301.569843015736;3334.7832390894514;3429.811360375203;3431.107911942913;3438.1748380388526;3442.1566716154703;552.737449711392;621.4191709793221;639.8080575827233;639.9936000639934;653.8757636049136;599.8950183717832;610.7673820424501;639.8048595178423;659.2484567592948;672.9831754206184;7488.4009279257525;8798.912010879938;8942.418742781829;9443.152441884844;9574.760630984141;627.2974268818951;648.2322517881012;652.7584793626294;661.1768348406168;673.562184580027;4478.664100192498;4492.550744925481;4680.859574212779;4681.368015317957;4693.155232058262;102138;102878;104323.4783826076;104418;107955.46022269838;77678.83481747778;89671.10328896627;91341.77923986873;92498.53750731204;93221;67274.65450691056;72067;76199.61900190463;82150.58924705339;82232.76650850242;8942.955285223532;8946.642134314814;8975.506347150707;9124.95437522808;9170.541472926194;36308.27383452263;45769.54230457653;48979.26531101965;48994.51005490042;49242.75378623084;53257.733711331195;62600;67808;68829.96755048579;70674.29325706678;11222.66332010041;11279.062223974292;11292.040176585082;11525.82711259315;11573;12349.93825030869;13576.524821631196;13695.931520342334;14349;14446.662009779799;13443;13511.662208444473;13570.93214533921;14077.929610351883;14223;11432.828507572227;12297.877021230031;12407.441665125272;12579.245245285312;12704.745905081661;12677.683057923625;13226.272555009567;13243.47026118983;13526.323683815574;13760.518381856587;10049.346792458467;10916.836247456136;11003.779924401628;11376.544938202711;11446.37044962535;8642.43824151428;10470.738231544272;11086.390248536409;11232.491053460939;11503.827442588201 04:52:43 INFO - PROCESS | 1646 | __end_tp_report 04:52:43 INFO - PROCESS | 1646 | __start_cc_report 04:52:43 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,1483 04:52:43 INFO - PROCESS | 1646 | __end_cc_report 04:52:43 INFO - PROCESS | 1646 | __startTimestamp1483707163781__endTimestamp 04:52:43 INFO - PROCESS | 1646 | 04:52:43 INFO - PROCESS | 1646 | ------- Summary: start ------- 04:52:43 INFO - PROCESS | 1646 | Number of tests: 6 04:52:43 INFO - PROCESS | 1646 | 04:52:43 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5671.73 Median:5881.35 stddev:666.95 (11.3%) stddev-sans-first:636.43 04:52:43 INFO - PROCESS | 1646 | Values: 3047.4 3315.1 3333.9 3373.0 3394.4 5495.3 5554.5 5680.3 5706.8 5725.6 4759.3 5540.6 5650.4 5749.4 5758.3 5082.2 5134.9 5135.6 5185.1 5198.4 4593.8 5202.7 5280.0 5301.2 5362.7 4613.4 5082.8 5242.5 5263.7 5273.0 4262.2 5123.4 5152.9 5183.0 5185.0 5132.9 5176.9 5177.9 5191.2 5203.6 5602.3 5651.8 5665.4 5691.5 5719.0 6035.3 6087.2 6115.4 6195.1 6232.8 5007.7 6174.3 6204.0 6282.1 6326.1 5735.8 5790.7 5804.6 5813.3 5822.8 6193.4 6204.2 6271.0 6278.2 6292.7 5969.3 6047.7 6095.3 6114.5 6137.6 6117.5 6233.1 6233.3 6240.4 6241.1 6094.0 6117.5 6127.4 6134.2 6134.7 6217.7 6221.2 6228.0 6231.1 6233.0 4821.2 4856.9 4882.4 4882.4 4893.0 4299.4 4361.3 4366.7 4368.0 4384.8 5670.9 5680.1 5692.8 5694.5 5709.9 6168.3 6292.7 6307.9 6311.9 6318.1 5524.5 5559.4 5567.6 5637.9 5719.6 5357.4 5380.0 5385.9 5393.8 5393.9 6181.1 6273.5 6280.0 6289.5 6323.4 6142.7 6277.8 6303.6 6310.8 6320.7 6266.5 6273.1 6275.5 6284.4 6286.6 4890.9 5711.2 6091.8 6134.9 6142.2 6172.2 6200.2 6227.2 6241.5 6257.9 6042.1 6048.5 6052.9 6053.0 6080.6 5494.5 5529.3 5881.4 5937.1 6018.4 5855.5 5895.3 5924.7 5942.7 5976.6 6007.5 6047.7 6074.1 6075.7 6077.6 5879.6 5897.6 5928.6 5937.0 5943.6 04:52:43 INFO - PROCESS | 1646 | 04:52:43 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:58526.27 Median:19899.73 stddev:117656.54 (591.2%) stddev-sans-first:117905.78 04:52:43 INFO - PROCESS | 1646 | Values: 445.3 471.6 488.3 503.3 508.8 30441.5 39455.0 41472.0 41566.8 41649.6 40097.0 43046.4 43176.4 43185.0 43192.4 9285.0 9493.0 9504.6 9507.4 9516.8 8467.9 8681.0 11279.4 11510.7 11521.5 11395.4 11497.9 11575.7 11584.5 11598.4 8453.4 11449.5 11496.3 11519.7 11555.1 8426.5 8568.3 8575.4 8583.3 11019.9 39120.6 40642.0 40660.4 40673.6 40745.0 3601.7 3604.2 3886.6 3888.2 3926.9 24742.8 30276.4 30654.7 30856.0 31359.7 3159.9 3194.1 3205.6 3265.2 3301.9 297789.0 301821.0 301891.0 302108.0 302392.0 60417.4 61203.7 61596.7 61675.0 62929.0 64563.7 64623.0 64804.0 64865.7 64877.0 15683.7 16168.6 16197.6 16202.2 16232.4 10097.9 11626.2 12129.0 12146.9 12147.9 15590.8 15988.8 18098.3 18201.7 18486.4 6692.5 6749.2 6803.7 6828.9 6846.6 830.3 845.8 846.3 866.5 884.9 29983.9 30046.5 30349.0 30632.7 30776.7 1024.2 1034.2 1079.6 1083.6 1138.9 15388.7 15406.0 15470.1 15594.2 15802.4 553134.0 554336.0 554687.0 555269.0 556610.0 47446.5 57863.7 58054.4 58200.0 58236.0 1404.7 1426.9 1431.5 1438.3 1445.0 21581.6 22618.2 29090.0 29101.9 29974.9 50624.5 51131.0 51145.2 51193.7 51237.5 432373.0 433541.0 434625.0 435586.0 436066.0 39278.4 39836.6 39926.0 40079.6 40200.4 41247.0 41302.0 42790.1 44799.1 45325.8 29967.9 35129.8 35617.6 36818.8 38251.4 35274.5 35328.8 36195.3 36339.1 36371.8 34507.1 34560.0 35506.9 35738.3 35804.8 31222.2 31653.8 32336.4 32352.8 33575.2 23654.4 24050.4 24083.9 24288.3 24349.5 04:52:43 INFO - PROCESS | 1646 | 04:52:43 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:40185.15 Median:21558.35 stddev:122621.28 (568.8%) stddev-sans-first:122928.76 04:52:43 INFO - PROCESS | 1646 | Values: 334.2 364.9 385.4 389.7 410.5 15043.4 15344.7 16899.9 20466.4 21038.9 16985.7 20954.6 21347.6 21563.4 21754.1 5468.5 5691.0 5895.3 5931.6 5948.4 5821.4 6007.4 6015.1 6018.4 6018.7 3850.4 3965.9 4056.5 4058.5 5994.2 4037.4 6499.2 6552.3 6566.6 6572.5 5667.9 5851.6 5874.6 5881.3 5930.9 23365.3 24740.4 25750.0 25873.4 26138.7 2530.2 2573.3 2634.2 2641.2 2643.7 9716.0 10320.7 10442.9 10607.9 14163.4 1798.3 1834.5 1834.6 1840.3 1848.8 132529.0 138312.0 140292.0 140988.3 142563.3 9884.7 11330.5 11640.0 11757.2 12027.7 15342.4 15395.5 15653.4 15709.9 15751.4 8395.5 8549.2 8552.1 8729.5 8731.1 8289.8 8346.9 8352.2 8428.0 8438.9 4177.5 4662.8 4754.4 4764.9 4847.5 1927.9 2021.3 2021.8 2029.4 2032.4 999.7 1004.8 1006.9 1023.3 1032.3 21047.9 21529.4 21544.9 21553.4 21601.9 1027.9 1028.6 1063.7 1068.0 1078.3 8560.7 11159.7 11343.0 11347.6 11412.3 722365.0 725372.0 733473.0 751722.0 752242.0 113041.4 119681.2 142387.0 150543.2 153805.0 994.2 1006.2 1030.3 1035.9 1037.1 19963.6 20116.5 20293.7 20330.3 20396.0 58743.7 59075.0 59330.7 59671.7 59906.7 84674.0 85834.6 85847.6 86149.0 87916.6 7942.8 8191.8 8428.2 8467.8 8548.7 9280.6 12614.4 13067.6 13470.8 13651.6 10961.6 11580.2 11616.8 11707.4 11829.5 11357.0 11477.8 11484.0 11894.8 11930.9 11623.3 11682.6 11956.8 12085.8 12096.2 8462.5 8483.7 8505.7 8604.4 8614.1 11333.7 11439.8 11646.7 11855.0 12025.6 04:52:43 INFO - PROCESS | 1646 | 04:52:43 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5374.22 Median:5197.30 stddev:2963.54 (57.0%) stddev-sans-first:2956.14 04:52:43 INFO - PROCESS | 1646 | Values: 1312.5 1439.6 1447.5 1456.5 1461.3 8008.6 8412.7 8441.6 8444.5 8447.9 7368.5 7668.6 7671.8 7747.5 7755.0 1912.0 2187.1 2259.0 2266.1 2268.2 1972.9 2016.3 2291.1 2365.4 2371.8 2041.9 2112.2 2305.3 2325.7 2328.4 1765.6 2231.5 2240.5 2245.4 2255.3 2040.4 2233.1 2289.4 2309.2 2315.7 8256.2 8375.5 8386.2 8388.8 8421.7 4444.3 5062.5 5097.5 5229.5 5238.1 3290.4 3474.0 3525.9 3531.6 3540.3 1148.6 1203.7 1222.5 1240.6 1268.8 10857.6 10858.7 10861.8 10921.6 10925.7 3823.6 4471.5 4670.0 4692.8 4710.6 9503.3 9576.0 9599.1 9631.2 9654.7 5126.7 5151.1 5216.4 5248.4 5266.2 4587.3 4696.0 4779.6 4797.8 4817.9 3224.4 3725.3 3744.2 3805.2 3898.9 1065.3 1250.1 1250.7 1253.3 1263.0 862.3 877.2 884.9 910.1 920.4 5306.3 5323.7 5695.9 5743.4 5779.5 558.9 568.9 574.1 585.7 594.0 3681.3 3684.6 3790.8 3792.7 3805.2 9169.6 9946.5 10101.7 10108.8 10128.9 9318.1 9678.0 10043.4 10068.9 10092.3 7607.5 7647.3 7829.0 7858.8 7860.3 5400.0 5421.2 5472.5 5495.9 5542.3 8871.0 9157.9 9270.5 9274.1 9290.4 6683.7 7964.2 9198.6 9206.5 9266.5 7226.2 7371.3 7387.9 7394.6 7406.0 7673.3 7831.1 7858.4 7908.6 7925.5 7036.3 7133.1 7133.6 7172.0 7224.9 6960.2 7004.5 7066.2 7105.9 7133.6 6215.9 6299.4 6369.5 6411.3 6505.3 4936.2 4977.0 4989.8 4994.3 5138.0 4929.3 5046.3 5065.2 5131.2 5178.2 04:52:43 INFO - PROCESS | 1646 | 04:52:43 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4327.27 Median:4813.41 stddev:1282.26 (26.6%) stddev-sans-first:1250.33 04:52:43 INFO - PROCESS | 1646 | Values: 333.5 355.3 356.6 368.0 380.0 4072.0 4516.6 4752.0 4904.7 5067.7 4637.7 4920.4 5059.0 5130.7 5140.0 3613.0 3994.4 4031.4 4048.1 4066.0 3812.0 3848.9 4006.4 4037.0 4091.1 4011.2 4056.1 4077.3 4079.0 4117.9 3587.6 4063.7 4084.8 4086.8 4110.6 3408.4 3460.9 3546.9 3656.8 3657.8 4780.8 4854.5 4988.4 5099.3 5103.5 4657.3 4668.6 4678.9 4686.0 4704.6 4979.9 5004.2 5168.9 5262.9 5486.6 3476.6 3588.9 3605.8 4001.4 4001.9 4404.0 5525.1 5621.8 5714.6 5753.3 3337.8 3549.6 3733.4 3761.2 4019.4 3825.5 3887.6 4021.3 4096.8 4103.4 3688.3 4233.4 4285.6 4536.1 4671.1 5479.6 5499.0 5507.4 5511.6 5581.1 1251.0 1332.8 1338.7 1347.9 1348.8 798.9 816.9 816.9 823.2 854.7 3867.9 4126.9 4861.9 4882.3 4925.5 4782.9 5103.8 5161.0 5329.0 5347.6 4235.7 4641.7 4672.8 4860.0 4882.5 2677.0 2795.2 2805.4 2838.3 2840.8 5239.0 5415.3 5452.6 5565.9 5595.7 4612.5 5121.5 5225.9 5493.4 5552.8 4563.6 4633.4 4970.9 5454.4 5578.3 4402.6 5298.4 5313.5 5317.6 5335.6 4935.7 5014.4 5257.2 5277.9 5357.3 4952.3 5266.5 5411.8 5448.8 5602.9 2866.0 3143.9 3435.3 3596.2 3651.3 4817.5 4841.6 5121.8 5194.4 5273.0 4809.3 5197.8 5455.2 5504.3 5511.2 4336.0 4745.6 5209.5 5293.7 5492.3 4454.6 5069.5 5349.0 5389.8 5400.9 4346.1 4997.3 5236.7 5494.7 5506.0 5144.0 5238.7 5331.3 5336.8 5386.6 04:52:43 INFO - PROCESS | 1646 | 04:52:43 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:20008.34 Median:4447.71 stddev:28969.68 (651.3%) stddev-sans-first:29014.46 04:52:43 INFO - PROCESS | 1646 | Values: 643.7 678.2 698.8 768.3 796.3 11997.8 15210.5 15562.0 15562.4 15592.6 14064.4 14131.7 14315.2 14388.6 14403.9 672.0 854.0 865.6 876.4 876.5 690.4 841.7 869.9 872.1 872.3 928.7 931.0 931.6 932.9 933.3 925.3 925.6 929.1 930.0 930.9 523.2 537.5 657.1 670.8 675.5 15276.0 15293.0 15367.4 15369.6 15403.9 13539.2 13584.4 13591.3 13635.8 13689.3 12348.8 12432.4 12434.6 12506.2 12651.7 4416.8 4499.3 4555.7 4585.2 4595.7 100039.0 101560.0 102475.5 103778.0 105248.5 10811.4 10866.2 10905.5 11101.0 11398.3 21153.5 21461.5 21563.9 21608.8 21610.6 7541.4 7601.8 7603.2 7655.8 7831.1 4150.6 4193.3 4247.0 4249.5 4301.6 3334.8 3429.8 3431.1 3438.2 3442.2 552.7 621.4 639.8 640.0 653.9 599.9 610.8 639.8 659.2 673.0 7488.4 8798.9 8942.4 9443.2 9574.8 627.3 648.2 652.8 661.2 673.6 4478.7 4492.6 4680.9 4681.4 4693.2 102138.0 102878.0 104323.5 104418.0 107955.5 77678.8 89671.1 91341.8 92498.5 93221.0 67274.7 72067.0 76199.6 82150.6 82232.8 8943.0 8946.6 8975.5 9125.0 9170.5 36308.3 45769.5 48979.3 48994.5 49242.8 53257.7 62600.0 67808.0 68830.0 70674.3 11222.7 11279.1 11292.0 11525.8 11573.0 12349.9 13576.5 13695.9 14349.0 14446.7 13443.0 13511.7 13570.9 14077.9 14223.0 11432.8 12297.9 12407.4 12579.2 12704.7 12677.7 13226.3 13243.5 13526.3 13760.5 10049.3 10916.8 11003.8 11376.5 11446.4 8642.4 10470.7 11086.4 11232.5 11503.8 04:52:43 INFO - PROCESS | 1646 | -------- Summary: end -------- 04:52:43 INFO - PROCESS | 1646 | 04:52:44 INFO - TEST-INFO | 1646: exit 0 04:52:44 INFO - TEST-OK | dromaeo_css | took 1119396ms 04:52:44 INFO - TEST-START | kraken 04:52:44 INFO - operating with platform_type : mac_ 04:52:44 INFO - Initialising browser for kraken test... 04:52:44 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmprOHJhn/profile http://localhost:49241/getInfo.html) 04:52:44 INFO - PROCESS | 1652 | 1483707164967 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/FirefoxNightly.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/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < 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 04:52:46 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 04:52:46 INFO - PROCESS | 1652 | colorDepth:24 04:52:46 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 04:52:46 INFO - PROCESS | 1652 | __metrics 04:52:46 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" 04:52:46 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] 04:52:47 INFO - TEST-INFO | 1652: exit 0 04:52:47 INFO - Browser initialized. 04:52:47 INFO - Running cycle 1/1 for kraken test... 04:52:47 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmprOHJhn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:52:59 INFO - PROCESS | 1654 | RSS: Main: 234225664 04:52:59 INFO - PROCESS | 1654 | 04:53:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 04:53:03 INFO - PROCESS | 1654 | RSS: Main: 286195712 04:53:03 INFO - PROCESS | 1654 | 04:53:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 04:53:06 INFO - PROCESS | 1654 | RSS: Main: 285102080 04:53:06 INFO - PROCESS | 1654 | 04:53:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 04:53:10 INFO - PROCESS | 1654 | RSS: Main: 377503744 04:53:10 INFO - PROCESS | 1654 | 04:53:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 04:53:13 INFO - PROCESS | 1654 | RSS: Main: 282660864 04:53:13 INFO - PROCESS | 1654 | 04:53:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:53:15 INFO - PROCESS | 1654 | RSS: Main: 286486528 04:53:15 INFO - PROCESS | 1654 | 04:53:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 04:53:19 INFO - PROCESS | 1654 | RSS: Main: 327122944 04:53:19 INFO - PROCESS | 1654 | 04:53:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 04:53:22 INFO - PROCESS | 1654 | RSS: Main: 330051584 04:53:22 INFO - PROCESS | 1654 | 04:53:25 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 04:53:26 INFO - PROCESS | 1654 | RSS: Main: 325234688 04:53:26 INFO - PROCESS | 1654 | 04:53:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:53:28 INFO - PROCESS | 1654 | RSS: Main: 303484928 04:53:28 INFO - PROCESS | 1654 | 04:53:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:53:30 INFO - PROCESS | 1654 | RSS: Main: 307118080 04:53:30 INFO - PROCESS | 1654 | 04:53:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:53:33 INFO - PROCESS | 1654 | RSS: Main: 307392512 04:53:33 INFO - PROCESS | 1654 | 04:53:36 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:53:37 INFO - PROCESS | 1654 | RSS: Main: 324591616 04:53:37 INFO - PROCESS | 1654 | 04:53:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:53:40 INFO - PROCESS | 1654 | RSS: Main: 318562304 04:53:40 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 04:53:42 INFO - PROCESS | 1654 | RSS: Main: 362434560 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | __start_tp_report 04:53:42 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 04:53:42 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 04:53:42 INFO - PROCESS | 1654 | |i|pagename|runs| 04:53:42 INFO - PROCESS | 1654 | |0;ai-astar;114;113;113;114;113;113;113;113;113;114 04:53:42 INFO - PROCESS | 1654 | |1;audio-beat-detection;140;109;109;145;109;108;110;108;130;112 04:53:42 INFO - PROCESS | 1654 | |2;audio-dft;186;182;185;184;183;185;180;178;182;184 04:53:42 INFO - PROCESS | 1654 | |3;audio-fft;83;76;83;112;59;59;64;58;73;58 04:53:42 INFO - PROCESS | 1654 | |4;audio-oscillator;88;69;87;70;68;68;84;68;68;83 04:53:42 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;88;86;89;85;87;84;89;86;89;84 04:53:42 INFO - PROCESS | 1654 | |6;imaging-darkroom;93;95;93;94;93;93;94;103;149;118 04:53:42 INFO - PROCESS | 1654 | |7;imaging-desaturate;87;87;86;88;83;86;85;87;85;87 04:53:42 INFO - PROCESS | 1654 | |8;json-parse-financial;56;55;55;54;55;55;54;55;56;54 04:53:42 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;63;76;79;75;72;69;69;69;70;71 04:53:42 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;88;87;87;87;87;89;86;89;87;87 04:53:42 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;140;139;141;139;138;140;138;140;191;130 04:53:42 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;172;171;171;169;172;169;170;168;170;171 04:53:42 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;68;68;66;67;67;72;84;85;86;67 04:53:42 INFO - PROCESS | 1654 | __end_tp_report 04:53:42 INFO - PROCESS | 1654 | __start_cc_report 04:53:42 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2084 04:53:42 INFO - PROCESS | 1654 | __end_cc_report 04:53:42 INFO - PROCESS | 1654 | __startTimestamp1483707222977__endTimestamp 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | ------- Summary: start ------- 04:53:42 INFO - PROCESS | 1654 | Number of tests: 14 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:113.30 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 04:53:42 INFO - PROCESS | 1654 | Values: 114.0 113.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 114.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:118.00 Median:111.00 stddev:14.53 (13.1%) stddev-sans-first:13.05 04:53:42 INFO - PROCESS | 1654 | Values: 140.0 109.0 109.0 145.0 109.0 108.0 110.0 108.0 130.0 112.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:182.90 Median:184.00 stddev:2.47 (1.3%) stddev-sans-first:2.35 04:53:42 INFO - PROCESS | 1654 | Values: 186.0 182.0 185.0 184.0 183.0 185.0 180.0 178.0 182.0 184.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:72.50 Median:68.50 stddev:17.16 (25.1%) stddev-sans-first:17.78 04:53:42 INFO - PROCESS | 1654 | Values: 83.0 76.0 83.0 112.0 59.0 59.0 64.0 58.0 73.0 58.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:76.50 stddev:8.91 (11.6%) stddev-sans-first:8.18 04:53:42 INFO - PROCESS | 1654 | Values: 88.0 69.0 87.0 70.0 68.0 68.0 84.0 68.0 68.0 83.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.50 stddev:2.00 (2.3%) stddev-sans-first:2.07 04:53:42 INFO - PROCESS | 1654 | Values: 88.0 86.0 89.0 85.0 87.0 84.0 89.0 86.0 89.0 84.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:93.00 stddev:18.15 (19.5%) stddev-sans-first:18.92 04:53:42 INFO - PROCESS | 1654 | Values: 93.0 95.0 93.0 94.0 93.0 93.0 94.0 103.0 149.0 118.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:87.00 stddev:1.45 (1.7%) stddev-sans-first:1.50 04:53:42 INFO - PROCESS | 1654 | Values: 87.0 87.0 86.0 88.0 83.0 86.0 85.0 87.0 85.0 87.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.74 (1.3%) stddev-sans-first:0.67 04:53:42 INFO - PROCESS | 1654 | Values: 56.0 55.0 55.0 54.0 55.0 55.0 54.0 55.0 56.0 54.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:4.50 (6.3%) stddev-sans-first:3.63 04:53:42 INFO - PROCESS | 1654 | Values: 63.0 76.0 79.0 75.0 72.0 69.0 69.0 69.0 70.0 71.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:87.00 stddev:0.97 (1.1%) stddev-sans-first:1.00 04:53:42 INFO - PROCESS | 1654 | Values: 88.0 87.0 87.0 87.0 87.0 89.0 86.0 89.0 87.0 87.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:143.60 Median:140.00 stddev:16.94 (12.1%) stddev-sans-first:17.92 04:53:42 INFO - PROCESS | 1654 | Values: 140.0 139.0 141.0 139.0 138.0 140.0 138.0 140.0 191.0 130.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.30 Median:171.00 stddev:1.34 (0.8%) stddev-sans-first:1.27 04:53:42 INFO - PROCESS | 1654 | Values: 172.0 171.0 171.0 169.0 172.0 169.0 170.0 168.0 170.0 171.0 04:53:42 INFO - PROCESS | 1654 | 04:53:42 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:70.00 stddev:8.45 (12.1%) stddev-sans-first:8.76 04:53:42 INFO - PROCESS | 1654 | Values: 68.0 68.0 66.0 67.0 67.0 72.0 84.0 85.0 86.0 67.0 04:53:42 INFO - PROCESS | 1654 | -------- Summary: end -------- 04:53:42 INFO - PROCESS | 1654 | 04:53:43 INFO - TEST-INFO | 1654: exit 0 04:53:43 INFO - TEST-OK | kraken | took 59423ms 04:53:43 INFO - SUITE-END | took 1178s 04:53:44 INFO - Completed test suite (00:19:39) 04:53:44 INFO - javascript benchmark 04:53:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3047.4209900118963, 3315.071779901627, 3333.9166520836984, 3373.0386839750677, 3394.4059789536823, 5495.313085864267, 5554.527865131458, 5680.261565996419, 5706.77172913084, 5725.599208055438, 4759.286107083948, 5540.612157149001, 5650.350209725882, 5749.396313387097, 5758.280214973129, 5082.23766435034, 5134.948650513485, 5135.614828887829, 5185.144451165572, 5198.3761948566225, 4593.8268752463455, 5202.739863006854, 5279.973600131994, 5301.20481927712, 5362.705051222181, 4613.446386433633, 5082.771275292584, 5242.475752424765, 5263.736813159365, 5273.024490469237, 4262.232798096342, 5123.359580052494, 5152.92270615941, 5183.041137389558, 5185.0, 5132.897342053176, 5176.8964620707375, 5177.870553236199, 5191.195364718475, 5203.60972927032, 5602.2717046783655, 5651.8304450866535, 5665.405132461074, 5691.459311365414, 5719.0, 6035.3059398169335, 6087.2086628738425, 6115.4190351916495, 6195.110491300152, 6232.750689972396, 5007.749612519359, 6174.259088909314, 6203.968980155116, 6282.120503129566, 6326.145970294044, 5735.799247026417, 5790.739416726257, 5804.622699524517, 5813.331466881321, 5822.767089316422, 6193.4425901669065, 6204.193454850841, 6271.0, 6278.152449419361, 6292.653904035322, 5969.253843269591, 6047.697615119227, 6095.329513753483, 6114.510839132859, 6137.62214577108, 6117.480014198843, 6233.065040244, 6233.34549872266, 6240.438360547481, 6241.126242326078, 6094.0, 6117.471378795421, 6127.417895299939, 6134.2332208473945, 6134.693265336716, 6217.689115544295, 6221.191245138103, 6228.0, 6231.065340199007, 6233.0, 4821.156297647968, 4856.902861942742, 4882.365292512021, 4882.438519570295, 4893.02139572087, 4299.419578356882, 4361.280388735823, 4366.6506679465565, 4368.0, 4384.802683879233, 5670.858228544319, 5680.147977803363, 5692.829215123553, 5694.4590263924865, 5709.885802283931, 6168.321484636686, 6292.685365731695, 6307.905381419282, 6311.873762524724, 6318.147050148263, 5524.475174858382, 5559.416261292587, 5567.638103523258, 5637.915431268534, 5719.628224165416, 5357.357117145967, 5379.973100134553, 5385.892282154335, 5393.811216607478, 5393.892122157535, 6181.134641150243, 6273.466755325849, 6279.963805972055, 6289.465395441439, 6323.430891219812, 6142.692865356804, 6277.780277690259, 6303.590266632654, 6310.7791227306825, 6320.747170113144, 6266.530010249341, 6273.121762953099, 6275.466585340298, 6284.4344009039405, 6286.622802631857, 4890.923996720716, 5711.200431939615, 6091.847703807263, 6134.938650613437, 6142.232220972379, 6172.22847144107, 6200.224971878515, 6227.190465239671, 6241.500679945502, 6257.906131408124, 6042.063480160582, 6048.4556389923355, 6052.90920636182, 6053.031514957762, 6080.635161890301, 5494.5054945055135, 5529.253550770594, 5881.353051164283, 5937.139114828412, 6018.428249316308, 5855.473007429352, 5895.263092113486, 5924.674142922008, 5942.6731529766275, 5976.61152025117, 6007.459328660441, 6047.667378294236, 6074.119252708421, 6075.665838378932, 6077.604955677867, 5879.559033072451, 5897.587168898136, 5928.584999050197, 5937.0, 5943.58394912352], "value": 5628.553632081626, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.34756581607945, 471.59678474903944, 488.3456168733896, 503.2928735127148, 508.82954210339534, 30441.543376849364, 39455.0, 41472.0, 41566.79216603913, 41649.58350416495, 40096.99757506064, 43046.41721496712, 43176.352354714705, 43185.0, 43192.352114718306, 9285.025072367405, 9492.952535237315, 9504.619815207383, 9507.429554226734, 9516.80966380672, 8467.915320846774, 8680.956595217014, 11279.37963412012, 11510.71223219422, 11521.481533330978, 11395.373254471, 11497.94251028748, 11575.652730418102, 11584.536618535249, 11598.420078996058, 8453.408261421702, 11449.484773185184, 11496.310221386744, 11519.654410367702, 11555.075593952464, 8426.452280601741, 8568.271696905771, 8575.399722019461, 8583.270422014137, 11019.889801101966, 39120.60879391198, 40641.98395040118, 40660.39009414861, 40673.593264067276, 40745.0, 3601.693856022241, 3604.1920256509293, 3886.5724770275247, 3888.1640447303685, 3926.8821935341984, 24742.752572474226, 30276.394472110434, 30654.693453065407, 30856.0, 31359.686403136133, 3159.9052028439182, 3194.105650417887, 3205.5512228288057, 3265.1673823175174, 3301.9174520636934, 297789.0, 301821.0, 301891.0, 302108.0, 302392.0, 60417.395826041175, 61203.6939815307, 61596.69201653963, 61675.0, 62929.0, 64563.67718161379, 64623.0, 64804.02793958094, 64865.67567162134, 64877.0, 15683.686326273639, 16168.595785105466, 16197.595060123356, 16202.189890505664, 16232.350705971628, 10097.949510252402, 11626.186166968231, 12129.0, 12146.939265303618, 12147.939260303641, 15590.844091559165, 15988.840111598967, 18098.276068957093, 18201.7269740954, 18486.445406637824, 6692.52764391833, 6749.161950045275, 6803.65981700913, 6828.863422731518, 6846.5892046477375, 830.3440282177133, 845.8012367093728, 846.2806614377853, 866.4974314897345, 884.9247813935888, 29983.850080749457, 30046.54930176049, 30349.0, 30632.693673062982, 30776.69223307738, 1024.2164743970868, 1034.2243317511866, 1079.611339917629, 1083.6369816111533, 1138.914581406398, 15388.692226155414, 15406.0, 15470.071795692069, 15594.220288985505, 15802.446914357943, 553134.0, 554336.0, 554687.0, 555269.0, 556610.0, 47446.5255347449, 57863.710681446326, 58054.4194558049, 58200.0, 58236.0, 1404.6558593144541, 1426.9357878895476, 1431.4918204037624, 1438.2880474165356, 1444.9638759031109, 21581.568368632226, 22618.208362707883, 29089.981850635126, 29101.85449072741, 29974.85012574923, 50624.49375506198, 51130.977380452925, 51145.23282150845, 51193.7440312796, 51237.48762512327, 432373.0, 433541.0, 434625.0, 435586.0, 436066.0, 39278.3788511034, 39836.601633983286, 39926.00739925984, 40079.59920400759, 40200.39699404453, 41247.0, 41302.0, 42790.144197116504, 44799.10401792011, 45325.773371132935, 29967.850160749058, 35129.82435087808, 35617.64382356143, 36818.815905920295, 38251.426228607146, 35274.47088293729, 35328.823355883054, 36195.276094477435, 36339.091522712144, 36371.81814090913, 34507.13732156716, 34560.0, 35506.934791956286, 35738.28523429569, 35804.82097589495, 31222.21944451407, 31653.8417307912, 32336.35327293488, 32352.83823580867, 33575.16062098467, 23654.408639784142, 24050.39874003164, 24083.88400415026, 24288.271351859472, 24349.51300974006], "value": 19395.530400584827, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.21126142304166, 364.937960546707, 385.37761515153016, 389.7116134060795, 410.5053410640179, 15043.398264069438, 15344.693106137871, 16899.91550042248, 20466.386008419733, 21038.948052597385, 16985.74521382177, 20954.580908381824, 21347.57304853902, 21563.353099407002, 21754.12983480659, 5468.535174510172, 5691.0, 5895.322037965646, 5931.614445061079, 5948.375420580842, 5821.446962538552, 6007.369226231248, 6015.0676645120075, 6018.368071352511, 6018.729157187937, 3850.4224366344993, 3965.8611948581806, 4056.472658554384, 4058.5129784425762, 5994.190784244115, 4037.394390841368, 6499.18760154983, 6552.344765523457, 6566.573172743804, 6572.5399222054475, 5667.943320566824, 5851.561132915049, 5874.588778785486, 5881.323647780475, 5930.911036334458, 23365.299041028797, 24740.38149046288, 25750.0, 25873.353166170808, 26138.73861261382, 2530.2029860593852, 2573.2537564106387, 2634.249238966885, 2641.220839852248, 2643.722409147031, 9715.951420242924, 10320.690379288633, 10442.895571044268, 10607.939206079407, 14163.433462661482, 1798.2896755781524, 1834.5321942904475, 1834.596388794463, 1840.2638944422204, 1848.7689038870142, 132529.0, 138312.0, 140292.0, 140988.29505852406, 142563.28718356343, 9884.703458896245, 11330.490127944098, 11640.0, 11757.235779674294, 12027.699307517381, 15342.386304547694, 15395.461158859385, 15653.373865045498, 15709.921450392674, 15751.44869929547, 8395.538245396561, 8549.230569248797, 8552.144785521523, 8729.476231426135, 8731.126887311219, 8289.8342033159, 8346.874796878052, 8352.248297653241, 8428.030776460662, 8438.90294261742, 4177.5047638641845, 4662.813487460531, 4754.429468463737, 4764.928526072112, 4847.466778654345, 1927.9036048197534, 2021.3329601231558, 2021.7978202179959, 2029.3607513633158, 2032.400441869652, 999.7200783780553, 1004.8141093897652, 1006.9295149339476, 1023.2785885950387, 1032.3238278927315, 21047.8947605261, 21529.354119376443, 21544.89227553852, 21553.35339939804, 21601.891990539945, 1027.8715160604925, 1028.5937054863286, 1063.6649455421518, 1067.989320106789, 1078.2667785905503, 8560.657573696983, 11159.72867403928, 11343.03584195336, 11347.602833900773, 11412.258203216765, 722365.0, 725372.0, 733473.0, 751722.0, 752242.0, 113041.43479282552, 119681.20478192835, 142387.0, 150543.24728376287, 153805.0, 994.1996692662332, 1006.2352612014921, 1030.340582027503, 1035.9482025898674, 1037.1339931157568, 19963.60072798565, 20116.49708757234, 20293.69559456639, 20330.288439904532, 20396.0, 58743.706281468316, 59075.0, 59330.703346482995, 59671.70164149151, 59906.70046649913, 84674.0, 85834.57082714546, 85847.57076214578, 86149.0, 87916.5604171975, 7942.761717148494, 8191.83616327682, 8428.199321064734, 8467.79837242309, 8548.700795472128, 9280.628774849201, 12614.432350544432, 13067.607971760863, 13470.797938031134, 13651.590452286448, 10961.56153753841, 11580.189386742848, 11616.767664646828, 11707.414629268673, 11829.467673954865, 11357.0, 11477.827832582685, 11484.0, 11894.821577676168, 11930.926216640544, 11623.302601843916, 11682.591109311134, 11956.76086478283, 12085.758284834079, 12096.185572164246, 8462.534560599226, 8483.74548763538, 8505.74482765518, 8604.397692161489, 8614.052454229904, 11333.716657083638, 11439.828402574134, 11646.650600481998, 11855.0, 12025.63923082309], "value": 9896.628388205847, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1312.4881296294445, 1439.5969128643983, 1447.5295528953086, 1456.5484699743074, 1461.305879707139, 8008.599570021504, 8412.747617571467, 8441.62012709428, 8444.4511106778, 8447.873281900776, 7368.5210461319975, 7668.616569171547, 7671.84656306875, 7747.496412733182, 7754.961225193881, 1911.980880191201, 2187.070495039614, 2259.039908039085, 2266.0709109265204, 2268.206127855254, 1972.8618996670239, 2016.3346095788354, 2291.0835665733675, 2365.4441206316587, 2371.8221133414977, 2041.8876961767096, 2112.176251262009, 2305.3199306204624, 2325.697659304288, 2328.429534763977, 1765.6380442009358, 2231.520223152022, 2240.4958884250914, 2245.438640339915, 2255.3459496745922, 2040.4082815983347, 2233.050953344822, 2289.427643089228, 2309.191782875997, 2315.733690625566, 8256.215659512336, 8375.539345336005, 8386.203310685476, 8388.790280243044, 8421.663133474654, 4444.31113177458, 5062.519060689228, 5097.49025097491, 5229.503197196298, 5238.135707608279, 3290.3583801158784, 3473.9826300868585, 3525.8589656413715, 3531.5938667053106, 3540.2565461253166, 1148.6496618531344, 1203.7231436769505, 1222.4926655437982, 1240.6216104088248, 1268.8413948256468, 10857.565697372096, 10858.72853178669, 10861.782764344747, 10921.563137474572, 10925.672229833117, 3823.6176382362096, 4471.5081341052455, 4669.976650116728, 4692.788824502905, 4710.646701497333, 9503.28725345602, 9576.0, 9599.088086631758, 9631.181349585224, 9654.662086826927, 5126.6923984561045, 5151.124308867502, 5216.37403511581, 5248.422673505911, 5266.183741520071, 4587.288970209623, 4695.95304046962, 4779.569838714532, 4797.7601119943865, 4817.9036419272115, 3224.3873664003763, 3725.2921944830396, 3744.1949980753725, 3805.238952209569, 3898.922021559553, 1065.2543219746208, 1250.0686988193854, 1250.662321173278, 1253.3044160490833, 1262.955796547135, 862.3055695558259, 877.1929824561419, 884.8539990901429, 910.0853642089658, 920.447731361178, 5306.25809107634, 5323.7410071942295, 5695.857603559862, 5743.368229494752, 5779.479846813807, 558.904986152355, 568.914662800575, 574.067140896044, 585.689584520205, 594.0, 3681.3189559931498, 3684.5578530576504, 3790.8483660653264, 3792.6965842732516, 3805.162864169878, 9169.587368568431, 9946.45294508809, 10101.69694909154, 10108.797824043624, 10128.949355253324, 9318.114779095975, 9678.032196780267, 10043.4476103815, 10068.949655251823, 10092.293539452168, 7607.543547387064, 7647.273509016746, 7828.960855195687, 7858.842823143619, 7860.292573668397, 5400.0, 5421.186821976735, 5472.452754724496, 5495.945040549544, 5542.307211598551, 8870.955645221733, 9157.862632060658, 9270.490123043297, 9274.118958698913, 9290.442573445614, 6683.732650693918, 7964.20357964199, 9198.586063627286, 9206.493642849708, 9266.536673166449, 7226.205117437183, 7371.262873712586, 7387.852242955219, 7394.55632662042, 7406.0, 7673.34776544, 7831.138574756658, 7858.371330293448, 7908.6045697713735, 7925.484843485155, 7036.331548502884, 7133.144022717307, 7133.643317833984, 7171.99592057102, 7224.855502889807, 6960.23437421873, 7004.509684322253, 7066.152061752623, 7105.857882842418, 7133.5719856808755, 6215.875682486416, 6299.370062993664, 6369.45859601939, 6411.262704789009, 6505.349465053457, 4936.24845117706, 4976.950230497649, 4989.750512474435, 4994.300797888372, 5138.049460849755, 4929.285253638274, 5046.293518907282, 5065.164247899203, 5131.230315452712, 5178.223266510054], "value": 4309.055571312181, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.464789013633, 355.307151055442, 356.62554317966135, 368.03391098366785, 380.0347118319469, 4072.0430698785835, 4516.645006498054, 4751.9762401187945, 4904.705717656939, 5067.6959382437035, 4637.65217608679, 4920.376275828977, 5059.026979478006, 5130.692158470494, 5140.0, 3612.981935090334, 3994.4208089826957, 4031.4355990161403, 4048.068944142849, 4065.9911665367713, 3812.0, 3848.8845334640005, 4006.4190692349594, 4036.9798151009204, 4091.1204091120403, 4011.2150093208534, 4056.1482088761395, 4077.3272410052305, 4078.9592104078874, 4117.855875044374, 3587.587427445825, 4063.674906007512, 4084.775337356459, 4086.836526538935, 4110.568390319033, 3408.386490431721, 3460.8961731148106, 3546.946795798065, 3656.8171591420323, 3657.8294945617413, 4780.796866099721, 4854.466008739036, 4988.401391833003, 5099.3370861787735, 5103.489651034867, 4657.347971284023, 4668.603168730628, 4678.929816052762, 4685.969086800898, 4704.647151463654, 4979.90040199194, 5004.174311238678, 5168.9224661630105, 5262.92105618416, 5486.643368181056, 3476.5654293213347, 3588.8743893963588, 3605.8281058656044, 4001.4397984282223, 4001.8799436016966, 4404.0, 5525.060739674265, 5621.80323688673, 5714.571407144481, 5753.252077229975, 3337.8331083445732, 3549.6272891346553, 3733.3839916413485, 3761.247750449921, 4019.376996565537, 3825.5218097737784, 3887.630675085862, 4021.2761702893176, 4096.754194748325, 4103.425520427142, 3688.317661232627, 4233.364995250737, 4285.550017248206, 4536.115457485825, 4671.065786842645, 5479.643823151482, 5498.95519851227, 5507.366652834975, 5511.649645836796, 5581.079121944915, 1250.9804509324872, 1332.8000799880097, 1338.7188690374935, 1347.905646604748, 1348.8313960754906, 798.9440739148204, 816.8978877640295, 816.9223923727236, 823.1603764160642, 854.6538651845888, 3867.864624738177, 4126.855560055444, 4861.885079990216, 4882.26765985098, 4925.5074492551175, 4782.880427989328, 5103.770330335144, 5161.045206636784, 5329.003247564327, 5347.572194224453, 4235.703500754979, 4641.651876109306, 4672.836450724209, 4859.975700121548, 4882.511748825089, 2677.0362669438987, 2795.175423250146, 2805.3547684032787, 2838.3177896887655, 2840.821059260425, 5239.004589128053, 5415.292353823089, 5452.6455780374945, 5565.860853478695, 5595.720213989284, 4612.492625811158, 5121.539061484485, 5225.947740522623, 5493.368262649767, 5552.805651802168, 4563.589276965089, 4633.374494443207, 4970.90058198834, 5454.390954668383, 5578.330600327987, 4402.647788176874, 5298.417174110922, 5313.495217954288, 5317.601179911445, 5335.573154147582, 4935.728534930613, 5014.423341315795, 5257.211418287287, 5277.920831187458, 5357.303550538405, 4952.2819191217795, 5266.499047771366, 5411.83764487066, 5448.782048718007, 5602.915956260578, 2865.9856700716364, 3143.9214019649694, 3435.3301106284034, 3596.1549035977046, 3651.3062518121474, 4817.518248175154, 4841.636877234292, 5121.795128194831, 5194.402643696023, 5273.0, 4809.3026511154985, 5197.792088316576, 5455.199784071167, 5504.339479262514, 5511.22842802, 4335.956640433556, 4745.603470247503, 5209.53114219722, 5293.703042754396, 5492.313460817398, 4454.643628509776, 5069.529836347418, 5349.0, 5389.784408623611, 5400.891982160256, 4346.087321662486, 4997.276484315984, 5236.659617125027, 5494.697791621499, 5505.972470137784, 5143.9712057587885, 5238.703393578546, 5331.333583302087, 5336.813211537733, 5386.569074474111], "value": 3935.3561960104867, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.7296335539073, 678.233596036479, 698.8462538241585, 768.3008462299309, 796.2793671727087, 11997.760044799099, 15210.467633632825, 15562.0, 15562.37750489979, 15592.610184745387, 14064.437422503088, 14131.717365652707, 14315.212663303515, 14388.640283992905, 14403.855961440382, 671.9630420326904, 853.9658413663461, 865.6191275838643, 876.3952872517974, 876.4916348517844, 690.3614156904868, 841.7264389073575, 869.8738682890977, 872.1191596487562, 872.2847265242503, 928.7306681062483, 930.9945259120134, 931.6133804471136, 932.9160375566198, 933.3093510802025, 925.3013974449248, 925.6436272035281, 929.1173385284008, 930.0188301342081, 930.906909309067, 523.2413001148312, 537.5054949446519, 657.1424291299852, 670.7518218259227, 675.4528831646383, 15276.0, 15293.0, 15367.385304587802, 15369.615759606098, 15403.92172547911, 13539.187648741008, 13584.388702508411, 13591.320433978262, 13635.795463068062, 13689.31553422325, 12348.814767778491, 12432.440540175714, 12434.564790232298, 12506.249625022529, 12651.683707907285, 4416.757078360689, 4499.325101234808, 4555.681102322838, 4585.220512512881, 4595.747233902134, 100039.0, 101560.0, 102475.48762256141, 103778.0, 105248.4737576315, 10811.405372704578, 10866.239363244655, 10905.509252083675, 11101.0, 11398.316101033799, 21153.471163220736, 21461.46346341354, 21563.89218053931, 21608.783912160678, 21610.56778864414, 7541.43439242048, 7601.7719468416035, 7603.163651998276, 7655.846883062308, 7831.138574756772, 4150.5641907599875, 4193.308104162779, 4247.0444150066605, 4249.511306199826, 4301.569843015736, 3334.7832390894514, 3429.811360375203, 3431.107911942913, 3438.1748380388526, 3442.1566716154703, 552.737449711392, 621.4191709793221, 639.8080575827233, 639.9936000639934, 653.8757636049136, 599.8950183717832, 610.7673820424501, 639.8048595178423, 659.2484567592948, 672.9831754206184, 7488.4009279257525, 8798.912010879938, 8942.418742781829, 9443.152441884844, 9574.760630984141, 627.2974268818951, 648.2322517881012, 652.7584793626294, 661.1768348406168, 673.562184580027, 4478.664100192498, 4492.550744925481, 4680.859574212779, 4681.368015317957, 4693.155232058262, 102138.0, 102878.0, 104323.4783826076, 104418.0, 107955.46022269838, 77678.83481747778, 89671.10328896627, 91341.77923986873, 92498.53750731204, 93221.0, 67274.65450691056, 72067.0, 76199.61900190463, 82150.58924705339, 82232.76650850242, 8942.955285223532, 8946.642134314814, 8975.506347150707, 9124.95437522808, 9170.541472926194, 36308.27383452263, 45769.54230457653, 48979.26531101965, 48994.51005490042, 49242.75378623084, 53257.733711331195, 62600.0, 67808.0, 68829.96755048579, 70674.29325706678, 11222.66332010041, 11279.062223974292, 11292.040176585082, 11525.82711259315, 11573.0, 12349.93825030869, 13576.524821631196, 13695.931520342334, 14349.0, 14446.662009779799, 13443.0, 13511.662208444473, 13570.93214533921, 14077.929610351883, 14223.0, 11432.828507572227, 12297.877021230031, 12407.441665125272, 12579.245245285312, 12704.745905081661, 12677.683057923625, 13226.272555009567, 13243.47026118983, 13526.323683815574, 13760.518381856587, 10049.346792458467, 10916.836247456136, 11003.779924401628, 11376.544938202711, 11446.37044962535, 8642.43824151428, 10470.738231544272, 11086.390248536409, 11232.491053460939, 11503.827442588201], "value": 7333.684391207787, "unit": "score"}], "name": "dromaeo_css", "value": 7156.828818603401, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 109.0, 109.0, 145.0, 109.0, 108.0, 110.0, 108.0, 130.0, 112.0], "value": 118.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 185.0, 184.0, 183.0, 185.0, 180.0, 178.0, 182.0, 184.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 76.0, 83.0, 112.0, 59.0, 59.0, 64.0, 58.0, 73.0, 58.0], "value": 72.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 87.0, 70.0, 68.0, 68.0, 84.0, 68.0, 68.0, 83.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 85.0, 87.0, 84.0, 89.0, 86.0, 89.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 93.0, 94.0, 93.0, 93.0, 94.0, 103.0, 149.0, 118.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 88.0, 83.0, 86.0, 85.0, 87.0, 85.0, 87.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 54.0, 55.0, 55.0, 54.0, 55.0, 56.0, 54.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 76.0, 79.0, 75.0, 72.0, 69.0, 69.0, 69.0, 70.0, 71.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 87.0, 87.0, 89.0, 86.0, 89.0, 87.0, 87.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 141.0, 139.0, 138.0, 140.0, 138.0, 140.0, 191.0, 130.0], "value": 143.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 171.0, 171.0, 169.0, 172.0, 169.0, 170.0, 168.0, 170.0, 171.0], "value": 170.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 66.0, 67.0, 67.0, 72.0, 84.0, 85.0, 86.0, 67.0], "value": 73.0, "unit": "score"}], "name": "kraken", "value": 1437.8, "alertThreshold": 2.0}]} 04:53:44 INFO - Return code: 0 04:53:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:53:44 INFO - # TBPL SUCCESS # 04:53:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:53:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:53:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:53:44 INFO - 2017-01-06 04:53:44.311 screenresolution[1658:10488] starting screenresolution argv=screenresolution get 04:53:44 INFO - 2017-01-06 04:53:44.314 screenresolution[1658:10488] Display 0: 1600x1200x32@60 04:53:44 INFO - 2017-01-06 04:53:44.320 screenresolution[1659:10491] starting screenresolution argv=screenresolution list 04:53:44 INFO - Available Modes on Display 0 04:53:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:53:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:53:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:53:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:53:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:53:44 INFO - Intel Iris: 04:53:44 INFO - Chipset Model: Intel Iris 04:53:44 INFO - Type: GPU 04:53:44 INFO - Bus: Built-In 04:53:44 INFO - VRAM (Dynamic, Max): 1536 MB 04:53:44 INFO - Vendor: Intel (0x8086) 04:53:44 INFO - Device ID: 0x0a2e 04:53:44 INFO - Revision ID: 0x0009 04:53:44 INFO - Displays: 04:53:44 INFO - Display: 04:53:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:53:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:53:44 INFO - Display Serial Number: 1600x1200 60 04:53:44 INFO - Main Display: Yes 04:53:44 INFO - Mirror: Off 04:53:44 INFO - Online: Yes 04:53:44 INFO - Rotation: Supported 04:53:44 INFO - Return code: 0 04:53:44 INFO - Running post-action listener: _resource_record_post_action 04:53:44 INFO - [mozharness: 2017-01-06 12:53:44.555782Z] Finished run-tests step (success) 04:53:44 INFO - Running post-run listener: _resource_record_post_run 04:53:44 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 32459776; Write bytes: 552704512; Read time: 196; Write time: 1670 04:53:44 INFO - TinderboxPrint: CPU usage
53.8% 04:53:44 INFO - TinderboxPrint: I/O read bytes / time
32,459,776 / 196 04:53:44 INFO - TinderboxPrint: I/O write bytes / time
552,704,512 / 1,670 04:53:44 INFO - TinderboxPrint: CPU idle
2,218.9 (46.2%) 04:53:44 INFO - TinderboxPrint: CPU system
960.3 (20.0%) 04:53:44 INFO - TinderboxPrint: CPU user
1,626.4 (33.8%) 04:53:44 INFO - TinderboxPrint: Swap in / out
278,188,032 / 0 04:53:44 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250632704; Write bytes: 250454016; Read time: 18241; Write time: 537 04:53:44 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29027328; Write bytes: 300857856; Read time: 172; Write time: 1129 04:53:45 INFO - Running post-run listener: _upload_blobber_files 04:53:45 INFO - Blob upload gear active. 04:53:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:53:45 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 04:53:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:53:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:53:45 INFO - (blobuploader) - INFO - Open directory for files ... 04:53:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:53:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:53:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:46 INFO - (blobuploader) - INFO - Done attempting. 04:53:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:53:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:53:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:47 INFO - (blobuploader) - INFO - Done attempting. 04:53:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:53:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:53:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:48 INFO - (blobuploader) - INFO - Done attempting. 04:53:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:53:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:53:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:53:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:53:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:53:50 INFO - (blobuploader) - INFO - Done attempting. 04:53:50 INFO - (blobuploader) - INFO - Iteration through files over. 04:53:50 INFO - Return code: 0 04:53:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:53:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:53:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02bd9d90f6a5798e6a067c6841f340f4788a244c9647a07bf171c41617341159960e01633e4ee92361b4f644fa7db96ebd8882e272644cec5487e3994b0e0576", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/912e14fb3ecd24fa15a32c13dddda7a64440a5fbf579910b45587c3832a4824703dcdad551fa2bb5e2d6f98aedcfd84adf343afd6eeebd066e5c061d408ee671", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/601511b19815d5cfb67adb34ccf98f8844787e7e5537fd4b3d89dbb34d22dbfa68140e5cabac4e863dea94e0af656fbfc54347c2cd5f5e270c31d2dd875ca016", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7a6b116fb1cd4234faa6d526cf51af9f278e24aa75a0d98e3da260a5a2ff6ecf2cd540fe2c16ea0f4f31ddd2ba119943868e3843b6f40c9661bec7a7ca9d563"} 04:53:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:53:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:53:50 INFO - Contents: 04:53:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02bd9d90f6a5798e6a067c6841f340f4788a244c9647a07bf171c41617341159960e01633e4ee92361b4f644fa7db96ebd8882e272644cec5487e3994b0e0576", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/912e14fb3ecd24fa15a32c13dddda7a64440a5fbf579910b45587c3832a4824703dcdad551fa2bb5e2d6f98aedcfd84adf343afd6eeebd066e5c061d408ee671", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/601511b19815d5cfb67adb34ccf98f8844787e7e5537fd4b3d89dbb34d22dbfa68140e5cabac4e863dea94e0af656fbfc54347c2cd5f5e270c31d2dd875ca016", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7a6b116fb1cd4234faa6d526cf51af9f278e24aa75a0d98e3da260a5a2ff6ecf2cd540fe2c16ea0f4f31ddd2ba119943868e3843b6f40c9661bec7a7ca9d563"} 04:53:50 INFO - Running post-run listener: copy_logs_to_upload_dir 04:53:50 INFO - Copying logs to upload dir... 04:53:50 INFO - mkdir: /builds/slave/test/build/upload/logs 04:53:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.066598 ========= master_lag: 1.15 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-06 04:53:51.642600) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 04:53:51.646394) ========= 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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02bd9d90f6a5798e6a067c6841f340f4788a244c9647a07bf171c41617341159960e01633e4ee92361b4f644fa7db96ebd8882e272644cec5487e3994b0e0576", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/912e14fb3ecd24fa15a32c13dddda7a64440a5fbf579910b45587c3832a4824703dcdad551fa2bb5e2d6f98aedcfd84adf343afd6eeebd066e5c061d408ee671", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/601511b19815d5cfb67adb34ccf98f8844787e7e5537fd4b3d89dbb34d22dbfa68140e5cabac4e863dea94e0af656fbfc54347c2cd5f5e270c31d2dd875ca016", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7a6b116fb1cd4234faa6d526cf51af9f278e24aa75a0d98e3da260a5a2ff6ecf2cd540fe2c16ea0f4f31ddd2ba119943868e3843b6f40c9661bec7a7ca9d563"} build_url:https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010907 build_url: 'https://queue.taskcluster.net/v1/task/dF7lvveuQP2t5trRK1FzUg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02bd9d90f6a5798e6a067c6841f340f4788a244c9647a07bf171c41617341159960e01633e4ee92361b4f644fa7db96ebd8882e272644cec5487e3994b0e0576", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/912e14fb3ecd24fa15a32c13dddda7a64440a5fbf579910b45587c3832a4824703dcdad551fa2bb5e2d6f98aedcfd84adf343afd6eeebd066e5c061d408ee671", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/601511b19815d5cfb67adb34ccf98f8844787e7e5537fd4b3d89dbb34d22dbfa68140e5cabac4e863dea94e0af656fbfc54347c2cd5f5e270c31d2dd875ca016", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7a6b116fb1cd4234faa6d526cf51af9f278e24aa75a0d98e3da260a5a2ff6ecf2cd540fe2c16ea0f4f31ddd2ba119943868e3843b6f40c9661bec7a7ca9d563"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 04:53:51.687622) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 04:53:51.687911) ========= 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.hvkLZ4cosS/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.5GiQdWVgNh/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005169 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 04:53:51.742046) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 04:53:51.742720) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 04:53:51.743369) ========= ========= Total master_lag: 1.31 =========