builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0127 starttime: 1483114949.94 results: success (0) buildid: 20161230070524 builduid: d270c7529bbe4bc381cd4b027230fa93 revision: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:29.944601) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:29.945203) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:29.945532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:30.011891) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:30.012229) ========= 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.N3bU4igER5/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.uMRgFvnMqj/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-30 08:22:30-- 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% 159M=0s 2016-12-30 08:22:30 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.607756 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:30.647798) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:30.648123) ========= 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.N3bU4igER5/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.uMRgFvnMqj/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.019269 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:30.702129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:30.705587) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9104708cc3ac0ccfe4cf5d518e13736773c565d7 --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 9104708cc3ac0ccfe4cf5d518e13736773c565d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.N3bU4igER5/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.uMRgFvnMqj/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-30 08:22:30,785 truncating revision to first 12 chars 2016-12-30 08:22:30,785 Setting DEBUG logging. 2016-12-30 08:22:30,785 attempt 1/10 2016-12-30 08:22:30,785 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9104708cc3ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-30 08:22:31,410 unpacking tar archive at: mozilla-central-9104708cc3ac/testing/mozharness/ program finished with exit code 0 elapsedTime=0.887523 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:31.617626) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:31.617960) ========= script_repo_revision: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:31.618374) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:31.618650) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 08:22:31.651409) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-30 08:22:31.651790) ========= /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.N3bU4igER5/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.uMRgFvnMqj/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:22:31 INFO - MultiFileLogger online at 20161230 08:22:31 in /builds/slave/test 08:22:31 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 08:22:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:22:31 INFO - {'append_to_log': False, 08:22:31 INFO - 'base_work_dir': '/builds/slave/test', 08:22:31 INFO - 'blob_upload_branch': 'Firefox', 08:22:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:22:31 INFO - 'branch': 'Firefox', 08:22:31 INFO - 'buildbot_json_path': 'buildprops.json', 08:22:31 INFO - 'config_files': ('talos/mac_config.py',), 08:22:31 INFO - 'default_actions': ('clobber', 08:22:31 INFO - 'read-buildbot-config', 08:22:31 INFO - 'download-and-extract', 08:22:31 INFO - 'populate-webroot', 08:22:31 INFO - 'create-virtualenv', 08:22:31 INFO - 'install', 08:22:31 INFO - 'run-tests'), 08:22:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:22:31 INFO - 'download_minidump_stackwalk': True, 08:22:31 INFO - 'download_symbols': 'ondemand', 08:22:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:22:31 INFO - 'tooltool.py': '/tools/tooltool.py', 08:22:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:22:31 INFO - '/tools/misc-python/virtualenv.py')}, 08:22:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:22:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:22:31 INFO - 'installer_path': 'installer.exe', 08:22:31 INFO - 'log_level': 'info', 08:22:31 INFO - 'log_name': 'talos', 08:22:31 INFO - 'log_to_console': True, 08:22:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:22:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:22:31 INFO - 'opt_config_files': (), 08:22:31 INFO - 'pip_index': False, 08:22:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:22:31 INFO - 'cmd': ('bash', 08:22:31 INFO - '-c', 08:22:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:22:31 INFO - 'enabled': True, 08:22:31 INFO - 'halt_on_failure': False, 08:22:31 INFO - 'name': 'check_screen_resolution'},), 08:22:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:22:31 INFO - 'cmd': ('bash', 08:22:31 INFO - '-c', 08:22:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:22:31 INFO - 'enabled': True, 08:22:31 INFO - 'halt_on_failure': False, 08:22:31 INFO - 'name': 'check_screen_resolution'},), 08:22:31 INFO - 'run_cmd_checks_enabled': True, 08:22:31 INFO - 'sps_profile': False, 08:22:31 INFO - 'sps_profile_interval': 0, 08:22:31 INFO - 'suite': 'dromaeojs', 08:22:31 INFO - 'system_bits': '32', 08:22:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:22:31 INFO - 'title': 't-yosemite-r7-0127', 08:22:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:22:31 INFO - 'use_talos_json': True, 08:22:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:22:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:22:31 INFO - 'work_dir': 'build'} 08:22:31 INFO - [mozharness: 2016-12-30 16:22:31.855153Z] Running clobber step. 08:22:31 INFO - Running pre-action listener: _resource_record_pre_action 08:22:31 INFO - Running main action method: clobber 08:22:31 INFO - rmtree: /builds/slave/test/build 08:22:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:22:32 INFO - Running post-action listener: _resource_record_post_action 08:22:32 INFO - [mozharness: 2016-12-30 16:22:32.543377Z] Finished clobber step (success) 08:22:32 INFO - [mozharness: 2016-12-30 16:22:32.543651Z] Running read-buildbot-config step. 08:22:32 INFO - Running pre-action listener: _resource_record_pre_action 08:22:32 INFO - Running main action method: read_buildbot_config 08:22:32 INFO - Using buildbot properties: 08:22:32 INFO - { 08:22:32 INFO - "project": "", 08:22:32 INFO - "product": "firefox", 08:22:32 INFO - "script_repo_revision": "production", 08:22:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:22:32 INFO - "repository": "", 08:22:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:22:32 INFO - "buildid": "20161230070524", 08:22:32 INFO - "pgo_build": "False", 08:22:32 INFO - "basedir": "/builds/slave/test", 08:22:32 INFO - "buildnumber": 45, 08:22:32 INFO - "slavename": "t-yosemite-r7-0127", 08:22:32 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:22:32 INFO - "platform": "yosemite_r7", 08:22:32 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:22:32 INFO - "branch": "mozilla-central", 08:22:32 INFO - "repo_path": "mozilla-central", 08:22:32 INFO - "revision": "9104708cc3ac0ccfe4cf5d518e13736773c565d7", 08:22:32 INFO - "stage_platform": "macosx64", 08:22:32 INFO - "builduid": "d270c7529bbe4bc381cd4b027230fa93", 08:22:32 INFO - "slavebuilddir": "test" 08:22:32 INFO - } 08:22:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:22:32 INFO - Running post-action listener: _resource_record_post_action 08:22:32 INFO - [mozharness: 2016-12-30 16:22:32.549663Z] Finished read-buildbot-config step (success) 08:22:32 INFO - [mozharness: 2016-12-30 16:22:32.549881Z] Running download-and-extract step. 08:22:32 INFO - Running pre-action listener: _resource_record_pre_action 08:22:32 INFO - Running main action method: download_and_extract 08:22:32 INFO - mkdir: /builds/slave/test/build/tests 08:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:32 INFO - trying https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:22:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:22:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:22:34 INFO - Downloaded 1199 bytes. 08:22:34 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:22:34 INFO - Using the following test package requirements: 08:22:34 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:22:34 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:34 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:22:34 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:34 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:22:34 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:34 INFO - u'jsshell-mac.zip'], 08:22:34 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:34 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:22:34 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:22:34 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:34 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:22:34 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:34 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:22:34 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:34 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:22:34 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:22:34 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:22:34 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:22:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:22:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:22:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:22:35 INFO - Expected file size: 16299397 08:22:35 INFO - Obtained file size: 16299397 08:22:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:22:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:22:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:22:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:22:43 INFO - Expected file size: 16299397 08:22:43 INFO - Obtained file size: 16299397 08:22:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:22:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:22:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:22:51 INFO - Expected file size: 14409776 08:22:51 INFO - Obtained file size: 14409776 08:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:51 INFO - trying https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:22:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:22:54 INFO - Downloaded 69761034 bytes. 08:22:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:22:54 INFO - mkdir: /builds/slave/test/properties 08:22:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:22:54 INFO - Writing to file /builds/slave/test/properties/build_url 08:22:54 INFO - Contents: 08:22:54 INFO - build_url:https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:22:55 INFO - Running post-action listener: _resource_record_post_action 08:22:55 INFO - Running post-action listener: set_extra_try_arguments 08:22:55 INFO - [mozharness: 2016-12-30 16:22:55.256848Z] Finished download-and-extract step (success) 08:22:55 INFO - [mozharness: 2016-12-30 16:22:55.257006Z] Running populate-webroot step. 08:22:55 INFO - Running pre-action listener: _resource_record_pre_action 08:22:55 INFO - Running main action method: populate_webroot 08:22:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:22:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:22:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:22:55 INFO - '--tppagecycles', 08:22:55 INFO - '7'], 08:22:55 INFO - 'tests': ['tsvgm']}}, 08:22:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:22:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:22:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:22:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:22:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:22:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:22:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['damp', 'tps']}, 08:22:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'tests': ['damp', 'tps']}, 08:22:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['dromaeo_dom']}, 08:22:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:22:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['basic_compositor_video']}, 08:22:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:22:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'other-e10s': {'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['a11yr', 08:22:55 INFO - 'ts_paint', 08:22:55 INFO - 'tpaint', 08:22:55 INFO - 'sessionrestore', 08:22:55 INFO - 'sessionrestore_no_auto_restore', 08:22:55 INFO - 'tabpaint']}, 08:22:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['tsvgx', 08:22:55 INFO - 'tsvgr_opacity', 08:22:55 INFO - 'tart', 08:22:55 INFO - 'tscrollx', 08:22:55 INFO - 'cart', 08:22:55 INFO - 'tsvg_static']}, 08:22:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:22:55 INFO - 'tsvgr_opacity', 08:22:55 INFO - 'tart', 08:22:55 INFO - 'tscrollx', 08:22:55 INFO - 'cart', 08:22:55 INFO - 'tsvg_static']}, 08:22:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'talos_options': ['--disable-e10s'], 08:22:55 INFO - 'tests': ['tp5o']}, 08:22:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:22:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'talos_options': ['--disable-e10s', 08:22:55 INFO - '--xperf_path', 08:22:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:22:55 INFO - 'tests': ['tp5n']}, 08:22:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:22:55 INFO - 'talos_options': ['--xperf_path', 08:22:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:22:55 INFO - 'tests': ['tp5n']}}, 08:22:55 INFO - 'talos.zip': {'path': '', 08:22:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:22:55 INFO - Running post-action listener: _resource_record_post_action 08:22:55 INFO - [mozharness: 2016-12-30 16:22:55.275765Z] Finished populate-webroot step (success) 08:22:55 INFO - [mozharness: 2016-12-30 16:22:55.275901Z] Running create-virtualenv step. 08:22:55 INFO - Running pre-action listener: _resource_record_pre_action 08:22:55 INFO - Running main action method: create_virtualenv 08:22:55 INFO - Creating virtualenv /builds/slave/test/build/venv 08:22:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:22:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:22:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:22:55 INFO - Using real prefix '/tools/python27' 08:22:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:22:56 INFO - Installing distribute.............................................................................................................................................................................................done. 08:22:58 INFO - Installing pip.................done. 08:22:58 INFO - Return code: 0 08:22:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:22:59 INFO - Reading from file tmpfile_stdout 08:22:59 INFO - Output received: 08:22:59 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:22:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:59 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 0x10b9efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa110ca5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7cdd30>, '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 0x10b6f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c2be8>, '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.N3bU4igER5/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.uMRgFvnMqj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:22:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N3bU4igER5/Render', 08:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:59 INFO - 'HOME': '/Users/cltbld', 08:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:59 INFO - 'LOGNAME': 'cltbld', 08:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:59 INFO - 'MOZ_NO_REMOTE': '1', 08:22:59 INFO - 'NO_EM_RESTART': '1', 08:22:59 INFO - 'PAGER': '/bin/cat', 08:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:59 INFO - 'PWD': '/builds/slave/test', 08:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:59 INFO - 'SHELL': '/bin/bash', 08:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uMRgFvnMqj/Listeners', 08:22:59 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 08:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:59 INFO - 'USER': 'cltbld', 08:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:59 INFO - 'XPC_FLAGS': '0x0', 08:22:59 INFO - 'XPC_SERVICE_NAME': '0', 08:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:22:59 INFO - Cleaning up... 08:22:59 INFO - Return code: 0 08:22:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:59 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 0x10b9efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa110ca5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7cdd30>, '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 0x10b6f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c2be8>, '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.N3bU4igER5/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.uMRgFvnMqj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:22:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N3bU4igER5/Render', 08:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:59 INFO - 'HOME': '/Users/cltbld', 08:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:59 INFO - 'LOGNAME': 'cltbld', 08:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:59 INFO - 'MOZ_NO_REMOTE': '1', 08:22:59 INFO - 'NO_EM_RESTART': '1', 08:22:59 INFO - 'PAGER': '/bin/cat', 08:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:59 INFO - 'PWD': '/builds/slave/test', 08:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:59 INFO - 'SHELL': '/bin/bash', 08:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uMRgFvnMqj/Listeners', 08:22:59 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 08:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:59 INFO - 'USER': 'cltbld', 08:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:59 INFO - 'XPC_FLAGS': '0x0', 08:22:59 INFO - 'XPC_SERVICE_NAME': '0', 08:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:59 INFO - Downloading/unpacking psutil>=3.1.1 08:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:23:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:23:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:23:03 INFO - Installing collected packages: psutil 08:23:03 INFO - Running setup.py install for psutil 08:23:03 INFO - building 'psutil._psutil_osx' extension 08:23:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:23:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:23:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:23:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:23:04 INFO - building 'psutil._psutil_posix' extension 08:23:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:23:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:23:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:23:04 INFO - ^ 08:23:04 INFO - 1 warning generated. 08:23:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:23:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:23:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:23:04 INFO - Successfully installed psutil 08:23:04 INFO - Cleaning up... 08:23:04 INFO - Return code: 0 08:23:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa110ca5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7cdd30>, '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 0x10b6f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c2be8>, '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.N3bU4igER5/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.uMRgFvnMqj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:23: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 mozsystemmonitor==0.3 08:23:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N3bU4igER5/Render', 08:23:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:04 INFO - 'HOME': '/Users/cltbld', 08:23:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:04 INFO - 'LOGNAME': 'cltbld', 08:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:04 INFO - 'MOZ_NO_REMOTE': '1', 08:23:04 INFO - 'NO_EM_RESTART': '1', 08:23:04 INFO - 'PAGER': '/bin/cat', 08:23:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:04 INFO - 'PWD': '/builds/slave/test', 08:23:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:04 INFO - 'SHELL': '/bin/bash', 08:23:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uMRgFvnMqj/Listeners', 08:23:04 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 08:23:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:04 INFO - 'USER': 'cltbld', 08:23:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:04 INFO - 'XPC_FLAGS': '0x0', 08:23:04 INFO - 'XPC_SERVICE_NAME': '0', 08:23:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:23: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 08:23: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 08:23: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 08:23: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 08:23: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 08:23: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 08:23:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:23:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:23:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:23:07 INFO - Installing collected packages: mozsystemmonitor 08:23:07 INFO - Running setup.py install for mozsystemmonitor 08:23:07 INFO - Successfully installed mozsystemmonitor 08:23:07 INFO - Cleaning up... 08:23:07 INFO - Return code: 0 08:23:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:07 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 0x10b9efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa110ca5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7cdd30>, '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 0x10b6f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c2be8>, '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.N3bU4igER5/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.uMRgFvnMqj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:23:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N3bU4igER5/Render', 08:23:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:07 INFO - 'HOME': '/Users/cltbld', 08:23:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:07 INFO - 'LOGNAME': 'cltbld', 08:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:07 INFO - 'MOZ_NO_REMOTE': '1', 08:23:07 INFO - 'NO_EM_RESTART': '1', 08:23:07 INFO - 'PAGER': '/bin/cat', 08:23:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:07 INFO - 'PWD': '/builds/slave/test', 08:23:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:07 INFO - 'SHELL': '/bin/bash', 08:23:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uMRgFvnMqj/Listeners', 08:23:07 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 08:23:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:07 INFO - 'USER': 'cltbld', 08:23:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:07 INFO - 'XPC_FLAGS': '0x0', 08:23:07 INFO - 'XPC_SERVICE_NAME': '0', 08:23:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:07 INFO - Downloading/unpacking jsonschema==2.5.1 08:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:23:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:10 INFO - Downloading functools32-3.2.3-2.tar.gz 08:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:23:11 INFO - warning: no files found matching '*.txt' 08:23:11 INFO - no previously-included directories found matching 'build' 08:23:11 INFO - no previously-included directories found matching 'dist' 08:23:11 INFO - no previously-included directories found matching '.git*' 08:23:11 INFO - Installing collected packages: jsonschema, functools32 08:23:11 INFO - Running setup.py install for functools32 08:23:11 INFO - warning: no files found matching '*.txt' 08:23:11 INFO - no previously-included directories found matching 'build' 08:23:11 INFO - no previously-included directories found matching 'dist' 08:23:11 INFO - no previously-included directories found matching '.git*' 08:23:11 INFO - Successfully installed jsonschema functools32 08:23:11 INFO - Cleaning up... 08:23:11 INFO - Return code: 0 08:23:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b9efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa110ca5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7cdd30>, '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 0x10b6f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c2be8>, '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.N3bU4igER5/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.uMRgFvnMqj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:23:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N3bU4igER5/Render', 08:23:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:11 INFO - 'HOME': '/Users/cltbld', 08:23:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:11 INFO - 'LOGNAME': 'cltbld', 08:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:11 INFO - 'MOZ_NO_REMOTE': '1', 08:23:11 INFO - 'NO_EM_RESTART': '1', 08:23:11 INFO - 'PAGER': '/bin/cat', 08:23:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:11 INFO - 'PWD': '/builds/slave/test', 08:23:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:11 INFO - 'SHELL': '/bin/bash', 08:23:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uMRgFvnMqj/Listeners', 08:23:11 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 08:23:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:11 INFO - 'USER': 'cltbld', 08:23:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:11 INFO - 'XPC_FLAGS': '0x0', 08:23:11 INFO - 'XPC_SERVICE_NAME': '0', 08:23:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:23:11 INFO - Cleaning up... 08:23:11 INFO - Return code: 0 08:23:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b9efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa110ca5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7cdd30>, '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 0x10b6f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c2be8>, '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.N3bU4igER5/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.uMRgFvnMqj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:23:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N3bU4igER5/Render', 08:23:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:11 INFO - 'HOME': '/Users/cltbld', 08:23:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:11 INFO - 'LOGNAME': 'cltbld', 08:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:11 INFO - 'MOZ_NO_REMOTE': '1', 08:23:11 INFO - 'NO_EM_RESTART': '1', 08:23:11 INFO - 'PAGER': '/bin/cat', 08:23:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:11 INFO - 'PWD': '/builds/slave/test', 08:23:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:11 INFO - 'SHELL': '/bin/bash', 08:23:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uMRgFvnMqj/Listeners', 08:23:11 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 08:23:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:11 INFO - 'USER': 'cltbld', 08:23:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:11 INFO - 'XPC_FLAGS': '0x0', 08:23:11 INFO - 'XPC_SERVICE_NAME': '0', 08:23:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:11 INFO - Downloading/unpacking blobuploader==1.2.4 08:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:14 INFO - Downloading blobuploader-1.2.4.tar.gz 08:23:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:23:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:23:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:23: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 08:23: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 08:23: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 08:23: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 08:23: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 08:23: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 08:23:15 INFO - Downloading docopt-0.6.1.tar.gz 08:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:23:15 INFO - Installing collected packages: blobuploader, requests, docopt 08:23:15 INFO - Running setup.py install for blobuploader 08:23:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:23:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:23:15 INFO - Running setup.py install for requests 08:23:16 INFO - Running setup.py install for docopt 08:23:16 INFO - Successfully installed blobuploader requests docopt 08:23:16 INFO - Cleaning up... 08:23:16 INFO - Return code: 0 08:23:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:16 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 0x10b9efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa110ca5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7cdd30>, '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 0x10b6f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c2be8>, '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.N3bU4igER5/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.uMRgFvnMqj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:23:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N3bU4igER5/Render', 08:23:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:16 INFO - 'HOME': '/Users/cltbld', 08:23:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:16 INFO - 'LOGNAME': 'cltbld', 08:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:16 INFO - 'MOZ_NO_REMOTE': '1', 08:23:16 INFO - 'NO_EM_RESTART': '1', 08:23:16 INFO - 'PAGER': '/bin/cat', 08:23:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:16 INFO - 'PWD': '/builds/slave/test', 08:23:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:16 INFO - 'SHELL': '/bin/bash', 08:23:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uMRgFvnMqj/Listeners', 08:23:16 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 08:23:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:16 INFO - 'USER': 'cltbld', 08:23:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:16 INFO - 'XPC_FLAGS': '0x0', 08:23:16 INFO - 'XPC_SERVICE_NAME': '0', 08:23:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:23:16 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-E5Phk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:23:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-8reFNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:23:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-n19iti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:23:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-L7HS4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:23:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-sO4cbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:23:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-kMJqaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:23:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-eQR3k5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:23:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-JDh2Dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:23:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-WayIf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:23:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-S3csKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:23:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-aqySNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:23:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-G5qh6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:23:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-LHOneg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:23:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-sa1Xzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ttDku6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:23:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-lfhS7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:23:18 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-USyuAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:23:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:23:18 INFO - Running setup.py install for manifestparser 08:23:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:23:18 INFO - Running setup.py install for mozcrash 08:23:18 INFO - Running setup.py install for mozdebug 08:23:19 INFO - Running setup.py install for mozdevice 08:23:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:23:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:23:19 INFO - Running setup.py install for mozfile 08:23:19 INFO - Running setup.py install for mozhttpd 08:23:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:23:19 INFO - Running setup.py install for mozinfo 08:23:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:23:19 INFO - Running setup.py install for mozInstall 08:23:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:23:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:23:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:23:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:23:19 INFO - Running setup.py install for mozleak 08:23:19 INFO - Running setup.py install for mozlog 08:23:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Running setup.py install for moznetwork 08:23:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Running setup.py install for mozprocess 08:23:20 INFO - Running setup.py install for mozprofile 08:23:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Running setup.py install for mozrunner 08:23:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:23:20 INFO - Running setup.py install for mozscreenshot 08:23:20 INFO - Running setup.py install for moztest 08:23:21 INFO - Running setup.py install for mozversion 08:23:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:23:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:23:21 INFO - Cleaning up... 08:23:21 INFO - Return code: 0 08:23:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:23: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')]} 08:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23: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')]} 08:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:21 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 0x10b9efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa110ca5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7cdd30>, '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 0x10b6f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c2be8>, '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.N3bU4igER5/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.uMRgFvnMqj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N3bU4igER5/Render', 08:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:21 INFO - 'HOME': '/Users/cltbld', 08:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:21 INFO - 'LOGNAME': 'cltbld', 08:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:21 INFO - 'MOZ_NO_REMOTE': '1', 08:23:21 INFO - 'NO_EM_RESTART': '1', 08:23:21 INFO - 'PAGER': '/bin/cat', 08:23:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:21 INFO - 'PWD': '/builds/slave/test', 08:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:21 INFO - 'SHELL': '/bin/bash', 08:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uMRgFvnMqj/Listeners', 08:23:21 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 08:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:21 INFO - 'USER': 'cltbld', 08:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:21 INFO - 'XPC_FLAGS': '0x0', 08:23:21 INFO - 'XPC_SERVICE_NAME': '0', 08:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:23:21 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-StUyLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:23:21 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-opm7hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:23:21 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-vntMSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:23:21 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ciJcYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:23:21 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-PpciLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:23:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-1bBOb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:23:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-e6EG9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:23:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-pCfxUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:23:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-NuqjWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:23:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-kU7S2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:23:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-rbhJ9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:23:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-DqMcCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:23:22 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)) 08:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:23:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ZVHLw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:23:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Sp6plK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-xNbMeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:23:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-5t6XG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:23:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-S4xIg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:23:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:23:25 INFO - Downloading blessings-1.6.tar.gz 08:23:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:23:26 INFO - Installing collected packages: blessings 08:23:26 INFO - Running setup.py install for blessings 08:23:26 INFO - Successfully installed blessings 08:23:26 INFO - Cleaning up... 08:23:26 INFO - Return code: 0 08:23:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:23:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:23:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:23:26 INFO - Reading from file tmpfile_stdout 08:23:26 INFO - Current package versions: 08:23:26 INFO - blessings == 1.6 08:23:26 INFO - blobuploader == 1.2.4 08:23:26 INFO - docopt == 0.6.1 08:23:26 INFO - functools32 == 3.2.3-2 08:23:26 INFO - jsonschema == 2.5.1 08:23:26 INFO - manifestparser == 1.1 08:23:26 INFO - mozInstall == 1.12 08:23:26 INFO - mozcrash == 1.0 08:23:26 INFO - mozdebug == 0.1 08:23:26 INFO - mozdevice == 0.49 08:23:26 INFO - mozfile == 1.2 08:23:26 INFO - mozhttpd == 0.7 08:23:26 INFO - mozinfo == 0.9 08:23:26 INFO - mozleak == 0.1 08:23:26 INFO - mozlog == 3.4 08:23:26 INFO - moznetwork == 0.27 08:23:26 INFO - mozprocess == 0.23 08:23:26 INFO - mozprofile == 0.28 08:23:26 INFO - mozrunner == 6.13 08:23:26 INFO - mozscreenshot == 0.1 08:23:26 INFO - mozsystemmonitor == 0.3 08:23:26 INFO - moztest == 0.8 08:23:26 INFO - mozversion == 1.4 08:23:26 INFO - psutil == 3.1.1 08:23:26 INFO - requests == 1.2.3 08:23:26 INFO - wsgiref == 0.1.2 08:23:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:26 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 0x10b9efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa110ca5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7cdd30>, '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 0x10b6f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c2be8>, '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.N3bU4igER5/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.uMRgFvnMqj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:23:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:23:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N3bU4igER5/Render', 08:23:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:26 INFO - 'HOME': '/Users/cltbld', 08:23:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:26 INFO - 'LOGNAME': 'cltbld', 08:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:26 INFO - 'MOZ_NO_REMOTE': '1', 08:23:26 INFO - 'NO_EM_RESTART': '1', 08:23:26 INFO - 'PAGER': '/bin/cat', 08:23:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:26 INFO - 'PWD': '/builds/slave/test', 08:23:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:26 INFO - 'SHELL': '/bin/bash', 08:23:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uMRgFvnMqj/Listeners', 08:23:26 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 08:23:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:26 INFO - 'USER': 'cltbld', 08:23:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:26 INFO - 'XPC_FLAGS': '0x0', 08:23:26 INFO - 'XPC_SERVICE_NAME': '0', 08:23:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:23:26 INFO - Cleaning up... 08:23:26 INFO - Return code: 0 08:23:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b9efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa110ca5c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7cdd30>, '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 0x10b6f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c2be8>, '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.N3bU4igER5/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.uMRgFvnMqj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:23:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:23:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:23:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N3bU4igER5/Render', 08:23:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:26 INFO - 'HOME': '/Users/cltbld', 08:23:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:26 INFO - 'LOGNAME': 'cltbld', 08:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:26 INFO - 'MOZ_NO_REMOTE': '1', 08:23:26 INFO - 'NO_EM_RESTART': '1', 08:23:26 INFO - 'PAGER': '/bin/cat', 08:23:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:26 INFO - 'PWD': '/builds/slave/test', 08:23:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:26 INFO - 'SHELL': '/bin/bash', 08:23:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uMRgFvnMqj/Listeners', 08:23:26 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 08:23:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:26 INFO - 'USER': 'cltbld', 08:23:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:26 INFO - 'XPC_FLAGS': '0x0', 08:23:26 INFO - 'XPC_SERVICE_NAME': '0', 08:23:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:23:27 INFO - Cleaning up... 08:23:27 INFO - Return code: 0 08:23:27 INFO - Running post-action listener: _resource_record_post_action 08:23:27 INFO - Running post-action listener: _start_resource_monitoring 08:23:27 INFO - Starting resource monitoring. 08:23:27 INFO - [mozharness: 2016-12-30 16:23:27.156634Z] Finished create-virtualenv step (success) 08:23:27 INFO - [mozharness: 2016-12-30 16:23:27.157615Z] Running install step. 08:23:27 INFO - Running pre-action listener: _resource_record_pre_action 08:23:27 INFO - Running main action method: install 08:23:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:23:27 INFO - Reading from file tmpfile_stdout 08:23:27 INFO - Detecting whether we're running mozinstall >=1.0... 08:23:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:23:27 INFO - Reading from file tmpfile_stdout 08:23:27 INFO - Output received: 08:23:27 INFO - Usage: mozinstall [options] installer 08:23:27 INFO - Options: 08:23:27 INFO - -h, --help show this help message and exit 08:23:27 INFO - -d DEST, --destination=DEST 08:23:27 INFO - Directory to install application into. [default: 08:23:27 INFO - "/builds/slave/test"] 08:23:27 INFO - --app=APP Application being installed. [default: firefox] 08:23:27 INFO - mkdir: /builds/slave/test/build/application 08:23:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:23:49 INFO - Reading from file tmpfile_stdout 08:23:49 INFO - Output received: 08:23:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:23:49 INFO - Running post-action listener: _resource_record_post_action 08:23:49 INFO - [mozharness: 2016-12-30 16:23:49.031969Z] Finished install step (success) 08:23:49 INFO - [mozharness: 2016-12-30 16:23:49.032129Z] Running run-tests step. 08:23:49 INFO - Running pre-action listener: _resource_record_pre_action 08:23:49 INFO - Running main action method: run_tests 08:23:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:23:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:23:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:23:49 INFO - 2016-12-30 08:23:49.046 screenresolution[6541:117645] starting screenresolution argv=screenresolution get 08:23:49 INFO - 2016-12-30 08:23:49.049 screenresolution[6541:117645] Display 0: 1600x1200x32@60 08:23:49 INFO - 2016-12-30 08:23:49.055 screenresolution[6542:117648] starting screenresolution argv=screenresolution list 08:23:49 INFO - Available Modes on Display 0 08:23:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:23:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:23:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:23:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:23:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:23:49 INFO - Intel Iris: 08:23:49 INFO - Chipset Model: Intel Iris 08:23:49 INFO - Type: GPU 08:23:49 INFO - Bus: Built-In 08:23:49 INFO - VRAM (Dynamic, Max): 1536 MB 08:23:49 INFO - Vendor: Intel (0x8086) 08:23:49 INFO - Device ID: 0x0a2e 08:23:49 INFO - Revision ID: 0x0009 08:23:49 INFO - Displays: 08:23:49 INFO - Display: 08:23:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:23:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:23:49 INFO - Display Serial Number: 1600x1200 60 08:23:49 INFO - Main Display: Yes 08:23:49 INFO - Mirror: Off 08:23:49 INFO - Online: Yes 08:23:49 INFO - Rotation: Supported 08:23:49 INFO - Return code: 0 08:23:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:23:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:23:49 INFO - Python 2.7.3 08:23:49 INFO - Return code: 0 08:23:49 INFO - grabbing minidump binary from tooltool 08:23:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:49 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 0x10b7cdd30>, '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 0x10b6f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7c2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:23:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:23:49 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:23:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:23:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:23:49 INFO - Return code: 0 08:23:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:23:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:23:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:23:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:23:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:23:49 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:23:49 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:23:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N3bU4igER5/Render', 08:23:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:49 INFO - 'HOME': '/Users/cltbld', 08:23:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:49 INFO - 'LOGNAME': 'cltbld', 08:23:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:23:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:49 INFO - 'MOZ_NO_REMOTE': '1', 08:23:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:23:49 INFO - 'NO_EM_RESTART': '1', 08:23:49 INFO - 'PAGER': '/bin/cat', 08:23:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:49 INFO - 'PWD': '/builds/slave/test', 08:23:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:23:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:49 INFO - 'SHELL': '/bin/bash', 08:23:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uMRgFvnMqj/Listeners', 08:23:49 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 08:23:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:49 INFO - 'USER': 'cltbld', 08:23:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:49 INFO - 'XPC_FLAGS': '0x0', 08:23:49 INFO - 'XPC_SERVICE_NAME': '0', 08:23:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:49 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:23:49 INFO - mozversion application_buildid: 20161230070524 08:23:49 INFO - mozversion application_changeset: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 08:23:49 INFO - mozversion application_display_name: Nightly 08:23:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:23:49 INFO - mozversion application_name: Firefox 08:23:49 INFO - mozversion application_remotingname: firefox 08:23:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:23:49 INFO - mozversion application_vendor: Mozilla 08:23:49 INFO - mozversion application_version: 53.0a1 08:23:49 INFO - mozversion platform_buildid: 20161230070524 08:23:49 INFO - mozversion platform_changeset: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 08:23:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:23:49 INFO - mozversion platform_version: 53.0a1 08:23:49 INFO - using testdate: 1483115029 08:23:49 INFO - actual date: 1483115029 08:23:49 INFO - starting webserver on 'localhost:58524' 08:23:49 INFO - SUITE-START | Running 2 tests 08:23:49 INFO - TEST-START | dromaeo_css 08:23:49 INFO - operating with platform_type : mac_ 08:23:49 INFO - Initialising browser for dromaeo_css test... 08:23:49 INFO - TEST-INFO | started process 6552 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpXNmmKL/profile http://localhost:58524/getInfo.html) 08:23:50 INFO - PROCESS | 6552 | 1483115030187 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:23:51 INFO - PROCESS | 6552 | __metrics Screen width/height:1600/1200 08:23:51 INFO - PROCESS | 6552 | colorDepth:24 08:23:51 INFO - PROCESS | 6552 | Browser inner width/height: 1024/689 08:23:51 INFO - PROCESS | 6552 | __metrics 08:23:52 INFO - PROCESS | 6552 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:23:52 INFO - PROCESS | 6552 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:23:52 INFO - TEST-INFO | 6552: exit 0 08:23:52 INFO - Browser initialized. 08:23:52 INFO - Running cycle 1/1 for dromaeo_css test... 08:23:52 INFO - TEST-INFO | started process 6555 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpXNmmKL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:24:04 INFO - PROCESS | 6555 | RSS: Main: 232939520 08:24:04 INFO - PROCESS | 6555 | 08:24:55 INFO - PROCESS | 6555 | 1483115095727 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] 08:27:09 INFO - PROCESS | 6555 | Cycle 1(1): loaded http://localhost:58524/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58524/tests/dromaeo/cssquery-ext.html) 08:27:10 INFO - PROCESS | 6555 | RSS: Main: 360185856 08:27:10 INFO - PROCESS | 6555 | 08:28:53 INFO - PROCESS | 6555 | ************************* 08:28:53 INFO - PROCESS | 6555 | A coding exception was thrown and uncaught in a Task. 08:28:53 INFO - PROCESS | 6555 | 08:28:53 INFO - PROCESS | 6555 | Full message: ReferenceError: fetch is not defined 08:28:53 INFO - PROCESS | 6555 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:28:53 INFO - PROCESS | 6555 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:28:53 INFO - PROCESS | 6555 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:28:53 INFO - PROCESS | 6555 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:28:53 INFO - PROCESS | 6555 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:28:53 INFO - PROCESS | 6555 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:28:53 INFO - PROCESS | 6555 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:28:53 INFO - PROCESS | 6555 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:28:53 INFO - PROCESS | 6555 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:28:53 INFO - PROCESS | 6555 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:28:53 INFO - PROCESS | 6555 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:28:53 INFO - PROCESS | 6555 | 08:28:53 INFO - PROCESS | 6555 | ************************* 08:30:13 INFO - PROCESS | 6555 | Cycle 1(1): loaded http://localhost:58524/tests/dromaeo/cssquery-ext.html (next: http://localhost:58524/tests/dromaeo/cssquery-jquery.html) 08:30:14 INFO - PROCESS | 6555 | RSS: Main: 366882816 08:30:14 INFO - PROCESS | 6555 | 08:33:16 INFO - PROCESS | 6555 | Cycle 1(1): loaded http://localhost:58524/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58524/tests/dromaeo/cssquery-mootools.html) 08:33:17 INFO - PROCESS | 6555 | RSS: Main: 343703552 08:33:17 INFO - PROCESS | 6555 | 08:36:19 INFO - PROCESS | 6555 | Cycle 1(1): loaded http://localhost:58524/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58524/tests/dromaeo/cssquery-prototype.html) 08:36:20 INFO - PROCESS | 6555 | RSS: Main: 335695872 08:36:20 INFO - PROCESS | 6555 | 08:39:24 INFO - PROCESS | 6555 | Cycle 1(1): loaded http://localhost:58524/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58524/tests/dromaeo/cssquery-yui.html) 08:39:24 INFO - PROCESS | 6555 | RSS: Main: 329117696 08:39:24 INFO - PROCESS | 6555 | 08:42:28 INFO - PROCESS | 6555 | Cycle 1(1): loaded http://localhost:58524/tests/dromaeo/cssquery-yui.html (next: http://localhost:58524/tests/dromaeo/cssquery-dojo.html) 08:42:28 INFO - PROCESS | 6555 | RSS: Main: 375640064 08:42:28 INFO - PROCESS | 6555 | 08:42:28 INFO - PROCESS | 6555 | __start_tp_report 08:42:28 INFO - PROCESS | 6555 | _x_x_mozilla_page_load 08:42:28 INFO - PROCESS | 6555 | _x_x_mozilla_page_load_details 08:42:28 INFO - PROCESS | 6555 | |i|pagename|runs| 08:42:28 INFO - PROCESS | 6555 | |0;dojo.html;3319.2863534340095;3329.66703329667;3333.69996700297;3342.364950659373;3396.473546600276;5544.833654990347;5667.631603945746;5684.431556844308;5686.374498805133;5687.6303040302355;5660.518855897254;5700;5787.594868359217;5790.652560846353;5795.246617939672;5120.4623514530995;5144.202648589475;5189.091908915943;5224.843254702346;5238.816641417551;5306.8407947761625;5312.840614781563;5318.282031925699;5320.787168513274;5360.517553420192;5150.484951504857;5207.713575753332;5225.242339860737;5240.318758561381;5252.579793616521;5187.351581052369;5188.584913206934;5223.268742376071;5231.529162375405;5251.264822924793;5167.586593072545;5193.298904647862;5202.583793296565;5233.241180028875;5259.579233661336;4690.742009189493;5576.9125020620995;5731.398203188689;5752.5110365618975;5763.538916886639;6096.329403765582;6104.45915544718;6137.570370074097;6146.323904370515;6191.81424557264;6099.847503812396;6120.294761999575;6128.442013630915;6170.382961703838;6180.907286390708;5663.971680141655;5706.885862282773;5727.942720572824;5758.193852860602;5791.478766910998;6081.817545473555;6177.320494745664;6193.071039344134;6205.410486003822;6205.596636218631;6061.969690151521;6062.393760623902;6073.908891366633;6084.84387966285;6086.6652334122045;6094.360092190344;6101.603395779349;6111.083337499411;6111.622125543816;6112.208004058284;6087.904177248138;6148.831721972811;6161.168242287324;6169.81490555284;6174.629522228591;6129.682118344577;6148.44663980244;6153.138560601519;6156.168917196211;6162.969185154046;4845.030993801254;4900.215965445512;4902.2646603009125;4910.312556242129;5002.274670172805;4410.492793328808;4416.624586910084;4422.911541769146;4465.0623369092855;4468.955310446919;5821.18503409523;5838;5856.092305692625;5867.853303667357;5870.530357571384;6078.300995385587;6172.567920245539;6176.166217560572;6184.598001130003;6185.41238582334;5716.1997320375185;5726.169705392778;5782.161586569924;5784.652920824764;5845.561582881216;5399.919001214985;5418.620696551282;5429.67421954684;5431.972840135774;5481.588880833953;6343.777967771198;6351.460125889259;6353.904691429632;6354.968225158845;6371.076193951943;6109.75560977556;6131.40725074338;6138.2044803098925;6173.043178307369;6196.690165491797;6156.630602163885;6246.781362652286;6256.280527739368;6315.3053164152825;6326.683665816783;6065.150878877049;6117.979653188298;6119.265688117454;6124.081387791867;6136.3046386708;6105.877882442415;6135.538906164888;6165.969170154121;6173.444390004921;6185.226846644169;6131.468419844996;6140.140380346666;6141.048137538689;6166.876662466815;6171.012637978081;6128.448439640454;6148.292946311231;6155.661438620739;6155.969220153871;6158.107074474086;5800.1299805029585;5833.708314584169;5875.3243377012195;5926.851828704317;5965.015772397679;5965.791197308074;6107.947487154961;6121.300552271563;6121.788327166137;6129.601575897553;5180.834312279707;5942.405759424023;5950.077737950624;5982.1325907744;6009.2188015557695 08:42:28 INFO - PROCESS | 6555 | |1;ext.html;416.74995002998185;451.9379458273059;465.44611911824927;482.9903401931961;516.279789693378;23887.880560597194;31541.68458315416;31620.525692114606;38246.80876595613;40207.195856082864;41078.58921410785;41212.79393603035;41394.37908431368;41426.585734142725;41478.792606037;9247.815043699122;9453.81092378152;9461.195798357114;9476.43141411514;9476.43141411514;8517;8728.170823771732;9253.95373023134;9369.718908432724;11104.056155226775;11081.889181108207;11345.602903898365;11354.489047992818;11357.488912998893;11392.373419461926;11384.146189035813;11449.713757156054;11462.48418821155;11474;11495;7268.345848873574;8675.305975522006;10943.945280273627;11246.606368777135;11257.54969801207;39070.804645976874;39618.603813962065;39761.005974850574;39810.40284395737;39828.402573961415;3442.4319987202334;3899.415087736834;3944.8027598619956;3955.802209889494;3966.464527288808;29115.708842911725;30323;30474;30484.390312193853;30625.081247562608;2871.0238518903466;3082.3835232953497;3196.6323872754465;3222.1139186723603;3251.7073463388174;458673.66579177603;463429;465867;465936;467421;59326.70336648289;59813;59819.70090149521;59907.700461497414;60072.69963650154;63255.36744632495;63457.68271158707;63613.68193159004;63664.363356365844;63736.68131659405;15009.249537523081;15592.532224033297;15593.922030390002;15597.220138993232;15597.610059748371;12016.71682380345;12051.096167787451;12057.819132713017;12062.336571488653;12063.336516491501;15222.086674799326;15624.453144139901;15658.416361300486;16016.75974860378;16021.43657014872;6064.696765161724;6555.475561955032;6987.510874238857;7031.437485001187;7193.676284567207;841.5441286574138;846.6528723223572;848.6775025490274;879.9660399031113;889.5996801439378;29039.56440653392;29199;29466.82174644472;29473.410531789672;29535.556966645516;1005.7133716890651;1029.2435060230725;1030.525958059286;1051.2115913065202;1056.5140035583563;14538.563843084725;15198.392064317304;15204.315805788767;15291.464798731991;15370;579177;587835;588559;590593.0470347621;590905;56366;56533.71733141308;56617.716911416006;56628.43371566314;56888.43111568831;1346.9730605387838;1378.9724205516034;1401.726663300667;1403.2142000479764;1404.3048690898072;22636;27686.615669216055;28954.13137605875;29247.70752292535;29271.682774275636;48979.26531101965;49440;49453.50546494633;49517.752411237714;49553.50446495633;507771.3005829345;508457;511410;511492;513800;24259.87870060709;24940.002399904526;25053.624195637443;38680.6131938677;39255.60744392634;35729.1067723309;36609.63390366062;39854.2029159421;41476.37785433161;41571.7921410391;34205.65794342025;34694.82652586721;34856.65143348635;35637;35870.8206458966;33600.663993359754;34009.31981360408;34427;35092.473612895315;35560.82219588885;32823.67176328206;33571.49642755312;33575.16062098467;34834.65165348314;35102.12244693903;22897.88551057234;29698.703012970458;29719.959801406847;30804;31151.221219469695;22617.869106544935;22986;23290.76709232886;23466.53066938686;23557.411064723517 08:42:28 INFO - PROCESS | 6555 | |2;jquery.html;356.33543441481635;369.86499927526444;375.9529709758323;391.90986073203146;398.8185375654078;16390.180490975432;18354.265829366825;19133.904330478344;20075;20191.394258172277;11814.40927953601;12614.684632884182;18029;20593.38219853403;20600.38198854037;5076.441591424949;5299.8410047698435;5303.045451818671;5303.893922121555;5316.707581083039;4508.842190523333;4988.825391111312;5145.562627176694;5173.715445650488;5187.636865419422;5187.377514698242;5212.791488340462;5219.843404697846;5235.057689615867;5237.947620523803;5016.072026675077;5189.974050129763;5228.37259528859;5234.057869583474;5254.106801843695;5043.293938848564;5092.414372347153;5122.3096378195105;5124.128898087334;5124.4106927703415;13890.374933128034;23689.526209475887;24359.391015224584;24360.26919192427;24634.876825615935;2587;2618.5417551928335;2625.1993142091655;2656.907008254721;2662.733726627341;9660.420374777535;9964.701058968243;10200.285979981405;10304.124149447305;12508.061895357885;1783.545195975031;1796.2096677461875;1801.926881635126;1826.5707558723689;1841.5119993201813;115465.84534154719;119803.4009829954;120090.39954800258;120178.99552511169;120616.39691801484;10877.292975956538;11346.716332091599;11486.0811135109;11503.712407189887;11696.532138714527;14659.34032968519;14661.34023968924;14693.853061469248;14703.852961470462;14707.55877323682;7908.5650289234645;8205.384596155192;8227.218414250636;8299.875501867476;8404.327653787683;7899.921000789918;7995.600219988977;7999.1600881907725;8158.224968627971;8161.143079976636;4512.120136573402;4522.909541809146;4528.252838281713;4551.067031258584;4679.3916790816975;1866.7479890214918;1920.596674698301;1923.5864289177894;1923.7691477022568;1928.4889504281377;855.2260204514924;868.2749903830372;913.5340976102103;928.4011812380976;929.0848514213494;14454.566363009126;18517.81482185188;20795.896020519802;21232.938353082285;22250.666240006412;884.2925659472525;889.7953470701775;907.5054095517959;914.5335878701777;914.5427286356821;5919.230500035054;6622.437092847065;6638.900416493757;6820.727170913207;6856.348646878539;557728;562306;574162;575137;577948;91063;113255.43372283086;113375.43312283386;119239.55952628347;119347.40326298313;970.1171933540585;973.5278389980854;1022.1005515146624;1026.9332493387906;1026.9537870795682;19122.808771912103;19333.806661933762;19355.709664354763;19445.31941382045;19469;46377.84055398642;53063.48960338414;55650;56009;56113.43886561082;77048;79602;80681;81239.40862218909;82193;6663.400293973566;7949.523028618301;8084.87872681922;8141.674333026849;8199.467034642728;11484.540618375171;12395.62813115608;12473.064520160842;12930.288834114213;12962.962962963129;9530.046995300414;10898.182636302468;10986.945065274622;10994.230403871652;11051.90013897357;9391.906080939103;10520.894791052298;10613.9469302653;11078.889211108108;11112.888871111185;10985.505652245509;11062;11374.601888933848;11439.14206434504;11486.483108259981;7991.800204994922;8046;8109.959450202711;8111.39164562672;8138.064122625974;9499.620015199314;10951.288166269167;11128.109751220045;11210.943945280222;11211.60759373418 08:42:28 INFO - PROCESS | 6555 | |3;mootools.html;1290.8257385252991;1397.308332375474;1408.7745960646291;1435.3110506956655;1438.2952353346864;8045.517268963859;8447.36644751643;8483.448575842565;8484.957575212124;8533.530655813927;7444.925550744491;7665.808354791133;7679.6928122875015;7995.120536740968;8267.793305167374;2276.123692378438;2326.8487548309304;2329.149860300995;2332.2070496031347;2332.790048895599;2300.1374484568287;2333.230034088755;2338.5790557699606;2338.7193536775526;2341.2507997440825;2037.500812300981;2112.6302896993043;2226.6214743493647;2280.9543809123807;2304.101400453816;2174.9456263593456;2279.168103642175;2283.748787633359;2288.8054515366216;2289.084366253512;1957.725918371429;2278.8833471598796;2306.573283942476;2307.14635584833;2327.685762422085;8261.586920653943;8340.708075217339;8340.958295208486;8341.41610087294;8350.498970061815;5007;5179.223116532513;5189.273501709764;5201.739913004335;5205.791768329263;3218.6620404857385;3403.8127902965325;3424.3493736190294;3442.4836274559016;3462.3767721810063;1138.6868611131922;1170.2101081769772;1206.541514224589;1215.4834195466892;1227.5028613411582;10729.785404291948;10778;10798.406087665175;10805.513751881106;10825.78348433027;4251.44731184944;4491.079328737601;4560.794764235618;4568.74871882043;4572.314152877095;9439.811203776024;9460.8580871287;9464.1009104135;9465.432074075577;9474.242060635133;5167.940572182693;5176.1459359206065;5176.611754118456;5189;5197.454267301954;4245.299525578307;4383.473983122046;4567.520410356931;4685.320628508847;4751.026039661861;3068.938621227563;3747.2318174774105;3790.223004284115;3839.9040023999623;3879.1078052048188;1247.8253044573585;1256.9371531423392;1257.528426839935;1264.5068423314915;1265.6899059730424;633.4045996763028;753.6909866954632;769.2230846844727;774.8915151878741;787.1223585701902;5279.551238144801;5433.809816656398;5523.6685798852195;5600.243967064475;5689.203511508392;878.7407714724171;885.2121611765534;886.4504007515382;896.3680605173337;934.8878134623889;3569.803660798629;3589.210373717778;3646.6535679110443;3687.3362794697214;3754.131788472357;9511.714648560554;9918.603255869684;9971.351862128939;10037.548310326052;10039.598416063422;10061.245406594535;10071.942446043207;10074.042965918226;10080;10092.192624590016;6462.644554549451;7600.923990760021;7769;7884.921150788418;7904.604769761489;5340.279062326692;5366.141417373357;5441.945580544144;5501.944980550144;5643.633163844337;9273.119053689888;9277.62889484413;9281.814363712552;9315.487648179414;9376.343655944018;9277.211437027658;9397.765055873659;9491.525423728648;9605.37565058269;9637.662681806103;6587.4071333580205;6823.795286141423;6833.521653484208;6835.4189893859575;6836.93163068363;7682.423818213768;7814.726484573012;7828.217178282354;7904.525728456311;7939.6030198491;5694.886102278014;7105.893411598727;7141.714331426685;7169;7234.710611575478;6837.247902730795;6918.411934985583;6925.168979722465;6981.127359080115;6986.650667466708;6245.96877015612;6254;6384.7446102157255;6386.329435409215;6391.616503009835;4576.244919588231;4681.929771053505;4719.480857105785;4734.952650473589;4849.442314133778;4615.88105344161;4617.399738034034;4733.029728905635;4867.853964381075;4876.317315575751 08:42:28 INFO - PROCESS | 6555 | |4;prototype.html;306.633572880408;348.4337950829902;359.4320972862876;363.2299525006986;374.40843467321633;5069.2649565812935;5168.147255702814;5172.560332371748;5188;5191.1953647184655;4790.928136077953;5094.261332106842;5130.794768209276;5132.3071385363055;5138.2806407103035;3688.7827017084296;3840.6955826504154;3972.443857859902;3987.940180897289;4079.4288799568085;3579.4630805379143;3740.4202348635868;3879.010852232677;4010.9799451002705;4100.753954762724;4028.3151864183014;4039.6467183493564;4049.129437171008;4055.9797201013953;4067.3288907330257;2994.940101197964;3211.811629697004;3511.613722490524;3699.7225208109367;3707.703383729295;3256.527803468484;3547.7871327720163;3584.2473080653094;3605.675489205958;3611.8735844245325;4551.453825540956;4656.2284549515;4746.098241334135;4989.725565093919;5009.373828271466;3991.381335892941;4130.814113364906;4398.934015989763;4535.229011068126;4649.721326635203;5103.770330335144;5321.020228671918;5370.892582148335;5382.569394448474;5430.647007944471;3946.4474973503447;3947.407888816672;3951.269015232191;3959.3269144245546;3978.6021397860272;5126.4364368867855;5185.496206100189;5195.090859099718;5216.556592689626;5379.488948549882;3435.6220815710253;3546.925048846402;3704.962950370462;4007.8396864125107;4035.8385664573675;3697.8351819176874;3855.5951625078965;4068.2066996935314;4072;4074;4592.380028696083;4619.376384188159;4633.675642705046;4651.4418269807165;4673.789679464432;4679.800493767923;5135.820246291362;5342.011727830364;5474.151506516497;5495.697736624444;1114.2645853736494;1271.879171478708;1327.7610030194658;1330.9933450332687;1332.8004795683962;752.221450798432;914.2594498456139;923.2798417234568;929.5398777605129;936.0125068053269;4067.1662309226535;4234.216669916075;4865.443058221406;4958.52893975079;5253.791627925599;4643.956659069999;4786.688865223749;5326.36083669962;5571.192177134374;5606.97196514023;4460.754658493814;4645.581897629229;4753.952460475351;4843.8999040614035;5107.284980102789;2420.370703617072;2555.3169172369307;2571.9712115153716;2801.7058208887775;2813.268550176969;4983.558056552263;4989.80040798364;5173.22401639757;5557.499825015687;5557.749901254453;4723.291506274087;5238.8166414176085;5245.763940622681;5350.4649535046965;5455.727213639302;4968.205087186033;5071.6130547077255;5532.640378375393;5537.363203231599;5551.7779288828;4630.6758526902795;4953.554180123806;5103.693778373309;5286.76209570578;5370.41572736036;4921.286413469955;4965.875853103701;5064.772085256099;5228.928069745617;5512.283403157564;4856.63575231852;4931.580815630711;5141.871453213699;5810.6513609183585;5816.941830581809;3198.648148703687;3801.498408128829;3819.5416550014174;3826.9808650956566;3834.5206849143856;4557.088582283623;4766.380370551806;5237.580993520433;5322.494363035506;5581.358143813513;4590.609798167193;5019.422766381766;5184.662996905189;5476.67139971603;5487.972560137174;4553.929826490706;4629.120467111373;5209.6874187548865;5331.960267747752;5409.404965453876;4712.62299016071;5126.07772085468;5366.892662146814;5383.569314454912;5402.405735369028;5168.922466162935;5336.265713643004;5366.6779993200535;5442.319710036246;5478.616496845182;5070.200078971912;5341.412444631165;5345.38528069277;5353.22378255143;5367.651102678314 08:42:28 INFO - PROCESS | 6555 | |5;yui.html;697.476892330752;709.9929000709991;746.0003595182454;776.4564804636755;804.7947773317803;14395.64010899728;15403.383864645415;15405.306761195745;15420.922895385507;15435.228238588083;13724.176549407042;13880.514182003632;13896;13912.93043534781;13916.582502524916;655.7114869457423;678.8099332186971;679.6262055869253;820.7332616899502;837.9120192379804;835.6323217784186;848;852.5864955496579;855.0295414704324;857.9356548258875;884.4869975414244;903.0157128729684;906.9591868365907;907.1790030022806;909.381620498061;862.7023676831483;863.6329559937002;863.7192912303526;881.8721285413643;890.2432932007806;511.1132185657881;528.2419727690738;648.0538413915689;662.3244290823382;671.8521925176453;14377.281135943162;14387.640308992255;14417.207053612046;14419;14434.494792682312;10576.629817956335;12769.87230127696;12833;12860.742785144244;12962;11748.647540573797;11819.704507387298;11865.52537898483;11901.821472677917;12251.69370765738;4265.914681706379;4351.108022855307;4368.951451651581;4376.212281789277;4469.597736203757;73482.8977565337;95482;100909.495452523;101860;102493;9645.662401815904;9827.361221520721;9921.057499537677;9952.203823694088;10171.440570768531;18219.271229150687;18577.721334179998;18733.531661708566;18752;18827.15277812502;7300.671469783872;7341.59621220838;7499.662515186829;7543.358814500829;7558.433117516208;4078.2659121357847;4134.607212314825;4135.048938744046;4137.627613514798;4143.316352801814;3198.9680103199134;3248.772585918963;3267.2648654053123;3279.4752839546036;3289.194147433894;602.6474512410201;609.7560975609703;611.7767015039567;628.8836565235355;629.2826178156905;491.229465812816;500.3820281951787;524.9684370212551;564.3481778545786;604.7611193578512;7810.531368117931;8287.171282871785;8449.662013519513;8630.654773808978;8780.824383512421;571.5085026876883;615.1449485215554;615.5691016288616;620.3951147631024;632.8038308124496;4070.8371665133063;4098.098418347959;4206.915861682811;4218.430511880918;4357.891052723708;62709.37290627127;96220;98495;100393.49803250936;103204;58992.705036474545;70226.64886675637;74718.13204669819;76467;85876;70250.9462358065;71498.9275160873;73023.63488182524;75383;76411;9206.355555111079;9656.71029869105;9837.213022958003;9855.31012829095;10026.24803139782;41736;46623.834404140165;50957.47127586178;53567.464325356246;53768.462315376346;60693;64117.358826413;64261.35738642554;64919.72780952644;65620.67189664021;10984.725381865517;11417.673865662835;13498.98757593204;13810.516631917835;13862.584122476343;14472.27638618086;14573.489927852474;14695.338709757872;14865;15389.153596551849;13745.725085498034;13943.302834858014;14115.858841411866;14361.425542978164;14398;13174.341282936006;13693.31533423305;13919.4432222714;14181.43274269059;14220.431182752574;11358.659240222807;13420.664483387993;13497.527586534425;13519;13959.302034898012;9913.405195688281;10048.447335396624;10299.3820370778;10574.788504230026;10601.522931468257;10197.439140847027;10874.67141100839;10964.56141754321;10983.901609838953;11078 08:42:28 INFO - PROCESS | 6555 | __end_tp_report 08:42:28 INFO - PROCESS | 6555 | __start_cc_report 08:42:28 INFO - PROCESS | 6555 | _x_x_mozilla_cycle_collect,1388 08:42:28 INFO - PROCESS | 6555 | __end_cc_report 08:42:28 INFO - PROCESS | 6555 | __startTimestamp1483116148807__endTimestamp 08:42:28 INFO - PROCESS | 6555 | 08:42:28 INFO - PROCESS | 6555 | ------- Summary: start ------- 08:42:28 INFO - PROCESS | 6555 | Number of tests: 6 08:42:28 INFO - PROCESS | 6555 | 08:42:28 INFO - PROCESS | 6555 | [#0] dojo.html Cycles:165 Average:5719.09 Median:5965.02 stddev:624.04 (10.5%) stddev-sans-first:596.88 08:42:28 INFO - PROCESS | 6555 | Values: 3319.3 3329.7 3333.7 3342.4 3396.5 5544.8 5667.6 5684.4 5686.4 5687.6 5660.5 5700.0 5787.6 5790.7 5795.2 5120.5 5144.2 5189.1 5224.8 5238.8 5306.8 5312.8 5318.3 5320.8 5360.5 5150.5 5207.7 5225.2 5240.3 5252.6 5187.4 5188.6 5223.3 5231.5 5251.3 5167.6 5193.3 5202.6 5233.2 5259.6 4690.7 5576.9 5731.4 5752.5 5763.5 6096.3 6104.5 6137.6 6146.3 6191.8 6099.8 6120.3 6128.4 6170.4 6180.9 5664.0 5706.9 5727.9 5758.2 5791.5 6081.8 6177.3 6193.1 6205.4 6205.6 6062.0 6062.4 6073.9 6084.8 6086.7 6094.4 6101.6 6111.1 6111.6 6112.2 6087.9 6148.8 6161.2 6169.8 6174.6 6129.7 6148.4 6153.1 6156.2 6163.0 4845.0 4900.2 4902.3 4910.3 5002.3 4410.5 4416.6 4422.9 4465.1 4469.0 5821.2 5838.0 5856.1 5867.9 5870.5 6078.3 6172.6 6176.2 6184.6 6185.4 5716.2 5726.2 5782.2 5784.7 5845.6 5399.9 5418.6 5429.7 5432.0 5481.6 6343.8 6351.5 6353.9 6355.0 6371.1 6109.8 6131.4 6138.2 6173.0 6196.7 6156.6 6246.8 6256.3 6315.3 6326.7 6065.2 6118.0 6119.3 6124.1 6136.3 6105.9 6135.5 6166.0 6173.4 6185.2 6131.5 6140.1 6141.0 6166.9 6171.0 6128.4 6148.3 6155.7 6156.0 6158.1 5800.1 5833.7 5875.3 5926.9 5965.0 5965.8 6107.9 6121.3 6121.8 6129.6 5180.8 5942.4 5950.1 5982.1 6009.2 08:42:28 INFO - PROCESS | 6555 | 08:42:28 INFO - PROCESS | 6555 | [#1] ext.html Cycles:180 Average:65142.51 Median:34974.56 stddev:139602.12 (399.2%) stddev-sans-first:139909.16 08:42:28 INFO - PROCESS | 6555 | Values: 416.7 451.9 465.4 483.0 516.3 23887.9 31541.7 31620.5 38246.8 40207.2 41078.6 41212.8 41394.4 41426.6 41478.8 9247.8 9453.8 9461.2 9476.4 9476.4 8517.0 8728.2 9254.0 9369.7 11104.1 11081.9 11345.6 11354.5 11357.5 11392.4 11384.1 11449.7 11462.5 11474.0 11495.0 7268.3 8675.3 10943.9 11246.6 11257.5 39070.8 39618.6 39761.0 39810.4 39828.4 3442.4 3899.4 3944.8 3955.8 3966.5 29115.7 30323.0 30474.0 30484.4 30625.1 2871.0 3082.4 3196.6 3222.1 3251.7 458673.7 463429.0 465867.0 465936.0 467421.0 59326.7 59813.0 59819.7 59907.7 60072.7 63255.4 63457.7 63613.7 63664.4 63736.7 15009.2 15592.5 15593.9 15597.2 15597.6 12016.7 12051.1 12057.8 12062.3 12063.3 15222.1 15624.5 15658.4 16016.8 16021.4 6064.7 6555.5 6987.5 7031.4 7193.7 841.5 846.7 848.7 880.0 889.6 29039.6 29199.0 29466.8 29473.4 29535.6 1005.7 1029.2 1030.5 1051.2 1056.5 14538.6 15198.4 15204.3 15291.5 15370.0 579177.0 587835.0 588559.0 590593.0 590905.0 56366.0 56533.7 56617.7 56628.4 56888.4 1347.0 1379.0 1401.7 1403.2 1404.3 22636.0 27686.6 28954.1 29247.7 29271.7 48979.3 49440.0 49453.5 49517.8 49553.5 507771.3 508457.0 511410.0 511492.0 513800.0 24259.9 24940.0 25053.6 38680.6 39255.6 35729.1 36609.6 39854.2 41476.4 41571.8 34205.7 34694.8 34856.7 35637.0 35870.8 33600.7 34009.3 34427.0 35092.5 35560.8 32823.7 33571.5 33575.2 34834.7 35102.1 22897.9 29698.7 29720.0 30804.0 31151.2 22617.9 22986.0 23290.8 23466.5 23557.4 08:42:28 INFO - PROCESS | 6555 | 08:42:28 INFO - PROCESS | 6555 | [#2] jquery.html Cycles:180 Average:33361.15 Median:395.36 stddev:95097.21 (24053.1%) stddev-sans-first:95331.69 08:42:28 INFO - PROCESS | 6555 | Values: 356.3 369.9 376.0 391.9 398.8 16390.2 18354.3 19133.9 20075.0 20191.4 11814.4 12614.7 18029.0 20593.4 20600.4 5076.4 5299.8 5303.0 5303.9 5316.7 4508.8 4988.8 5145.6 5173.7 5187.6 5187.4 5212.8 5219.8 5235.1 5237.9 5016.1 5190.0 5228.4 5234.1 5254.1 5043.3 5092.4 5122.3 5124.1 5124.4 13890.4 23689.5 24359.4 24360.3 24634.9 2587.0 2618.5 2625.2 2656.9 2662.7 9660.4 9964.7 10200.3 10304.1 12508.1 1783.5 1796.2 1801.9 1826.6 1841.5 115465.8 119803.4 120090.4 120179.0 120616.4 10877.3 11346.7 11486.1 11503.7 11696.5 14659.3 14661.3 14693.9 14703.9 14707.6 7908.6 8205.4 8227.2 8299.9 8404.3 7899.9 7995.6 7999.2 8158.2 8161.1 4512.1 4522.9 4528.3 4551.1 4679.4 1866.7 1920.6 1923.6 1923.8 1928.5 855.2 868.3 913.5 928.4 929.1 14454.6 18517.8 20795.9 21232.9 22250.7 884.3 889.8 907.5 914.5 914.5 5919.2 6622.4 6638.9 6820.7 6856.3 557728.0 562306.0 574162.0 575137.0 577948.0 91063.0 113255.4 113375.4 119239.6 119347.4 970.1 973.5 1022.1 1026.9 1027.0 19122.8 19333.8 19355.7 19445.3 19469.0 46377.8 53063.5 55650.0 56009.0 56113.4 77048.0 79602.0 80681.0 81239.4 82193.0 6663.4 7949.5 8084.9 8141.7 8199.5 11484.5 12395.6 12473.1 12930.3 12963.0 9530.0 10898.2 10986.9 10994.2 11051.9 9391.9 10520.9 10613.9 11078.9 11112.9 10985.5 11062.0 11374.6 11439.1 11486.5 7991.8 8046.0 8110.0 8111.4 8138.1 9499.6 10951.3 11128.1 11210.9 11211.6 08:42:28 INFO - PROCESS | 6555 | 08:42:28 INFO - PROCESS | 6555 | [#3] mootools.html Cycles:180 Average:5364.41 Median:5177.92 stddev:2972.42 (57.4%) stddev-sans-first:2964.99 08:42:28 INFO - PROCESS | 6555 | Values: 1290.8 1397.3 1408.8 1435.3 1438.3 8045.5 8447.4 8483.4 8485.0 8533.5 7444.9 7665.8 7679.7 7995.1 8267.8 2276.1 2326.8 2329.1 2332.2 2332.8 2300.1 2333.2 2338.6 2338.7 2341.3 2037.5 2112.6 2226.6 2281.0 2304.1 2174.9 2279.2 2283.7 2288.8 2289.1 1957.7 2278.9 2306.6 2307.1 2327.7 8261.6 8340.7 8341.0 8341.4 8350.5 5007.0 5179.2 5189.3 5201.7 5205.8 3218.7 3403.8 3424.3 3442.5 3462.4 1138.7 1170.2 1206.5 1215.5 1227.5 10729.8 10778.0 10798.4 10805.5 10825.8 4251.4 4491.1 4560.8 4568.7 4572.3 9439.8 9460.9 9464.1 9465.4 9474.2 5167.9 5176.1 5176.6 5189.0 5197.5 4245.3 4383.5 4567.5 4685.3 4751.0 3068.9 3747.2 3790.2 3839.9 3879.1 1247.8 1256.9 1257.5 1264.5 1265.7 633.4 753.7 769.2 774.9 787.1 5279.6 5433.8 5523.7 5600.2 5689.2 878.7 885.2 886.5 896.4 934.9 3569.8 3589.2 3646.7 3687.3 3754.1 9511.7 9918.6 9971.4 10037.5 10039.6 10061.2 10071.9 10074.0 10080.0 10092.2 6462.6 7600.9 7769.0 7884.9 7904.6 5340.3 5366.1 5441.9 5501.9 5643.6 9273.1 9277.6 9281.8 9315.5 9376.3 9277.2 9397.8 9491.5 9605.4 9637.7 6587.4 6823.8 6833.5 6835.4 6836.9 7682.4 7814.7 7828.2 7904.5 7939.6 5694.9 7105.9 7141.7 7169.0 7234.7 6837.2 6918.4 6925.2 6981.1 6986.7 6246.0 6254.0 6384.7 6386.3 6391.6 4576.2 4681.9 4719.5 4735.0 4849.4 4615.9 4617.4 4733.0 4867.9 4876.3 08:42:28 INFO - PROCESS | 6555 | 08:42:28 INFO - PROCESS | 6555 | [#4] prototype.html Cycles:180 Average:4335.49 Median:4850.27 stddev:1285.68 (26.5%) stddev-sans-first:1253.22 08:42:28 INFO - PROCESS | 6555 | Values: 306.6 348.4 359.4 363.2 374.4 5069.3 5168.1 5172.6 5188.0 5191.2 4790.9 5094.3 5130.8 5132.3 5138.3 3688.8 3840.7 3972.4 3987.9 4079.4 3579.5 3740.4 3879.0 4011.0 4100.8 4028.3 4039.6 4049.1 4056.0 4067.3 2994.9 3211.8 3511.6 3699.7 3707.7 3256.5 3547.8 3584.2 3605.7 3611.9 4551.5 4656.2 4746.1 4989.7 5009.4 3991.4 4130.8 4398.9 4535.2 4649.7 5103.8 5321.0 5370.9 5382.6 5430.6 3946.4 3947.4 3951.3 3959.3 3978.6 5126.4 5185.5 5195.1 5216.6 5379.5 3435.6 3546.9 3705.0 4007.8 4035.8 3697.8 3855.6 4068.2 4072.0 4074.0 4592.4 4619.4 4633.7 4651.4 4673.8 4679.8 5135.8 5342.0 5474.2 5495.7 1114.3 1271.9 1327.8 1331.0 1332.8 752.2 914.3 923.3 929.5 936.0 4067.2 4234.2 4865.4 4958.5 5253.8 4644.0 4786.7 5326.4 5571.2 5607.0 4460.8 4645.6 4754.0 4843.9 5107.3 2420.4 2555.3 2572.0 2801.7 2813.3 4983.6 4989.8 5173.2 5557.5 5557.7 4723.3 5238.8 5245.8 5350.5 5455.7 4968.2 5071.6 5532.6 5537.4 5551.8 4630.7 4953.6 5103.7 5286.8 5370.4 4921.3 4965.9 5064.8 5228.9 5512.3 4856.6 4931.6 5141.9 5810.7 5816.9 3198.6 3801.5 3819.5 3827.0 3834.5 4557.1 4766.4 5237.6 5322.5 5581.4 4590.6 5019.4 5184.7 5476.7 5488.0 4553.9 4629.1 5209.7 5332.0 5409.4 4712.6 5126.1 5366.9 5383.6 5402.4 5168.9 5336.3 5366.7 5442.3 5478.6 5070.2 5341.4 5345.4 5353.2 5367.7 08:42:28 INFO - PROCESS | 6555 | 08:42:28 INFO - PROCESS | 6555 | [#5] yui.html Cycles:180 Average:18913.76 Median:4422.91 stddev:26511.17 (599.4%) stddev-sans-first:26550.26 08:42:28 INFO - PROCESS | 6555 | Values: 697.5 710.0 746.0 776.5 804.8 14395.6 15403.4 15405.3 15420.9 15435.2 13724.2 13880.5 13896.0 13912.9 13916.6 655.7 678.8 679.6 820.7 837.9 835.6 848.0 852.6 855.0 857.9 884.5 903.0 907.0 907.2 909.4 862.7 863.6 863.7 881.9 890.2 511.1 528.2 648.1 662.3 671.9 14377.3 14387.6 14417.2 14419.0 14434.5 10576.6 12769.9 12833.0 12860.7 12962.0 11748.6 11819.7 11865.5 11901.8 12251.7 4265.9 4351.1 4369.0 4376.2 4469.6 73482.9 95482.0 100909.5 101860.0 102493.0 9645.7 9827.4 9921.1 9952.2 10171.4 18219.3 18577.7 18733.5 18752.0 18827.2 7300.7 7341.6 7499.7 7543.4 7558.4 4078.3 4134.6 4135.0 4137.6 4143.3 3199.0 3248.8 3267.3 3279.5 3289.2 602.6 609.8 611.8 628.9 629.3 491.2 500.4 525.0 564.3 604.8 7810.5 8287.2 8449.7 8630.7 8780.8 571.5 615.1 615.6 620.4 632.8 4070.8 4098.1 4206.9 4218.4 4357.9 62709.4 96220.0 98495.0 100393.5 103204.0 58992.7 70226.6 74718.1 76467.0 85876.0 70250.9 71498.9 73023.6 75383.0 76411.0 9206.4 9656.7 9837.2 9855.3 10026.2 41736.0 46623.8 50957.5 53567.5 53768.5 60693.0 64117.4 64261.4 64919.7 65620.7 10984.7 11417.7 13499.0 13810.5 13862.6 14472.3 14573.5 14695.3 14865.0 15389.2 13745.7 13943.3 14115.9 14361.4 14398.0 13174.3 13693.3 13919.4 14181.4 14220.4 11358.7 13420.7 13497.5 13519.0 13959.3 9913.4 10048.4 10299.4 10574.8 10601.5 10197.4 10874.7 10964.6 10983.9 11078.0 08:42:28 INFO - PROCESS | 6555 | -------- Summary: end -------- 08:42:28 INFO - PROCESS | 6555 | 08:42:29 INFO - TEST-INFO | 6555: exit 0 08:42:29 INFO - TEST-OK | dromaeo_css | took 1119827ms 08:42:29 INFO - TEST-START | kraken 08:42:29 INFO - operating with platform_type : mac_ 08:42:29 INFO - Initialising browser for kraken test... 08:42:29 INFO - TEST-INFO | started process 6558 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpf6NW4_/profile http://localhost:58524/getInfo.html) 08:42:29 INFO - PROCESS | 6558 | 1483116149957 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:42:31 INFO - PROCESS | 6558 | __metrics Screen width/height:1600/1200 08:42:31 INFO - PROCESS | 6558 | colorDepth:24 08:42:31 INFO - PROCESS | 6558 | Browser inner width/height: 1024/689 08:42:31 INFO - PROCESS | 6558 | __metrics 08:42:31 INFO - PROCESS | 6558 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:42:31 INFO - PROCESS | 6558 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:42:32 INFO - TEST-INFO | 6558: exit 0 08:42:32 INFO - Browser initialized. 08:42:32 INFO - Running cycle 1/1 for kraken test... 08:42:32 INFO - TEST-INFO | started process 6559 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpf6NW4_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:42:44 INFO - PROCESS | 6559 | RSS: Main: 225832960 08:42:44 INFO - PROCESS | 6559 | 08:42:47 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58524/tests/kraken/driver.html?testName=audio-beat-detection) 08:42:48 INFO - PROCESS | 6559 | RSS: Main: 285712384 08:42:48 INFO - PROCESS | 6559 | 08:42:50 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58524/tests/kraken/driver.html?testName=audio-dft) 08:42:51 INFO - PROCESS | 6559 | RSS: Main: 287571968 08:42:51 INFO - PROCESS | 6559 | 08:42:54 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58524/tests/kraken/driver.html?testName=audio-fft) 08:42:55 INFO - PROCESS | 6559 | RSS: Main: 376426496 08:42:55 INFO - PROCESS | 6559 | 08:42:57 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58524/tests/kraken/driver.html?testName=audio-oscillator) 08:42:58 INFO - PROCESS | 6559 | RSS: Main: 283361280 08:42:58 INFO - PROCESS | 6559 | 08:43:00 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58524/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:43:00 INFO - PROCESS | 6559 | RSS: Main: 286674944 08:43:00 INFO - PROCESS | 6559 | 08:43:03 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58524/tests/kraken/driver.html?testName=imaging-darkroom) 08:43:04 INFO - PROCESS | 6559 | RSS: Main: 328302592 08:43:04 INFO - PROCESS | 6559 | 08:43:06 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58524/tests/kraken/driver.html?testName=imaging-desaturate) 08:43:07 INFO - PROCESS | 6559 | RSS: Main: 328785920 08:43:07 INFO - PROCESS | 6559 | 08:43:10 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58524/tests/kraken/driver.html?testName=json-parse-financial) 08:43:10 INFO - PROCESS | 6559 | RSS: Main: 325926912 08:43:10 INFO - PROCESS | 6559 | 08:43:12 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58524/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:43:13 INFO - PROCESS | 6559 | RSS: Main: 303706112 08:43:13 INFO - PROCESS | 6559 | 08:43:15 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58524/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:43:15 INFO - PROCESS | 6559 | RSS: Main: 304590848 08:43:15 INFO - PROCESS | 6559 | 08:43:17 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58524/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:43:18 INFO - PROCESS | 6559 | RSS: Main: 307216384 08:43:18 INFO - PROCESS | 6559 | 08:43:21 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58524/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:43:22 INFO - PROCESS | 6559 | RSS: Main: 323182592 08:43:22 INFO - PROCESS | 6559 | 08:43:25 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58524/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:43:25 INFO - PROCESS | 6559 | RSS: Main: 317882368 08:43:25 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:58524/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58524/tests/kraken/driver.html?testName=ai-astar) 08:43:27 INFO - PROCESS | 6559 | RSS: Main: 362217472 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | __start_tp_report 08:43:27 INFO - PROCESS | 6559 | _x_x_mozilla_page_load 08:43:27 INFO - PROCESS | 6559 | _x_x_mozilla_page_load_details 08:43:27 INFO - PROCESS | 6559 | |i|pagename|runs| 08:43:27 INFO - PROCESS | 6559 | |0;ai-astar;112;113;114;112;111;115;113;112;113;112 08:43:27 INFO - PROCESS | 6559 | |1;audio-beat-detection;150;111;112;148;127;124;110;113;130;111 08:43:27 INFO - PROCESS | 6559 | |2;audio-dft;187;223;183;186;183;183;183;181;182;183 08:43:27 INFO - PROCESS | 6559 | |3;audio-fft;80;61;49;87;73;83;83;81;76;59 08:43:27 INFO - PROCESS | 6559 | |4;audio-oscillator;87;70;90;69;70;68;83;70;71;85 08:43:27 INFO - PROCESS | 6559 | |5;imaging-gaussian-blur;89;86;89;84;85;84;87;83;86;83 08:43:27 INFO - PROCESS | 6559 | |6;imaging-darkroom;129;92;93;93;93;93;92;93;93;92 08:43:27 INFO - PROCESS | 6559 | |7;imaging-desaturate;85;87;87;85;88;85;84;84;86;87 08:43:27 INFO - PROCESS | 6559 | |8;json-parse-financial;54;55;54;54;55;54;55;55;56;56 08:43:27 INFO - PROCESS | 6559 | |9;json-stringify-tinderbox;64;72;73;74;71;69;70;71;70;71 08:43:27 INFO - PROCESS | 6559 | |10;stanford-crypto-aes;87;91;90;87;86;83;107;88;88;92 08:43:27 INFO - PROCESS | 6559 | |11;stanford-crypto-ccm;144;152;149;141;139;140;141;138;183;152 08:43:27 INFO - PROCESS | 6559 | |12;stanford-crypto-pbkdf2;170;170;172;171;173;176;163;153;240;211 08:43:27 INFO - PROCESS | 6559 | |13;stanford-crypto-sha256-iterative;68;68;68;67;68;67;67;67;66;67 08:43:27 INFO - PROCESS | 6559 | __end_tp_report 08:43:27 INFO - PROCESS | 6559 | __start_cc_report 08:43:27 INFO - PROCESS | 6559 | _x_x_mozilla_cycle_collect,1946 08:43:27 INFO - PROCESS | 6559 | __end_cc_report 08:43:27 INFO - PROCESS | 6559 | __startTimestamp1483116207765__endTimestamp 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | ------- Summary: start ------- 08:43:27 INFO - PROCESS | 6559 | Number of tests: 14 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:1.16 (1.0%) stddev-sans-first:1.20 08:43:27 INFO - PROCESS | 6559 | Values: 112.0 113.0 114.0 112.0 111.0 115.0 113.0 112.0 113.0 112.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:125.50 stddev:15.25 (12.2%) stddev-sans-first:12.85 08:43:27 INFO - PROCESS | 6559 | Values: 150.0 111.0 112.0 148.0 127.0 124.0 110.0 113.0 130.0 111.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#2] audio-dft Cycles:10 Average:187.40 Median:183.00 stddev:12.63 (6.9%) stddev-sans-first:13.40 08:43:27 INFO - PROCESS | 6559 | Values: 187.0 223.0 183.0 186.0 183.0 183.0 183.0 181.0 182.0 183.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#3] audio-fft Cycles:10 Average:73.20 Median:80.50 stddev:12.62 (15.7%) stddev-sans-first:13.14 08:43:27 INFO - PROCESS | 6559 | Values: 80.0 61.0 49.0 87.0 73.0 83.0 83.0 81.0 76.0 59.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:77.00 stddev:8.77 (11.4%) stddev-sans-first:8.40 08:43:27 INFO - PROCESS | 6559 | Values: 87.0 70.0 90.0 69.0 70.0 68.0 83.0 70.0 71.0 85.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.00 stddev:2.22 (2.6%) stddev-sans-first:1.99 08:43:27 INFO - PROCESS | 6559 | Values: 89.0 86.0 89.0 84.0 85.0 84.0 87.0 83.0 86.0 83.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#6] imaging-darkroom Cycles:10 Average:96.30 Median:93.00 stddev:11.50 (12.4%) stddev-sans-first:0.50 08:43:27 INFO - PROCESS | 6559 | Values: 129.0 92.0 93.0 93.0 93.0 93.0 92.0 93.0 93.0 92.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.50 stddev:1.40 (1.6%) stddev-sans-first:1.45 08:43:27 INFO - PROCESS | 6559 | Values: 85.0 87.0 87.0 85.0 88.0 85.0 84.0 84.0 86.0 87.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:0.79 (1.4%) stddev-sans-first:0.78 08:43:27 INFO - PROCESS | 6559 | Values: 54.0 55.0 54.0 54.0 55.0 54.0 55.0 55.0 56.0 56.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.00 stddev:2.72 (3.8%) stddev-sans-first:1.56 08:43:27 INFO - PROCESS | 6559 | Values: 64.0 72.0 73.0 74.0 71.0 69.0 70.0 71.0 70.0 71.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:88.00 stddev:6.54 (7.4%) stddev-sans-first:6.85 08:43:27 INFO - PROCESS | 6559 | Values: 87.0 91.0 90.0 87.0 86.0 83.0 107.0 88.0 88.0 92.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:146.50 stddev:13.40 (9.1%) stddev-sans-first:14.14 08:43:27 INFO - PROCESS | 6559 | Values: 144.0 152.0 149.0 141.0 139.0 140.0 141.0 138.0 183.0 152.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.90 Median:172.50 stddev:25.80 (15.0%) stddev-sans-first:27.11 08:43:27 INFO - PROCESS | 6559 | Values: 170.0 170.0 172.0 171.0 173.0 176.0 163.0 153.0 240.0 211.0 08:43:27 INFO - PROCESS | 6559 | 08:43:27 INFO - PROCESS | 6559 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:0.67 (1.0%) stddev-sans-first:0.67 08:43:27 INFO - PROCESS | 6559 | Values: 68.0 68.0 68.0 67.0 68.0 67.0 67.0 67.0 66.0 67.0 08:43:27 INFO - PROCESS | 6559 | -------- Summary: end -------- 08:43:27 INFO - PROCESS | 6559 | 08:43:28 INFO - TEST-INFO | 6559: exit 0 08:43:28 INFO - TEST-OK | kraken | took 59212ms 08:43:28 INFO - SUITE-END | took 1179s 08:43:28 INFO - Completed test suite (00:19:39) 08:43:28 INFO - javascript benchmark 08:43:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3319.2863534340095, 3329.66703329667, 3333.69996700297, 3342.364950659373, 3396.473546600276, 5544.833654990347, 5667.631603945746, 5684.431556844308, 5686.374498805133, 5687.6303040302355, 5660.518855897254, 5700.0, 5787.594868359217, 5790.652560846353, 5795.246617939672, 5120.4623514530995, 5144.202648589475, 5189.091908915943, 5224.843254702346, 5238.816641417551, 5306.8407947761625, 5312.840614781563, 5318.282031925699, 5320.787168513274, 5360.517553420192, 5150.484951504857, 5207.713575753332, 5225.242339860737, 5240.318758561381, 5252.579793616521, 5187.351581052369, 5188.584913206934, 5223.268742376071, 5231.529162375405, 5251.264822924793, 5167.586593072545, 5193.298904647862, 5202.583793296565, 5233.241180028875, 5259.579233661336, 4690.742009189493, 5576.9125020620995, 5731.398203188689, 5752.5110365618975, 5763.538916886639, 6096.329403765582, 6104.45915544718, 6137.570370074097, 6146.323904370515, 6191.81424557264, 6099.847503812396, 6120.294761999575, 6128.442013630915, 6170.382961703838, 6180.907286390708, 5663.971680141655, 5706.885862282773, 5727.942720572824, 5758.193852860602, 5791.478766910998, 6081.817545473555, 6177.320494745664, 6193.071039344134, 6205.410486003822, 6205.596636218631, 6061.969690151521, 6062.393760623902, 6073.908891366633, 6084.84387966285, 6086.6652334122045, 6094.360092190344, 6101.603395779349, 6111.083337499411, 6111.622125543816, 6112.208004058284, 6087.904177248138, 6148.831721972811, 6161.168242287324, 6169.81490555284, 6174.629522228591, 6129.682118344577, 6148.44663980244, 6153.138560601519, 6156.168917196211, 6162.969185154046, 4845.030993801254, 4900.215965445512, 4902.2646603009125, 4910.312556242129, 5002.274670172805, 4410.492793328808, 4416.624586910084, 4422.911541769146, 4465.0623369092855, 4468.955310446919, 5821.18503409523, 5838.0, 5856.092305692625, 5867.853303667357, 5870.530357571384, 6078.300995385587, 6172.567920245539, 6176.166217560572, 6184.598001130003, 6185.41238582334, 5716.1997320375185, 5726.169705392778, 5782.161586569924, 5784.652920824764, 5845.561582881216, 5399.919001214985, 5418.620696551282, 5429.67421954684, 5431.972840135774, 5481.588880833953, 6343.777967771198, 6351.460125889259, 6353.904691429632, 6354.968225158845, 6371.076193951943, 6109.75560977556, 6131.40725074338, 6138.2044803098925, 6173.043178307369, 6196.690165491797, 6156.630602163885, 6246.781362652286, 6256.280527739368, 6315.3053164152825, 6326.683665816783, 6065.150878877049, 6117.979653188298, 6119.265688117454, 6124.081387791867, 6136.3046386708, 6105.877882442415, 6135.538906164888, 6165.969170154121, 6173.444390004921, 6185.226846644169, 6131.468419844996, 6140.140380346666, 6141.048137538689, 6166.876662466815, 6171.012637978081, 6128.448439640454, 6148.292946311231, 6155.661438620739, 6155.969220153871, 6158.107074474086, 5800.1299805029585, 5833.708314584169, 5875.3243377012195, 5926.851828704317, 5965.015772397679, 5965.791197308074, 6107.947487154961, 6121.300552271563, 6121.788327166137, 6129.601575897553, 5180.834312279707, 5942.405759424023, 5950.077737950624, 5982.1325907744, 6009.2188015557695], "value": 5679.382344861827, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.74995002998185, 451.9379458273059, 465.44611911824927, 482.9903401931961, 516.279789693378, 23887.880560597194, 31541.68458315416, 31620.525692114606, 38246.80876595613, 40207.195856082864, 41078.58921410785, 41212.79393603035, 41394.37908431368, 41426.585734142725, 41478.792606037, 9247.815043699122, 9453.81092378152, 9461.195798357114, 9476.43141411514, 9476.43141411514, 8517.0, 8728.170823771732, 9253.95373023134, 9369.718908432724, 11104.056155226775, 11081.889181108207, 11345.602903898365, 11354.489047992818, 11357.488912998893, 11392.373419461926, 11384.146189035813, 11449.713757156054, 11462.48418821155, 11474.0, 11495.0, 7268.345848873574, 8675.305975522006, 10943.945280273627, 11246.606368777135, 11257.54969801207, 39070.804645976874, 39618.603813962065, 39761.005974850574, 39810.40284395737, 39828.402573961415, 3442.4319987202334, 3899.415087736834, 3944.8027598619956, 3955.802209889494, 3966.464527288808, 29115.708842911725, 30323.0, 30474.0, 30484.390312193853, 30625.081247562608, 2871.0238518903466, 3082.3835232953497, 3196.6323872754465, 3222.1139186723603, 3251.7073463388174, 458673.66579177603, 463429.0, 465867.0, 465936.0, 467421.0, 59326.70336648289, 59813.0, 59819.70090149521, 59907.700461497414, 60072.69963650154, 63255.36744632495, 63457.68271158707, 63613.68193159004, 63664.363356365844, 63736.68131659405, 15009.249537523081, 15592.532224033297, 15593.922030390002, 15597.220138993232, 15597.610059748371, 12016.71682380345, 12051.096167787451, 12057.819132713017, 12062.336571488653, 12063.336516491501, 15222.086674799326, 15624.453144139901, 15658.416361300486, 16016.75974860378, 16021.43657014872, 6064.696765161724, 6555.475561955032, 6987.510874238857, 7031.437485001187, 7193.676284567207, 841.5441286574138, 846.6528723223572, 848.6775025490274, 879.9660399031113, 889.5996801439378, 29039.56440653392, 29199.0, 29466.82174644472, 29473.410531789672, 29535.556966645516, 1005.7133716890651, 1029.2435060230725, 1030.525958059286, 1051.2115913065202, 1056.5140035583563, 14538.563843084725, 15198.392064317304, 15204.315805788767, 15291.464798731991, 15370.0, 579177.0, 587835.0, 588559.0, 590593.0470347621, 590905.0, 56366.0, 56533.71733141308, 56617.716911416006, 56628.43371566314, 56888.43111568831, 1346.9730605387838, 1378.9724205516034, 1401.726663300667, 1403.2142000479764, 1404.3048690898072, 22636.0, 27686.615669216055, 28954.13137605875, 29247.70752292535, 29271.682774275636, 48979.26531101965, 49440.0, 49453.50546494633, 49517.752411237714, 49553.50446495633, 507771.3005829345, 508457.0, 511410.0, 511492.0, 513800.0, 24259.87870060709, 24940.002399904526, 25053.624195637443, 38680.6131938677, 39255.60744392634, 35729.1067723309, 36609.63390366062, 39854.2029159421, 41476.37785433161, 41571.7921410391, 34205.65794342025, 34694.82652586721, 34856.65143348635, 35637.0, 35870.8206458966, 33600.663993359754, 34009.31981360408, 34427.0, 35092.473612895315, 35560.82219588885, 32823.67176328206, 33571.49642755312, 33575.16062098467, 34834.65165348314, 35102.12244693903, 22897.88551057234, 29698.703012970458, 29719.959801406847, 30804.0, 31151.221219469695, 22617.869106544935, 22986.0, 23290.76709232886, 23466.53066938686, 23557.411064723517], "value": 19176.579179315017, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.33543441481635, 369.86499927526444, 375.9529709758323, 391.90986073203146, 398.8185375654078, 16390.180490975432, 18354.265829366825, 19133.904330478344, 20075.0, 20191.394258172277, 11814.40927953601, 12614.684632884182, 18029.0, 20593.38219853403, 20600.38198854037, 5076.441591424949, 5299.8410047698435, 5303.045451818671, 5303.893922121555, 5316.707581083039, 4508.842190523333, 4988.825391111312, 5145.562627176694, 5173.715445650488, 5187.636865419422, 5187.377514698242, 5212.791488340462, 5219.843404697846, 5235.057689615867, 5237.947620523803, 5016.072026675077, 5189.974050129763, 5228.37259528859, 5234.057869583474, 5254.106801843695, 5043.293938848564, 5092.414372347153, 5122.3096378195105, 5124.128898087334, 5124.4106927703415, 13890.374933128034, 23689.526209475887, 24359.391015224584, 24360.26919192427, 24634.876825615935, 2587.0, 2618.5417551928335, 2625.1993142091655, 2656.907008254721, 2662.733726627341, 9660.420374777535, 9964.701058968243, 10200.285979981405, 10304.124149447305, 12508.061895357885, 1783.545195975031, 1796.2096677461875, 1801.926881635126, 1826.5707558723689, 1841.5119993201813, 115465.84534154719, 119803.4009829954, 120090.39954800258, 120178.99552511169, 120616.39691801484, 10877.292975956538, 11346.716332091599, 11486.0811135109, 11503.712407189887, 11696.532138714527, 14659.34032968519, 14661.34023968924, 14693.853061469248, 14703.852961470462, 14707.55877323682, 7908.5650289234645, 8205.384596155192, 8227.218414250636, 8299.875501867476, 8404.327653787683, 7899.921000789918, 7995.600219988977, 7999.1600881907725, 8158.224968627971, 8161.143079976636, 4512.120136573402, 4522.909541809146, 4528.252838281713, 4551.067031258584, 4679.3916790816975, 1866.7479890214918, 1920.596674698301, 1923.5864289177894, 1923.7691477022568, 1928.4889504281377, 855.2260204514924, 868.2749903830372, 913.5340976102103, 928.4011812380976, 929.0848514213494, 14454.566363009126, 18517.81482185188, 20795.896020519802, 21232.938353082285, 22250.666240006412, 884.2925659472525, 889.7953470701775, 907.5054095517959, 914.5335878701777, 914.5427286356821, 5919.230500035054, 6622.437092847065, 6638.900416493757, 6820.727170913207, 6856.348646878539, 557728.0, 562306.0, 574162.0, 575137.0, 577948.0, 91063.0, 113255.43372283086, 113375.43312283386, 119239.55952628347, 119347.40326298313, 970.1171933540585, 973.5278389980854, 1022.1005515146624, 1026.9332493387906, 1026.9537870795682, 19122.808771912103, 19333.806661933762, 19355.709664354763, 19445.31941382045, 19469.0, 46377.84055398642, 53063.48960338414, 55650.0, 56009.0, 56113.43886561082, 77048.0, 79602.0, 80681.0, 81239.40862218909, 82193.0, 6663.400293973566, 7949.523028618301, 8084.87872681922, 8141.674333026849, 8199.467034642728, 11484.540618375171, 12395.62813115608, 12473.064520160842, 12930.288834114213, 12962.962962963129, 9530.046995300414, 10898.182636302468, 10986.945065274622, 10994.230403871652, 11051.90013897357, 9391.906080939103, 10520.894791052298, 10613.9469302653, 11078.889211108108, 11112.888871111185, 10985.505652245509, 11062.0, 11374.601888933848, 11439.14206434504, 11486.483108259981, 7991.800204994922, 8046.0, 8109.959450202711, 8111.39164562672, 8138.064122625974, 9499.620015199314, 10951.288166269167, 11128.109751220045, 11210.943945280222, 11211.60759373418], "value": 9093.776401686275, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1290.8257385252991, 1397.308332375474, 1408.7745960646291, 1435.3110506956655, 1438.2952353346864, 8045.517268963859, 8447.36644751643, 8483.448575842565, 8484.957575212124, 8533.530655813927, 7444.925550744491, 7665.808354791133, 7679.6928122875015, 7995.120536740968, 8267.793305167374, 2276.123692378438, 2326.8487548309304, 2329.149860300995, 2332.2070496031347, 2332.790048895599, 2300.1374484568287, 2333.230034088755, 2338.5790557699606, 2338.7193536775526, 2341.2507997440825, 2037.500812300981, 2112.6302896993043, 2226.6214743493647, 2280.9543809123807, 2304.101400453816, 2174.9456263593456, 2279.168103642175, 2283.748787633359, 2288.8054515366216, 2289.084366253512, 1957.725918371429, 2278.8833471598796, 2306.573283942476, 2307.14635584833, 2327.685762422085, 8261.586920653943, 8340.708075217339, 8340.958295208486, 8341.41610087294, 8350.498970061815, 5007.0, 5179.223116532513, 5189.273501709764, 5201.739913004335, 5205.791768329263, 3218.6620404857385, 3403.8127902965325, 3424.3493736190294, 3442.4836274559016, 3462.3767721810063, 1138.6868611131922, 1170.2101081769772, 1206.541514224589, 1215.4834195466892, 1227.5028613411582, 10729.785404291948, 10778.0, 10798.406087665175, 10805.513751881106, 10825.78348433027, 4251.44731184944, 4491.079328737601, 4560.794764235618, 4568.74871882043, 4572.314152877095, 9439.811203776024, 9460.8580871287, 9464.1009104135, 9465.432074075577, 9474.242060635133, 5167.940572182693, 5176.1459359206065, 5176.611754118456, 5189.0, 5197.454267301954, 4245.299525578307, 4383.473983122046, 4567.520410356931, 4685.320628508847, 4751.026039661861, 3068.938621227563, 3747.2318174774105, 3790.223004284115, 3839.9040023999623, 3879.1078052048188, 1247.8253044573585, 1256.9371531423392, 1257.528426839935, 1264.5068423314915, 1265.6899059730424, 633.4045996763028, 753.6909866954632, 769.2230846844727, 774.8915151878741, 787.1223585701902, 5279.551238144801, 5433.809816656398, 5523.6685798852195, 5600.243967064475, 5689.203511508392, 878.7407714724171, 885.2121611765534, 886.4504007515382, 896.3680605173337, 934.8878134623889, 3569.803660798629, 3589.210373717778, 3646.6535679110443, 3687.3362794697214, 3754.131788472357, 9511.714648560554, 9918.603255869684, 9971.351862128939, 10037.548310326052, 10039.598416063422, 10061.245406594535, 10071.942446043207, 10074.042965918226, 10080.0, 10092.192624590016, 6462.644554549451, 7600.923990760021, 7769.0, 7884.921150788418, 7904.604769761489, 5340.279062326692, 5366.141417373357, 5441.945580544144, 5501.944980550144, 5643.633163844337, 9273.119053689888, 9277.62889484413, 9281.814363712552, 9315.487648179414, 9376.343655944018, 9277.211437027658, 9397.765055873659, 9491.525423728648, 9605.37565058269, 9637.662681806103, 6587.4071333580205, 6823.795286141423, 6833.521653484208, 6835.4189893859575, 6836.93163068363, 7682.423818213768, 7814.726484573012, 7828.217178282354, 7904.525728456311, 7939.6030198491, 5694.886102278014, 7105.893411598727, 7141.714331426685, 7169.0, 7234.710611575478, 6837.247902730795, 6918.411934985583, 6925.168979722465, 6981.127359080115, 6986.650667466708, 6245.96877015612, 6254.0, 6384.7446102157255, 6386.329435409215, 6391.616503009835, 4576.244919588231, 4681.929771053505, 4719.480857105785, 4734.952650473589, 4849.442314133778, 4615.88105344161, 4617.399738034034, 4733.029728905635, 4867.853964381075, 4876.317315575751], "value": 4329.9377396863065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.633572880408, 348.4337950829902, 359.4320972862876, 363.2299525006986, 374.40843467321633, 5069.2649565812935, 5168.147255702814, 5172.560332371748, 5188.0, 5191.1953647184655, 4790.928136077953, 5094.261332106842, 5130.794768209276, 5132.3071385363055, 5138.2806407103035, 3688.7827017084296, 3840.6955826504154, 3972.443857859902, 3987.940180897289, 4079.4288799568085, 3579.4630805379143, 3740.4202348635868, 3879.010852232677, 4010.9799451002705, 4100.753954762724, 4028.3151864183014, 4039.6467183493564, 4049.129437171008, 4055.9797201013953, 4067.3288907330257, 2994.940101197964, 3211.811629697004, 3511.613722490524, 3699.7225208109367, 3707.703383729295, 3256.527803468484, 3547.7871327720163, 3584.2473080653094, 3605.675489205958, 3611.8735844245325, 4551.453825540956, 4656.2284549515, 4746.098241334135, 4989.725565093919, 5009.373828271466, 3991.381335892941, 4130.814113364906, 4398.934015989763, 4535.229011068126, 4649.721326635203, 5103.770330335144, 5321.020228671918, 5370.892582148335, 5382.569394448474, 5430.647007944471, 3946.4474973503447, 3947.407888816672, 3951.269015232191, 3959.3269144245546, 3978.6021397860272, 5126.4364368867855, 5185.496206100189, 5195.090859099718, 5216.556592689626, 5379.488948549882, 3435.6220815710253, 3546.925048846402, 3704.962950370462, 4007.8396864125107, 4035.8385664573675, 3697.8351819176874, 3855.5951625078965, 4068.2066996935314, 4072.0, 4074.0, 4592.380028696083, 4619.376384188159, 4633.675642705046, 4651.4418269807165, 4673.789679464432, 4679.800493767923, 5135.820246291362, 5342.011727830364, 5474.151506516497, 5495.697736624444, 1114.2645853736494, 1271.879171478708, 1327.7610030194658, 1330.9933450332687, 1332.8004795683962, 752.221450798432, 914.2594498456139, 923.2798417234568, 929.5398777605129, 936.0125068053269, 4067.1662309226535, 4234.216669916075, 4865.443058221406, 4958.52893975079, 5253.791627925599, 4643.956659069999, 4786.688865223749, 5326.36083669962, 5571.192177134374, 5606.97196514023, 4460.754658493814, 4645.581897629229, 4753.952460475351, 4843.8999040614035, 5107.284980102789, 2420.370703617072, 2555.3169172369307, 2571.9712115153716, 2801.7058208887775, 2813.268550176969, 4983.558056552263, 4989.80040798364, 5173.22401639757, 5557.499825015687, 5557.749901254453, 4723.291506274087, 5238.8166414176085, 5245.763940622681, 5350.4649535046965, 5455.727213639302, 4968.205087186033, 5071.6130547077255, 5532.640378375393, 5537.363203231599, 5551.7779288828, 4630.6758526902795, 4953.554180123806, 5103.693778373309, 5286.76209570578, 5370.41572736036, 4921.286413469955, 4965.875853103701, 5064.772085256099, 5228.928069745617, 5512.283403157564, 4856.63575231852, 4931.580815630711, 5141.871453213699, 5810.6513609183585, 5816.941830581809, 3198.648148703687, 3801.498408128829, 3819.5416550014174, 3826.9808650956566, 3834.5206849143856, 4557.088582283623, 4766.380370551806, 5237.580993520433, 5322.494363035506, 5581.358143813513, 4590.609798167193, 5019.422766381766, 5184.662996905189, 5476.67139971603, 5487.972560137174, 4553.929826490706, 4629.120467111373, 5209.6874187548865, 5331.960267747752, 5409.404965453876, 4712.62299016071, 5126.07772085468, 5366.892662146814, 5383.569314454912, 5402.405735369028, 5168.922466162935, 5336.265713643004, 5366.6779993200535, 5442.319710036246, 5478.616496845182, 5070.200078971912, 5341.412444631165, 5345.38528069277, 5353.22378255143, 5367.651102678314], "value": 3941.5391896188753, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.476892330752, 709.9929000709991, 746.0003595182454, 776.4564804636755, 804.7947773317803, 14395.64010899728, 15403.383864645415, 15405.306761195745, 15420.922895385507, 15435.228238588083, 13724.176549407042, 13880.514182003632, 13896.0, 13912.93043534781, 13916.582502524916, 655.7114869457423, 678.8099332186971, 679.6262055869253, 820.7332616899502, 837.9120192379804, 835.6323217784186, 848.0, 852.5864955496579, 855.0295414704324, 857.9356548258875, 884.4869975414244, 903.0157128729684, 906.9591868365907, 907.1790030022806, 909.381620498061, 862.7023676831483, 863.6329559937002, 863.7192912303526, 881.8721285413643, 890.2432932007806, 511.1132185657881, 528.2419727690738, 648.0538413915689, 662.3244290823382, 671.8521925176453, 14377.281135943162, 14387.640308992255, 14417.207053612046, 14419.0, 14434.494792682312, 10576.629817956335, 12769.87230127696, 12833.0, 12860.742785144244, 12962.0, 11748.647540573797, 11819.704507387298, 11865.52537898483, 11901.821472677917, 12251.69370765738, 4265.914681706379, 4351.108022855307, 4368.951451651581, 4376.212281789277, 4469.597736203757, 73482.8977565337, 95482.0, 100909.495452523, 101860.0, 102493.0, 9645.662401815904, 9827.361221520721, 9921.057499537677, 9952.203823694088, 10171.440570768531, 18219.271229150687, 18577.721334179998, 18733.531661708566, 18752.0, 18827.15277812502, 7300.671469783872, 7341.59621220838, 7499.662515186829, 7543.358814500829, 7558.433117516208, 4078.2659121357847, 4134.607212314825, 4135.048938744046, 4137.627613514798, 4143.316352801814, 3198.9680103199134, 3248.772585918963, 3267.2648654053123, 3279.4752839546036, 3289.194147433894, 602.6474512410201, 609.7560975609703, 611.7767015039567, 628.8836565235355, 629.2826178156905, 491.229465812816, 500.3820281951787, 524.9684370212551, 564.3481778545786, 604.7611193578512, 7810.531368117931, 8287.171282871785, 8449.662013519513, 8630.654773808978, 8780.824383512421, 571.5085026876883, 615.1449485215554, 615.5691016288616, 620.3951147631024, 632.8038308124496, 4070.8371665133063, 4098.098418347959, 4206.915861682811, 4218.430511880918, 4357.891052723708, 62709.37290627127, 96220.0, 98495.0, 100393.49803250936, 103204.0, 58992.705036474545, 70226.64886675637, 74718.13204669819, 76467.0, 85876.0, 70250.9462358065, 71498.9275160873, 73023.63488182524, 75383.0, 76411.0, 9206.355555111079, 9656.71029869105, 9837.213022958003, 9855.31012829095, 10026.24803139782, 41736.0, 46623.834404140165, 50957.47127586178, 53567.464325356246, 53768.462315376346, 60693.0, 64117.358826413, 64261.35738642554, 64919.72780952644, 65620.67189664021, 10984.725381865517, 11417.673865662835, 13498.98757593204, 13810.516631917835, 13862.584122476343, 14472.27638618086, 14573.489927852474, 14695.338709757872, 14865.0, 15389.153596551849, 13745.725085498034, 13943.302834858014, 14115.858841411866, 14361.425542978164, 14398.0, 13174.341282936006, 13693.31533423305, 13919.4432222714, 14181.43274269059, 14220.431182752574, 11358.659240222807, 13420.664483387993, 13497.527586534425, 13519.0, 13959.302034898012, 9913.405195688281, 10048.447335396624, 10299.3820370778, 10574.788504230026, 10601.522931468257, 10197.439140847027, 10874.67141100839, 10964.56141754321, 10983.901609838953, 11078.0], "value": 7102.178431182544, "unit": "score"}], "name": "dromaeo_css", "value": 7023.719875361596, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 114.0, 112.0, 111.0, 115.0, 113.0, 112.0, 113.0, 112.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 111.0, 112.0, 148.0, 127.0, 124.0, 110.0, 113.0, 130.0, 111.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 223.0, 183.0, 186.0, 183.0, 183.0, 183.0, 181.0, 182.0, 183.0], "value": 187.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 49.0, 87.0, 73.0, 83.0, 83.0, 81.0, 76.0, 59.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 90.0, 69.0, 70.0, 68.0, 83.0, 70.0, 71.0, 85.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 84.0, 85.0, 84.0, 87.0, 83.0, 86.0, 83.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 92.0, 93.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 92.0], "value": 96.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 87.0, 85.0, 88.0, 85.0, 84.0, 84.0, 86.0, 87.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 54.0, 55.0, 54.0, 55.0, 55.0, 56.0, 56.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 73.0, 74.0, 71.0, 69.0, 70.0, 71.0, 70.0, 71.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 91.0, 90.0, 87.0, 86.0, 83.0, 107.0, 88.0, 88.0, 92.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 152.0, 149.0, 141.0, 139.0, 140.0, 141.0, 138.0, 183.0, 152.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 172.0, 171.0, 173.0, 176.0, 163.0, 153.0, 240.0, 211.0], "value": 179.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 68.0, 67.0, 67.0, 67.0, 66.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1451.2, "alertThreshold": 2.0}]} 08:43:28 INFO - Return code: 0 08:43:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:43:29 INFO - # TBPL SUCCESS # 08:43:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:43:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:43:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:43:29 INFO - 2016-12-30 08:43:29.070 screenresolution[6563:119790] starting screenresolution argv=screenresolution get 08:43:29 INFO - 2016-12-30 08:43:29.073 screenresolution[6563:119790] Display 0: 1600x1200x32@60 08:43:29 INFO - 2016-12-30 08:43:29.079 screenresolution[6564:119793] starting screenresolution argv=screenresolution list 08:43:29 INFO - Available Modes on Display 0 08:43:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:43:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:43:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:43:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:43:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:43:29 INFO - Intel Iris: 08:43:29 INFO - Chipset Model: Intel Iris 08:43:29 INFO - Type: GPU 08:43:29 INFO - Bus: Built-In 08:43:29 INFO - VRAM (Dynamic, Max): 1536 MB 08:43:29 INFO - Vendor: Intel (0x8086) 08:43:29 INFO - Device ID: 0x0a2e 08:43:29 INFO - Revision ID: 0x0009 08:43:29 INFO - Displays: 08:43:29 INFO - Display: 08:43:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:43:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:43:29 INFO - Display Serial Number: 1600x1200 60 08:43:29 INFO - Main Display: Yes 08:43:29 INFO - Mirror: Off 08:43:29 INFO - Online: Yes 08:43:29 INFO - Rotation: Supported 08:43:29 INFO - Return code: 0 08:43:29 INFO - Running post-action listener: _resource_record_post_action 08:43:29 INFO - [mozharness: 2016-12-30 16:43:29.317640Z] Finished run-tests step (success) 08:43:29 INFO - Running post-run listener: _resource_record_post_run 08:43:29 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 1371136; Write bytes: 549714944; Read time: 17; Write time: 1642 08:43:29 INFO - TinderboxPrint: CPU usage
53.6% 08:43:29 INFO - TinderboxPrint: I/O read bytes / time
1,371,136 / 17 08:43:29 INFO - TinderboxPrint: I/O write bytes / time
549,714,944 / 1,642 08:43:29 INFO - TinderboxPrint: CPU idle
2,228.5 (46.4%) 08:43:29 INFO - TinderboxPrint: CPU system
960.6 (20.0%) 08:43:29 INFO - TinderboxPrint: CPU user
1,616.6 (33.6%) 08:43:29 INFO - TinderboxPrint: Swap in / out
251,219,968 / 0 08:43:29 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228737536; Write bytes: 221278208; Read time: 16371; Write time: 430 08:43:29 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1371136; Write bytes: 299387904; Read time: 17; Write time: 1155 08:43:29 INFO - Running post-run listener: _upload_blobber_files 08:43:29 INFO - Blob upload gear active. 08:43:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:43:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:43:29 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'] 08:43:29 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 08:43:30 INFO - (blobuploader) - INFO - Open directory for files ... 08:43:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:43:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:43:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:30 INFO - (blobuploader) - INFO - Done attempting. 08:43:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:43:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:43:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:31 INFO - (blobuploader) - INFO - Done attempting. 08:43:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:43:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:43:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:32 INFO - (blobuploader) - INFO - Done attempting. 08:43:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:43:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:43:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:35 INFO - (blobuploader) - INFO - Done attempting. 08:43:35 INFO - (blobuploader) - INFO - Iteration through files over. 08:43:35 INFO - Return code: 0 08:43:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:43:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:43:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89142d92e5cb0b44ad500089443a492342e7c8e6a7e984bcc00edae31cacee87ee47347580bf7ba8b8b729d07718fad2f2fd0c563e93a4619ee274ba26b94f81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26f537e406647b9f7fd59914a2fbcce90222bb979083d3b9a51ea3abf5e2e264b6c9fe924b3d1a18b6b55c3d8cd82bd40bb33f251c752daffa4ca4f6a074a0c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a02ed8139c52aed5a100e78489d89f4c253a99bc702fa3adacbe443a79cc963607f25be0bfb45f0691babe0354b6d01fd9cbc3c5cfc5819961d11759ba52239", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b49e1fb1bc190add670daeda49fe854afb409529e6e5921fbbc7ac3771de671e84c3e0fce1216cc8a17c36c54975e8e0948817244f83fcc84b8ac3f676bcdf8"} 08:43:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:43:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:43:35 INFO - Contents: 08:43:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89142d92e5cb0b44ad500089443a492342e7c8e6a7e984bcc00edae31cacee87ee47347580bf7ba8b8b729d07718fad2f2fd0c563e93a4619ee274ba26b94f81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26f537e406647b9f7fd59914a2fbcce90222bb979083d3b9a51ea3abf5e2e264b6c9fe924b3d1a18b6b55c3d8cd82bd40bb33f251c752daffa4ca4f6a074a0c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a02ed8139c52aed5a100e78489d89f4c253a99bc702fa3adacbe443a79cc963607f25be0bfb45f0691babe0354b6d01fd9cbc3c5cfc5819961d11759ba52239", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b49e1fb1bc190add670daeda49fe854afb409529e6e5921fbbc7ac3771de671e84c3e0fce1216cc8a17c36c54975e8e0948817244f83fcc84b8ac3f676bcdf8"} 08:43:35 INFO - Running post-run listener: copy_logs_to_upload_dir 08:43:35 INFO - Copying logs to upload dir... 08:43:35 INFO - mkdir: /builds/slave/test/build/upload/logs 08:43:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.497070 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-30 08:43:35.205540) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 08:43:35.208660) ========= 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.N3bU4igER5/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.uMRgFvnMqj/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/89142d92e5cb0b44ad500089443a492342e7c8e6a7e984bcc00edae31cacee87ee47347580bf7ba8b8b729d07718fad2f2fd0c563e93a4619ee274ba26b94f81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26f537e406647b9f7fd59914a2fbcce90222bb979083d3b9a51ea3abf5e2e264b6c9fe924b3d1a18b6b55c3d8cd82bd40bb33f251c752daffa4ca4f6a074a0c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a02ed8139c52aed5a100e78489d89f4c253a99bc702fa3adacbe443a79cc963607f25be0bfb45f0691babe0354b6d01fd9cbc3c5cfc5819961d11759ba52239", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b49e1fb1bc190add670daeda49fe854afb409529e6e5921fbbc7ac3771de671e84c3e0fce1216cc8a17c36c54975e8e0948817244f83fcc84b8ac3f676bcdf8"} build_url:https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011450 build_url: 'https://queue.taskcluster.net/v1/task/Ef67-HXIRlSoPlohOwihsg/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/89142d92e5cb0b44ad500089443a492342e7c8e6a7e984bcc00edae31cacee87ee47347580bf7ba8b8b729d07718fad2f2fd0c563e93a4619ee274ba26b94f81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26f537e406647b9f7fd59914a2fbcce90222bb979083d3b9a51ea3abf5e2e264b6c9fe924b3d1a18b6b55c3d8cd82bd40bb33f251c752daffa4ca4f6a074a0c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a02ed8139c52aed5a100e78489d89f4c253a99bc702fa3adacbe443a79cc963607f25be0bfb45f0691babe0354b6d01fd9cbc3c5cfc5819961d11759ba52239", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b49e1fb1bc190add670daeda49fe854afb409529e6e5921fbbc7ac3771de671e84c3e0fce1216cc8a17c36c54975e8e0948817244f83fcc84b8ac3f676bcdf8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 08:43:35.243840) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:43:35.244203) ========= 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.N3bU4igER5/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.uMRgFvnMqj/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005328 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:43:35.296045) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 08:43:35.299403) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 08:43:35.299767) ========= ========= Total master_lag: 0.21 =========