builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0010 starttime: 1483620392.6 results: success (0) buildid: 20170105030229 builduid: dbbaa93b8a024c5a8380078f0c7af328 revision: f13abb8ba9f366c9f32a3146245adf642528becd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:32.608847) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:32.609312) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:32.609589) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:32.633891) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:32.634244) ========= 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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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-05 04:46:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.19M=0.001s 2017-01-05 04:46:33 (8.19 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.534101 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:33.188577) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:33.188887) ========= 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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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.018797 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:33.239323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-05 04:46:33.239975) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f13abb8ba9f366c9f32a3146245adf642528becd --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 f13abb8ba9f366c9f32a3146245adf642528becd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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-05 04:46:33,312 truncating revision to first 12 chars 2017-01-05 04:46:33,312 Setting DEBUG logging. 2017-01-05 04:46:33,312 attempt 1/10 2017-01-05 04:46:33,312 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f13abb8ba9f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 04:46:35,096 unpacking tar archive at: mozilla-central-f13abb8ba9f3/testing/mozharness/ program finished with exit code 0 elapsedTime=2.036692 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-05 04:46:35.300925) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:35.301247) ========= script_repo_revision: f13abb8ba9f366c9f32a3146245adf642528becd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:35.301676) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:35.301978) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 04:46:35.325228) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-05 04:46:35.325549) ========= /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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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:46:35 INFO - MultiFileLogger online at 20170105 04:46:35 in /builds/slave/test 04:46:35 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:46:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:46:35 INFO - {'append_to_log': False, 04:46:35 INFO - 'base_work_dir': '/builds/slave/test', 04:46:35 INFO - 'blob_upload_branch': 'Firefox', 04:46:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:46:35 INFO - 'branch': 'Firefox', 04:46:35 INFO - 'buildbot_json_path': 'buildprops.json', 04:46:35 INFO - 'config_files': ('talos/mac_config.py',), 04:46:35 INFO - 'default_actions': ('clobber', 04:46:35 INFO - 'read-buildbot-config', 04:46:35 INFO - 'download-and-extract', 04:46:35 INFO - 'populate-webroot', 04:46:35 INFO - 'create-virtualenv', 04:46:35 INFO - 'install', 04:46:35 INFO - 'run-tests'), 04:46:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:46:35 INFO - 'download_minidump_stackwalk': True, 04:46:35 INFO - 'download_symbols': 'ondemand', 04:46:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:46:35 INFO - 'tooltool.py': '/tools/tooltool.py', 04:46:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:46:35 INFO - '/tools/misc-python/virtualenv.py')}, 04:46:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:46:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:46:35 INFO - 'installer_path': 'installer.exe', 04:46:35 INFO - 'log_level': 'info', 04:46:35 INFO - 'log_name': 'talos', 04:46:35 INFO - 'log_to_console': True, 04:46:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:46:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:46:35 INFO - 'opt_config_files': (), 04:46:35 INFO - 'pip_index': False, 04:46:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:35 INFO - 'cmd': ('bash', 04:46:35 INFO - '-c', 04:46:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:35 INFO - 'enabled': True, 04:46:35 INFO - 'halt_on_failure': False, 04:46:35 INFO - 'name': 'check_screen_resolution'},), 04:46:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:35 INFO - 'cmd': ('bash', 04:46:35 INFO - '-c', 04:46:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:35 INFO - 'enabled': True, 04:46:35 INFO - 'halt_on_failure': False, 04:46:35 INFO - 'name': 'check_screen_resolution'},), 04:46:35 INFO - 'run_cmd_checks_enabled': True, 04:46:35 INFO - 'sps_profile': False, 04:46:35 INFO - 'sps_profile_interval': 0, 04:46:35 INFO - 'suite': 'dromaeojs', 04:46:35 INFO - 'system_bits': '32', 04:46:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:46:35 INFO - 'title': 't-yosemite-r7-0010', 04:46:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:46:35 INFO - 'use_talos_json': True, 04:46:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:46:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:46:35 INFO - 'work_dir': 'build'} 04:46:35 INFO - [mozharness: 2017-01-05 12:46:35.525783Z] Running clobber step. 04:46:35 INFO - Running pre-action listener: _resource_record_pre_action 04:46:35 INFO - Running main action method: clobber 04:46:35 INFO - rmtree: /builds/slave/test/build 04:46:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:46:35 INFO - Running post-action listener: _resource_record_post_action 04:46:35 INFO - [mozharness: 2017-01-05 12:46:35.859530Z] Finished clobber step (success) 04:46:35 INFO - [mozharness: 2017-01-05 12:46:35.859657Z] Running read-buildbot-config step. 04:46:35 INFO - Running pre-action listener: _resource_record_pre_action 04:46:35 INFO - Running main action method: read_buildbot_config 04:46:35 INFO - Using buildbot properties: 04:46:35 INFO - { 04:46:35 INFO - "project": "", 04:46:35 INFO - "product": "firefox", 04:46:35 INFO - "script_repo_revision": "production", 04:46:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:46:35 INFO - "repository": "", 04:46:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:46:35 INFO - "buildid": "20170105030229", 04:46:35 INFO - "stage_platform": "macosx64", 04:46:35 INFO - "basedir": "/builds/slave/test", 04:46:35 INFO - "nightly_build": "True", 04:46:35 INFO - "buildnumber": 55, 04:46:35 INFO - "slavename": "t-yosemite-r7-0010", 04:46:35 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:46:35 INFO - "platform": "yosemite_r7", 04:46:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:46:35 INFO - "branch": "mozilla-central", 04:46:35 INFO - "repo_path": "mozilla-central", 04:46:35 INFO - "revision": "f13abb8ba9f366c9f32a3146245adf642528becd", 04:46:35 INFO - "pgo_build": "False", 04:46:35 INFO - "builduid": "dbbaa93b8a024c5a8380078f0c7af328", 04:46:35 INFO - "slavebuilddir": "test" 04:46:35 INFO - } 04:46:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:46:35 INFO - Running post-action listener: _resource_record_post_action 04:46:35 INFO - [mozharness: 2017-01-05 12:46:35.862882Z] Finished read-buildbot-config step (success) 04:46:35 INFO - [mozharness: 2017-01-05 12:46:35.863008Z] Running download-and-extract step. 04:46:35 INFO - Running pre-action listener: _resource_record_pre_action 04:46:35 INFO - Running main action method: download_and_extract 04:46:35 INFO - mkdir: /builds/slave/test/build/tests 04:46:35 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:46:35 INFO - trying https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:46:35 INFO - Downloading https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/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:46:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/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:46:36 INFO - Downloaded 1199 bytes. 04:46:36 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:46:36 INFO - Using the following test package requirements: 04:46:36 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:46:36 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:46:36 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:46:36 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:46:36 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:46:36 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:46:36 INFO - u'jsshell-mac.zip'], 04:46:36 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:46:36 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:46:36 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:46:36 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:46:36 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:46:36 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:46:36 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:46:36 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:46:36 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:46:36 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:46:36 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:46:36 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:46:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:46:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:46:36 INFO - Fetch https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:46:38 INFO - Expected file size: 16298594 04:46:38 INFO - Obtained file size: 16298594 04:46:45 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:46:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:46:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:46:45 INFO - Fetch https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:46:46 INFO - Expected file size: 16298594 04:46:46 INFO - Obtained file size: 16298594 04:46:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:46:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:46:52 INFO - Fetch https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:46:54 INFO - Expected file size: 14403730 04:46:54 INFO - Obtained file size: 14403730 04:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:54 INFO - trying https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:46:54 INFO - Downloading https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:46:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/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:46:59 INFO - Downloaded 69776385 bytes. 04:46:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:46:59 INFO - mkdir: /builds/slave/test/properties 04:46:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:46:59 INFO - Writing to file /builds/slave/test/properties/build_url 04:46:59 INFO - Contents: 04:46:59 INFO - build_url:https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:46:59 INFO - Running post-action listener: _resource_record_post_action 04:46:59 INFO - Running post-action listener: set_extra_try_arguments 04:46:59 INFO - [mozharness: 2017-01-05 12:46:59.873544Z] Finished download-and-extract step (success) 04:46:59 INFO - [mozharness: 2017-01-05 12:46:59.873664Z] Running populate-webroot step. 04:46:59 INFO - Running pre-action listener: _resource_record_pre_action 04:46:59 INFO - Running main action method: populate_webroot 04:46:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:46:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:46:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:46:59 INFO - '--tppagecycles', 04:46:59 INFO - '7'], 04:46:59 INFO - 'tests': ['tsvgm']}}, 04:46:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:46:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:46:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:46:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:46:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:46:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:46:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:46:59 INFO - 'talos_options': ['--disable-e10s'], 04:46:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:46:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:46:59 INFO - 'talos_options': ['--disable-e10s'], 04:46:59 INFO - 'tests': ['damp', 'tps']}, 04:46:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:46:59 INFO - 'tests': ['damp', 'tps']}, 04:46:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:46:59 INFO - 'tests': ['dromaeo_dom']}, 04:46:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:46:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:46:59 INFO - 'tests': ['basic_compositor_video']}, 04:46:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:46:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:46:59 INFO - 'tests': ['a11yr', 04:46:59 INFO - 'ts_paint', 04:46:59 INFO - 'tpaint', 04:46:59 INFO - 'sessionrestore', 04:46:59 INFO - 'sessionrestore_no_auto_restore', 04:46:59 INFO - 'tabpaint']}, 04:46:59 INFO - 'other-e10s': {'tests': ['a11yr', 04:46:59 INFO - 'ts_paint', 04:46:59 INFO - 'tpaint', 04:46:59 INFO - 'sessionrestore', 04:46:59 INFO - 'sessionrestore_no_auto_restore', 04:46:59 INFO - 'tabpaint']}, 04:46:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:46:59 INFO - 'ts_paint', 04:46:59 INFO - 'tpaint', 04:46:59 INFO - 'sessionrestore', 04:46:59 INFO - 'sessionrestore_no_auto_restore', 04:46:59 INFO - 'tabpaint']}, 04:46:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:46:59 INFO - 'ts_paint', 04:46:59 INFO - 'tpaint', 04:46:59 INFO - 'sessionrestore', 04:46:59 INFO - 'sessionrestore_no_auto_restore', 04:46:59 INFO - 'tabpaint']}, 04:46:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:46:59 INFO - 'tests': ['a11yr', 04:46:59 INFO - 'ts_paint', 04:46:59 INFO - 'tpaint', 04:46:59 INFO - 'sessionrestore', 04:46:59 INFO - 'sessionrestore_no_auto_restore', 04:46:59 INFO - 'tabpaint']}, 04:46:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:46:59 INFO - 'tests': ['a11yr', 04:46:59 INFO - 'ts_paint', 04:46:59 INFO - 'tpaint', 04:46:59 INFO - 'sessionrestore', 04:46:59 INFO - 'sessionrestore_no_auto_restore', 04:46:59 INFO - 'tabpaint']}, 04:46:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:46:59 INFO - 'tests': ['tsvgx', 04:46:59 INFO - 'tsvgr_opacity', 04:46:59 INFO - 'tart', 04:46:59 INFO - 'tscrollx', 04:46:59 INFO - 'cart', 04:46:59 INFO - 'tsvg_static']}, 04:46:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:46:59 INFO - 'tsvgr_opacity', 04:46:59 INFO - 'tart', 04:46:59 INFO - 'tscrollx', 04:46:59 INFO - 'cart', 04:46:59 INFO - 'tsvg_static']}, 04:46:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:46:59 INFO - 'talos_options': ['--disable-e10s'], 04:46:59 INFO - 'tests': ['tp5o']}, 04:46:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:46:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:46:59 INFO - 'talos_options': ['--disable-e10s', 04:46:59 INFO - '--xperf_path', 04:46:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:59 INFO - 'tests': ['tp5n']}, 04:46:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:46:59 INFO - 'talos_options': ['--xperf_path', 04:46:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:59 INFO - 'tests': ['tp5n']}}, 04:46:59 INFO - 'talos.zip': {'path': '', 04:46:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:46:59 INFO - Running post-action listener: _resource_record_post_action 04:46:59 INFO - [mozharness: 2017-01-05 12:46:59.891938Z] Finished populate-webroot step (success) 04:46:59 INFO - [mozharness: 2017-01-05 12:46:59.892050Z] Running create-virtualenv step. 04:46:59 INFO - Running pre-action listener: _resource_record_pre_action 04:46:59 INFO - Running main action method: create_virtualenv 04:46:59 INFO - Creating virtualenv /builds/slave/test/build/venv 04:46:59 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:46:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:00 INFO - Using real prefix '/tools/python27' 04:47:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:00 INFO - Installing distribute.............................................................................................................................................................................................done. 04:47:03 INFO - Installing pip.................done. 04:47:03 INFO - Return code: 0 04:47:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:47:03 INFO - Reading from file tmpfile_stdout 04:47:03 INFO - Output received: 04:47:03 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:47:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:03 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 0x10a4adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85b0c58ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28bd30>, '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 0x10a1b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a280be8>, '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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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:47:03 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:47:03 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:47:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qNqnWkynrB/Render', 04:47:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:03 INFO - 'HOME': '/Users/cltbld', 04:47:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:03 INFO - 'LOGNAME': 'cltbld', 04:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:03 INFO - 'MOZ_NO_REMOTE': '1', 04:47:03 INFO - 'NO_EM_RESTART': '1', 04:47:03 INFO - 'PAGER': '/bin/cat', 04:47:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:03 INFO - 'PWD': '/builds/slave/test', 04:47:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:03 INFO - 'SHELL': '/bin/bash', 04:47:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SaOpQ1Sfqv/Listeners', 04:47:03 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 04:47:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:03 INFO - 'USER': 'cltbld', 04:47:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:03 INFO - 'XPC_FLAGS': '0x0', 04:47:03 INFO - 'XPC_SERVICE_NAME': '0', 04:47:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:04 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:47:04 INFO - Cleaning up... 04:47:04 INFO - Return code: 0 04:47:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:47: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:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:04 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 0x10a4adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85b0c58ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28bd30>, '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 0x10a1b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a280be8>, '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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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:47:04 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:47:04 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:47:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qNqnWkynrB/Render', 04:47:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:04 INFO - 'HOME': '/Users/cltbld', 04:47:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:04 INFO - 'LOGNAME': 'cltbld', 04:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:04 INFO - 'MOZ_NO_REMOTE': '1', 04:47:04 INFO - 'NO_EM_RESTART': '1', 04:47:04 INFO - 'PAGER': '/bin/cat', 04:47:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:04 INFO - 'PWD': '/builds/slave/test', 04:47:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:04 INFO - 'SHELL': '/bin/bash', 04:47:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SaOpQ1Sfqv/Listeners', 04:47:04 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 04:47:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:04 INFO - 'USER': 'cltbld', 04:47:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:04 INFO - 'XPC_FLAGS': '0x0', 04:47:04 INFO - 'XPC_SERVICE_NAME': '0', 04:47:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:04 INFO - Downloading/unpacking psutil>=3.1.1 04:47:04 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:47:04 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:47:04 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:47:04 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:47:04 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:47:04 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:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:47:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:08 INFO - Installing collected packages: psutil 04:47:08 INFO - Running setup.py install for psutil 04:47:08 INFO - building 'psutil._psutil_osx' extension 04:47:08 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:47:08 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:47:08 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:47:08 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:47:08 INFO - building 'psutil._psutil_posix' extension 04:47:08 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:47:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:47:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:47:08 INFO - ^ 04:47:08 INFO - 1 warning generated. 04:47:08 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:47:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:08 INFO - Successfully installed psutil 04:47:08 INFO - Cleaning up... 04:47:09 INFO - Return code: 0 04:47:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:47:09 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:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:09 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:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:09 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 0x10a4adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85b0c58ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28bd30>, '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 0x10a1b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a280be8>, '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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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:47:09 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:47:09 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:47:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qNqnWkynrB/Render', 04:47:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:09 INFO - 'HOME': '/Users/cltbld', 04:47:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:09 INFO - 'LOGNAME': 'cltbld', 04:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:09 INFO - 'MOZ_NO_REMOTE': '1', 04:47:09 INFO - 'NO_EM_RESTART': '1', 04:47:09 INFO - 'PAGER': '/bin/cat', 04:47:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:09 INFO - 'PWD': '/builds/slave/test', 04:47:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:09 INFO - 'SHELL': '/bin/bash', 04:47:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SaOpQ1Sfqv/Listeners', 04:47:09 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 04:47:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:09 INFO - 'USER': 'cltbld', 04:47:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:09 INFO - 'XPC_FLAGS': '0x0', 04:47:09 INFO - 'XPC_SERVICE_NAME': '0', 04:47:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:47:09 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:47:09 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:47:09 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:47:09 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:47:09 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:47:09 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:47:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:47:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:47:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:47:12 INFO - Installing collected packages: mozsystemmonitor 04:47:12 INFO - Running setup.py install for mozsystemmonitor 04:47:12 INFO - Successfully installed mozsystemmonitor 04:47:12 INFO - Cleaning up... 04:47:12 INFO - Return code: 0 04:47:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:47:12 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:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:12 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:47:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:12 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 0x10a4adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85b0c58ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28bd30>, '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 0x10a1b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a280be8>, '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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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:47:12 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:47:12 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:47:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qNqnWkynrB/Render', 04:47:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:12 INFO - 'HOME': '/Users/cltbld', 04:47:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:12 INFO - 'LOGNAME': 'cltbld', 04:47:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:12 INFO - 'MOZ_NO_REMOTE': '1', 04:47:12 INFO - 'NO_EM_RESTART': '1', 04:47:12 INFO - 'PAGER': '/bin/cat', 04:47:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:12 INFO - 'PWD': '/builds/slave/test', 04:47:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:12 INFO - 'SHELL': '/bin/bash', 04:47:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SaOpQ1Sfqv/Listeners', 04:47:12 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 04:47:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:12 INFO - 'USER': 'cltbld', 04:47:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:12 INFO - 'XPC_FLAGS': '0x0', 04:47:12 INFO - 'XPC_SERVICE_NAME': '0', 04:47:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:12 INFO - Downloading/unpacking jsonschema==2.5.1 04:47:12 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:47:12 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:47:12 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:47:12 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:47:12 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:47:12 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:47:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:47:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:15 INFO - Downloading functools32-3.2.3-2.tar.gz 04:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:47:15 INFO - warning: no files found matching '*.txt' 04:47:15 INFO - no previously-included directories found matching 'build' 04:47:15 INFO - no previously-included directories found matching 'dist' 04:47:15 INFO - no previously-included directories found matching '.git*' 04:47:15 INFO - Installing collected packages: jsonschema, functools32 04:47:15 INFO - Running setup.py install for functools32 04:47:15 INFO - warning: no files found matching '*.txt' 04:47:15 INFO - no previously-included directories found matching 'build' 04:47:15 INFO - no previously-included directories found matching 'dist' 04:47:15 INFO - no previously-included directories found matching '.git*' 04:47:15 INFO - Successfully installed jsonschema functools32 04:47:15 INFO - Cleaning up... 04:47:15 INFO - Return code: 0 04:47:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a4adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85b0c58ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28bd30>, '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 0x10a1b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a280be8>, '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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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:47:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qNqnWkynrB/Render', 04:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:15 INFO - 'HOME': '/Users/cltbld', 04:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:15 INFO - 'LOGNAME': 'cltbld', 04:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:15 INFO - 'MOZ_NO_REMOTE': '1', 04:47:15 INFO - 'NO_EM_RESTART': '1', 04:47:15 INFO - 'PAGER': '/bin/cat', 04:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:15 INFO - 'PWD': '/builds/slave/test', 04:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:15 INFO - 'SHELL': '/bin/bash', 04:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SaOpQ1Sfqv/Listeners', 04:47:15 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 04:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:15 INFO - 'USER': 'cltbld', 04:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:15 INFO - 'XPC_FLAGS': '0x0', 04:47:15 INFO - 'XPC_SERVICE_NAME': '0', 04:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:47:16 INFO - Cleaning up... 04:47:16 INFO - Return code: 0 04:47:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a4adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85b0c58ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28bd30>, '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 0x10a1b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a280be8>, '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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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:47:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:47:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qNqnWkynrB/Render', 04:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:16 INFO - 'HOME': '/Users/cltbld', 04:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:16 INFO - 'LOGNAME': 'cltbld', 04:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:16 INFO - 'MOZ_NO_REMOTE': '1', 04:47:16 INFO - 'NO_EM_RESTART': '1', 04:47:16 INFO - 'PAGER': '/bin/cat', 04:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:16 INFO - 'PWD': '/builds/slave/test', 04:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:16 INFO - 'SHELL': '/bin/bash', 04:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SaOpQ1Sfqv/Listeners', 04:47:16 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 04:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:16 INFO - 'USER': 'cltbld', 04:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:16 INFO - 'XPC_FLAGS': '0x0', 04:47:16 INFO - 'XPC_SERVICE_NAME': '0', 04:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:16 INFO - Downloading/unpacking blobuploader==1.2.4 04:47:16 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:47:16 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:47:16 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:47:16 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:47:16 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:47:16 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:47:19 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47: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:47: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:47: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:47: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:47: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:47: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:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:47:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:47: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:47: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:47: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:47: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:47: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:47: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:47:20 INFO - Downloading docopt-0.6.1.tar.gz 04:47:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:47:20 INFO - Installing collected packages: blobuploader, requests, docopt 04:47:20 INFO - Running setup.py install for blobuploader 04:47:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:47:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:47:20 INFO - Running setup.py install for requests 04:47:21 INFO - Running setup.py install for docopt 04:47:21 INFO - Successfully installed blobuploader requests docopt 04:47:21 INFO - Cleaning up... 04:47:21 INFO - Return code: 0 04:47:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:21 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:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:21 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:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:21 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 0x10a4adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85b0c58ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28bd30>, '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 0x10a1b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a280be8>, '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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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:47:21 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:47:21 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:47:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qNqnWkynrB/Render', 04:47:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:21 INFO - 'HOME': '/Users/cltbld', 04:47:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:21 INFO - 'LOGNAME': 'cltbld', 04:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:21 INFO - 'MOZ_NO_REMOTE': '1', 04:47:21 INFO - 'NO_EM_RESTART': '1', 04:47:21 INFO - 'PAGER': '/bin/cat', 04:47:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:21 INFO - 'PWD': '/builds/slave/test', 04:47:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:21 INFO - 'SHELL': '/bin/bash', 04:47:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SaOpQ1Sfqv/Listeners', 04:47:21 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 04:47:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:21 INFO - 'USER': 'cltbld', 04:47:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:21 INFO - 'XPC_FLAGS': '0x0', 04:47:21 INFO - 'XPC_SERVICE_NAME': '0', 04:47:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:21 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-hapipz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:21 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-KTdRef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:21 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-TqUkEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:21 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-jN2Hus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:21 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-n9Riur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:22 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-83f2WG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:22 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-HZxRKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:22 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-lwlSBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:22 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-rqlxHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:22 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-VfJ6Ed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:22 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-gpnM2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:22 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-RwStcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:22 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-4r2VT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:22 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-WDeiFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:23 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-XCZMtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:23 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-W7h4yI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:23 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-T4tldi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:47:23 INFO - Running setup.py install for manifestparser 04:47:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:47:23 INFO - Running setup.py install for mozcrash 04:47:23 INFO - Running setup.py install for mozdebug 04:47:23 INFO - Running setup.py install for mozdevice 04:47:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:47:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:47:23 INFO - Running setup.py install for mozfile 04:47:24 INFO - Running setup.py install for mozhttpd 04:47:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:47:24 INFO - Running setup.py install for mozinfo 04:47:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:47:24 INFO - Running setup.py install for mozInstall 04:47:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:47:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:47:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:47:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:47:24 INFO - Running setup.py install for mozleak 04:47:24 INFO - Running setup.py install for mozlog 04:47:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:47:24 INFO - Running setup.py install for moznetwork 04:47:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:47:24 INFO - Running setup.py install for mozprocess 04:47:25 INFO - Running setup.py install for mozprofile 04:47:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:47:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:47:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:47:25 INFO - Running setup.py install for mozrunner 04:47:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:47:25 INFO - Running setup.py install for mozscreenshot 04:47:25 INFO - Running setup.py install for moztest 04:47:25 INFO - Running setup.py install for mozversion 04:47:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:47:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:47:25 INFO - Cleaning up... 04:47:25 INFO - Return code: 0 04:47:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:25 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:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:25 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:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:25 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 0x10a4adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85b0c58ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28bd30>, '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 0x10a1b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a280be8>, '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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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:47:25 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:47:25 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:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qNqnWkynrB/Render', 04:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:25 INFO - 'HOME': '/Users/cltbld', 04:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:25 INFO - 'LOGNAME': 'cltbld', 04:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:25 INFO - 'MOZ_NO_REMOTE': '1', 04:47:25 INFO - 'NO_EM_RESTART': '1', 04:47:25 INFO - 'PAGER': '/bin/cat', 04:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:25 INFO - 'PWD': '/builds/slave/test', 04:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:25 INFO - 'SHELL': '/bin/bash', 04:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SaOpQ1Sfqv/Listeners', 04:47:25 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 04:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:25 INFO - 'USER': 'cltbld', 04:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:25 INFO - 'XPC_FLAGS': '0x0', 04:47:25 INFO - 'XPC_SERVICE_NAME': '0', 04:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:26 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-EhNRql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:26 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:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:26 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-QtrLYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:26 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:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:26 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-qIiITD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:26 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:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:26 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Oc1Ok5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:26 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:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:26 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-IuzqBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:26 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:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:26 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-tPxcki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:26 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:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:26 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-a4TUPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:26 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:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:26 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-RzbZ_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:27 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:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:27 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-pCuARb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:27 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:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:27 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-2W2k8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:27 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:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:27 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-IG1afu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:27 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:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:27 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-h9wPWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:27 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:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:27 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-IGK3KM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:27 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:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:27 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-ymSYZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:27 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:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:27 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-sZeKeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:27 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:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:27 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-khYtQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:27 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:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:27 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-asm66_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:27 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:47:27 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:47:27 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:47:27 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:47:27 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:47:27 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:47:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:30 INFO - Downloading blessings-1.6.tar.gz 04:47:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:47:30 INFO - Installing collected packages: blessings 04:47:30 INFO - Running setup.py install for blessings 04:47:30 INFO - Successfully installed blessings 04:47:30 INFO - Cleaning up... 04:47:30 INFO - Return code: 0 04:47:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:47:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:31 INFO - Reading from file tmpfile_stdout 04:47:31 INFO - Current package versions: 04:47:31 INFO - blessings == 1.6 04:47:31 INFO - blobuploader == 1.2.4 04:47:31 INFO - docopt == 0.6.1 04:47:31 INFO - functools32 == 3.2.3-2 04:47:31 INFO - jsonschema == 2.5.1 04:47:31 INFO - manifestparser == 1.1 04:47:31 INFO - mozInstall == 1.12 04:47:31 INFO - mozcrash == 1.0 04:47:31 INFO - mozdebug == 0.1 04:47:31 INFO - mozdevice == 0.49 04:47:31 INFO - mozfile == 1.2 04:47:31 INFO - mozhttpd == 0.7 04:47:31 INFO - mozinfo == 0.9 04:47:31 INFO - mozleak == 0.1 04:47:31 INFO - mozlog == 3.4 04:47:31 INFO - moznetwork == 0.27 04:47:31 INFO - mozprocess == 0.23 04:47:31 INFO - mozprofile == 0.28 04:47:31 INFO - mozrunner == 6.13 04:47:31 INFO - mozscreenshot == 0.1 04:47:31 INFO - mozsystemmonitor == 0.3 04:47:31 INFO - moztest == 0.8 04:47:31 INFO - mozversion == 1.4 04:47:31 INFO - psutil == 3.1.1 04:47:31 INFO - requests == 1.2.3 04:47:31 INFO - wsgiref == 0.1.2 04:47:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47: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:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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:47:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:31 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 0x10a4adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85b0c58ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28bd30>, '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 0x10a1b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a280be8>, '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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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:47:31 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:47:31 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:47:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qNqnWkynrB/Render', 04:47:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:31 INFO - 'HOME': '/Users/cltbld', 04:47:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:31 INFO - 'LOGNAME': 'cltbld', 04:47:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:31 INFO - 'MOZ_NO_REMOTE': '1', 04:47:31 INFO - 'NO_EM_RESTART': '1', 04:47:31 INFO - 'PAGER': '/bin/cat', 04:47:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:31 INFO - 'PWD': '/builds/slave/test', 04:47:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:31 INFO - 'SHELL': '/bin/bash', 04:47:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SaOpQ1Sfqv/Listeners', 04:47:31 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 04:47:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:31 INFO - 'USER': 'cltbld', 04:47:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:31 INFO - 'XPC_FLAGS': '0x0', 04:47:31 INFO - 'XPC_SERVICE_NAME': '0', 04:47:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:31 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:47:31 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:47:31 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:47:31 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:47:31 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:47:31 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:47:31 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:47:31 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:47:31 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:47:31 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:47:31 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:47:31 INFO - Cleaning up... 04:47:31 INFO - Return code: 0 04:47:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:47: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:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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:47:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a4adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85b0c58ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28bd30>, '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 0x10a1b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a280be8>, '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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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:47:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:47:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:47:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qNqnWkynrB/Render', 04:47:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:31 INFO - 'HOME': '/Users/cltbld', 04:47:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:31 INFO - 'LOGNAME': 'cltbld', 04:47:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:31 INFO - 'MOZ_NO_REMOTE': '1', 04:47:31 INFO - 'NO_EM_RESTART': '1', 04:47:31 INFO - 'PAGER': '/bin/cat', 04:47:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:31 INFO - 'PWD': '/builds/slave/test', 04:47:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:31 INFO - 'SHELL': '/bin/bash', 04:47:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SaOpQ1Sfqv/Listeners', 04:47:31 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 04:47:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:31 INFO - 'USER': 'cltbld', 04:47:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:31 INFO - 'XPC_FLAGS': '0x0', 04:47:31 INFO - 'XPC_SERVICE_NAME': '0', 04:47:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:47:31 INFO - Cleaning up... 04:47:31 INFO - Return code: 0 04:47:31 INFO - Running post-action listener: _resource_record_post_action 04:47:31 INFO - Running post-action listener: _start_resource_monitoring 04:47:31 INFO - Starting resource monitoring. 04:47:31 INFO - [mozharness: 2017-01-05 12:47:31.741302Z] Finished create-virtualenv step (success) 04:47:31 INFO - [mozharness: 2017-01-05 12:47:31.742375Z] Running install step. 04:47:31 INFO - Running pre-action listener: _resource_record_pre_action 04:47:31 INFO - Running main action method: install 04:47:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:32 INFO - Reading from file tmpfile_stdout 04:47:32 INFO - Detecting whether we're running mozinstall >=1.0... 04:47:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:47:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:47:32 INFO - Reading from file tmpfile_stdout 04:47:32 INFO - Output received: 04:47:32 INFO - Usage: mozinstall [options] installer 04:47:32 INFO - Options: 04:47:32 INFO - -h, --help show this help message and exit 04:47:32 INFO - -d DEST, --destination=DEST 04:47:32 INFO - Directory to install application into. [default: 04:47:32 INFO - "/builds/slave/test"] 04:47:32 INFO - --app=APP Application being installed. [default: firefox] 04:47:32 INFO - mkdir: /builds/slave/test/build/application 04:47:32 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:47:32 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:47:54 INFO - Reading from file tmpfile_stdout 04:47:54 INFO - Output received: 04:47:54 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:47:54 INFO - Running post-action listener: _resource_record_post_action 04:47:54 INFO - [mozharness: 2017-01-05 12:47:54.016463Z] Finished install step (success) 04:47:54 INFO - [mozharness: 2017-01-05 12:47:54.016607Z] Running run-tests step. 04:47:54 INFO - Running pre-action listener: _resource_record_pre_action 04:47:54 INFO - Running main action method: run_tests 04:47:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:47:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:47:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:47:54 INFO - 2017-01-05 04:47:54.030 screenresolution[1967:278025] starting screenresolution argv=screenresolution get 04:47:54 INFO - 2017-01-05 04:47:54.033 screenresolution[1967:278025] Display 0: 1600x1200x32@60 04:47:54 INFO - 2017-01-05 04:47:54.039 screenresolution[1968:278028] starting screenresolution argv=screenresolution list 04:47:54 INFO - Available Modes on Display 0 04:47:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:47:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:47:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:47:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:47:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:47:54 INFO - Intel Iris: 04:47:54 INFO - Chipset Model: Intel Iris 04:47:54 INFO - Type: GPU 04:47:54 INFO - Bus: Built-In 04:47:54 INFO - VRAM (Dynamic, Max): 1536 MB 04:47:54 INFO - Vendor: Intel (0x8086) 04:47:54 INFO - Device ID: 0x0a2e 04:47:54 INFO - Revision ID: 0x0009 04:47:54 INFO - Displays: 04:47:54 INFO - Display: 04:47:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:47:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:47:54 INFO - Display Serial Number: 1600x1200 60 04:47:54 INFO - Main Display: Yes 04:47:54 INFO - Mirror: Off 04:47:54 INFO - Online: Yes 04:47:54 INFO - Rotation: Supported 04:47:54 INFO - Return code: 0 04:47:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:47:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:47:54 INFO - Python 2.7.3 04:47:54 INFO - Return code: 0 04:47:54 INFO - grabbing minidump binary from tooltool 04:47:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:54 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 0x10a28bd30>, '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 0x10a1b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a280be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:47:54 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:47:54 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:47:54 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:47:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:47:54 INFO - Return code: 0 04:47:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:47:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:47:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:47:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:47:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:47:54 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/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0010', '--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:47:54 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/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0010 --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:47:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qNqnWkynrB/Render', 04:47:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:54 INFO - 'HOME': '/Users/cltbld', 04:47:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:54 INFO - 'LOGNAME': 'cltbld', 04:47:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:47:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:47:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:54 INFO - 'MOZ_NO_REMOTE': '1', 04:47:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:47:54 INFO - 'NO_EM_RESTART': '1', 04:47:54 INFO - 'PAGER': '/bin/cat', 04:47:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:54 INFO - 'PWD': '/builds/slave/test', 04:47:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:47:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:54 INFO - 'SHELL': '/bin/bash', 04:47:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SaOpQ1Sfqv/Listeners', 04:47:54 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 04:47:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:54 INFO - 'USER': 'cltbld', 04:47:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:54 INFO - 'XPC_FLAGS': '0x0', 04:47:54 INFO - 'XPC_SERVICE_NAME': '0', 04:47:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:54 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/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0010', '--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:47:54 INFO - mozversion application_buildid: 20170105030229 04:47:54 INFO - mozversion application_changeset: f13abb8ba9f366c9f32a3146245adf642528becd 04:47:54 INFO - mozversion application_display_name: Nightly 04:47:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:47:54 INFO - mozversion application_name: Firefox 04:47:54 INFO - mozversion application_remotingname: firefox 04:47:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:47:54 INFO - mozversion application_vendor: Mozilla 04:47:54 INFO - mozversion application_version: 53.0a1 04:47:54 INFO - mozversion platform_buildid: 20170105030229 04:47:54 INFO - mozversion platform_changeset: f13abb8ba9f366c9f32a3146245adf642528becd 04:47:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:47:54 INFO - mozversion platform_version: 53.0a1 04:47:54 INFO - using testdate: 1483620474 04:47:54 INFO - actual date: 1483620474 04:47:54 INFO - starting webserver on 'localhost:49304' 04:47:54 INFO - SUITE-START | Running 2 tests 04:47:54 INFO - TEST-START | dromaeo_css 04:47:54 INFO - operating with platform_type : mac_ 04:47:54 INFO - Initialising browser for dromaeo_css test... 04:47:54 INFO - TEST-INFO | started process 1978 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmphJY1pp/profile http://localhost:49304/getInfo.html) 04:47:55 INFO - PROCESS | 1978 | 1483620475516 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:263 < _startProvider()@resource://gre/modules/AddonManager.jsm:758 < startup()@resource://gre/modules/AddonManager.jsm:940 < startup()@resource://gre/modules/AddonManager.jsm:2913 < observe()@resource://gre/components/addonManager.js:65 04:47:57 INFO - PROCESS | 1978 | __metrics Screen width/height:1600/1200 04:47:57 INFO - PROCESS | 1978 | colorDepth:24 04:47:57 INFO - PROCESS | 1978 | Browser inner width/height: 1024/689 04:47:57 INFO - PROCESS | 1978 | __metrics 04:47:57 INFO - PROCESS | 1978 | 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:47:57 INFO - PROCESS | 1978 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:47:58 INFO - TEST-INFO | 1978: exit 0 04:47:58 INFO - Browser initialized. 04:47:58 INFO - Running cycle 1/1 for dromaeo_css test... 04:47:58 INFO - TEST-INFO | started process 1983 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmphJY1pp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:10 INFO - PROCESS | 1983 | RSS: Main: 233955328 04:48:10 INFO - PROCESS | 1983 | 04:49:01 INFO - PROCESS | 1983 | 1483620541779 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:51:13 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49304/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49304/tests/dromaeo/cssquery-ext.html) 04:51:16 INFO - PROCESS | 1983 | RSS: Main: 369561600 04:51:16 INFO - PROCESS | 1983 | 04:52:59 INFO - PROCESS | 1983 | ************************* 04:52:59 INFO - PROCESS | 1983 | A coding exception was thrown and uncaught in a Task. 04:52:59 INFO - PROCESS | 1983 | 04:52:59 INFO - PROCESS | 1983 | Full message: ReferenceError: fetch is not defined 04:52:59 INFO - PROCESS | 1983 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:52:59 INFO - PROCESS | 1983 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:52:59 INFO - PROCESS | 1983 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:52:59 INFO - PROCESS | 1983 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:52:59 INFO - PROCESS | 1983 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:52:59 INFO - PROCESS | 1983 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:52:59 INFO - PROCESS | 1983 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:52:59 INFO - PROCESS | 1983 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:52:59 INFO - PROCESS | 1983 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:52:59 INFO - PROCESS | 1983 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:52:59 INFO - PROCESS | 1983 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:52:59 INFO - PROCESS | 1983 | 04:52:59 INFO - PROCESS | 1983 | ************************* 04:54:19 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49304/tests/dromaeo/cssquery-ext.html (next: http://localhost:49304/tests/dromaeo/cssquery-jquery.html) 04:54:20 INFO - PROCESS | 1983 | RSS: Main: 367669248 04:54:20 INFO - PROCESS | 1983 | 04:57:22 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49304/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49304/tests/dromaeo/cssquery-mootools.html) 04:57:22 INFO - PROCESS | 1983 | RSS: Main: 342667264 04:57:22 INFO - PROCESS | 1983 | 05:00:25 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49304/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49304/tests/dromaeo/cssquery-prototype.html) 05:00:25 INFO - PROCESS | 1983 | RSS: Main: 331505664 05:00:25 INFO - PROCESS | 1983 | 05:03:29 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49304/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49304/tests/dromaeo/cssquery-yui.html) 05:03:30 INFO - PROCESS | 1983 | RSS: Main: 329510912 05:03:30 INFO - PROCESS | 1983 | 05:06:33 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49304/tests/dromaeo/cssquery-yui.html (next: http://localhost:49304/tests/dromaeo/cssquery-dojo.html) 05:06:33 INFO - PROCESS | 1983 | RSS: Main: 389775360 05:06:33 INFO - PROCESS | 1983 | 05:06:33 INFO - PROCESS | 1983 | __start_tp_report 05:06:33 INFO - PROCESS | 1983 | _x_x_mozilla_page_load 05:06:33 INFO - PROCESS | 1983 | _x_x_mozilla_page_load_details 05:06:33 INFO - PROCESS | 1983 | |i|pagename|runs| 05:06:33 INFO - PROCESS | 1983 | |0;dojo.html;3154.8264845433505;3162.4623813951625;3218.340240250748;3272.967270327297;3274.2632907595807;5445.863853403667;5477.972610136953;5500.972495137524;5550.2507161533185;5599.972000140004;5535.889282214353;5536.916946245809;5592.356878958921;5600.915986260199;5627.043402621564;5056.747162641853;5069.847904562869;5073.365829271341;5089.847304580869;5095;5116.820911268106;5208;5216;5222.7649755760895;5247.160454327309;5150.665206761549;5166.844994650167;5195.272661827347;5202.947970520284;5222.138347172731;5002.299678045077;5027.5977921766535;5029.321041659366;5051.44434112251;5100.056489549445;5054.3176671149295;5059.519345662156;5085.186370180791;5132.615053870974;5149.794008239666;4806.582058292777;5284.180951952415;5554.1113421852715;5561.721913904329;5597.6641401516035;5893.174955506232;5932.644041357532;6077.726502307407;6108.144859719643;6110.175126357929;5948.7323070461935;6091.664958427285;6091.847703807396;6116.327204007555;6120.540959428061;5560.193771903092;5596.300462442236;5607.158926161068;5608.607397482218;5658.292713410824;6135.5398345124295;6209.751609935643;6217.129601855744;6218.9378106219265;6244.594101383395;6021.217241758631;6034.2155519782145;6041.426064523863;6048.153258543807;6054.727537260834;6029.909551356734;6040.516758659297;6065.787697430569;6085.17850090241;6169.136320915076;6027.758889644365;6115.357887421846;6122.938770612326;6128.754849806047;6134.4172303631085;6076.574639775174;6086.573939824169;6091.908621370683;6095.085737139376;6102.420270074336;4727.929081063787;4774.832880849153;4805.519448055236;4814.494478079821;4827.662063655511;4254.574542545721;4311.922019495126;4317.093410383792;4323.805428755651;4340.934885976713;5717.085266357363;5733.283339582552;5735.283089613798;5745.368009518949;5752.568557358215;6110.969445152746;6122.326544080148;6131.019036954066;6136.8772624547255;6140.969295153495;5642.039822061063;5720.856978575569;5721.284839395075;5722.055860782924;5727.570432217517;5218.191180367049;5232.555232805178;5253.159494480865;5261.184516399964;5266.710330931835;6283.6858157091965;6301;6303.432691057918;6310.842228944313;6359.395857393541;6153.630782153085;6168.1672974149715;6175.5677102602385;6189.319174890848;6197.349278325711;4977.054359671795;5165.9483405165465;6273.466755325666;6273.9372606273355;6288.119663247057;5983.461488465885;5988.490978266722;5996;5998.82003539877;6008.008678567988;6079.513638908788;6088.178095956989;6093.75624974996;6113.724882380393;6122.387761223842;4683.227267500961;6007.038873780174;6007.279126504847;6016.338202797776;6044.33512313654;5795.188673585617;5925.111233314865;5941.524678025834;5942.494887934401;5954.970225148846;5607.831765047055;5665.915011274917;5677.261955945701;5784.363719990882;5787.536997040311;5838.678872662044;5858.648481091148;5903.291605007426;5942.078977758454;5995.730192141277;5754.741036653444;5763.827085187451;5769.221155144001;5774.653520788598;5779.884402312015 05:06:33 INFO - PROCESS | 1983 | |1;ext.html;483.7025229483868;491.8942427378114;494.792187281342;497.1275411652549;523.7952708769828;28414.857925710367;38923.221535569275;38935;39367;39405.80297098514;39817;39949.40075898864;40518.392224116586;40983.38524922128;41056.38415423763;8260.752177434657;8451.915480845175;9062.048484909088;9269.721908342726;9283.72148835536;10927.453627318642;11201.271917325348;11223.158263130257;11368.658940231806;11371.204015718902;9332.53337333134;11021.44892755359;11026.173037022216;11049.17131215158;11070.833937490945;8320.583970801435;8508.276796472246;11091.33451992879;11103.944480277627;11209.663710088711;9633.662821801274;10414.646096007471;11040.447977601169;11169.329840209532;11236.66290011301;39588.22882398205;39866;39883.8005809972;39932.80201593957;39994.20011599781;4008.178323443687;4012.4181993611055;4015.196960607861;4034.9798251008556;4036.6880763751897;24862;29078.408774788262;30024.348660823645;30079.398412031856;30361.544576831366;3087;3188.2985743136474;3249.431349513826;3258.7393008559256;3294.5387645729616;355373;356432.43567564513;357842.210788947;358760.20619897;477196;59039.40960590339;59327.70336148292;59414;59430.40569594335;59458.40541594615;61363.6931815338;61571.692141539;61583.6920815393;61589.38410615926;61610.38389616046;15337.386504539916;15508.689826203412;15525.844741552666;15526.369731178063;15526.689466210613;11129.666110016713;11383.430828458544;11389.031932285683;12347.3826308686;12411.75176496483;18355.75345676119;20225.393238202876;20364.796352036585;21075.68386474204;21124;8398.496090234605;8411.747647570583;8464.873026904601;8496.405251632326;8557.421779348144;719.9280071992864;818.423011776696;842.7261140129483;846.2975730143966;881.0792721605937;23942.88028559846;29756.851215743784;29948.40103197924;30532.847335763625;30700.84649576738;1061.6284300494765;1070.978580428387;1073.2487258918788;1077.7144056824948;1203.7532305877273;14989.40042398292;15210.783137349206;15591.610209744847;15592.688146237011;15666;643700.7814960895;648376;650614;650916;651434;58768;59503.70248148818;59619.701901491084;59670.70164649149;59716.014199289864;1366.269046060353;1418.858114188573;1423.252792284059;1424.0103128325713;1433.5269361110809;25786.742132578434;29157.70842291635;29476;29682.25794355072;30040.549391758705;48215;48337.75831120822;48536.5146348532;48546.5145348542;48717.7820554489;494098;494920;495547;496404;498593;40813.97965050779;41955.37066944059;42441.78779106208;42562.5743742571;42694.78652606717;45692.77153614211;46181.538184617726;46337;46466.07067858691;46517.604471865896;38032.42951355787;38304.80847595744;38927.416088758124;39225.80387098046;39335.60664393319;37366;37613.811930941265;38245;39029.80485097556;39042.80478597589;37728.245435091696;37777.81111094427;38348.424773628976;38349;38387.6161238384;29062.564061539517;32187.51718724268;32542.698292068053;33748.325033499685;33966.32067358688;24300.635490467303;24303;24561.385965351008;25332.620010699484;25352 05:06:33 INFO - PROCESS | 1983 | |2;jquery.html;364.39328518017504;382.83155411618884;387.4324115171274;390.9218156368726;416.6083881151717;15006.849931500696;15043.548693539198;17230.310787568513;17611.911940440314;21411.35765927021;21808.781912180875;22014.569053011543;22762;22821;22882.199123030696;6524.641144737037;6669.9666501667425;6693;6770.255271920109;6924.896126558106;6463.192100987377;6622.9006564901565;6730.0577919091365;6735.797926062226;6736.427403670694;6583.539152259344;6688.93311066888;6695.799126026227;6706.295838936914;6719.932800672004;6407.263164736069;6588.571742836701;6668.433183179435;6687.063811066479;6689.33106689332;6474.125992990933;6509.511786616023;6511.38141876521;6527;6527.281998980156;28517.71482285192;28891.844326226925;29347.266318341997;29607.555886661718;29735.405291894258;2833.8724757385967;2871.8851245950136;2886.07645553423;2899.6752164808886;2909.689816085652;16698.415555455624;16875.83124168755;16962.915185424117;17420.65158696819;17505.387311444167;1945.8346040586566;1986.4636548132069;2001.6897380905982;2014.98992505038;2024.8278896293832;138327;156991;159902;161846;163341;22006.559868802855;22649;22665.546689066126;22765.544689106457;22831.086756529887;39649.603503964594;39943.80028099841;40043.399349009786;40124.799376002935;40196.76950072685;8051.275385215359;8245.51580715477;8595.3553483489;8607.956960215159;8697.130286971378;8278.254957053894;8295.585220738938;8320.292775114061;8410.158984101541;8440.324774018063;4728.527147285244;4733.887536428936;4741.170295198326;4743.857684269477;4816.133096042746;1677.5051359848874;1847.5196448923377;1853.3698542495615;1988.9303874364327;2013.7986201379745;995.8157740817971;1001.1490233301781;1002.3835341265069;1014.1390548343725;1045.9110975567162;21550.13799448036;22201.333959981228;22243.332700019026;22312;22605.660915086286;882.4176043811054;949.8480243161199;1020.7397113736098;1026.296986564206;1048.3343077145914;9105.954470227607;10747.83878241827;11384.20310578268;11548.422578871023;11614.419279036014;701150;732714;736166;749119;758321;140185;143710.84433733503;144854.27572862068;148053;160661;958.9664361747306;993.9155171810477;1033.4574348467115;1039.9012093851222;1043.5356266461504;19520.414387568395;19907.701384478954;19916.60166796685;20014.69977950361;20045.59908801845;55471.722641386536;60907;61198;61325.693371532856;61622.38377616167;85658.57170714106;86092.56953715191;87400.5629971846;88357;89434;8110;8172.468789528662;8389.580520973805;8470.49177049379;8716.477011379338;9258.53707314645;12638.55765048219;12684;12794.36028198605;13101;9805.72525571671;10141.340812847142;11020.393878336768;11221.55113795439;11313.547458101913;10834.349939003685;11101.944490277498;11272.323660580392;11325.32048077118;11389.202755807013;10887.292325998786;11140;11366.943165284121;11579.594714184963;11891.405429728651;7161.606111663908;7425.8143546411775;7523.3229009389415;7787.844243114992;8418.6211620476;10052;10906.291091079054;10966.945165274123;11379.772404552028;11464 05:06:33 INFO - PROCESS | 1983 | |3;mootools.html;1419.3131874953444;1441.819772528434;1447.413797412048;1458.5551406820916;1464.1507925403264;8174.591270436476;8307;8532.018817835951;8569.442986205908;8572.785680357994;7565.281298276669;7816.17930117338;7827.178146294643;8138.430309878311;8456.323494120472;2268.659701044844;2315.166540045582;2319.4433335999406;2322.105989194164;2323.8024767894663;2311.9537609247805;2338.497223097034;2340.520193360357;2349.119080344871;2351.623740201568;2295.5523672883796;2298.10373954158;2322.1059891941554;2324.5467133908896;2330.8018818400456;2298.9310320690406;2299.655051742235;2309.457277539777;2316.7104111986;2318.2929206591984;2305.262316058862;2324.639680849471;2331.9067237310487;2332.207049603143;2332.207049603143;8325.708600199025;8353.498790072554;8419.45273557223;8440.704575339894;8463.06906240313;5046.21783623539;5199.168133098724;5202.531772140525;5204.609654275944;5214.191800270926;2921.6640086390125;2985.4327677741157;3248.8538015789345;3256.3161736035463;3527.171114788023;1190.1728298832315;1203.4584437003384;1225.7609766095616;1251.2367455851922;1254.1722463174353;10932.617358392497;10945.890541094566;11056.944715276453;11060.557577696964;11157.497912593954;4396.450443694538;4627.67606267558;4690.108879312919;4699.718016918996;4720.787564559603;9554.331196816294;9559.996200398997;9571.138597526256;9594.856077158849;9595.376300540582;5155.252488389237;5206.817761378334;5249.343832020997;5256.553192978639;5269.0252303324005;3519.8240087995496;4200.348945913388;4723.126221649006;4801.231802911588;4823.397075365579;3191.250056236829;3746.306933217309;3756.229972855558;3778.886633401002;3823.3882578787816;1270.0474644016988;1270.5362542671858;1272.567327108769;1276.1513593460431;1280.8142819291152;758.8406434648675;837.3426859914938;886.7029545102463;893.1782759860944;918.1782304837134;5324.254604355393;5407.459254074561;5658.801941932013;5764.106563482667;5786.537077033911;969.6606187834343;979.4123525884412;985.4481490365275;992.4914130521711;1024.774549599087;3589.5513060867393;3708.9258214835554;3743.5694895087413;3822.6750726188584;3875.1087249932675;9993.200543956465;10233.795324093477;10337.431441270803;10343.27597068213;10382.273240873066;9604;9989.550470228856;10002.849957250646;10245.897541024644;10283;6279.686015699197;7867.370610351159;7867.409944254204;7887.763367098996;7893.842123157505;5519.254900588369;5554.694491802989;5582.7766889323975;5714.942850571441;5766.2503874497725;7826.139124696165;9060.864087038568;9158.450492970444;9426.434413934912;9431.339806213498;9013.414128081653;9437.056294370508;9511.619535218513;9628.75928101775;9659.46872921996;6141.662208578571;6470.8675981703955;6933.965330173317;7235.167955685163;7437.553746775211;7727.806804829925;7879.684812607432;8044.2760151586635;8114.066882308374;8124.675012999413;7136.393406560501;7242.311980361857;7263.019492368674;7323.231060738546;7405.370543503863;7077.929220707727;7080.2919708028785;7134.643267836691;7156.499045066796;7265.745698900513;5675.637846916793;6250.624962502265;6376.840578985562;6385;6395.584287021329;3632;4552.635789136927;4834.709917404967;4868.95131048685;4997.400311962588;4717.929231061468;4755.144074066701;4765.928511072406;4780.995990841962;4824.34871292371 05:06:33 INFO - PROCESS | 1983 | |4;prototype.html;310.67999960041135;349.2735110969184;359.48234542259155;363.8090002748557;371.85311801838293;5155.149400348942;5237.86905327367;5241.344831896004;5250.448702886194;5278.973605131969;3826.387777955528;5159.50984656458;5194.324737784093;5216.513293711284;5237;3433.8768969742623;3864.632859878307;3986.8604598839047;4008.859689910854;4020.899477513071;3503.6321186275454;3558;3571;4049.75701457912;4078.490188726409;3467.6705712957355;3924.274009308287;4046.5751096134923;4051.250518654044;4062.7359221650645;3503.4394496880377;3724.720645951551;3968.920621587552;4006.3758238353635;4061.8578349757763;3514.2604410816552;3516.40221162403;3578.4632305154173;3598.8946466317234;3634.309481198564;4280.72175308604;5064.215046667772;5091.79632814687;5104.4640312767;5144.61415393847;4287.871363859089;4509.359888452695;4528.18492671319;4601.723896566184;4654.162250794856;4590.862274131781;4916.803327866881;5148.485151484859;5379.4716483252805;5510.338759348863;3503.789772613651;3943.1719338938856;3962.0887195945093;3990.4014397840265;4000.959990400088;4597.540245975376;4690.929636055462;5653.943460565424;5714.514266287329;5826.563007774392;3463.8268086595567;3671.8531258749886;3932.980335098306;4042.9797851011144;4066.779966010244;4027.8993025174605;4120.62914337705;4121.9381709274385;4151.667866570667;4155.542890282067;4522.8643140705835;4587.912499812577;4660.86017419478;4689.835855745033;4700.106979673851;5510.256115424367;5535.723213839292;5550.7779688812;5589.888202235932;5599;1232.3530146673068;1232.7904256276454;1271.0848189303686;1299.8950891741945;1330.813686083949;821.2485575698289;858.2576071698061;923.4505469245788;941.9576119074521;945.5933948402115;4815.977289538402;4928.7289199093675;5033.748312584356;5048.419431765321;5079.771410286472;4641.25739881624;4818.747125747331;5041.042201981685;5307.65500242483;5347.652402593819;4537.977310113428;4580.635908189478;4636.814527418933;5004.9690123401215;5014.423341315795;2364.817591204398;2530.607755797854;2795.7344052314997;2816;2818.478581462436;4958.091134913012;5272;5317.787288508416;5508.862278443071;5628.774849006076;4622.653301002439;5212.9408883490805;5401.839264294235;5417.377001644862;5476.67139971603;4973.378327709036;5054.717819878236;5328.934213157307;5727.541796656257;5767.423257674115;4399.538048504861;4742.620590352694;4906.779194936165;5380.488853558905;5427;4479.484859241099;5197.584193264454;5342.519173274424;5578.693171875586;5581.860453488695;5141.742912854417;5308.734563271898;5524.944750552443;5857.355690873916;5882.411758824083;3105.90682279532;3718.665320121094;3773.716971227114;3777.1690228149764;3818.866339678098;5612.747426365741;5614.831555053355;5636.632614302085;5647.5199608032135;5657.32112146545;5292.147748288068;5430.104032834538;5497.8075767347955;5500.614956952975;5524.889502209853;5471;5475.972620136874;5478.342598888159;5505.788726480168;5515.917261241005;5002.798209146222;5397.8920421590565;5426.158945363475;5435.565154787687;5441.483059109379;4651.976740116278;4832.806687732451;5256.4743525648655;5475.5072043516275;5549.195366671891;4347.913041739211;5397.514223719727;5431.619786614899;5435.396558015474;5450.863728406822 05:06:33 INFO - PROCESS | 1983 | |5;yui.html;690.9274526174754;709.8260926073112;751.3200553499082;793.3534169651733;798.0307301385637;14899.5530134096;15801.288941997609;15830.366785328588;15833.92083039586;15841.287142078605;12771.361431928413;12964;14300.856991430082;14304.141751494892;14311.355988980495;673.2089794491477;687.8039758668781;833.3166803221361;844.7550210438981;857.3698331726176;858.0561382479285;862.7929296968745;864.5417928497906;867.0852250875324;870.1298701298701;916.3860213656833;917.6054296652437;918.2562124678998;919.2645883293372;920.3143657974819;708.2351060854265;912.3932584831146;913.1279627955287;913.6117150211134;914.1818072824818;535.7428434351494;542.8914217156585;543.6194663735401;544.2516539757833;659.5020759326726;14860.702785944328;15304;15318.923405382902;15332;15345.693086138215;13578.932105339509;13730.588082357544;13739.24434156121;13822;13868.583942481742;12493.812592811115;12661.556845510455;12703.872961270361;12771.808422873572;12919.031072669588;4277.657787377002;4379.978100109511;4383.430154079949;4437.267850804614;4556.088782243532;102009;103489.99999999924;106855;106964;108464;9073.59168837404;10664.89335106639;10742.194335424718;10924.617638382777;11263.831042534368;20231.19075236974;21611.351659450243;21630.675539866916;21635.783642163376;21659.566808663738;7824.9608751955875;7844.882326765103;7906.604669766603;8043.1554686757045;8095.635696393676;4251.851185208503;4304.913901721948;4358.413924568595;4380.3210502372185;4392.538783427758;3584.6415358464465;3658.1951970566433;3685.94471082934;3692.796896170683;3704.351738445815;595.965998991749;623.4357906094991;639.6322114784018;650.6649075726004;659.2813832922138;653.8430776613578;662.3244290823333;669.3072669786748;675.3921470676332;693.8646963842103;7677.692892284246;8868.27068721593;9193.448393096436;9330.160285574331;9715.854262186072;561.2675458526662;580.962237454564;585.2917969257161;585.6925114315019;588.9869424589699;4435.334699794992;4531.320301954667;4542.954570454253;4599;4647.790849411717;101453;102732.48633756784;104437;106325.46837265765;107186.46406767916;79828.6008569965;80707.59646201732;83186.75219871706;83321.91695207669;86659;69355.30644693489;69745.65127174433;69889.9516507263;74853;78882.81675774753;9442.15020648145;9782.951085244527;9845.704628861145;9850.852237216304;10024.949875250577;42807.3578896323;45855;47780;50386.74806625943;51211.74394128005;66806;66880;67924.3207567918;68858.65570672315;69728.65135674289;11878.049756019325;12179.391030448265;12325.75348493043;12329.568465103679;12519.436625351698;14441.277936103363;14643.56069317922;14725.116493010453;14736.705265894409;14786;13598.184108953494;13603.251821149932;13625;13797.931010344884;13895.58313250604;13360.265185414895;13701.45194192261;13851;13981.300934953415;14161.221132837793;13234.338283086;13396.062275640612;13422.261775602436;13520.59438216855;13615.978801590118;10765.515551800345;11048.889511104786;11175.888241117485;11411.543538258376;11418.714532136431;10074.949625251827;10829.282427645821;10838.674839754505;10863.619773307897;11013.559457621606 05:06:33 INFO - PROCESS | 1983 | __end_tp_report 05:06:33 INFO - PROCESS | 1983 | __start_cc_report 05:06:33 INFO - PROCESS | 1983 | _x_x_mozilla_cycle_collect,2457 05:06:33 INFO - PROCESS | 1983 | __end_cc_report 05:06:33 INFO - PROCESS | 1983 | __startTimestamp1483621593646__endTimestamp 05:06:33 INFO - PROCESS | 1983 | 05:06:33 INFO - PROCESS | 1983 | ------- Summary: start ------- 05:06:33 INFO - PROCESS | 1983 | Number of tests: 6 05:06:33 INFO - PROCESS | 1983 | 05:06:33 INFO - PROCESS | 1983 | [#0] dojo.html Cycles:165 Average:5601.24 Median:5763.83 stddev:645.60 (11.2%) stddev-sans-first:618.40 05:06:33 INFO - PROCESS | 1983 | Values: 3154.8 3162.5 3218.3 3273.0 3274.3 5445.9 5478.0 5501.0 5550.3 5600.0 5535.9 5536.9 5592.4 5600.9 5627.0 5056.7 5069.8 5073.4 5089.8 5095.0 5116.8 5208.0 5216.0 5222.8 5247.2 5150.7 5166.8 5195.3 5202.9 5222.1 5002.3 5027.6 5029.3 5051.4 5100.1 5054.3 5059.5 5085.2 5132.6 5149.8 4806.6 5284.2 5554.1 5561.7 5597.7 5893.2 5932.6 6077.7 6108.1 6110.2 5948.7 6091.7 6091.8 6116.3 6120.5 5560.2 5596.3 5607.2 5608.6 5658.3 6135.5 6209.8 6217.1 6218.9 6244.6 6021.2 6034.2 6041.4 6048.2 6054.7 6029.9 6040.5 6065.8 6085.2 6169.1 6027.8 6115.4 6122.9 6128.8 6134.4 6076.6 6086.6 6091.9 6095.1 6102.4 4727.9 4774.8 4805.5 4814.5 4827.7 4254.6 4311.9 4317.1 4323.8 4340.9 5717.1 5733.3 5735.3 5745.4 5752.6 6111.0 6122.3 6131.0 6136.9 6141.0 5642.0 5720.9 5721.3 5722.1 5727.6 5218.2 5232.6 5253.2 5261.2 5266.7 6283.7 6301.0 6303.4 6310.8 6359.4 6153.6 6168.2 6175.6 6189.3 6197.3 4977.1 5165.9 6273.5 6273.9 6288.1 5983.5 5988.5 5996.0 5998.8 6008.0 6079.5 6088.2 6093.8 6113.7 6122.4 4683.2 6007.0 6007.3 6016.3 6044.3 5795.2 5925.1 5941.5 5942.5 5955.0 5607.8 5665.9 5677.3 5784.4 5787.5 5838.7 5858.6 5903.3 5942.1 5995.7 5754.7 5763.8 5769.2 5774.7 5779.9 05:06:33 INFO - PROCESS | 1983 | 05:06:33 INFO - PROCESS | 1983 | [#1] ext.html Cycles:180 Average:65370.15 Median:38655.42 stddev:139117.01 (359.9%) stddev-sans-first:139421.97 05:06:33 INFO - PROCESS | 1983 | Values: 483.7 491.9 494.8 497.1 523.8 28414.9 38923.2 38935.0 39367.0 39405.8 39817.0 39949.4 40518.4 40983.4 41056.4 8260.8 8451.9 9062.0 9269.7 9283.7 10927.5 11201.3 11223.2 11368.7 11371.2 9332.5 11021.4 11026.2 11049.2 11070.8 8320.6 8508.3 11091.3 11103.9 11209.7 9633.7 10414.6 11040.4 11169.3 11236.7 39588.2 39866.0 39883.8 39932.8 39994.2 4008.2 4012.4 4015.2 4035.0 4036.7 24862.0 29078.4 30024.3 30079.4 30361.5 3087.0 3188.3 3249.4 3258.7 3294.5 355373.0 356432.4 357842.2 358760.2 477196.0 59039.4 59327.7 59414.0 59430.4 59458.4 61363.7 61571.7 61583.7 61589.4 61610.4 15337.4 15508.7 15525.8 15526.4 15526.7 11129.7 11383.4 11389.0 12347.4 12411.8 18355.8 20225.4 20364.8 21075.7 21124.0 8398.5 8411.7 8464.9 8496.4 8557.4 719.9 818.4 842.7 846.3 881.1 23942.9 29756.9 29948.4 30532.8 30700.8 1061.6 1071.0 1073.2 1077.7 1203.8 14989.4 15210.8 15591.6 15592.7 15666.0 643700.8 648376.0 650614.0 650916.0 651434.0 58768.0 59503.7 59619.7 59670.7 59716.0 1366.3 1418.9 1423.3 1424.0 1433.5 25786.7 29157.7 29476.0 29682.3 30040.5 48215.0 48337.8 48536.5 48546.5 48717.8 494098.0 494920.0 495547.0 496404.0 498593.0 40814.0 41955.4 42441.8 42562.6 42694.8 45692.8 46181.5 46337.0 46466.1 46517.6 38032.4 38304.8 38927.4 39225.8 39335.6 37366.0 37613.8 38245.0 39029.8 39042.8 37728.2 37777.8 38348.4 38349.0 38387.6 29062.6 32187.5 32542.7 33748.3 33966.3 24300.6 24303.0 24561.4 25332.6 25352.0 05:06:33 INFO - PROCESS | 1983 | 05:06:33 INFO - PROCESS | 1983 | [#2] jquery.html Cycles:180 Average:42369.93 Median:15888.96 stddev:122867.08 (773.3%) stddev-sans-first:123171.27 05:06:33 INFO - PROCESS | 1983 | Values: 364.4 382.8 387.4 390.9 416.6 15006.8 15043.5 17230.3 17611.9 21411.4 21808.8 22014.6 22762.0 22821.0 22882.2 6524.6 6670.0 6693.0 6770.3 6924.9 6463.2 6622.9 6730.1 6735.8 6736.4 6583.5 6688.9 6695.8 6706.3 6719.9 6407.3 6588.6 6668.4 6687.1 6689.3 6474.1 6509.5 6511.4 6527.0 6527.3 28517.7 28891.8 29347.3 29607.6 29735.4 2833.9 2871.9 2886.1 2899.7 2909.7 16698.4 16875.8 16962.9 17420.7 17505.4 1945.8 1986.5 2001.7 2015.0 2024.8 138327.0 156991.0 159902.0 161846.0 163341.0 22006.6 22649.0 22665.5 22765.5 22831.1 39649.6 39943.8 40043.4 40124.8 40196.8 8051.3 8245.5 8595.4 8608.0 8697.1 8278.3 8295.6 8320.3 8410.2 8440.3 4728.5 4733.9 4741.2 4743.9 4816.1 1677.5 1847.5 1853.4 1988.9 2013.8 995.8 1001.1 1002.4 1014.1 1045.9 21550.1 22201.3 22243.3 22312.0 22605.7 882.4 949.8 1020.7 1026.3 1048.3 9106.0 10747.8 11384.2 11548.4 11614.4 701150.0 732714.0 736166.0 749119.0 758321.0 140185.0 143710.8 144854.3 148053.0 160661.0 959.0 993.9 1033.5 1039.9 1043.5 19520.4 19907.7 19916.6 20014.7 20045.6 55471.7 60907.0 61198.0 61325.7 61622.4 85658.6 86092.6 87400.6 88357.0 89434.0 8110.0 8172.5 8389.6 8470.5 8716.5 9258.5 12638.6 12684.0 12794.4 13101.0 9805.7 10141.3 11020.4 11221.6 11313.5 10834.3 11101.9 11272.3 11325.3 11389.2 10887.3 11140.0 11366.9 11579.6 11891.4 7161.6 7425.8 7523.3 7787.8 8418.6 10052.0 10906.3 10966.9 11379.8 11464.0 05:06:33 INFO - PROCESS | 1983 | 05:06:33 INFO - PROCESS | 1983 | [#3] mootools.html Cycles:180 Average:5419.88 Median:5200.85 stddev:2998.24 (57.6%) stddev-sans-first:2991.57 05:06:33 INFO - PROCESS | 1983 | Values: 1419.3 1441.8 1447.4 1458.6 1464.2 8174.6 8307.0 8532.0 8569.4 8572.8 7565.3 7816.2 7827.2 8138.4 8456.3 2268.7 2315.2 2319.4 2322.1 2323.8 2312.0 2338.5 2340.5 2349.1 2351.6 2295.6 2298.1 2322.1 2324.5 2330.8 2298.9 2299.7 2309.5 2316.7 2318.3 2305.3 2324.6 2331.9 2332.2 2332.2 8325.7 8353.5 8419.5 8440.7 8463.1 5046.2 5199.2 5202.5 5204.6 5214.2 2921.7 2985.4 3248.9 3256.3 3527.2 1190.2 1203.5 1225.8 1251.2 1254.2 10932.6 10945.9 11056.9 11060.6 11157.5 4396.5 4627.7 4690.1 4699.7 4720.8 9554.3 9560.0 9571.1 9594.9 9595.4 5155.3 5206.8 5249.3 5256.6 5269.0 3519.8 4200.3 4723.1 4801.2 4823.4 3191.3 3746.3 3756.2 3778.9 3823.4 1270.0 1270.5 1272.6 1276.2 1280.8 758.8 837.3 886.7 893.2 918.2 5324.3 5407.5 5658.8 5764.1 5786.5 969.7 979.4 985.4 992.5 1024.8 3589.6 3708.9 3743.6 3822.7 3875.1 9993.2 10233.8 10337.4 10343.3 10382.3 9604.0 9989.6 10002.8 10245.9 10283.0 6279.7 7867.4 7867.4 7887.8 7893.8 5519.3 5554.7 5582.8 5714.9 5766.3 7826.1 9060.9 9158.5 9426.4 9431.3 9013.4 9437.1 9511.6 9628.8 9659.5 6141.7 6470.9 6934.0 7235.2 7437.6 7727.8 7879.7 8044.3 8114.1 8124.7 7136.4 7242.3 7263.0 7323.2 7405.4 7077.9 7080.3 7134.6 7156.5 7265.7 5675.6 6250.6 6376.8 6385.0 6395.6 3632.0 4552.6 4834.7 4869.0 4997.4 4717.9 4755.1 4765.9 4781.0 4824.3 05:06:33 INFO - PROCESS | 1983 | 05:06:33 INFO - PROCESS | 1983 | [#4] prototype.html Cycles:180 Average:4404.00 Median:4943.41 stddev:1322.43 (26.8%) stddev-sans-first:1289.96 05:06:33 INFO - PROCESS | 1983 | Values: 310.7 349.3 359.5 363.8 371.9 5155.1 5237.9 5241.3 5250.4 5279.0 3826.4 5159.5 5194.3 5216.5 5237.0 3433.9 3864.6 3986.9 4008.9 4020.9 3503.6 3558.0 3571.0 4049.8 4078.5 3467.7 3924.3 4046.6 4051.3 4062.7 3503.4 3724.7 3968.9 4006.4 4061.9 3514.3 3516.4 3578.5 3598.9 3634.3 4280.7 5064.2 5091.8 5104.5 5144.6 4287.9 4509.4 4528.2 4601.7 4654.2 4590.9 4916.8 5148.5 5379.5 5510.3 3503.8 3943.2 3962.1 3990.4 4001.0 4597.5 4690.9 5653.9 5714.5 5826.6 3463.8 3671.9 3933.0 4043.0 4066.8 4027.9 4120.6 4121.9 4151.7 4155.5 4522.9 4587.9 4660.9 4689.8 4700.1 5510.3 5535.7 5550.8 5589.9 5599.0 1232.4 1232.8 1271.1 1299.9 1330.8 821.2 858.3 923.5 942.0 945.6 4816.0 4928.7 5033.7 5048.4 5079.8 4641.3 4818.7 5041.0 5307.7 5347.7 4538.0 4580.6 4636.8 5005.0 5014.4 2364.8 2530.6 2795.7 2816.0 2818.5 4958.1 5272.0 5317.8 5508.9 5628.8 4622.7 5212.9 5401.8 5417.4 5476.7 4973.4 5054.7 5328.9 5727.5 5767.4 4399.5 4742.6 4906.8 5380.5 5427.0 4479.5 5197.6 5342.5 5578.7 5581.9 5141.7 5308.7 5524.9 5857.4 5882.4 3105.9 3718.7 3773.7 3777.2 3818.9 5612.7 5614.8 5636.6 5647.5 5657.3 5292.1 5430.1 5497.8 5500.6 5524.9 5471.0 5476.0 5478.3 5505.8 5515.9 5002.8 5397.9 5426.2 5435.6 5441.5 4652.0 4832.8 5256.5 5475.5 5549.2 4347.9 5397.5 5431.6 5435.4 5450.9 05:06:33 INFO - PROCESS | 1983 | 05:06:33 INFO - PROCESS | 1983 | [#5] yui.html Cycles:180 Average:20108.45 Median:4380.15 stddev:28755.25 (656.5%) stddev-sans-first:28798.95 05:06:33 INFO - PROCESS | 1983 | Values: 690.9 709.8 751.3 793.4 798.0 14899.6 15801.3 15830.4 15833.9 15841.3 12771.4 12964.0 14300.9 14304.1 14311.4 673.2 687.8 833.3 844.8 857.4 858.1 862.8 864.5 867.1 870.1 916.4 917.6 918.3 919.3 920.3 708.2 912.4 913.1 913.6 914.2 535.7 542.9 543.6 544.3 659.5 14860.7 15304.0 15318.9 15332.0 15345.7 13578.9 13730.6 13739.2 13822.0 13868.6 12493.8 12661.6 12703.9 12771.8 12919.0 4277.7 4380.0 4383.4 4437.3 4556.1 102009.0 103490.0 106855.0 106964.0 108464.0 9073.6 10664.9 10742.2 10924.6 11263.8 20231.2 21611.4 21630.7 21635.8 21659.6 7825.0 7844.9 7906.6 8043.2 8095.6 4251.9 4304.9 4358.4 4380.3 4392.5 3584.6 3658.2 3685.9 3692.8 3704.4 596.0 623.4 639.6 650.7 659.3 653.8 662.3 669.3 675.4 693.9 7677.7 8868.3 9193.4 9330.2 9715.9 561.3 581.0 585.3 585.7 589.0 4435.3 4531.3 4543.0 4599.0 4647.8 101453.0 102732.5 104437.0 106325.5 107186.5 79828.6 80707.6 83186.8 83321.9 86659.0 69355.3 69745.7 69890.0 74853.0 78882.8 9442.2 9783.0 9845.7 9850.9 10024.9 42807.4 45855.0 47780.0 50386.7 51211.7 66806.0 66880.0 67924.3 68858.7 69728.7 11878.0 12179.4 12325.8 12329.6 12519.4 14441.3 14643.6 14725.1 14736.7 14786.0 13598.2 13603.3 13625.0 13797.9 13895.6 13360.3 13701.5 13851.0 13981.3 14161.2 13234.3 13396.1 13422.3 13520.6 13616.0 10765.5 11048.9 11175.9 11411.5 11418.7 10074.9 10829.3 10838.7 10863.6 11013.6 05:06:33 INFO - PROCESS | 1983 | -------- Summary: end -------- 05:06:33 INFO - PROCESS | 1983 | 05:06:34 INFO - TEST-INFO | 1983: exit 0 05:06:34 INFO - TEST-OK | dromaeo_css | took 1119678ms 05:06:34 INFO - TEST-START | kraken 05:06:34 INFO - operating with platform_type : mac_ 05:06:34 INFO - Initialising browser for kraken test... 05:06:34 INFO - TEST-INFO | started process 1989 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpBR4SCE/profile http://localhost:49304/getInfo.html) 05:06:34 INFO - PROCESS | 1989 | 1483621594818 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:263 < _startProvider()@resource://gre/modules/AddonManager.jsm:758 < startup()@resource://gre/modules/AddonManager.jsm:940 < startup()@resource://gre/modules/AddonManager.jsm:2913 < observe()@resource://gre/components/addonManager.js:65 05:06:36 INFO - PROCESS | 1989 | __metrics Screen width/height:1600/1200 05:06:36 INFO - PROCESS | 1989 | colorDepth:24 05:06:36 INFO - PROCESS | 1989 | Browser inner width/height: 1024/689 05:06:36 INFO - PROCESS | 1989 | __metrics 05:06:36 INFO - PROCESS | 1989 | 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" 05:06:36 INFO - PROCESS | 1989 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:37 INFO - TEST-INFO | 1989: exit 0 05:06:37 INFO - Browser initialized. 05:06:37 INFO - Running cycle 1/1 for kraken test... 05:06:37 INFO - TEST-INFO | started process 1991 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpBR4SCE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:49 INFO - PROCESS | 1991 | RSS: Main: 225828864 05:06:49 INFO - PROCESS | 1991 | 05:06:52 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49304/tests/kraken/driver.html?testName=audio-beat-detection) 05:06:53 INFO - PROCESS | 1991 | RSS: Main: 285970432 05:06:53 INFO - PROCESS | 1991 | 05:06:55 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49304/tests/kraken/driver.html?testName=audio-dft) 05:06:56 INFO - PROCESS | 1991 | RSS: Main: 288555008 05:06:56 INFO - PROCESS | 1991 | 05:06:59 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49304/tests/kraken/driver.html?testName=audio-fft) 05:07:00 INFO - PROCESS | 1991 | RSS: Main: 378642432 05:07:00 INFO - PROCESS | 1991 | 05:07:02 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49304/tests/kraken/driver.html?testName=audio-oscillator) 05:07:03 INFO - PROCESS | 1991 | RSS: Main: 283652096 05:07:03 INFO - PROCESS | 1991 | 05:07:05 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49304/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:07:05 INFO - PROCESS | 1991 | RSS: Main: 286019584 05:07:05 INFO - PROCESS | 1991 | 05:07:08 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49304/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:09 INFO - PROCESS | 1991 | RSS: Main: 327680000 05:07:09 INFO - PROCESS | 1991 | 05:07:12 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49304/tests/kraken/driver.html?testName=imaging-desaturate) 05:07:12 INFO - PROCESS | 1991 | RSS: Main: 330477568 05:07:12 INFO - PROCESS | 1991 | 05:07:15 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49304/tests/kraken/driver.html?testName=json-parse-financial) 05:07:16 INFO - PROCESS | 1991 | RSS: Main: 324939776 05:07:16 INFO - PROCESS | 1991 | 05:07:17 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49304/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:07:18 INFO - PROCESS | 1991 | RSS: Main: 302505984 05:07:18 INFO - PROCESS | 1991 | 05:07:20 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49304/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:07:20 INFO - PROCESS | 1991 | RSS: Main: 304410624 05:07:20 INFO - PROCESS | 1991 | 05:07:23 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49304/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:07:23 INFO - PROCESS | 1991 | RSS: Main: 306966528 05:07:23 INFO - PROCESS | 1991 | 05:07:26 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49304/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:07:27 INFO - PROCESS | 1991 | RSS: Main: 325570560 05:07:27 INFO - PROCESS | 1991 | 05:07:30 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49304/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:07:30 INFO - PROCESS | 1991 | RSS: Main: 319594496 05:07:30 INFO - PROCESS | 1991 | 05:07:32 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49304/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49304/tests/kraken/driver.html?testName=ai-astar) 05:07:33 INFO - PROCESS | 1991 | RSS: Main: 364109824 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | __start_tp_report 05:07:33 INFO - PROCESS | 1991 | _x_x_mozilla_page_load 05:07:33 INFO - PROCESS | 1991 | _x_x_mozilla_page_load_details 05:07:33 INFO - PROCESS | 1991 | |i|pagename|runs| 05:07:33 INFO - PROCESS | 1991 | |0;ai-astar;110;112;113;117;111;113;112;113;113;111 05:07:33 INFO - PROCESS | 1991 | |1;audio-beat-detection;141;109;110;148;110;108;110;109;129;108 05:07:33 INFO - PROCESS | 1991 | |2;audio-dft;184;199;266;196;185;182;184;182;183;185 05:07:33 INFO - PROCESS | 1991 | |3;audio-fft;87;69;84;115;62;59;59;58;74;59 05:07:33 INFO - PROCESS | 1991 | |4;audio-oscillator;86;70;87;69;68;69;83;68;68;85 05:07:33 INFO - PROCESS | 1991 | |5;imaging-gaussian-blur;90;88;91;89;96;131;92;89;92;87 05:07:33 INFO - PROCESS | 1991 | |6;imaging-darkroom;96;94;95;95;95;94;95;95;94;95 05:07:33 INFO - PROCESS | 1991 | |7;imaging-desaturate;88;85;85;85;85;86;85;86;85;85 05:07:33 INFO - PROCESS | 1991 | |8;json-parse-financial;57;55;55;54;55;55;56;55;56;57 05:07:33 INFO - PROCESS | 1991 | |9;json-stringify-tinderbox;65;74;76;75;74;75;72;71;71;72 05:07:33 INFO - PROCESS | 1991 | |10;stanford-crypto-aes;86;87;86;87;85;87;81;75;100;98 05:07:33 INFO - PROCESS | 1991 | |11;stanford-crypto-ccm;147;155;152;150;153;147;136;145;166;171 05:07:33 INFO - PROCESS | 1991 | |12;stanford-crypto-pbkdf2;170;172;171;168;170;170;171;170;173;171 05:07:33 INFO - PROCESS | 1991 | |13;stanford-crypto-sha256-iterative;68;68;70;79;85;87;85;69;67;66 05:07:33 INFO - PROCESS | 1991 | __end_tp_report 05:07:33 INFO - PROCESS | 1991 | __start_cc_report 05:07:33 INFO - PROCESS | 1991 | _x_x_mozilla_cycle_collect,2026 05:07:33 INFO - PROCESS | 1991 | __end_cc_report 05:07:33 INFO - PROCESS | 1991 | __startTimestamp1483621653023__endTimestamp 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | ------- Summary: start ------- 05:07:33 INFO - PROCESS | 1991 | Number of tests: 14 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:1.90 (1.7%) stddev-sans-first:1.79 05:07:33 INFO - PROCESS | 1991 | Values: 110.0 112.0 113.0 117.0 111.0 113.0 112.0 113.0 113.0 111.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#1] audio-beat-detection Cycles:10 Average:118.20 Median:110.00 stddev:15.29 (13.9%) stddev-sans-first:13.81 05:07:33 INFO - PROCESS | 1991 | Values: 141.0 109.0 110.0 148.0 110.0 108.0 110.0 109.0 129.0 108.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#2] audio-dft Cycles:10 Average:194.60 Median:185.00 stddev:25.78 (13.9%) stddev-sans-first:27.05 05:07:33 INFO - PROCESS | 1991 | Values: 184.0 199.0 266.0 196.0 185.0 182.0 184.0 182.0 183.0 185.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#3] audio-fft Cycles:10 Average:72.60 Median:65.50 stddev:18.35 (28.0%) stddev-sans-first:18.71 05:07:33 INFO - PROCESS | 1991 | Values: 87.0 69.0 84.0 115.0 62.0 59.0 59.0 58.0 74.0 59.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:76.50 stddev:8.64 (11.3%) stddev-sans-first:8.25 05:07:33 INFO - PROCESS | 1991 | Values: 86.0 70.0 87.0 69.0 68.0 69.0 83.0 68.0 68.0 85.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:90.50 stddev:13.07 (14.4%) stddev-sans-first:13.77 05:07:33 INFO - PROCESS | 1991 | Values: 90.0 88.0 91.0 89.0 96.0 131.0 92.0 89.0 92.0 87.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 05:07:33 INFO - PROCESS | 1991 | Values: 96.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:85.00 stddev:0.97 (1.1%) stddev-sans-first:0.44 05:07:33 INFO - PROCESS | 1991 | Values: 88.0 85.0 85.0 85.0 85.0 86.0 85.0 86.0 85.0 85.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.50 stddev:0.97 (1.8%) stddev-sans-first:0.87 05:07:33 INFO - PROCESS | 1991 | Values: 57.0 55.0 55.0 54.0 55.0 55.0 56.0 55.0 56.0 57.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:74.00 stddev:3.17 (4.3%) stddev-sans-first:1.87 05:07:33 INFO - PROCESS | 1991 | Values: 65.0 74.0 76.0 75.0 74.0 75.0 72.0 71.0 71.0 72.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:86.50 stddev:7.27 (8.4%) stddev-sans-first:7.70 05:07:33 INFO - PROCESS | 1991 | Values: 86.0 87.0 86.0 87.0 85.0 87.0 81.0 75.0 100.0 98.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:152.50 stddev:10.14 (6.6%) stddev-sans-first:10.58 05:07:33 INFO - PROCESS | 1991 | Values: 147.0 155.0 152.0 150.0 153.0 147.0 136.0 145.0 166.0 171.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:171.00 stddev:1.35 (0.8%) stddev-sans-first:1.41 05:07:33 INFO - PROCESS | 1991 | Values: 170.0 172.0 171.0 168.0 170.0 170.0 171.0 170.0 173.0 171.0 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - PROCESS | 1991 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:74.50 stddev:8.57 (11.5%) stddev-sans-first:8.77 05:07:33 INFO - PROCESS | 1991 | Values: 68.0 68.0 70.0 79.0 85.0 87.0 85.0 69.0 67.0 66.0 05:07:33 INFO - PROCESS | 1991 | -------- Summary: end -------- 05:07:33 INFO - PROCESS | 1991 | 05:07:33 INFO - TEST-INFO | 1991: exit 0 05:07:33 INFO - TEST-OK | kraken | took 59629ms 05:07:33 INFO - SUITE-END | took 1179s 05:07:34 INFO - Completed test suite (00:19:39) 05:07:34 INFO - javascript benchmark 05:07:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3154.8264845433505, 3162.4623813951625, 3218.340240250748, 3272.967270327297, 3274.2632907595807, 5445.863853403667, 5477.972610136953, 5500.972495137524, 5550.2507161533185, 5599.972000140004, 5535.889282214353, 5536.916946245809, 5592.356878958921, 5600.915986260199, 5627.043402621564, 5056.747162641853, 5069.847904562869, 5073.365829271341, 5089.847304580869, 5095.0, 5116.820911268106, 5208.0, 5216.0, 5222.7649755760895, 5247.160454327309, 5150.665206761549, 5166.844994650167, 5195.272661827347, 5202.947970520284, 5222.138347172731, 5002.299678045077, 5027.5977921766535, 5029.321041659366, 5051.44434112251, 5100.056489549445, 5054.3176671149295, 5059.519345662156, 5085.186370180791, 5132.615053870974, 5149.794008239666, 4806.582058292777, 5284.180951952415, 5554.1113421852715, 5561.721913904329, 5597.6641401516035, 5893.174955506232, 5932.644041357532, 6077.726502307407, 6108.144859719643, 6110.175126357929, 5948.7323070461935, 6091.664958427285, 6091.847703807396, 6116.327204007555, 6120.540959428061, 5560.193771903092, 5596.300462442236, 5607.158926161068, 5608.607397482218, 5658.292713410824, 6135.5398345124295, 6209.751609935643, 6217.129601855744, 6218.9378106219265, 6244.594101383395, 6021.217241758631, 6034.2155519782145, 6041.426064523863, 6048.153258543807, 6054.727537260834, 6029.909551356734, 6040.516758659297, 6065.787697430569, 6085.17850090241, 6169.136320915076, 6027.758889644365, 6115.357887421846, 6122.938770612326, 6128.754849806047, 6134.4172303631085, 6076.574639775174, 6086.573939824169, 6091.908621370683, 6095.085737139376, 6102.420270074336, 4727.929081063787, 4774.832880849153, 4805.519448055236, 4814.494478079821, 4827.662063655511, 4254.574542545721, 4311.922019495126, 4317.093410383792, 4323.805428755651, 4340.934885976713, 5717.085266357363, 5733.283339582552, 5735.283089613798, 5745.368009518949, 5752.568557358215, 6110.969445152746, 6122.326544080148, 6131.019036954066, 6136.8772624547255, 6140.969295153495, 5642.039822061063, 5720.856978575569, 5721.284839395075, 5722.055860782924, 5727.570432217517, 5218.191180367049, 5232.555232805178, 5253.159494480865, 5261.184516399964, 5266.710330931835, 6283.6858157091965, 6301.0, 6303.432691057918, 6310.842228944313, 6359.395857393541, 6153.630782153085, 6168.1672974149715, 6175.5677102602385, 6189.319174890848, 6197.349278325711, 4977.054359671795, 5165.9483405165465, 6273.466755325666, 6273.9372606273355, 6288.119663247057, 5983.461488465885, 5988.490978266722, 5996.0, 5998.82003539877, 6008.008678567988, 6079.513638908788, 6088.178095956989, 6093.75624974996, 6113.724882380393, 6122.387761223842, 4683.227267500961, 6007.038873780174, 6007.279126504847, 6016.338202797776, 6044.33512313654, 5795.188673585617, 5925.111233314865, 5941.524678025834, 5942.494887934401, 5954.970225148846, 5607.831765047055, 5665.915011274917, 5677.261955945701, 5784.363719990882, 5787.536997040311, 5838.678872662044, 5858.648481091148, 5903.291605007426, 5942.078977758454, 5995.730192141277, 5754.741036653444, 5763.827085187451, 5769.221155144001, 5774.653520788598, 5779.884402312015], "value": 5559.313036037578, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.7025229483868, 491.8942427378114, 494.792187281342, 497.1275411652549, 523.7952708769828, 28414.857925710367, 38923.221535569275, 38935.0, 39367.0, 39405.80297098514, 39817.0, 39949.40075898864, 40518.392224116586, 40983.38524922128, 41056.38415423763, 8260.752177434657, 8451.915480845175, 9062.048484909088, 9269.721908342726, 9283.72148835536, 10927.453627318642, 11201.271917325348, 11223.158263130257, 11368.658940231806, 11371.204015718902, 9332.53337333134, 11021.44892755359, 11026.173037022216, 11049.17131215158, 11070.833937490945, 8320.583970801435, 8508.276796472246, 11091.33451992879, 11103.944480277627, 11209.663710088711, 9633.662821801274, 10414.646096007471, 11040.447977601169, 11169.329840209532, 11236.66290011301, 39588.22882398205, 39866.0, 39883.8005809972, 39932.80201593957, 39994.20011599781, 4008.178323443687, 4012.4181993611055, 4015.196960607861, 4034.9798251008556, 4036.6880763751897, 24862.0, 29078.408774788262, 30024.348660823645, 30079.398412031856, 30361.544576831366, 3087.0, 3188.2985743136474, 3249.431349513826, 3258.7393008559256, 3294.5387645729616, 355373.0, 356432.43567564513, 357842.210788947, 358760.20619897, 477196.0, 59039.40960590339, 59327.70336148292, 59414.0, 59430.40569594335, 59458.40541594615, 61363.6931815338, 61571.692141539, 61583.6920815393, 61589.38410615926, 61610.38389616046, 15337.386504539916, 15508.689826203412, 15525.844741552666, 15526.369731178063, 15526.689466210613, 11129.666110016713, 11383.430828458544, 11389.031932285683, 12347.3826308686, 12411.75176496483, 18355.75345676119, 20225.393238202876, 20364.796352036585, 21075.68386474204, 21124.0, 8398.496090234605, 8411.747647570583, 8464.873026904601, 8496.405251632326, 8557.421779348144, 719.9280071992864, 818.423011776696, 842.7261140129483, 846.2975730143966, 881.0792721605937, 23942.88028559846, 29756.851215743784, 29948.40103197924, 30532.847335763625, 30700.84649576738, 1061.6284300494765, 1070.978580428387, 1073.2487258918788, 1077.7144056824948, 1203.7532305877273, 14989.40042398292, 15210.783137349206, 15591.610209744847, 15592.688146237011, 15666.0, 643700.7814960895, 648376.0, 650614.0, 650916.0, 651434.0, 58768.0, 59503.70248148818, 59619.701901491084, 59670.70164649149, 59716.014199289864, 1366.269046060353, 1418.858114188573, 1423.252792284059, 1424.0103128325713, 1433.5269361110809, 25786.742132578434, 29157.70842291635, 29476.0, 29682.25794355072, 30040.549391758705, 48215.0, 48337.75831120822, 48536.5146348532, 48546.5145348542, 48717.7820554489, 494098.0, 494920.0, 495547.0, 496404.0, 498593.0, 40813.97965050779, 41955.37066944059, 42441.78779106208, 42562.5743742571, 42694.78652606717, 45692.77153614211, 46181.538184617726, 46337.0, 46466.07067858691, 46517.604471865896, 38032.42951355787, 38304.80847595744, 38927.416088758124, 39225.80387098046, 39335.60664393319, 37366.0, 37613.811930941265, 38245.0, 39029.80485097556, 39042.80478597589, 37728.245435091696, 37777.81111094427, 38348.424773628976, 38349.0, 38387.6161238384, 29062.564061539517, 32187.51718724268, 32542.698292068053, 33748.325033499685, 33966.32067358688, 24300.635490467303, 24303.0, 24561.385965351008, 25332.620010699484, 25352.0], "value": 20041.609678410838, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.39328518017504, 382.83155411618884, 387.4324115171274, 390.9218156368726, 416.6083881151717, 15006.849931500696, 15043.548693539198, 17230.310787568513, 17611.911940440314, 21411.35765927021, 21808.781912180875, 22014.569053011543, 22762.0, 22821.0, 22882.199123030696, 6524.641144737037, 6669.9666501667425, 6693.0, 6770.255271920109, 6924.896126558106, 6463.192100987377, 6622.9006564901565, 6730.0577919091365, 6735.797926062226, 6736.427403670694, 6583.539152259344, 6688.93311066888, 6695.799126026227, 6706.295838936914, 6719.932800672004, 6407.263164736069, 6588.571742836701, 6668.433183179435, 6687.063811066479, 6689.33106689332, 6474.125992990933, 6509.511786616023, 6511.38141876521, 6527.0, 6527.281998980156, 28517.71482285192, 28891.844326226925, 29347.266318341997, 29607.555886661718, 29735.405291894258, 2833.8724757385967, 2871.8851245950136, 2886.07645553423, 2899.6752164808886, 2909.689816085652, 16698.415555455624, 16875.83124168755, 16962.915185424117, 17420.65158696819, 17505.387311444167, 1945.8346040586566, 1986.4636548132069, 2001.6897380905982, 2014.98992505038, 2024.8278896293832, 138327.0, 156991.0, 159902.0, 161846.0, 163341.0, 22006.559868802855, 22649.0, 22665.546689066126, 22765.544689106457, 22831.086756529887, 39649.603503964594, 39943.80028099841, 40043.399349009786, 40124.799376002935, 40196.76950072685, 8051.275385215359, 8245.51580715477, 8595.3553483489, 8607.956960215159, 8697.130286971378, 8278.254957053894, 8295.585220738938, 8320.292775114061, 8410.158984101541, 8440.324774018063, 4728.527147285244, 4733.887536428936, 4741.170295198326, 4743.857684269477, 4816.133096042746, 1677.5051359848874, 1847.5196448923377, 1853.3698542495615, 1988.9303874364327, 2013.7986201379745, 995.8157740817971, 1001.1490233301781, 1002.3835341265069, 1014.1390548343725, 1045.9110975567162, 21550.13799448036, 22201.333959981228, 22243.332700019026, 22312.0, 22605.660915086286, 882.4176043811054, 949.8480243161199, 1020.7397113736098, 1026.296986564206, 1048.3343077145914, 9105.954470227607, 10747.83878241827, 11384.20310578268, 11548.422578871023, 11614.419279036014, 701150.0, 732714.0, 736166.0, 749119.0, 758321.0, 140185.0, 143710.84433733503, 144854.27572862068, 148053.0, 160661.0, 958.9664361747306, 993.9155171810477, 1033.4574348467115, 1039.9012093851222, 1043.5356266461504, 19520.414387568395, 19907.701384478954, 19916.60166796685, 20014.69977950361, 20045.59908801845, 55471.722641386536, 60907.0, 61198.0, 61325.693371532856, 61622.38377616167, 85658.57170714106, 86092.56953715191, 87400.5629971846, 88357.0, 89434.0, 8110.0, 8172.468789528662, 8389.580520973805, 8470.49177049379, 8716.477011379338, 9258.53707314645, 12638.55765048219, 12684.0, 12794.36028198605, 13101.0, 9805.72525571671, 10141.340812847142, 11020.393878336768, 11221.55113795439, 11313.547458101913, 10834.349939003685, 11101.944490277498, 11272.323660580392, 11325.32048077118, 11389.202755807013, 10887.292325998786, 11140.0, 11366.943165284121, 11579.594714184963, 11891.405429728651, 7161.606111663908, 7425.8143546411775, 7523.3229009389415, 7787.844243114992, 8418.6211620476, 10052.0, 10906.291091079054, 10966.945165274123, 11379.772404552028, 11464.0], "value": 10797.206770759052, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.3131874953444, 1441.819772528434, 1447.413797412048, 1458.5551406820916, 1464.1507925403264, 8174.591270436476, 8307.0, 8532.018817835951, 8569.442986205908, 8572.785680357994, 7565.281298276669, 7816.17930117338, 7827.178146294643, 8138.430309878311, 8456.323494120472, 2268.659701044844, 2315.166540045582, 2319.4433335999406, 2322.105989194164, 2323.8024767894663, 2311.9537609247805, 2338.497223097034, 2340.520193360357, 2349.119080344871, 2351.623740201568, 2295.5523672883796, 2298.10373954158, 2322.1059891941554, 2324.5467133908896, 2330.8018818400456, 2298.9310320690406, 2299.655051742235, 2309.457277539777, 2316.7104111986, 2318.2929206591984, 2305.262316058862, 2324.639680849471, 2331.9067237310487, 2332.207049603143, 2332.207049603143, 8325.708600199025, 8353.498790072554, 8419.45273557223, 8440.704575339894, 8463.06906240313, 5046.21783623539, 5199.168133098724, 5202.531772140525, 5204.609654275944, 5214.191800270926, 2921.6640086390125, 2985.4327677741157, 3248.8538015789345, 3256.3161736035463, 3527.171114788023, 1190.1728298832315, 1203.4584437003384, 1225.7609766095616, 1251.2367455851922, 1254.1722463174353, 10932.617358392497, 10945.890541094566, 11056.944715276453, 11060.557577696964, 11157.497912593954, 4396.450443694538, 4627.67606267558, 4690.108879312919, 4699.718016918996, 4720.787564559603, 9554.331196816294, 9559.996200398997, 9571.138597526256, 9594.856077158849, 9595.376300540582, 5155.252488389237, 5206.817761378334, 5249.343832020997, 5256.553192978639, 5269.0252303324005, 3519.8240087995496, 4200.348945913388, 4723.126221649006, 4801.231802911588, 4823.397075365579, 3191.250056236829, 3746.306933217309, 3756.229972855558, 3778.886633401002, 3823.3882578787816, 1270.0474644016988, 1270.5362542671858, 1272.567327108769, 1276.1513593460431, 1280.8142819291152, 758.8406434648675, 837.3426859914938, 886.7029545102463, 893.1782759860944, 918.1782304837134, 5324.254604355393, 5407.459254074561, 5658.801941932013, 5764.106563482667, 5786.537077033911, 969.6606187834343, 979.4123525884412, 985.4481490365275, 992.4914130521711, 1024.774549599087, 3589.5513060867393, 3708.9258214835554, 3743.5694895087413, 3822.6750726188584, 3875.1087249932675, 9993.200543956465, 10233.795324093477, 10337.431441270803, 10343.27597068213, 10382.273240873066, 9604.0, 9989.550470228856, 10002.849957250646, 10245.897541024644, 10283.0, 6279.686015699197, 7867.370610351159, 7867.409944254204, 7887.763367098996, 7893.842123157505, 5519.254900588369, 5554.694491802989, 5582.7766889323975, 5714.942850571441, 5766.2503874497725, 7826.139124696165, 9060.864087038568, 9158.450492970444, 9426.434413934912, 9431.339806213498, 9013.414128081653, 9437.056294370508, 9511.619535218513, 9628.75928101775, 9659.46872921996, 6141.662208578571, 6470.8675981703955, 6933.965330173317, 7235.167955685163, 7437.553746775211, 7727.806804829925, 7879.684812607432, 8044.2760151586635, 8114.066882308374, 8124.675012999413, 7136.393406560501, 7242.311980361857, 7263.019492368674, 7323.231060738546, 7405.370543503863, 7077.929220707727, 7080.2919708028785, 7134.643267836691, 7156.499045066796, 7265.745698900513, 5675.637846916793, 6250.624962502265, 6376.840578985562, 6385.0, 6395.584287021329, 3632.0, 4552.635789136927, 4834.709917404967, 4868.95131048685, 4997.400311962588, 4717.929231061468, 4755.144074066701, 4765.928511072406, 4780.995990841962, 4824.34871292371], "value": 4401.961094746247, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.67999960041135, 349.2735110969184, 359.48234542259155, 363.8090002748557, 371.85311801838293, 5155.149400348942, 5237.86905327367, 5241.344831896004, 5250.448702886194, 5278.973605131969, 3826.387777955528, 5159.50984656458, 5194.324737784093, 5216.513293711284, 5237.0, 3433.8768969742623, 3864.632859878307, 3986.8604598839047, 4008.859689910854, 4020.899477513071, 3503.6321186275454, 3558.0, 3571.0, 4049.75701457912, 4078.490188726409, 3467.6705712957355, 3924.274009308287, 4046.5751096134923, 4051.250518654044, 4062.7359221650645, 3503.4394496880377, 3724.720645951551, 3968.920621587552, 4006.3758238353635, 4061.8578349757763, 3514.2604410816552, 3516.40221162403, 3578.4632305154173, 3598.8946466317234, 3634.309481198564, 4280.72175308604, 5064.215046667772, 5091.79632814687, 5104.4640312767, 5144.61415393847, 4287.871363859089, 4509.359888452695, 4528.18492671319, 4601.723896566184, 4654.162250794856, 4590.862274131781, 4916.803327866881, 5148.485151484859, 5379.4716483252805, 5510.338759348863, 3503.789772613651, 3943.1719338938856, 3962.0887195945093, 3990.4014397840265, 4000.959990400088, 4597.540245975376, 4690.929636055462, 5653.943460565424, 5714.514266287329, 5826.563007774392, 3463.8268086595567, 3671.8531258749886, 3932.980335098306, 4042.9797851011144, 4066.779966010244, 4027.8993025174605, 4120.62914337705, 4121.9381709274385, 4151.667866570667, 4155.542890282067, 4522.8643140705835, 4587.912499812577, 4660.86017419478, 4689.835855745033, 4700.106979673851, 5510.256115424367, 5535.723213839292, 5550.7779688812, 5589.888202235932, 5599.0, 1232.3530146673068, 1232.7904256276454, 1271.0848189303686, 1299.8950891741945, 1330.813686083949, 821.2485575698289, 858.2576071698061, 923.4505469245788, 941.9576119074521, 945.5933948402115, 4815.977289538402, 4928.7289199093675, 5033.748312584356, 5048.419431765321, 5079.771410286472, 4641.25739881624, 4818.747125747331, 5041.042201981685, 5307.65500242483, 5347.652402593819, 4537.977310113428, 4580.635908189478, 4636.814527418933, 5004.9690123401215, 5014.423341315795, 2364.817591204398, 2530.607755797854, 2795.7344052314997, 2816.0, 2818.478581462436, 4958.091134913012, 5272.0, 5317.787288508416, 5508.862278443071, 5628.774849006076, 4622.653301002439, 5212.9408883490805, 5401.839264294235, 5417.377001644862, 5476.67139971603, 4973.378327709036, 5054.717819878236, 5328.934213157307, 5727.541796656257, 5767.423257674115, 4399.538048504861, 4742.620590352694, 4906.779194936165, 5380.488853558905, 5427.0, 4479.484859241099, 5197.584193264454, 5342.519173274424, 5578.693171875586, 5581.860453488695, 5141.742912854417, 5308.734563271898, 5524.944750552443, 5857.355690873916, 5882.411758824083, 3105.90682279532, 3718.665320121094, 3773.716971227114, 3777.1690228149764, 3818.866339678098, 5612.747426365741, 5614.831555053355, 5636.632614302085, 5647.5199608032135, 5657.32112146545, 5292.147748288068, 5430.104032834538, 5497.8075767347955, 5500.614956952975, 5524.889502209853, 5471.0, 5475.972620136874, 5478.342598888159, 5505.788726480168, 5515.917261241005, 5002.798209146222, 5397.8920421590565, 5426.158945363475, 5435.565154787687, 5441.483059109379, 4651.976740116278, 4832.806687732451, 5256.4743525648655, 5475.5072043516275, 5549.195366671891, 4347.913041739211, 5397.514223719727, 5431.619786614899, 5435.396558015474, 5450.863728406822], "value": 3998.2346918487438, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.9274526174754, 709.8260926073112, 751.3200553499082, 793.3534169651733, 798.0307301385637, 14899.5530134096, 15801.288941997609, 15830.366785328588, 15833.92083039586, 15841.287142078605, 12771.361431928413, 12964.0, 14300.856991430082, 14304.141751494892, 14311.355988980495, 673.2089794491477, 687.8039758668781, 833.3166803221361, 844.7550210438981, 857.3698331726176, 858.0561382479285, 862.7929296968745, 864.5417928497906, 867.0852250875324, 870.1298701298701, 916.3860213656833, 917.6054296652437, 918.2562124678998, 919.2645883293372, 920.3143657974819, 708.2351060854265, 912.3932584831146, 913.1279627955287, 913.6117150211134, 914.1818072824818, 535.7428434351494, 542.8914217156585, 543.6194663735401, 544.2516539757833, 659.5020759326726, 14860.702785944328, 15304.0, 15318.923405382902, 15332.0, 15345.693086138215, 13578.932105339509, 13730.588082357544, 13739.24434156121, 13822.0, 13868.583942481742, 12493.812592811115, 12661.556845510455, 12703.872961270361, 12771.808422873572, 12919.031072669588, 4277.657787377002, 4379.978100109511, 4383.430154079949, 4437.267850804614, 4556.088782243532, 102009.0, 103489.99999999924, 106855.0, 106964.0, 108464.0, 9073.59168837404, 10664.89335106639, 10742.194335424718, 10924.617638382777, 11263.831042534368, 20231.19075236974, 21611.351659450243, 21630.675539866916, 21635.783642163376, 21659.566808663738, 7824.9608751955875, 7844.882326765103, 7906.604669766603, 8043.1554686757045, 8095.635696393676, 4251.851185208503, 4304.913901721948, 4358.413924568595, 4380.3210502372185, 4392.538783427758, 3584.6415358464465, 3658.1951970566433, 3685.94471082934, 3692.796896170683, 3704.351738445815, 595.965998991749, 623.4357906094991, 639.6322114784018, 650.6649075726004, 659.2813832922138, 653.8430776613578, 662.3244290823333, 669.3072669786748, 675.3921470676332, 693.8646963842103, 7677.692892284246, 8868.27068721593, 9193.448393096436, 9330.160285574331, 9715.854262186072, 561.2675458526662, 580.962237454564, 585.2917969257161, 585.6925114315019, 588.9869424589699, 4435.334699794992, 4531.320301954667, 4542.954570454253, 4599.0, 4647.790849411717, 101453.0, 102732.48633756784, 104437.0, 106325.46837265765, 107186.46406767916, 79828.6008569965, 80707.59646201732, 83186.75219871706, 83321.91695207669, 86659.0, 69355.30644693489, 69745.65127174433, 69889.9516507263, 74853.0, 78882.81675774753, 9442.15020648145, 9782.951085244527, 9845.704628861145, 9850.852237216304, 10024.949875250577, 42807.3578896323, 45855.0, 47780.0, 50386.74806625943, 51211.74394128005, 66806.0, 66880.0, 67924.3207567918, 68858.65570672315, 69728.65135674289, 11878.049756019325, 12179.391030448265, 12325.75348493043, 12329.568465103679, 12519.436625351698, 14441.277936103363, 14643.56069317922, 14725.116493010453, 14736.705265894409, 14786.0, 13598.184108953494, 13603.251821149932, 13625.0, 13797.931010344884, 13895.58313250604, 13360.265185414895, 13701.45194192261, 13851.0, 13981.300934953415, 14161.221132837793, 13234.338283086, 13396.062275640612, 13422.261775602436, 13520.59438216855, 13615.978801590118, 10765.515551800345, 11048.889511104786, 11175.888241117485, 11411.543538258376, 11418.714532136431, 10074.949625251827, 10829.282427645821, 10838.674839754505, 10863.619773307897, 11013.559457621606], "value": 7394.2187229464935, "unit": "score"}], "name": "dromaeo_css", "value": 7341.539961283758, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 113.0, 117.0, 111.0, 113.0, 112.0, 113.0, 113.0, 111.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 110.0, 148.0, 110.0, 108.0, 110.0, 109.0, 129.0, 108.0], "value": 118.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 199.0, 266.0, 196.0, 185.0, 182.0, 184.0, 182.0, 183.0, 185.0], "value": 194.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 84.0, 115.0, 62.0, 59.0, 59.0, 58.0, 74.0, 59.0], "value": 72.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 87.0, 69.0, 68.0, 69.0, 83.0, 68.0, 68.0, 85.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 91.0, 89.0, 96.0, 131.0, 92.0, 89.0, 92.0, 87.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 85.0, 85.0, 85.0, 86.0, 85.0, 86.0, 85.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 54.0, 55.0, 55.0, 56.0, 55.0, 56.0, 57.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 76.0, 75.0, 74.0, 75.0, 72.0, 71.0, 71.0, 72.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 87.0, 85.0, 87.0, 81.0, 75.0, 100.0, 98.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 155.0, 152.0, 150.0, 153.0, 147.0, 136.0, 145.0, 166.0, 171.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 171.0, 168.0, 170.0, 170.0, 171.0, 170.0, 173.0, 171.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 79.0, 85.0, 87.0, 85.0, 69.0, 67.0, 66.0], "value": 74.4, "unit": "score"}], "name": "kraken", "value": 1460.3999999999999, "alertThreshold": 2.0}]} 05:07:34 INFO - Return code: 0 05:07:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:07:34 INFO - # TBPL SUCCESS # 05:07:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:07:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:07:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:07:34 INFO - 2017-01-05 05:07:34.371 screenresolution[1995:280343] starting screenresolution argv=screenresolution get 05:07:34 INFO - 2017-01-05 05:07:34.374 screenresolution[1995:280343] Display 0: 1600x1200x32@60 05:07:34 INFO - 2017-01-05 05:07:34.380 screenresolution[1996:280345] starting screenresolution argv=screenresolution list 05:07:34 INFO - Available Modes on Display 0 05:07:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:07:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:07:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:07:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:07:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:07:34 INFO - Intel Iris: 05:07:34 INFO - Chipset Model: Intel Iris 05:07:34 INFO - Type: GPU 05:07:34 INFO - Bus: Built-In 05:07:34 INFO - VRAM (Dynamic, Max): 1536 MB 05:07:34 INFO - Vendor: Intel (0x8086) 05:07:34 INFO - Device ID: 0x0a2e 05:07:34 INFO - Revision ID: 0x0009 05:07:34 INFO - Displays: 05:07:34 INFO - Display: 05:07:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:07:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:07:34 INFO - Display Serial Number: 1600x1200 60 05:07:34 INFO - Main Display: Yes 05:07:34 INFO - Mirror: Off 05:07:34 INFO - Online: Yes 05:07:34 INFO - Rotation: Supported 05:07:34 INFO - Return code: 0 05:07:34 INFO - Running post-action listener: _resource_record_post_action 05:07:34 INFO - [mozharness: 2017-01-05 13:07:34.613747Z] Finished run-tests step (success) 05:07:34 INFO - Running post-run listener: _resource_record_post_run 05:07:34 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 19198464; Write bytes: 561060352; Read time: 239; Write time: 1800 05:07:34 INFO - TinderboxPrint: CPU usage
53.9% 05:07:34 INFO - TinderboxPrint: I/O read bytes / time
19,198,464 / 239 05:07:34 INFO - TinderboxPrint: I/O write bytes / time
561,060,352 / 1,800 05:07:34 INFO - TinderboxPrint: CPU idle
2,218.9 (46.1%) 05:07:34 INFO - TinderboxPrint: CPU system
968.4 (20.1%) 05:07:34 INFO - TinderboxPrint: CPU user
1,622.4 (33.7%) 05:07:34 INFO - TinderboxPrint: Swap in / out
270,843,904 / 0 05:07:34 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245625344; Write bytes: 238170112; Read time: 18067; Write time: 417 05:07:34 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 19153408; Write bytes: 309500416; Read time: 238; Write time: 1346 05:07:35 INFO - Running post-run listener: _upload_blobber_files 05:07:35 INFO - Blob upload gear active. 05:07:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:07:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:07:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:07:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:07:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:07:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:07:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:07:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:36 INFO - (blobuploader) - INFO - Done attempting. 05:07:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:07:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:07:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:37 INFO - (blobuploader) - INFO - Done attempting. 05:07:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:07:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:07:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:38 INFO - (blobuploader) - INFO - Done attempting. 05:07:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:07:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:07:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:40 INFO - (blobuploader) - INFO - Done attempting. 05:07:40 INFO - (blobuploader) - INFO - Iteration through files over. 05:07:40 INFO - Return code: 0 05:07:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:07:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:07:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/863a9a2fdb04d8b45fe9096cd154fcc0799df15ae9253299f34da76e12b651b698b3e85616357ecdd1a8f4e0843f781cb3c8510f613b53017b5cf7e9158a5de1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/144695ae84eebf186e387f3dfb18dc0da0b7aa14cf56ef54355bbf1ec6d309e9937229d3306363dbfec1d618f2b98133b289d61890d65ee403715e946a00726f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c91b1fbdc6eb0b7f7715cd0451aaa17a6cdd6ff6554e7e834f3d4f0628e4da10d4e06e2e6f2a30872c088c933375f7dd40229d6963a91ebc59b4fcc67c66f971", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ac33d4b16d589a911255eab4a24a6266d12427e34857f38e92355b6693842a2727ed76a7f2c7cc20d7f54ae6bee1cc7ea3a49e112d8e94733cc633748c69a89"} 05:07:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:07:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:07:40 INFO - Contents: 05:07:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/863a9a2fdb04d8b45fe9096cd154fcc0799df15ae9253299f34da76e12b651b698b3e85616357ecdd1a8f4e0843f781cb3c8510f613b53017b5cf7e9158a5de1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/144695ae84eebf186e387f3dfb18dc0da0b7aa14cf56ef54355bbf1ec6d309e9937229d3306363dbfec1d618f2b98133b289d61890d65ee403715e946a00726f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c91b1fbdc6eb0b7f7715cd0451aaa17a6cdd6ff6554e7e834f3d4f0628e4da10d4e06e2e6f2a30872c088c933375f7dd40229d6963a91ebc59b4fcc67c66f971", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ac33d4b16d589a911255eab4a24a6266d12427e34857f38e92355b6693842a2727ed76a7f2c7cc20d7f54ae6bee1cc7ea3a49e112d8e94733cc633748c69a89"} 05:07:40 INFO - Running post-run listener: copy_logs_to_upload_dir 05:07:40 INFO - Copying logs to upload dir... 05:07:40 INFO - mkdir: /builds/slave/test/build/upload/logs 05:07:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.090951 ========= master_lag: 2.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-05 05:07:42.498874) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 05:07:42.504745) ========= 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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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/863a9a2fdb04d8b45fe9096cd154fcc0799df15ae9253299f34da76e12b651b698b3e85616357ecdd1a8f4e0843f781cb3c8510f613b53017b5cf7e9158a5de1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/144695ae84eebf186e387f3dfb18dc0da0b7aa14cf56ef54355bbf1ec6d309e9937229d3306363dbfec1d618f2b98133b289d61890d65ee403715e946a00726f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c91b1fbdc6eb0b7f7715cd0451aaa17a6cdd6ff6554e7e834f3d4f0628e4da10d4e06e2e6f2a30872c088c933375f7dd40229d6963a91ebc59b4fcc67c66f971", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ac33d4b16d589a911255eab4a24a6266d12427e34857f38e92355b6693842a2727ed76a7f2c7cc20d7f54ae6bee1cc7ea3a49e112d8e94733cc633748c69a89"} build_url:https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011422 build_url: 'https://queue.taskcluster.net/v1/task/GtrzBvAqQF6HHhY7n-InXQ/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/863a9a2fdb04d8b45fe9096cd154fcc0799df15ae9253299f34da76e12b651b698b3e85616357ecdd1a8f4e0843f781cb3c8510f613b53017b5cf7e9158a5de1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/144695ae84eebf186e387f3dfb18dc0da0b7aa14cf56ef54355bbf1ec6d309e9937229d3306363dbfec1d618f2b98133b289d61890d65ee403715e946a00726f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c91b1fbdc6eb0b7f7715cd0451aaa17a6cdd6ff6554e7e834f3d4f0628e4da10d4e06e2e6f2a30872c088c933375f7dd40229d6963a91ebc59b4fcc67c66f971", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ac33d4b16d589a911255eab4a24a6266d12427e34857f38e92355b6693842a2727ed76a7f2c7cc20d7f54ae6bee1cc7ea3a49e112d8e94733cc633748c69a89"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 05:07:42.547272) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 05:07:42.547635) ========= 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.qNqnWkynrB/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.SaOpQ1Sfqv/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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.005264 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 05:07:42.598976) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 05:07:42.602713) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 05:07:42.603386) ========= ========= Total master_lag: 2.24 =========