builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0248 starttime: 1485848486.58 results: success (0) buildid: 20170130220808 builduid: 2e8a848060574263ab4d66c4626b69c6 revision: 9c06e744b1befb3a2e2fdac7414ce18220774a1d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:26.578865) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:26.579259) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:26.579540) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:26.677929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:26.678283) ========= 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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-30 23:41:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.9M=0.001s 2017-01-30 23:41:26 (17.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165325 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:26.871181) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:26.871502) ========= 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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.026792 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:26.936799) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-30 23:41:26.937143) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9c06e744b1befb3a2e2fdac7414ce18220774a1d --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 9c06e744b1befb3a2e2fdac7414ce18220774a1d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mlUvFY8I8Z/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.3HgITWbg0T/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-30 23:41:27,047 truncating revision to first 12 chars 2017-01-30 23:41:27,048 Setting DEBUG logging. 2017-01-30 23:41:27,048 attempt 1/10 2017-01-30 23:41:27,048 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9c06e744b1be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-30 23:41:28,826 unpacking tar archive at: mozilla-central-9c06e744b1be/testing/mozharness/ program finished with exit code 0 elapsedTime=2.052162 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-30 23:41:29.015269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:29.015546) ========= script_repo_revision: 9c06e744b1befb3a2e2fdac7414ce18220774a1d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:29.015923) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:29.016193) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 23:41:29.038728) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-30 23:41:29.039027) ========= /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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 23:41:29 INFO - MultiFileLogger online at 20170130 23:41:29 in /builds/slave/test 23:41:29 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 23:41:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:41:29 INFO - {'append_to_log': False, 23:41:29 INFO - 'base_work_dir': '/builds/slave/test', 23:41:29 INFO - 'blob_upload_branch': 'Firefox', 23:41:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:41:29 INFO - 'branch': 'Firefox', 23:41:29 INFO - 'buildbot_json_path': 'buildprops.json', 23:41:29 INFO - 'config_files': ('talos/mac_config.py',), 23:41:29 INFO - 'default_actions': ('clobber', 23:41:29 INFO - 'read-buildbot-config', 23:41:29 INFO - 'download-and-extract', 23:41:29 INFO - 'populate-webroot', 23:41:29 INFO - 'create-virtualenv', 23:41:29 INFO - 'install', 23:41:29 INFO - 'run-tests'), 23:41:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:41:29 INFO - 'download_minidump_stackwalk': True, 23:41:29 INFO - 'download_symbols': 'ondemand', 23:41:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:41:29 INFO - 'tooltool.py': '/tools/tooltool.py', 23:41:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:41:29 INFO - '/tools/misc-python/virtualenv.py')}, 23:41:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:41:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:41:29 INFO - 'installer_path': 'installer.exe', 23:41:29 INFO - 'log_level': 'info', 23:41:29 INFO - 'log_name': 'talos', 23:41:29 INFO - 'log_to_console': True, 23:41:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:41:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:41:29 INFO - 'opt_config_files': (), 23:41:29 INFO - 'pip_index': False, 23:41:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:41:29 INFO - 'cmd': ('bash', 23:41:29 INFO - '-c', 23:41:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:41:29 INFO - 'enabled': True, 23:41:29 INFO - 'halt_on_failure': False, 23:41:29 INFO - 'name': 'check_screen_resolution'},), 23:41:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:41:29 INFO - 'cmd': ('bash', 23:41:29 INFO - '-c', 23:41:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:41:29 INFO - 'enabled': True, 23:41:29 INFO - 'halt_on_failure': False, 23:41:29 INFO - 'name': 'check_screen_resolution'},), 23:41:29 INFO - 'run_cmd_checks_enabled': True, 23:41:29 INFO - 'sps_profile': False, 23:41:29 INFO - 'sps_profile_interval': 0, 23:41:29 INFO - 'suite': 'dromaeojs', 23:41:29 INFO - 'system_bits': '32', 23:41:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:41:29 INFO - 'title': 't-yosemite-r7-0248', 23:41:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:41:29 INFO - 'use_talos_json': True, 23:41:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:41:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:41:29 INFO - 'work_dir': 'build'} 23:41:29 INFO - [mozharness: 2017-01-31 07:41:29.253081Z] Running clobber step. 23:41:29 INFO - Running pre-action listener: _resource_record_pre_action 23:41:29 INFO - Running main action method: clobber 23:41:29 INFO - rmtree: /builds/slave/test/build 23:41:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:41:31 INFO - Running post-action listener: _resource_record_post_action 23:41:31 INFO - [mozharness: 2017-01-31 07:41:31.722768Z] Finished clobber step (success) 23:41:31 INFO - [mozharness: 2017-01-31 07:41:31.722897Z] Running read-buildbot-config step. 23:41:31 INFO - Running pre-action listener: _resource_record_pre_action 23:41:31 INFO - Running main action method: read_buildbot_config 23:41:31 INFO - Using buildbot properties: 23:41:31 INFO - { 23:41:31 INFO - "project": "", 23:41:31 INFO - "product": "firefox", 23:41:31 INFO - "script_repo_revision": "production", 23:41:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 23:41:31 INFO - "repository": "", 23:41:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 23:41:31 INFO - "buildid": "20170130220808", 23:41:31 INFO - "pgo_build": "False", 23:41:31 INFO - "basedir": "/builds/slave/test", 23:41:31 INFO - "buildnumber": 76, 23:41:31 INFO - "slavename": "t-yosemite-r7-0248", 23:41:31 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 23:41:31 INFO - "platform": "yosemite_r7", 23:41:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 23:41:31 INFO - "branch": "mozilla-central", 23:41:31 INFO - "repo_path": "mozilla-central", 23:41:31 INFO - "revision": "9c06e744b1befb3a2e2fdac7414ce18220774a1d", 23:41:31 INFO - "stage_platform": "macosx64", 23:41:31 INFO - "builduid": "2e8a848060574263ab4d66c4626b69c6", 23:41:31 INFO - "slavebuilddir": "test" 23:41:31 INFO - } 23:41:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 23:41:31 INFO - Running post-action listener: _resource_record_post_action 23:41:31 INFO - [mozharness: 2017-01-31 07:41:31.726398Z] Finished read-buildbot-config step (success) 23:41:31 INFO - [mozharness: 2017-01-31 07:41:31.726529Z] Running download-and-extract step. 23:41:31 INFO - Running pre-action listener: _resource_record_pre_action 23:41:31 INFO - Running main action method: download_and_extract 23:41:31 INFO - mkdir: /builds/slave/test/build/tests 23:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:31 INFO - trying https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 23:41:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 23:41:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 23:41:32 INFO - Downloaded 1199 bytes. 23:41:32 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 23:41:32 INFO - Using the following test package requirements: 23:41:32 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 23:41:32 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:41:32 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 23:41:32 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:41:32 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 23:41:32 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:41:32 INFO - u'jsshell-mac.zip'], 23:41:32 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:41:32 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 23:41:32 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 23:41:32 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:41:32 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 23:41:32 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:41:32 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 23:41:32 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:41:32 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 23:41:32 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:41:32 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 23:41:32 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:41:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 23:41:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:41:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 23:41:33 INFO - Expected file size: 16368156 23:41:33 INFO - Obtained file size: 16368156 23:41:40 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:41:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 23:41:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:41:40 INFO - Fetch https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 23:41:41 INFO - Expected file size: 16368156 23:41:41 INFO - Obtained file size: 16368156 23:41:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 23:41:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:41:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 23:41:48 INFO - Expected file size: 14403454 23:41:48 INFO - Obtained file size: 14403454 23:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:41:48 INFO - trying https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 23:41:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 23:41:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 23:41:51 INFO - Downloaded 70185108 bytes. 23:41:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 23:41:51 INFO - mkdir: /builds/slave/test/properties 23:41:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:41:51 INFO - Writing to file /builds/slave/test/properties/build_url 23:41:51 INFO - Contents: 23:41:51 INFO - build_url:https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 23:41:52 INFO - Running post-action listener: _resource_record_post_action 23:41:52 INFO - Running post-action listener: set_extra_try_arguments 23:41:52 INFO - [mozharness: 2017-01-31 07:41:52.462155Z] Finished download-and-extract step (success) 23:41:52 INFO - [mozharness: 2017-01-31 07:41:52.462275Z] Running populate-webroot step. 23:41:52 INFO - Running pre-action listener: _resource_record_pre_action 23:41:52 INFO - Running main action method: populate_webroot 23:41:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:41:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:41:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:41:52 INFO - '--tppagecycles', 23:41:52 INFO - '7'], 23:41:52 INFO - 'tests': ['tsvgm']}}, 23:41:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:41:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:41:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:41:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:41:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:41:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:41:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:41:52 INFO - 'talos_options': ['--disable-e10s'], 23:41:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:41:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:41:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:41:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:41:52 INFO - 'talos_options': ['--disable-e10s'], 23:41:52 INFO - 'tests': ['damp', 'tps']}, 23:41:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:41:52 INFO - 'tests': ['damp', 'tps']}, 23:41:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:41:52 INFO - 'tests': ['dromaeo_dom']}, 23:41:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:41:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:41:52 INFO - 'tests': ['basic_compositor_video']}, 23:41:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:41:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:41:52 INFO - 'tests': ['a11yr', 23:41:52 INFO - 'ts_paint', 23:41:52 INFO - 'tpaint', 23:41:52 INFO - 'sessionrestore', 23:41:52 INFO - 'sessionrestore_no_auto_restore', 23:41:52 INFO - 'tabpaint']}, 23:41:52 INFO - 'other-e10s': {'tests': ['a11yr', 23:41:52 INFO - 'ts_paint', 23:41:52 INFO - 'tpaint', 23:41:52 INFO - 'sessionrestore', 23:41:52 INFO - 'sessionrestore_no_auto_restore', 23:41:52 INFO - 'tabpaint']}, 23:41:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:41:52 INFO - 'ts_paint', 23:41:52 INFO - 'tpaint', 23:41:52 INFO - 'sessionrestore', 23:41:52 INFO - 'sessionrestore_no_auto_restore', 23:41:52 INFO - 'tabpaint']}, 23:41:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:41:52 INFO - 'ts_paint', 23:41:52 INFO - 'tpaint', 23:41:52 INFO - 'sessionrestore', 23:41:52 INFO - 'sessionrestore_no_auto_restore', 23:41:52 INFO - 'tabpaint']}, 23:41:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:41:52 INFO - 'tests': ['a11yr', 23:41:52 INFO - 'ts_paint', 23:41:52 INFO - 'tpaint', 23:41:52 INFO - 'sessionrestore', 23:41:52 INFO - 'sessionrestore_no_auto_restore', 23:41:52 INFO - 'tabpaint']}, 23:41:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:41:52 INFO - 'tests': ['a11yr', 23:41:52 INFO - 'ts_paint', 23:41:52 INFO - 'tpaint', 23:41:52 INFO - 'sessionrestore', 23:41:52 INFO - 'sessionrestore_no_auto_restore', 23:41:52 INFO - 'tabpaint']}, 23:41:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:41:52 INFO - 'tests': ['tsvgx', 23:41:52 INFO - 'tsvgr_opacity', 23:41:52 INFO - 'tart', 23:41:52 INFO - 'tscrollx', 23:41:52 INFO - 'cart', 23:41:52 INFO - 'tsvg_static']}, 23:41:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:41:52 INFO - 'tsvgr_opacity', 23:41:52 INFO - 'tart', 23:41:52 INFO - 'tscrollx', 23:41:52 INFO - 'cart', 23:41:52 INFO - 'tsvg_static']}, 23:41:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:41:52 INFO - 'talos_options': ['--disable-e10s'], 23:41:52 INFO - 'tests': ['tp5o']}, 23:41:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:41:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:41:52 INFO - 'talos_options': ['--disable-e10s', 23:41:52 INFO - '--xperf_path', 23:41:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:41:52 INFO - 'tests': ['tp5n']}, 23:41:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:41:52 INFO - 'talos_options': ['--xperf_path', 23:41:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:41:52 INFO - 'tests': ['tp5n']}}, 23:41:52 INFO - 'talos.zip': {'path': '', 23:41:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:41:52 INFO - Running post-action listener: _resource_record_post_action 23:41:52 INFO - [mozharness: 2017-01-31 07:41:52.480815Z] Finished populate-webroot step (success) 23:41:52 INFO - [mozharness: 2017-01-31 07:41:52.480950Z] Running create-virtualenv step. 23:41:52 INFO - Running pre-action listener: _resource_record_pre_action 23:41:52 INFO - Running main action method: create_virtualenv 23:41:52 INFO - Creating virtualenv /builds/slave/test/build/venv 23:41:52 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 23:41:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:41:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:41:52 INFO - Using real prefix '/tools/python27' 23:41:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:41:53 INFO - Installing distribute.............................................................................................................................................................................................done. 23:41:56 INFO - Installing pip.................done. 23:41:56 INFO - Return code: 0 23:41:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:41:56 INFO - Reading from file tmpfile_stdout 23:41:56 INFO - Output received: 23:41:56 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) 23:41:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:41:56 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')]} 23:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:56 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')]} 23:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:56 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 0x10a395b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a398768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc435e0480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a173d30>, '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 0x10a09adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a168be8>, '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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 23:41:56 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 23:41:56 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 23:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mlUvFY8I8Z/Render', 23:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:56 INFO - 'HOME': '/Users/cltbld', 23:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:56 INFO - 'LOGNAME': 'cltbld', 23:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:56 INFO - 'MOZ_NO_REMOTE': '1', 23:41:56 INFO - 'NO_EM_RESTART': '1', 23:41:56 INFO - 'PAGER': '/bin/cat', 23:41:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:56 INFO - 'PWD': '/builds/slave/test', 23:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:56 INFO - 'SHELL': '/bin/bash', 23:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HgITWbg0T/Listeners', 23:41:56 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 23:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:56 INFO - 'USER': 'cltbld', 23:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:56 INFO - 'XPC_FLAGS': '0x0', 23:41:56 INFO - 'XPC_SERVICE_NAME': '0', 23:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41:56 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 23:41:56 INFO - Cleaning up... 23:41:56 INFO - Return code: 0 23:41:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:41:56 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')]} 23:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:56 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')]} 23:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:41:56 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 0x10a395b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a398768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc435e0480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a173d30>, '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 0x10a09adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a168be8>, '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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 23:41:56 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 23:41:56 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 23:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mlUvFY8I8Z/Render', 23:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:41:56 INFO - 'HOME': '/Users/cltbld', 23:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:41:56 INFO - 'LOGNAME': 'cltbld', 23:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:41:56 INFO - 'MOZ_NO_REMOTE': '1', 23:41:56 INFO - 'NO_EM_RESTART': '1', 23:41:56 INFO - 'PAGER': '/bin/cat', 23:41:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:41:56 INFO - 'PWD': '/builds/slave/test', 23:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:41:56 INFO - 'SHELL': '/bin/bash', 23:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HgITWbg0T/Listeners', 23:41:56 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 23:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:41:56 INFO - 'USER': 'cltbld', 23:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:41:56 INFO - 'XPC_FLAGS': '0x0', 23:41:56 INFO - 'XPC_SERVICE_NAME': '0', 23:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:41:56 INFO - Downloading/unpacking psutil>=3.1.1 23:41:56 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 23:41:56 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 23:41:56 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 23:41:56 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 23:41:56 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 23:41:56 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 23:42:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:42:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:42:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:42:00 INFO - Installing collected packages: psutil 23:42:00 INFO - Running setup.py install for psutil 23:42:01 INFO - building 'psutil._psutil_osx' extension 23:42:01 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 23:42:01 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 23:42:01 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 23:42:01 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 23:42:01 INFO - building 'psutil._psutil_posix' extension 23:42:01 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 23:42:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:42:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:42:01 INFO - ^ 23:42:01 INFO - 1 warning generated. 23:42:01 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 23:42:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:42:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:42:01 INFO - Successfully installed psutil 23:42:01 INFO - Cleaning up... 23:42:02 INFO - Return code: 0 23:42:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:42:02 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')]} 23:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:02 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')]} 23:42:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:02 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 0x10a395b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a398768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc435e0480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a173d30>, '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 0x10a09adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a168be8>, '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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 23:42:02 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 23:42:02 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 23:42:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mlUvFY8I8Z/Render', 23:42:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:02 INFO - 'HOME': '/Users/cltbld', 23:42:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:02 INFO - 'LOGNAME': 'cltbld', 23:42:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:02 INFO - 'MOZ_NO_REMOTE': '1', 23:42:02 INFO - 'NO_EM_RESTART': '1', 23:42:02 INFO - 'PAGER': '/bin/cat', 23:42:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:02 INFO - 'PWD': '/builds/slave/test', 23:42:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:02 INFO - 'SHELL': '/bin/bash', 23:42:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HgITWbg0T/Listeners', 23:42:02 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 23:42:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:02 INFO - 'USER': 'cltbld', 23:42:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:02 INFO - 'XPC_FLAGS': '0x0', 23:42:02 INFO - 'XPC_SERVICE_NAME': '0', 23:42:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:42:02 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 23:42:02 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 23:42:02 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 23:42:02 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 23:42:02 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 23:42:02 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 23:42:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:42:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:42:04 INFO - Installing collected packages: mozsystemmonitor 23:42:04 INFO - Running setup.py install for mozsystemmonitor 23:42:05 INFO - Successfully installed mozsystemmonitor 23:42:05 INFO - Cleaning up... 23:42:05 INFO - Return code: 0 23:42:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:42:05 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')]} 23:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:05 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')]} 23:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:05 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 0x10a395b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a398768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc435e0480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a173d30>, '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 0x10a09adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a168be8>, '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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 23:42:05 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 23:42:05 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 23:42:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mlUvFY8I8Z/Render', 23:42:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:05 INFO - 'HOME': '/Users/cltbld', 23:42:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:05 INFO - 'LOGNAME': 'cltbld', 23:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:05 INFO - 'MOZ_NO_REMOTE': '1', 23:42:05 INFO - 'NO_EM_RESTART': '1', 23:42:05 INFO - 'PAGER': '/bin/cat', 23:42:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:05 INFO - 'PWD': '/builds/slave/test', 23:42:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:05 INFO - 'SHELL': '/bin/bash', 23:42:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HgITWbg0T/Listeners', 23:42:05 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 23:42:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:05 INFO - 'USER': 'cltbld', 23:42:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:05 INFO - 'XPC_FLAGS': '0x0', 23:42:05 INFO - 'XPC_SERVICE_NAME': '0', 23:42:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:05 INFO - Downloading/unpacking jsonschema==2.5.1 23:42:05 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 23:42:05 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 23:42:05 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 23:42:05 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 23:42:05 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 23:42:05 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 23:42:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:42:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:42: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 23:42: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 23:42: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 23:42: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 23:42: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 23:42: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 23:42:08 INFO - Downloading functools32-3.2.3-2.tar.gz 23:42:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:42:08 INFO - warning: no files found matching '*.txt' 23:42:08 INFO - no previously-included directories found matching 'build' 23:42:08 INFO - no previously-included directories found matching 'dist' 23:42:08 INFO - no previously-included directories found matching '.git*' 23:42:08 INFO - Installing collected packages: jsonschema, functools32 23:42:08 INFO - Running setup.py install for functools32 23:42:08 INFO - warning: no files found matching '*.txt' 23:42:08 INFO - no previously-included directories found matching 'build' 23:42:08 INFO - no previously-included directories found matching 'dist' 23:42:08 INFO - no previously-included directories found matching '.git*' 23:42:08 INFO - Successfully installed jsonschema functools32 23:42:08 INFO - Cleaning up... 23:42:08 INFO - Return code: 0 23:42:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:42:08 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')]} 23:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:08 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')]} 23:42:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:08 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 0x10a395b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a398768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc435e0480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a173d30>, '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 0x10a09adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a168be8>, '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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 23:42:08 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 23:42:08 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 23:42:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mlUvFY8I8Z/Render', 23:42:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:08 INFO - 'HOME': '/Users/cltbld', 23:42:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:08 INFO - 'LOGNAME': 'cltbld', 23:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:08 INFO - 'MOZ_NO_REMOTE': '1', 23:42:08 INFO - 'NO_EM_RESTART': '1', 23:42:08 INFO - 'PAGER': '/bin/cat', 23:42:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:08 INFO - 'PWD': '/builds/slave/test', 23:42:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:08 INFO - 'SHELL': '/bin/bash', 23:42:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HgITWbg0T/Listeners', 23:42:08 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 23:42:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:08 INFO - 'USER': 'cltbld', 23:42:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:08 INFO - 'XPC_FLAGS': '0x0', 23:42:08 INFO - 'XPC_SERVICE_NAME': '0', 23:42:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:42:08 INFO - Cleaning up... 23:42:09 INFO - Return code: 0 23:42:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a395b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a398768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc435e0480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a173d30>, '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 0x10a09adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a168be8>, '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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 23:42:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:42:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:42:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mlUvFY8I8Z/Render', 23:42:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:09 INFO - 'HOME': '/Users/cltbld', 23:42:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:09 INFO - 'LOGNAME': 'cltbld', 23:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:09 INFO - 'MOZ_NO_REMOTE': '1', 23:42:09 INFO - 'NO_EM_RESTART': '1', 23:42:09 INFO - 'PAGER': '/bin/cat', 23:42:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:09 INFO - 'PWD': '/builds/slave/test', 23:42:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:09 INFO - 'SHELL': '/bin/bash', 23:42:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HgITWbg0T/Listeners', 23:42:09 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 23:42:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:09 INFO - 'USER': 'cltbld', 23:42:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:09 INFO - 'XPC_FLAGS': '0x0', 23:42:09 INFO - 'XPC_SERVICE_NAME': '0', 23:42:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:09 INFO - Downloading/unpacking blobuploader==1.2.4 23:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:11 INFO - Downloading blobuploader-1.2.4.tar.gz 23:42:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:42:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:42: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 23:42: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 23:42: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 23:42:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:42:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:13 INFO - Downloading docopt-0.6.1.tar.gz 23:42:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:42:13 INFO - Installing collected packages: blobuploader, requests, docopt 23:42:13 INFO - Running setup.py install for blobuploader 23:42:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:42:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:42:13 INFO - Running setup.py install for requests 23:42:13 INFO - Running setup.py install for docopt 23:42:14 INFO - Successfully installed blobuploader requests docopt 23:42:14 INFO - Cleaning up... 23:42:14 INFO - Return code: 0 23:42:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:14 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 0x10a395b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a398768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc435e0480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a173d30>, '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 0x10a09adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a168be8>, '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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 23:42:14 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 23:42:14 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 23:42:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mlUvFY8I8Z/Render', 23:42:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:14 INFO - 'HOME': '/Users/cltbld', 23:42:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:14 INFO - 'LOGNAME': 'cltbld', 23:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:14 INFO - 'MOZ_NO_REMOTE': '1', 23:42:14 INFO - 'NO_EM_RESTART': '1', 23:42:14 INFO - 'PAGER': '/bin/cat', 23:42:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:14 INFO - 'PWD': '/builds/slave/test', 23:42:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:14 INFO - 'SHELL': '/bin/bash', 23:42:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HgITWbg0T/Listeners', 23:42:14 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 23:42:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:14 INFO - 'USER': 'cltbld', 23:42:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:14 INFO - 'XPC_FLAGS': '0x0', 23:42:14 INFO - 'XPC_SERVICE_NAME': '0', 23:42:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:42:14 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-9RtsOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:42:14 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-qJOJxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:42:14 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-gM5HaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:42:14 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-wE8Qab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:42:14 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-r0pQ_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:42:14 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-s49i_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:42:15 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-puzP6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:42:15 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-JIIWnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:42:15 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-vVsBUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:42:15 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-bgN9_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:42:15 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-bpzaGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:42:15 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-Q0cMin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:42:15 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-Ns2vfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:42:15 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-c47d3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:42:15 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-F8uXLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:42:15 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-AIR4ya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:42:16 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-RD16ty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:42:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:42:16 INFO - Running setup.py install for manifestparser 23:42:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:42:16 INFO - Running setup.py install for mozcrash 23:42:16 INFO - Running setup.py install for mozdebug 23:42:16 INFO - Running setup.py install for mozdevice 23:42:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:42:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:42:16 INFO - Running setup.py install for mozfile 23:42:16 INFO - Running setup.py install for mozhttpd 23:42:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:42:17 INFO - Running setup.py install for mozinfo 23:42:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:42:17 INFO - Running setup.py install for mozInstall 23:42:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:42:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:42:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:42:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:42:17 INFO - Running setup.py install for mozleak 23:42:17 INFO - Running setup.py install for mozlog 23:42:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:42:17 INFO - Running setup.py install for moznetwork 23:42:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:42:17 INFO - Running setup.py install for mozprocess 23:42:17 INFO - Running setup.py install for mozprofile 23:42:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:42:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:42:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:42:18 INFO - Running setup.py install for mozrunner 23:42:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:42:18 INFO - Running setup.py install for mozscreenshot 23:42:18 INFO - Running setup.py install for moztest 23:42:18 INFO - Running setup.py install for mozversion 23:42:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:42:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:42:18 INFO - Cleaning up... 23:42:18 INFO - Return code: 0 23:42:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:18 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 0x10a395b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a398768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc435e0480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a173d30>, '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 0x10a09adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a168be8>, '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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 23:42:18 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 23:42:18 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 23:42:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mlUvFY8I8Z/Render', 23:42:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:18 INFO - 'HOME': '/Users/cltbld', 23:42:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:18 INFO - 'LOGNAME': 'cltbld', 23:42:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:18 INFO - 'MOZ_NO_REMOTE': '1', 23:42:18 INFO - 'NO_EM_RESTART': '1', 23:42:18 INFO - 'PAGER': '/bin/cat', 23:42:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:18 INFO - 'PWD': '/builds/slave/test', 23:42:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:18 INFO - 'SHELL': '/bin/bash', 23:42:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HgITWbg0T/Listeners', 23:42:18 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 23:42:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:18 INFO - 'USER': 'cltbld', 23:42:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:18 INFO - 'XPC_FLAGS': '0x0', 23:42:18 INFO - 'XPC_SERVICE_NAME': '0', 23:42:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:42:19 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-eZP3KV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:42:19 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)) 23:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:42:19 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-5f0672-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:42:19 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)) 23:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:42:19 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-ReVgy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:42:19 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)) 23:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:42:19 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-1nZRGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:42:19 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)) 23:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:42:19 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-W_HWAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:42:19 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)) 23:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:42:19 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-UPSDrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:42:19 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)) 23:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:42:19 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-2vYIoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:42:19 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)) 23:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:42:19 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-hQuQZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:42:19 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)) 23:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:42:19 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-mEFA49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:42:19 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)) 23:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:42:20 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-lnllKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:42:20 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)) 23:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:42:20 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-Eln7o_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:42:20 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)) 23:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:42:20 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-zi2XSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:42:20 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-73sp7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:42:20 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)) 23:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:42:20 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-JR9sDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:42:20 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)) 23:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:42:20 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-1q8zSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:42:20 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)) 23:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:42:20 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-fZ0SpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:42:20 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)) 23:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:42:20 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-Bifn5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:42:20 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)) 23:42:20 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)) 23:42:20 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)) 23:42:20 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)) 23:42:20 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)) 23:42:20 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)) 23:42:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:23 INFO - Downloading blessings-1.6.tar.gz 23:42:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:42:23 INFO - Installing collected packages: blessings 23:42:23 INFO - Running setup.py install for blessings 23:42:23 INFO - Successfully installed blessings 23:42:23 INFO - Cleaning up... 23:42:23 INFO - Return code: 0 23:42:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:42:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:42:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:42:24 INFO - Reading from file tmpfile_stdout 23:42:24 INFO - Current package versions: 23:42:24 INFO - blessings == 1.6 23:42:24 INFO - blobuploader == 1.2.4 23:42:24 INFO - docopt == 0.6.1 23:42:24 INFO - functools32 == 3.2.3-2 23:42:24 INFO - jsonschema == 2.5.1 23:42:24 INFO - manifestparser == 1.1 23:42:24 INFO - mozInstall == 1.12 23:42:24 INFO - mozcrash == 1.0 23:42:24 INFO - mozdebug == 0.1 23:42:24 INFO - mozdevice == 0.49 23:42:24 INFO - mozfile == 1.2 23:42:24 INFO - mozhttpd == 0.7 23:42:24 INFO - mozinfo == 0.9 23:42:24 INFO - mozleak == 0.1 23:42:24 INFO - mozlog == 3.4 23:42:24 INFO - moznetwork == 0.27 23:42:24 INFO - mozprocess == 0.24 23:42:24 INFO - mozprofile == 0.28 23:42:24 INFO - mozrunner == 6.13 23:42:24 INFO - mozscreenshot == 0.1 23:42:24 INFO - mozsystemmonitor == 0.3 23:42:24 INFO - moztest == 0.8 23:42:24 INFO - mozversion == 1.4 23:42:24 INFO - psutil == 3.1.1 23:42:24 INFO - requests == 1.2.3 23:42:24 INFO - wsgiref == 0.1.2 23:42:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:42:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:24 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 0x10a395b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a398768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc435e0480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a173d30>, '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 0x10a09adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a168be8>, '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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 23:42:24 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 23:42:24 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 23:42:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mlUvFY8I8Z/Render', 23:42:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:24 INFO - 'HOME': '/Users/cltbld', 23:42:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:24 INFO - 'LOGNAME': 'cltbld', 23:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:24 INFO - 'MOZ_NO_REMOTE': '1', 23:42:24 INFO - 'NO_EM_RESTART': '1', 23:42:24 INFO - 'PAGER': '/bin/cat', 23:42:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:24 INFO - 'PWD': '/builds/slave/test', 23:42:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:24 INFO - 'SHELL': '/bin/bash', 23:42:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HgITWbg0T/Listeners', 23:42:24 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 23:42:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:24 INFO - 'USER': 'cltbld', 23:42:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:24 INFO - 'XPC_FLAGS': '0x0', 23:42:24 INFO - 'XPC_SERVICE_NAME': '0', 23:42:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:24 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)) 23:42:24 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)) 23:42:24 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)) 23:42:24 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)) 23:42:24 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)) 23:42:24 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)) 23:42:24 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)) 23:42:24 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)) 23:42:24 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)) 23:42:24 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)) 23:42:24 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)) 23:42:24 INFO - Cleaning up... 23:42:24 INFO - Return code: 0 23:42:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:42:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a395b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a398768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc435e0480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a173d30>, '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 0x10a09adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a168be8>, '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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 23:42:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:42:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mlUvFY8I8Z/Render', 23:42:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:24 INFO - 'HOME': '/Users/cltbld', 23:42:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:24 INFO - 'LOGNAME': 'cltbld', 23:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:24 INFO - 'MOZ_NO_REMOTE': '1', 23:42:24 INFO - 'NO_EM_RESTART': '1', 23:42:24 INFO - 'PAGER': '/bin/cat', 23:42:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:24 INFO - 'PWD': '/builds/slave/test', 23:42:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:24 INFO - 'SHELL': '/bin/bash', 23:42:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HgITWbg0T/Listeners', 23:42:24 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 23:42:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:24 INFO - 'USER': 'cltbld', 23:42:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:24 INFO - 'XPC_FLAGS': '0x0', 23:42:24 INFO - 'XPC_SERVICE_NAME': '0', 23:42:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:42:24 INFO - Cleaning up... 23:42:24 INFO - Return code: 0 23:42:24 INFO - Running post-action listener: _resource_record_post_action 23:42:24 INFO - Running post-action listener: _start_resource_monitoring 23:42:24 INFO - Starting resource monitoring. 23:42:24 INFO - [mozharness: 2017-01-31 07:42:24.698003Z] Finished create-virtualenv step (success) 23:42:24 INFO - [mozharness: 2017-01-31 07:42:24.699037Z] Running install step. 23:42:24 INFO - Running pre-action listener: _resource_record_pre_action 23:42:24 INFO - Running main action method: install 23:42:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:42:24 INFO - Reading from file tmpfile_stdout 23:42:24 INFO - Detecting whether we're running mozinstall >=1.0... 23:42:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:42:25 INFO - Reading from file tmpfile_stdout 23:42:25 INFO - Output received: 23:42:25 INFO - Usage: mozinstall [options] installer 23:42:25 INFO - Options: 23:42:25 INFO - -h, --help show this help message and exit 23:42:25 INFO - -d DEST, --destination=DEST 23:42:25 INFO - Directory to install application into. [default: 23:42:25 INFO - "/builds/slave/test"] 23:42:25 INFO - --app=APP Application being installed. [default: firefox] 23:42:25 INFO - mkdir: /builds/slave/test/build/application 23:42:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:42:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:42:47 INFO - Reading from file tmpfile_stdout 23:42:47 INFO - Output received: 23:42:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:42:47 INFO - Running post-action listener: _resource_record_post_action 23:42:47 INFO - [mozharness: 2017-01-31 07:42:47.085868Z] Finished install step (success) 23:42:47 INFO - [mozharness: 2017-01-31 07:42:47.086001Z] Running run-tests step. 23:42:47 INFO - Running pre-action listener: _resource_record_pre_action 23:42:47 INFO - Running main action method: run_tests 23:42:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:42:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:42:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:42:47 INFO - 2017-01-30 23:42:47.099 screenresolution[1635:7925] starting screenresolution argv=screenresolution get 23:42:47 INFO - 2017-01-30 23:42:47.101 screenresolution[1635:7925] Display 0: 1600x1200x32@60 23:42:47 INFO - 2017-01-30 23:42:47.107 screenresolution[1636:7928] starting screenresolution argv=screenresolution list 23:42:47 INFO - Available Modes on Display 0 23:42:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:42:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:42:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:42:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:42:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:42:47 INFO - Intel Iris: 23:42:47 INFO - Chipset Model: Intel Iris 23:42:47 INFO - Type: GPU 23:42:47 INFO - Bus: Built-In 23:42:47 INFO - VRAM (Dynamic, Max): 1536 MB 23:42:47 INFO - Vendor: Intel (0x8086) 23:42:47 INFO - Device ID: 0x0a2e 23:42:47 INFO - Revision ID: 0x0009 23:42:47 INFO - Displays: 23:42:47 INFO - Display: 23:42:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:42:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:42:47 INFO - Display Serial Number: 1600x1200 60 23:42:47 INFO - Main Display: Yes 23:42:47 INFO - Mirror: Off 23:42:47 INFO - Online: Yes 23:42:47 INFO - Rotation: Supported 23:42:47 INFO - Return code: 0 23:42:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:42:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:42:47 INFO - Python 2.7.3 23:42:47 INFO - Return code: 0 23:42:47 INFO - grabbing minidump binary from tooltool 23:42:47 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')]} 23:42:47 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 0x10a173d30>, '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 0x10a09adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a168be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:42:47 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 23:42:47 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 23:42:47 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 23:42:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:42:47 INFO - Return code: 0 23:42:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:42:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:42:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:42:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:42:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:42:47 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/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--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 23:42:47 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/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0248 --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 23:42:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mlUvFY8I8Z/Render', 23:42:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:42:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:42:47 INFO - 'HOME': '/Users/cltbld', 23:42:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:42:47 INFO - 'LOGNAME': 'cltbld', 23:42:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:42:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:47 INFO - 'MOZ_NO_REMOTE': '1', 23:42:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:42:47 INFO - 'NO_EM_RESTART': '1', 23:42:47 INFO - 'PAGER': '/bin/cat', 23:42:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:47 INFO - 'PWD': '/builds/slave/test', 23:42:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:42:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:42:47 INFO - 'SHELL': '/bin/bash', 23:42:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3HgITWbg0T/Listeners', 23:42:47 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 23:42:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:42:47 INFO - 'USER': 'cltbld', 23:42:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:42:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:47 INFO - 'XPC_FLAGS': '0x0', 23:42:47 INFO - 'XPC_SERVICE_NAME': '0', 23:42:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:42:47 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/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--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 23:42:47 INFO - mozversion application_buildid: 20170130220808 23:42:47 INFO - mozversion application_changeset: 9c06e744b1befb3a2e2fdac7414ce18220774a1d 23:42:47 INFO - mozversion application_display_name: Nightly 23:42:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:42:47 INFO - mozversion application_name: Firefox 23:42:47 INFO - mozversion application_remotingname: firefox 23:42:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:42:47 INFO - mozversion application_vendor: Mozilla 23:42:47 INFO - mozversion application_version: 54.0a1 23:42:47 INFO - mozversion platform_buildid: 20170130220808 23:42:47 INFO - mozversion platform_changeset: 9c06e744b1befb3a2e2fdac7414ce18220774a1d 23:42:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:42:47 INFO - mozversion platform_version: 54.0a1 23:42:47 INFO - using testdate: 1485848567 23:42:47 INFO - actual date: 1485848567 23:42:47 INFO - starting webserver on 'localhost:49239' 23:42:47 INFO - SUITE-START | Running 2 tests 23:42:47 INFO - TEST-START | dromaeo_css 23:42:47 INFO - operating with platform_type : mac_ 23:42:47 INFO - Initialising browser for dromaeo_css test... 23:42:47 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmp3_7uV1/profile http://localhost:49239/getInfo.html) 23:42:49 INFO - PROCESS | 1646 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 23:42:50 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 23:42:50 INFO - PROCESS | 1646 | colorDepth:24 23:42:50 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 23:42:50 INFO - PROCESS | 1646 | __metrics 23:42:50 INFO - PROCESS | 1646 | 1485848570319 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (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:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 23:42:50 INFO - PROCESS | 1646 | 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" 23:42:50 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:42:51 INFO - TEST-INFO | 1646: exit 0 23:42:51 INFO - Browser initialized. 23:42:51 INFO - Running cycle 1/1 for dromaeo_css test... 23:42:51 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmp3_7uV1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:43:03 INFO - PROCESS | 1649 | RSS: Main: 236654592 23:43:03 INFO - PROCESS | 1649 | 23:46:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 23:46:08 INFO - PROCESS | 1649 | RSS: Main: 381452288 23:46:08 INFO - PROCESS | 1649 | 23:47:52 INFO - PROCESS | 1649 | ************************* 23:47:52 INFO - PROCESS | 1649 | A coding exception was thrown and uncaught in a Task. 23:47:52 INFO - PROCESS | 1649 | 23:47:52 ERROR - PROCESS | 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:47:52 INFO - PROCESS | 1649 | Full stack: 23:47:52 INFO - PROCESS | 1649 | ************************* 23:49:11 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 23:49:12 INFO - PROCESS | 1649 | RSS: Main: 380018688 23:49:12 INFO - PROCESS | 1649 | 23:52:14 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 23:52:15 INFO - PROCESS | 1649 | RSS: Main: 343097344 23:52:15 INFO - PROCESS | 1649 | 23:55:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 23:55:18 INFO - PROCESS | 1649 | RSS: Main: 349786112 23:55:18 INFO - PROCESS | 1649 | 23:58:22 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 23:58:22 INFO - PROCESS | 1649 | RSS: Main: 333533184 23:58:22 INFO - PROCESS | 1649 | 00:01:26 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 00:01:26 INFO - PROCESS | 1649 | RSS: Main: 428154880 00:01:26 INFO - PROCESS | 1649 | 00:01:26 INFO - PROCESS | 1649 | __start_tp_report 00:01:26 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 00:01:26 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 00:01:26 INFO - PROCESS | 1649 | |i|pagename|runs| 00:01:26 INFO - PROCESS | 1649 | |0;dojo.html;2918.4309059733346;3302.141443224761;3343.8129464040267;3376.6116896556905;3395.575553055868;5614.185943038267;5664.688442135681;5705.572082093844;5714.542836573075;5782.987977104006;5612.186232996213;5650.067738823089;5679.290088738897;5683.801066962657;5693.202951586771;5119.795208191668;5157;5204.271402003723;5220.190870415092;5254.290670759456;5257.737113144347;5287.603429742765;5299.708516031636;5307.920381194285;5342.385625653061;5127.333446651949;5185.974070129663;5256.342957130359;5265.025970195487;5276.577873770089;3731.850725970985;4338.696291259613;5167.22491626259;5227.660202086852;5298.682079075268;5122.052420302219;5172.224166375074;5197.714125723083;5254.133068043769;5257.947420525784;5675.091985282335;5708.029634962025;5727.541796656257;5728.656280623176;5743.971280143614;6032.939670603281;6099.69501524922;6103.6032657877095;6314.305426403138;6314.652694101823;6198.318184999646;6205.627662340274;6216.782412615582;6225.7509699611965;6308.93691063088;5824.767009319579;5832.941670583325;5833.38749431312;5837.708114594296;5877.617954832965;6161.4146656067605;6294.590851594632;6318.178636777281;6320.146780184617;6323.146375239376;5976.701164941735;6021.969890150521;6028.668423236676;6098.939010609926;6171.012637977902;6185.783497577653;6201.162843016225;6215.595986260879;6289.930711779009;6321.146645202839;6167.136600875881;6247.0629405589525;6253.249610046823;6265.122882796412;6276.372362763778;6274.247090349188;6299.937000630027;6300.905486417708;6308.779192728232;6310.652914089769;4888.9755551222015;4894.608431325557;4944.90110197794;4945.208766597327;4984;4410.206162890647;4416.072624748838;4424.292113261863;4451.287793952952;4481.148581769518;5628.212050312959;5646.520045796154;5708.086706127;5711.143328500758;5776.335721392094;5951.3751056139345;6031.788887388921;6132.049532322497;6251.468625166821;6280.874382512324;4793.976030119827;5572.3870374258795;5646;5712.2288491054005;5776.508996735324;5388.676679399248;5408.053590621704;5409.513143817076;5413.458654134555;5416.404195538487;6313.684315784192;6320.810375688737;6331.3352098029045;6347.428731414195;6348.650824204713;6097.054956481752;6149.20060392164;6179.876402471836;6199.721012554356;6301.6219026858535;6259.906101408392;6261.655608941552;6309.842253943688;6332.873342533216;6334.461570766536;6124.075808605272;6124.319907527303;6130.540209484396;6162.691865406801;6165.075238714049;6248.937510624835;6313.273973493107;6318.62088274705;6334.239891213084;6335.3981371769605;5887.4406931343165;6145.84635384119;6146.661933593517;6155.35368786271;6161.72272247741;5932.466078052823;5998.070299103646;6047.848803779764;6072.332043475126;6088.964875971007;5936.614120082181;5961.344252132349;5962.105684147239;5965.552583556338;5985.341612422717;6076.301225358963;6091.939080609137;6105.45050945417;6112.205413296243;6114.296855861633;5675.943240567707;5767.452092051249;5782.190493331022;5888.793892213752;5933.910991335047 00:01:26 INFO - PROCESS | 1649 | |1;ext.html;459.2078664304076;461.547683270395;467.95412253613176;490.77424384783;493.06810128856466;31028.53457198141;32987.505187422175;41353.37969930453;41623.79188104055;43442.913927151756;42426.36360454588;42688.14623707524;42716.14567708644;42940.71177864661;42944.141117177714;8414.621342039623;8633.611487483078;9210.953945230282;9484.241260699127;9497;11028.613998510054;11322.980931716143;11332.320060796337;11469.139814513903;11529.654110376701;11290.774184516345;11303.943480282629;11358.772824543505;11359.318440893532;11403.201775875692;11275.830862537068;11349.432528373549;11349.546018159264;11359.602413915556;11368;11022.50398732059;11126.107212124825;11129.833052504218;11207.831882521768;11217;38936.61063389386;39376;39616.80191599023;39646;39857.60142398568;3655.670989610921;4007.3788562772816;4020.2361551305453;4035.9798201008807;4044.635982761536;29917.10248692543;30205.413878457966;30499.23751906198;30906.690933090606;31024;2897.6088228089147;3115.8286294253808;3145.1979745164836;3181.3955348483714;3272.378248132847;364205;365232;368544.1572792146;368595;370999;57794;58030.70984645134;58121;58163.4183658158;58172.70913645405;60078;61503.76992460215;61981.69009154925;62008;62102.75794484175;15259.923700381649;15460;15461.14963676986;15468.071915685096;15490;10657.147428205724;10876.075533579577;10896;11687;11856.762864742657;15066;15486.064241969754;15516.301766420538;15548.533543993699;15631.140287284308;7180.9893229895715;7185.497015208885;7215.891761623579;7263.3826124778925;7276.527025743416;806.2340776262574;814.4461765999177;814.4461765999177;822.4530687093136;840.4075127035426;29302.560461593093;29472.410551788846;29768.255793605334;30059;30520.542191867138;825.9504429734235;986.413084214891;1022.823752684404;1032.7573020340214;1043.139409986749;13268.469261229635;14946.626334341729;15174.848251517564;15424;15474.613134671497;623268;653589;666206;844655;845111;51539.71150721187;52165.217521737206;52305.476945230825;52404.21393679098;52449;1364.2701154882288;1394.3028485757122;1408.6971301170297;1416.4971435140583;1420.7939848721878;27146.592801108392;27336.863315683295;27544.58683119795;27942.580861287504;28297.717022829507;43249;44153.33769993517;44184.779076104416;44193.55806442023;44227.90404671642;533415.6658433521;534666;535124;535668;537127;19250.8074919249;19654.90172549128;23273.650895236922;23494.412639684146;23871.925763341038;38002.049948751504;38370.80814595909;38723;38915.80542097271;39135.80432097821;33161.336773264884;33316.66683333136;33332.833335833166;34253.48619770656;34508.65491345055;32504.83747581247;33120.834395827864;33910.491342630376;34002.14994625154;34385;32531.837340813145;32951.50572741363;32956.50565241475;33083.33833323272;33135.84024559129;27143.457130857667;29560;29760.25599359947;29817.25456863509;29970.100896973127;21124.577508450053;21239.256626018014;22507.212247571257;22837.429064273525;23106.653400199346 00:01:26 INFO - PROCESS | 1649 | |2;jquery.html;369.6562197156645;384.33318192935235;386.18707620458935;405.16940272441485;415.2173153605453;16997.830021699778;19768.802311976877;20506.897465512688;21909.780902190974;22265.998030088624;11960;12491.562795302167;12523.436445359957;12538.749225015494;21854.89072554639;5265.210218467232;5536.474034966672;5546.500814926656;5546.77812887484;5548.500634942854;4574.679772415916;5337.839864804062;5416.052190866602;5466.480684335002;5477.890442191154;4706.623470122399;5465.125579907216;5467.289252397183;5468.234447177399;5471.398146203915;5458.990086833928;5931.970340148293;6204.100405441231;6268.373162683741;6282.246130464374;5977.551683623746;5999.580029397944;6000.399960004008;6112.6638034908065;6139.201903752483;23113.7688623115;25028.624570631455;26319.73680263192;26800.463990720076;26956.865215673795;2566.7561581649716;2618.109842653507;2637.2483842104907;2641.5773476243903;2643.5109504741686;10166.9491652542;10198.898011019868;10204.23468239885;10801.513931873005;10830.72923176919;1826.2421095245456;1870.812918708132;1871.5321169707572;1878.64305781901;1881.491997160759;112094.87905121008;136393;143382;144001.67994960168;144890.13324000637;9866.80266394668;11689.88310116888;11728.648140555797;11914;12049.397530123633;15790.447334343244;15799.763003554956;15801.36794528206;15829.445969391247;15835.970661906938;8303.626336814857;8328.167183281623;8337.666493340199;8695.391322607356;8805.735827925173;8272.627731752085;8288.627011784483;8296.8340633187;8446.873296900429;8454.915450845412;4268.338407546835;4510.571495707903;4742.596879265232;4758.762061896891;4764.6664733469015;1703.9829601704073;1958.3831093205595;2013.7784843667184;2015.2543558883308;2022.1203776357038;955.2358113509165;962.2897154668494;964.4358050540512;970.1608108985677;986.4820968991337;22087.543432245748;22523.774762252495;22588.66117008246;22606;23005.424864378525;974.2449601558707;985.6008316631776;987.5506644476745;1002.8094662014194;1004.0712341084613;11290.943545282222;11553.942230288794;11561.42192890352;11572.189946703818;11616.361100139404;577340;617761;719474;729747;742614.7300425455;140313;170511;172335;173604;176717;1047.104725459721;1076.4025965589174;1119.227732864321;1126.2735535579504;1129.757102223026;17906.91046544811;19595;20828;20888.895555522126;20902.58194836125;45900.77049614864;57787.711061444425;58015.70992145012;59815.70092149511;61426;92388.61417078615;95378;95618.52190739002;96143.76006940141;96359.51820240854;8340.916590834015;8384.455010424303;8559.443636163629;8834.072422395557;8834.867476987722;13005.284709340698;13119.803202952155;13344.199348039148;13349.599512014654;13650.180989140683;11314.773704525698;11360;11686.766264674829;11715.824262635897;11838.467268972745;11257.49341279628;11269.042131418593;11370.772584548427;11653.76692466163;11735;11379.943100284445;11547.191696581158;11741.530338786695;11777.764444711229;11902.940485297519;6947.930520694728;7577.583232922242;7637.389008879165;7936.6428510718315;8695.130486951255;10058;11316.83024754613;11408.258463200198;11814.586489472827;11872.465739041936 00:01:26 INFO - PROCESS | 1649 | |3;mootools.html;1319.7294554616306;1421.587739555529;1425.4013314407948;1445.125698952134;1448.8840892728579;7417.592032438214;7727.72952946647;7963.24349186827;8217.465864718804;8236;8078.474899131553;8093.312068474187;8100.4734692244965;8131.430799844014;8192.631331590077;2017.4250338653487;2092;2235.4076169815053;2280.7605201453857;2291.4844160064017;2247.3819699582664;2312.19073324336;2324.34918222897;2324.360800779782;2327.7439481657;2253.1550668499312;2272.8409011369213;2278.168468508999;2286.588414085464;2289.496310811619;1787.49950013995;2014.335269361122;2080.6878968154747;2270.886455677218;2292.5758734634146;2199.4061603367163;2282.589133955887;2287.874166920819;2290.4960908600083;2295.5294164696365;8172.754817355489;8224.917750822535;8230.176982301782;8240.21717936795;8245.917540824636;4953.6037117030555;5056.393232812049;5083.694978301277;5084.237364395333;5090.54185123337;2876.2377969838008;3250.2199472126754;3422.6064002639764;3481.582210134775;3496.423090190141;1139.2367114033618;1182.4028865422993;1190.2739329009298;1240.6898275431142;1243.5150291386367;10135.138513226311;10235.0788429041;10257.128144107759;10262.794744105076;10266.384016959006;3717.386631205875;4370.672199585044;4560.853011422175;4574.176648203289;4624.953750462452;9268.165865072177;9273.72178834636;9285.72142835716;9286.860697089414;9297.023812499725;5106.412762532282;5203.765830537635;5206;5207.0366982108435;5212.530872221519;4571.588557029883;4586.6789324747615;4718.41019872516;4771.06964141989;4783.091212669582;3151.3697260547983;3734.607866174067;3759.661630453272;3788.0908581940134;3825.6748176404762;1260.1682889293022;1261.4512686981193;1267.790814515613;1270.1934271737327;1275.789494733377;807.7657479330928;834.561855026104;838.4340570115148;853.9615717292737;888.0187392930718;5218.556422704112;5220.634555581073;5434.728263586805;5524.58565607573;5582.218489411485;558.3355806590145;871.3029576338904;915.3135148638521;947.9715608531756;955.8709574207533;3382.864685412605;3599.6580324869096;3682.3003629310347;3763.190913953458;3802.6957843372466;9240.491772952551;9586.568604412818;9814.607415703293;9878.950605247072;9905.900940990497;9686.273529485317;9780.315377923476;9799.559019844124;9804.313698041069;9827.508624568743;7529.736459223901;7706.460547761715;7715.305622494003;7731.729389471341;7733;4514.954850451453;5415.187721841755;5429.67421954684;5463.754131064032;5615.634983726045;9048.773780655536;9168.31237657165;9181.989981102208;9202.309826763154;9203.907960920305;9107.681231156877;9197.448153110834;9308.95345523268;9426.95286523563;9431.764205894908;6877.277885822116;6966.616836073862;7032.542884712682;7064.364207221375;7087.255838136922;7477.513961592479;7546.509476883985;7546.849063018599;7571.3564347031115;7585.658645361083;6647.534672572873;6666.566673166229;6849.383555480031;6898.793036208922;6981.581105133709;6682.298358672464;6715;6716.6977486012265;6729.158855143107;6744.831379215362;5973;6005.489533389711;6018.4583387493585;6085.9695701521205;6094.9085763714465;4791.9281210782565;4868.026394721141;4891.192953162688;4941.209406494943;5054.74726263678;4791.5687588117235;4856.344393506973;4929.112759703288;4939.431965324034;5040.420351659606 00:01:26 INFO - PROCESS | 1649 | |4;prototype.html;326.2626464190928;351.8152969690912;355.94482855157446;362.5884620955216;371.8456840411229;4384.561543845613;4993.625478089144;5150.665206761567;5151.8877260323225;5156.246876062138;4024.919501609966;5102.617303702219;5171.250168725542;5214.582833373331;5239.345081864767;3496.702780263681;4095.9180816383655;4114.629683328492;4129.339305711087;4155.646770024551;3938.5667576566557;4089.7750623715683;4107.958920410788;4117.279476091687;4137.110521237933;3062.9234269143226;3686.7972261525606;3931.3906344516645;4119.6704263658985;4138.482689663792;3555.822208889545;3582.910427239314;3761.9278505625766;3910.569837317893;4134.3591743279685;3372.629010808809;3645.9453108203134;3650.233450975298;3658.579263384718;3666.8349924253207;4615.2769865779355;4623.583877451012;4760.357351757538;5148.407933087706;5159.74201289934;4638.281066434674;4741.288806679026;4755.334253204554;4765.475797662254;4788.712677239342;5159.303494028333;5496.340439147288;5567.8886422271735;5588.692621905793;5629.549636029108;3368.9663103369144;3588.102974256436;4053.716239863211;4064.2887494688607;4065.7357271777537;5771.8456308738005;5776.9422305776825;5809;5850.736716847752;5862.706864656835;3871.090293780959;3874.380099184117;3886.358750806142;3909.5113110861143;3936.8031598419966;3774.5659249186147;3972.463717398172;3977.6817854571564;3996.2606917720314;4002.479677641888;4300.247456695066;4536.977315113403;4576.954230457719;4580.862574122782;4606.401167848158;5265.183896496049;5471.890562188813;5485.122380419114;5490.258815059996;5503.7798488060025;973.090160699748;1151.441550847838;1222.2727477151082;1263.5072321794507;1275.4057428214733;821.4309988251373;859.7463748194298;872.908344623818;873.1748497669641;874.2699845628844;4329.891752706208;4815.362776655954;4830.782614782343;4878.219379980544;4939.53074457926;5061.392632884003;5232.607554433433;5342.412334643186;5389.541888939406;5443.8094666686475;3919.0398352403845;4480.4623445186135;4484.53788480464;4867.756612169377;4876.073950789419;2362.551115288124;2488.171194172318;2809.35652643206;2879.3809330993936;2885.5238885584063;4712;4900;5267.657602255841;5491.478309560585;5676.489115979582;5358.356997160287;5404.351477822608;5531.731989884128;5619.93221287954;5940.168376427304;4972.825951091767;5260.631755777059;5481.163810123653;5604.271444712162;5914.408559144051;4855.490173531728;5241.47585241488;5275.736213189249;5730.914036289376;5777.8555536111935;4572.428446444194;5326.41409444953;5548.639338442988;5735.369109398046;5744.396838331915;4978.950210497994;5071.822486212965;5942.346341902301;5963.403659634176;6020.946334391551;3298.8185649789493;3891.5719270879613;3939;3944.980275098606;3947;4987.9750601248215;5149.326468897549;5593.748281327432;5769.8557536061935;5785.942140578541;5481.451854814646;5489.258950041853;5510.641808282448;5630.704937864232;5644.6330988485615;4916.557509824133;5515.558755299486;5560.749766260447;5598.944010559842;5627.831165065055;4536.523665015258;5415.35015798091;5460.26286451324;5489.58828087903;5494.752736126804;5163.922541161961;5568.749406276646;5580.441955804387;5589.720513974366;5590.937721832839;4577.5880170784785;5398.78404863801;5483.862903427446;5503.284573005483;5505.339359276913 00:01:26 INFO - PROCESS | 1649 | |5;yui.html;708.5005071424646;737.3732327521607;765.885117232415;820.4994953078622;835.3150416658341;14806.185659788707;15827.525174244762;15838.76241856373;15871.603209919758;15885.761713574306;14547.127172369639;14727.116373017627;14735.484258050943;14737.336819843105;14844.628884277898;688.3563867783638;712.9536580122301;874.2830878679486;889.1775108025083;894.8568229083348;856.1866227084264;881.3962435731512;889.968851090212;891.0421297105605;894.2532984957568;942.189716843514;946.3801210207291;946.6592026870321;948.3172116076414;952.642758965388;940.9670661526882;942.6700654770843;943.2831048403194;945.6264775413707;945.7210123013728;534.4201541327665;550.9146082357241;671.1744554198315;692.4079911675495;701.638656092113;15391.461298854487;15464.845351546453;15476.922615386964;15510.30203640839;15510.767338489932;13715.657108572366;13724.65688357789;13759;13777.448902043907;13790.65523361914;12662.936685316514;12794.104412691115;12872.42074106658;12885.549005784753;13029.543965961146;4457.710248833815;4487.887802804923;4501.7974191161475;4631.143238500888;4648.442186937556;102816.48591757068;105203.94796052018;106278;107393;108795.45602271939;10816.27571094181;11117.777644447066;11128;11282.984531392214;11297.26567773092;18210.726839097424;21975.780242197372;22071.66892496614;22223.44441388978;22266.443338916655;7289.817754556178;7363.153237377663;7367.373773229228;7385.963070184722;7444.8883266751045;4167.395727619539;4187.430717146188;4250.978745106317;4253.574642535784;4358.738475691405;3360;3447.775894566845;3448.7067349743847;3449.034270404291;3452.5339079224486;623.2987888625297;630.9242890853035;636.6339354870968;651.2152855808739;654.443722835586;562.8030189433665;563.6167406163848;600.6936462404205;627.7727043629693;663.1975309875105;7950.363970882316;9052.321075919332;10239.539220735085;10270.6405275815;10500.317479363817;535.4484880573016;574.7554311410265;591.5474661883662;638.6897453162815;700.4466471487468;4652.209124448852;4943.4809345018975;5012.423571289349;5026.673266237683;5065.088284108896;75676;89149.10850891538;90867;93513;93648;79139.60430197927;83267;83576;84474.57762711147;85346;61751.07373389403;63471;71862.56274874473;72803.27196728176;74788;8947.823522649365;9545.904540954501;9575.760605984906;9638.95180524093;9657.951710241405;33913.32173356568;45784.77107614574;46348.30477542772;46439.30341044819;47016.477363621656;45776;52240.47759522356;57888.421115789984;60870.69564652148;61392.693036536315;11723.35521546323;11862.881371186179;12285.938570307091;12330.63008109758;12414.503419863104;12300.753984920073;12807.871921280668;12869.034822388172;12920.22478651284;13356.198628082346;11678;12105.51577936908;12160.391980400767;12265.693357665772;12311.261324320569;11827.231229970024;11864.406779661154;11958.64124076279;12177.878221217674;12411.069169812481;11819.290842549475;11881.881181188077;11892.167548271884;11933;12056.879431205576;10019.348742331726;10098.358692343614;10426.374417534973;10597.576096956036;10649.840252396065;9700.563474643517;9843.803123937338;9880;9894.950525247328;9898.752531186778 00:01:26 INFO - PROCESS | 1649 | __end_tp_report 00:01:26 INFO - PROCESS | 1649 | __start_cc_report 00:01:26 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1553 00:01:26 INFO - PROCESS | 1649 | __end_cc_report 00:01:26 INFO - PROCESS | 1649 | __startTimestamp1485849686686__endTimestamp 00:01:26 INFO - PROCESS | 1649 | 00:01:26 INFO - PROCESS | 1649 | ------- Summary: start ------- 00:01:26 INFO - PROCESS | 1649 | Number of tests: 6 00:01:26 INFO - PROCESS | 1649 | 00:01:26 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5719.68 Median:5936.61 stddev:672.78 (11.3%) stddev-sans-first:637.95 00:01:26 INFO - PROCESS | 1649 | Values: 2918.4 3302.1 3343.8 3376.6 3395.6 5614.2 5664.7 5705.6 5714.5 5783.0 5612.2 5650.1 5679.3 5683.8 5693.2 5119.8 5157.0 5204.3 5220.2 5254.3 5257.7 5287.6 5299.7 5307.9 5342.4 5127.3 5186.0 5256.3 5265.0 5276.6 3731.9 4338.7 5167.2 5227.7 5298.7 5122.1 5172.2 5197.7 5254.1 5257.9 5675.1 5708.0 5727.5 5728.7 5744.0 6032.9 6099.7 6103.6 6314.3 6314.7 6198.3 6205.6 6216.8 6225.8 6308.9 5824.8 5832.9 5833.4 5837.7 5877.6 6161.4 6294.6 6318.2 6320.1 6323.1 5976.7 6022.0 6028.7 6098.9 6171.0 6185.8 6201.2 6215.6 6289.9 6321.1 6167.1 6247.1 6253.2 6265.1 6276.4 6274.2 6299.9 6300.9 6308.8 6310.7 4889.0 4894.6 4944.9 4945.2 4984.0 4410.2 4416.1 4424.3 4451.3 4481.1 5628.2 5646.5 5708.1 5711.1 5776.3 5951.4 6031.8 6132.0 6251.5 6280.9 4794.0 5572.4 5646.0 5712.2 5776.5 5388.7 5408.1 5409.5 5413.5 5416.4 6313.7 6320.8 6331.3 6347.4 6348.7 6097.1 6149.2 6179.9 6199.7 6301.6 6259.9 6261.7 6309.8 6332.9 6334.5 6124.1 6124.3 6130.5 6162.7 6165.1 6248.9 6313.3 6318.6 6334.2 6335.4 5887.4 6145.8 6146.7 6155.4 6161.7 5932.5 5998.1 6047.8 6072.3 6089.0 5936.6 5961.3 5962.1 5965.6 5985.3 6076.3 6091.9 6105.5 6112.2 6114.3 5675.9 5767.5 5782.2 5888.8 5933.9 00:01:26 INFO - PROCESS | 1649 | 00:01:26 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:66518.08 Median:33128.34 stddev:151996.78 (458.8%) stddev-sans-first:152342.25 00:01:26 INFO - PROCESS | 1649 | Values: 459.2 461.5 468.0 490.8 493.1 31028.5 32987.5 41353.4 41623.8 43442.9 42426.4 42688.1 42716.1 42940.7 42944.1 8414.6 8633.6 9211.0 9484.2 9497.0 11028.6 11323.0 11332.3 11469.1 11529.7 11290.8 11303.9 11358.8 11359.3 11403.2 11275.8 11349.4 11349.5 11359.6 11368.0 11022.5 11126.1 11129.8 11207.8 11217.0 38936.6 39376.0 39616.8 39646.0 39857.6 3655.7 4007.4 4020.2 4036.0 4044.6 29917.1 30205.4 30499.2 30906.7 31024.0 2897.6 3115.8 3145.2 3181.4 3272.4 364205.0 365232.0 368544.2 368595.0 370999.0 57794.0 58030.7 58121.0 58163.4 58172.7 60078.0 61503.8 61981.7 62008.0 62102.8 15259.9 15460.0 15461.1 15468.1 15490.0 10657.1 10876.1 10896.0 11687.0 11856.8 15066.0 15486.1 15516.3 15548.5 15631.1 7181.0 7185.5 7215.9 7263.4 7276.5 806.2 814.4 814.4 822.5 840.4 29302.6 29472.4 29768.3 30059.0 30520.5 826.0 986.4 1022.8 1032.8 1043.1 13268.5 14946.6 15174.8 15424.0 15474.6 623268.0 653589.0 666206.0 844655.0 845111.0 51539.7 52165.2 52305.5 52404.2 52449.0 1364.3 1394.3 1408.7 1416.5 1420.8 27146.6 27336.9 27544.6 27942.6 28297.7 43249.0 44153.3 44184.8 44193.6 44227.9 533415.7 534666.0 535124.0 535668.0 537127.0 19250.8 19654.9 23273.7 23494.4 23871.9 38002.0 38370.8 38723.0 38915.8 39135.8 33161.3 33316.7 33332.8 34253.5 34508.7 32504.8 33120.8 33910.5 34002.1 34385.0 32531.8 32951.5 32956.5 33083.3 33135.8 27143.5 29560.0 29760.3 29817.3 29970.1 21124.6 21239.3 22507.2 22837.4 23106.7 00:01:26 INFO - PROCESS | 1649 | 00:01:26 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:39420.40 Median:23059.60 stddev:114620.63 (497.1%) stddev-sans-first:114904.67 00:01:26 INFO - PROCESS | 1649 | Values: 369.7 384.3 386.2 405.2 415.2 16997.8 19768.8 20506.9 21909.8 22266.0 11960.0 12491.6 12523.4 12538.7 21854.9 5265.2 5536.5 5546.5 5546.8 5548.5 4574.7 5337.8 5416.1 5466.5 5477.9 4706.6 5465.1 5467.3 5468.2 5471.4 5459.0 5932.0 6204.1 6268.4 6282.2 5977.6 5999.6 6000.4 6112.7 6139.2 23113.8 25028.6 26319.7 26800.5 26956.9 2566.8 2618.1 2637.2 2641.6 2643.5 10166.9 10198.9 10204.2 10801.5 10830.7 1826.2 1870.8 1871.5 1878.6 1881.5 112094.9 136393.0 143382.0 144001.7 144890.1 9866.8 11689.9 11728.6 11914.0 12049.4 15790.4 15799.8 15801.4 15829.4 15836.0 8303.6 8328.2 8337.7 8695.4 8805.7 8272.6 8288.6 8296.8 8446.9 8454.9 4268.3 4510.6 4742.6 4758.8 4764.7 1704.0 1958.4 2013.8 2015.3 2022.1 955.2 962.3 964.4 970.2 986.5 22087.5 22523.8 22588.7 22606.0 23005.4 974.2 985.6 987.6 1002.8 1004.1 11290.9 11553.9 11561.4 11572.2 11616.4 577340.0 617761.0 719474.0 729747.0 742614.7 140313.0 170511.0 172335.0 173604.0 176717.0 1047.1 1076.4 1119.2 1126.3 1129.8 17906.9 19595.0 20828.0 20888.9 20902.6 45900.8 57787.7 58015.7 59815.7 61426.0 92388.6 95378.0 95618.5 96143.8 96359.5 8340.9 8384.5 8559.4 8834.1 8834.9 13005.3 13119.8 13344.2 13349.6 13650.2 11314.8 11360.0 11686.8 11715.8 11838.5 11257.5 11269.0 11370.8 11653.8 11735.0 11379.9 11547.2 11741.5 11777.8 11902.9 6947.9 7577.6 7637.4 7936.6 8695.1 10058.0 11316.8 11408.3 11814.6 11872.5 00:01:26 INFO - PROCESS | 1649 | 00:01:26 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5294.16 Median:5215.54 stddev:2890.31 (55.4%) stddev-sans-first:2882.98 00:01:26 INFO - PROCESS | 1649 | Values: 1319.7 1421.6 1425.4 1445.1 1448.9 7417.6 7727.7 7963.2 8217.5 8236.0 8078.5 8093.3 8100.5 8131.4 8192.6 2017.4 2092.0 2235.4 2280.8 2291.5 2247.4 2312.2 2324.3 2324.4 2327.7 2253.2 2272.8 2278.2 2286.6 2289.5 1787.5 2014.3 2080.7 2270.9 2292.6 2199.4 2282.6 2287.9 2290.5 2295.5 8172.8 8224.9 8230.2 8240.2 8245.9 4953.6 5056.4 5083.7 5084.2 5090.5 2876.2 3250.2 3422.6 3481.6 3496.4 1139.2 1182.4 1190.3 1240.7 1243.5 10135.1 10235.1 10257.1 10262.8 10266.4 3717.4 4370.7 4560.9 4574.2 4625.0 9268.2 9273.7 9285.7 9286.9 9297.0 5106.4 5203.8 5206.0 5207.0 5212.5 4571.6 4586.7 4718.4 4771.1 4783.1 3151.4 3734.6 3759.7 3788.1 3825.7 1260.2 1261.5 1267.8 1270.2 1275.8 807.8 834.6 838.4 854.0 888.0 5218.6 5220.6 5434.7 5524.6 5582.2 558.3 871.3 915.3 948.0 955.9 3382.9 3599.7 3682.3 3763.2 3802.7 9240.5 9586.6 9814.6 9879.0 9905.9 9686.3 9780.3 9799.6 9804.3 9827.5 7529.7 7706.5 7715.3 7731.7 7733.0 4515.0 5415.2 5429.7 5463.8 5615.6 9048.8 9168.3 9182.0 9202.3 9203.9 9107.7 9197.4 9309.0 9427.0 9431.8 6877.3 6966.6 7032.5 7064.4 7087.3 7477.5 7546.5 7546.8 7571.4 7585.7 6647.5 6666.6 6849.4 6898.8 6981.6 6682.3 6715.0 6716.7 6729.2 6744.8 5973.0 6005.5 6018.5 6086.0 6094.9 4791.9 4868.0 4891.2 4941.2 5054.7 4791.6 4856.3 4929.1 4939.4 5040.4 00:01:26 INFO - PROCESS | 1649 | 00:01:26 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4451.99 Median:4956.18 stddev:1360.76 (27.5%) stddev-sans-first:1328.88 00:01:26 INFO - PROCESS | 1649 | Values: 326.3 351.8 355.9 362.6 371.8 4384.6 4993.6 5150.7 5151.9 5156.2 4024.9 5102.6 5171.3 5214.6 5239.3 3496.7 4095.9 4114.6 4129.3 4155.6 3938.6 4089.8 4108.0 4117.3 4137.1 3062.9 3686.8 3931.4 4119.7 4138.5 3555.8 3582.9 3761.9 3910.6 4134.4 3372.6 3645.9 3650.2 3658.6 3666.8 4615.3 4623.6 4760.4 5148.4 5159.7 4638.3 4741.3 4755.3 4765.5 4788.7 5159.3 5496.3 5567.9 5588.7 5629.5 3369.0 3588.1 4053.7 4064.3 4065.7 5771.8 5776.9 5809.0 5850.7 5862.7 3871.1 3874.4 3886.4 3909.5 3936.8 3774.6 3972.5 3977.7 3996.3 4002.5 4300.2 4537.0 4577.0 4580.9 4606.4 5265.2 5471.9 5485.1 5490.3 5503.8 973.1 1151.4 1222.3 1263.5 1275.4 821.4 859.7 872.9 873.2 874.3 4329.9 4815.4 4830.8 4878.2 4939.5 5061.4 5232.6 5342.4 5389.5 5443.8 3919.0 4480.5 4484.5 4867.8 4876.1 2362.6 2488.2 2809.4 2879.4 2885.5 4712.0 4900.0 5267.7 5491.5 5676.5 5358.4 5404.4 5531.7 5619.9 5940.2 4972.8 5260.6 5481.2 5604.3 5914.4 4855.5 5241.5 5275.7 5730.9 5777.9 4572.4 5326.4 5548.6 5735.4 5744.4 4979.0 5071.8 5942.3 5963.4 6020.9 3298.8 3891.6 3939.0 3945.0 3947.0 4988.0 5149.3 5593.7 5769.9 5785.9 5481.5 5489.3 5510.6 5630.7 5644.6 4916.6 5515.6 5560.7 5598.9 5627.8 4536.5 5415.4 5460.3 5489.6 5494.8 5163.9 5568.7 5580.4 5589.7 5590.9 4577.6 5398.8 5483.9 5503.3 5505.3 00:01:26 INFO - PROCESS | 1649 | 00:01:26 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:18969.59 Median:46393.80 stddev:26888.59 (58.0%) stddev-sans-first:26929.06 00:01:26 INFO - PROCESS | 1649 | Values: 708.5 737.4 765.9 820.5 835.3 14806.2 15827.5 15838.8 15871.6 15885.8 14547.1 14727.1 14735.5 14737.3 14844.6 688.4 713.0 874.3 889.2 894.9 856.2 881.4 890.0 891.0 894.3 942.2 946.4 946.7 948.3 952.6 941.0 942.7 943.3 945.6 945.7 534.4 550.9 671.2 692.4 701.6 15391.5 15464.8 15476.9 15510.3 15510.8 13715.7 13724.7 13759.0 13777.4 13790.7 12662.9 12794.1 12872.4 12885.5 13029.5 4457.7 4487.9 4501.8 4631.1 4648.4 102816.5 105203.9 106278.0 107393.0 108795.5 10816.3 11117.8 11128.0 11283.0 11297.3 18210.7 21975.8 22071.7 22223.4 22266.4 7289.8 7363.2 7367.4 7386.0 7444.9 4167.4 4187.4 4251.0 4253.6 4358.7 3360.0 3447.8 3448.7 3449.0 3452.5 623.3 630.9 636.6 651.2 654.4 562.8 563.6 600.7 627.8 663.2 7950.4 9052.3 10239.5 10270.6 10500.3 535.4 574.8 591.5 638.7 700.4 4652.2 4943.5 5012.4 5026.7 5065.1 75676.0 89149.1 90867.0 93513.0 93648.0 79139.6 83267.0 83576.0 84474.6 85346.0 61751.1 63471.0 71862.6 72803.3 74788.0 8947.8 9545.9 9575.8 9639.0 9658.0 33913.3 45784.8 46348.3 46439.3 47016.5 45776.0 52240.5 57888.4 60870.7 61392.7 11723.4 11862.9 12285.9 12330.6 12414.5 12300.8 12807.9 12869.0 12920.2 13356.2 11678.0 12105.5 12160.4 12265.7 12311.3 11827.2 11864.4 11958.6 12177.9 12411.1 11819.3 11881.9 11892.2 11933.0 12056.9 10019.3 10098.4 10426.4 10597.6 10649.8 9700.6 9843.8 9880.0 9895.0 9898.8 00:01:26 INFO - PROCESS | 1649 | -------- Summary: end -------- 00:01:26 INFO - PROCESS | 1649 | 00:01:27 INFO - TEST-INFO | 1649: exit 0 00:01:27 INFO - TEST-OK | dromaeo_css | took 1119764ms 00:01:27 INFO - TEST-START | kraken 00:01:27 INFO - operating with platform_type : mac_ 00:01:27 INFO - Initialising browser for kraken test... 00:01:27 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmp5dgEHt/profile http://localhost:49239/getInfo.html) 00:01:29 INFO - PROCESS | 1655 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 00:01:29 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 00:01:29 INFO - PROCESS | 1655 | colorDepth:24 00:01:29 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 00:01:29 INFO - PROCESS | 1655 | __metrics 00:01:29 INFO - PROCESS | 1655 | 1485849689713 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (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:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 00:01:29 INFO - PROCESS | 1655 | 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" 00:01:29 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:01:30 INFO - TEST-INFO | 1655: exit 0 00:01:30 INFO - Browser initialized. 00:01:30 INFO - Running cycle 1/1 for kraken test... 00:01:30 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmp5dgEHt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:01:42 INFO - PROCESS | 1656 | RSS: Main: 236929024 00:01:42 INFO - PROCESS | 1656 | 00:01:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 00:01:46 INFO - PROCESS | 1656 | RSS: Main: 289976320 00:01:46 INFO - PROCESS | 1656 | 00:01:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 00:01:49 INFO - PROCESS | 1656 | RSS: Main: 292171776 00:01:49 INFO - PROCESS | 1656 | 00:01:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 00:01:53 INFO - PROCESS | 1656 | RSS: Main: 381825024 00:01:53 INFO - PROCESS | 1656 | 00:01:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 00:01:56 INFO - PROCESS | 1656 | RSS: Main: 283955200 00:01:56 INFO - PROCESS | 1656 | 00:01:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:01:59 INFO - PROCESS | 1656 | RSS: Main: 287301632 00:01:59 INFO - PROCESS | 1656 | 00:02:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 00:02:02 INFO - PROCESS | 1656 | RSS: Main: 329842688 00:02:02 INFO - PROCESS | 1656 | 00:02:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 00:02:06 INFO - PROCESS | 1656 | RSS: Main: 331386880 00:02:06 INFO - PROCESS | 1656 | 00:02:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 00:02:09 INFO - PROCESS | 1656 | RSS: Main: 329359360 00:02:09 INFO - PROCESS | 1656 | 00:02:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:02:12 INFO - PROCESS | 1656 | RSS: Main: 305217536 00:02:12 INFO - PROCESS | 1656 | 00:02:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:02:14 INFO - PROCESS | 1656 | RSS: Main: 309092352 00:02:14 INFO - PROCESS | 1656 | 00:02:16 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:02:17 INFO - PROCESS | 1656 | RSS: Main: 312492032 00:02:17 INFO - PROCESS | 1656 | 00:02:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:02:21 INFO - PROCESS | 1656 | RSS: Main: 328560640 00:02:21 INFO - PROCESS | 1656 | 00:02:24 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:02:24 INFO - PROCESS | 1656 | RSS: Main: 322691072 00:02:24 INFO - PROCESS | 1656 | 00:02:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 00:02:27 INFO - PROCESS | 1656 | RSS: Main: 367026176 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | __start_tp_report 00:02:27 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 00:02:27 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 00:02:27 INFO - PROCESS | 1656 | |i|pagename|runs| 00:02:27 INFO - PROCESS | 1656 | |0;ai-astar;115;114;113;114;114;114;118;117;91;159 00:02:27 INFO - PROCESS | 1656 | |1;audio-beat-detection;137;110;106;140;111;110;109;110;129;109 00:02:27 INFO - PROCESS | 1656 | |2;audio-dft;200;255;203;186;183;180;183;182;185;184 00:02:27 INFO - PROCESS | 1656 | |3;audio-fft;79;61;71;112;84;89;83;59;74;59 00:02:27 INFO - PROCESS | 1656 | |4;audio-oscillator;90;70;84;70;69;99;117;97;99;112 00:02:27 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;89;86;89;85;87;86;90;87;89;85 00:02:27 INFO - PROCESS | 1656 | |6;imaging-darkroom;95;110;150;148;97;95;95;95;97;95 00:02:27 INFO - PROCESS | 1656 | |7;imaging-desaturate;88;85;88;85;84;88;87;88;85;87 00:02:27 INFO - PROCESS | 1656 | |8;json-parse-financial;62;60;59;58;59;59;60;59;59;59 00:02:27 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;53;98;100;104;75;71;69;70;70;68 00:02:27 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;87;85;85;86;91;88;84;102;90;88 00:02:27 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;140;141;140;142;153;147;143;140;186;182 00:02:27 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;173;171;174;173;173;172;190;152;231;210 00:02:27 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;68;69;68;70;78;87;85;85;70;65 00:02:27 INFO - PROCESS | 1656 | __end_tp_report 00:02:27 INFO - PROCESS | 1656 | __start_cc_report 00:02:27 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2054 00:02:27 INFO - PROCESS | 1656 | __end_cc_report 00:02:27 INFO - PROCESS | 1656 | __startTimestamp1485849746999__endTimestamp 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | ------- Summary: start ------- 00:02:27 INFO - PROCESS | 1656 | Number of tests: 14 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:116.90 Median:116.00 stddev:16.66 (14.4%) stddev-sans-first:17.65 00:02:27 INFO - PROCESS | 1656 | Values: 115.0 114.0 113.0 114.0 114.0 114.0 118.0 117.0 91.0 159.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:117.10 Median:110.50 stddev:12.93 (11.7%) stddev-sans-first:11.54 00:02:27 INFO - PROCESS | 1656 | Values: 137.0 110.0 106.0 140.0 111.0 110.0 109.0 110.0 129.0 109.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:194.10 Median:185.50 stddev:22.77 (12.3%) stddev-sans-first:24.05 00:02:27 INFO - PROCESS | 1656 | Values: 200.0 255.0 203.0 186.0 183.0 180.0 183.0 182.0 185.0 184.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:77.10 Median:76.50 stddev:16.35 (21.4%) stddev-sans-first:17.33 00:02:27 INFO - PROCESS | 1656 | Values: 79.0 61.0 71.0 112.0 84.0 89.0 83.0 59.0 74.0 59.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:87.00 stddev:17.31 (19.9%) stddev-sans-first:18.36 00:02:27 INFO - PROCESS | 1656 | Values: 90.0 70.0 84.0 70.0 69.0 99.0 117.0 97.0 99.0 112.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:88.00 stddev:1.83 (2.1%) stddev-sans-first:1.83 00:02:27 INFO - PROCESS | 1656 | Values: 89.0 86.0 89.0 85.0 87.0 86.0 90.0 87.0 89.0 85.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:107.70 Median:95.00 stddev:22.25 (23.4%) stddev-sans-first:23.12 00:02:27 INFO - PROCESS | 1656 | Values: 95.0 110.0 150.0 148.0 97.0 95.0 95.0 95.0 97.0 95.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:86.50 Median:87.50 stddev:1.58 (1.8%) stddev-sans-first:1.58 00:02:27 INFO - PROCESS | 1656 | Values: 88.0 85.0 88.0 85.0 84.0 88.0 87.0 88.0 85.0 87.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:59.00 stddev:1.07 (1.8%) stddev-sans-first:0.60 00:02:27 INFO - PROCESS | 1656 | Values: 62.0 60.0 59.0 58.0 59.0 59.0 60.0 59.0 59.0 59.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:77.80 Median:70.00 stddev:16.84 (24.1%) stddev-sans-first:15.28 00:02:27 INFO - PROCESS | 1656 | Values: 53.0 98.0 100.0 104.0 75.0 71.0 69.0 70.0 70.0 68.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:87.50 stddev:5.21 (6.0%) stddev-sans-first:5.49 00:02:27 INFO - PROCESS | 1656 | Values: 87.0 85.0 85.0 86.0 91.0 88.0 84.0 102.0 90.0 88.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:151.40 Median:145.00 stddev:17.68 (12.2%) stddev-sans-first:18.26 00:02:27 INFO - PROCESS | 1656 | Values: 140.0 141.0 140.0 142.0 153.0 147.0 143.0 140.0 186.0 182.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:173.50 stddev:22.80 (13.1%) stddev-sans-first:23.95 00:02:27 INFO - PROCESS | 1656 | Values: 173.0 171.0 174.0 173.0 173.0 172.0 190.0 152.0 231.0 210.0 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:74.00 stddev:8.40 (11.3%) stddev-sans-first:8.57 00:02:27 INFO - PROCESS | 1656 | Values: 68.0 69.0 68.0 70.0 78.0 87.0 85.0 85.0 70.0 65.0 00:02:27 INFO - PROCESS | 1656 | -------- Summary: end -------- 00:02:27 INFO - PROCESS | 1656 | 00:02:27 INFO - TEST-INFO | 1656: exit 0 00:02:27 INFO - TEST-OK | kraken | took 60404ms 00:02:27 INFO - SUITE-END | took 1180s 00:02:28 INFO - Completed test suite (00:19:40) 00:02:28 INFO - javascript benchmark 00:02:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7149.466443933753, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2918.4309059733346, 3302.141443224761, 3343.8129464040267, 3376.6116896556905, 3395.575553055868, 5614.185943038267, 5664.688442135681, 5705.572082093844, 5714.542836573075, 5782.987977104006, 5612.186232996213, 5650.067738823089, 5679.290088738897, 5683.801066962657, 5693.202951586771, 5119.795208191668, 5157.0, 5204.271402003723, 5220.190870415092, 5254.290670759456, 5257.737113144347, 5287.603429742765, 5299.708516031636, 5307.920381194285, 5342.385625653061, 5127.333446651949, 5185.974070129663, 5256.342957130359, 5265.025970195487, 5276.577873770089, 3731.850725970985, 4338.696291259613, 5167.22491626259, 5227.660202086852, 5298.682079075268, 5122.052420302219, 5172.224166375074, 5197.714125723083, 5254.133068043769, 5257.947420525784, 5675.091985282335, 5708.029634962025, 5727.541796656257, 5728.656280623176, 5743.971280143614, 6032.939670603281, 6099.69501524922, 6103.6032657877095, 6314.305426403138, 6314.652694101823, 6198.318184999646, 6205.627662340274, 6216.782412615582, 6225.7509699611965, 6308.93691063088, 5824.767009319579, 5832.941670583325, 5833.38749431312, 5837.708114594296, 5877.617954832965, 6161.4146656067605, 6294.590851594632, 6318.178636777281, 6320.146780184617, 6323.146375239376, 5976.701164941735, 6021.969890150521, 6028.668423236676, 6098.939010609926, 6171.012637977902, 6185.783497577653, 6201.162843016225, 6215.595986260879, 6289.930711779009, 6321.146645202839, 6167.136600875881, 6247.0629405589525, 6253.249610046823, 6265.122882796412, 6276.372362763778, 6274.247090349188, 6299.937000630027, 6300.905486417708, 6308.779192728232, 6310.652914089769, 4888.9755551222015, 4894.608431325557, 4944.90110197794, 4945.208766597327, 4984.0, 4410.206162890647, 4416.072624748838, 4424.292113261863, 4451.287793952952, 4481.148581769518, 5628.212050312959, 5646.520045796154, 5708.086706127, 5711.143328500758, 5776.335721392094, 5951.3751056139345, 6031.788887388921, 6132.049532322497, 6251.468625166821, 6280.874382512324, 4793.976030119827, 5572.3870374258795, 5646.0, 5712.2288491054005, 5776.508996735324, 5388.676679399248, 5408.053590621704, 5409.513143817076, 5413.458654134555, 5416.404195538487, 6313.684315784192, 6320.810375688737, 6331.3352098029045, 6347.428731414195, 6348.650824204713, 6097.054956481752, 6149.20060392164, 6179.876402471836, 6199.721012554356, 6301.6219026858535, 6259.906101408392, 6261.655608941552, 6309.842253943688, 6332.873342533216, 6334.461570766536, 6124.075808605272, 6124.319907527303, 6130.540209484396, 6162.691865406801, 6165.075238714049, 6248.937510624835, 6313.273973493107, 6318.62088274705, 6334.239891213084, 6335.3981371769605, 5887.4406931343165, 6145.84635384119, 6146.661933593517, 6155.35368786271, 6161.72272247741, 5932.466078052823, 5998.070299103646, 6047.848803779764, 6072.332043475126, 6088.964875971007, 5936.614120082181, 5961.344252132349, 5962.105684147239, 5965.552583556338, 5985.341612422717, 6076.301225358963, 6091.939080609137, 6105.45050945417, 6112.205413296243, 6114.296855861633, 5675.943240567707, 5767.452092051249, 5782.190493331022, 5888.793892213752, 5933.910991335047], "value": 5674.534478014409, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.2078664304076, 461.547683270395, 467.95412253613176, 490.77424384783, 493.06810128856466, 31028.53457198141, 32987.505187422175, 41353.37969930453, 41623.79188104055, 43442.913927151756, 42426.36360454588, 42688.14623707524, 42716.14567708644, 42940.71177864661, 42944.141117177714, 8414.621342039623, 8633.611487483078, 9210.953945230282, 9484.241260699127, 9497.0, 11028.613998510054, 11322.980931716143, 11332.320060796337, 11469.139814513903, 11529.654110376701, 11290.774184516345, 11303.943480282629, 11358.772824543505, 11359.318440893532, 11403.201775875692, 11275.830862537068, 11349.432528373549, 11349.546018159264, 11359.602413915556, 11368.0, 11022.50398732059, 11126.107212124825, 11129.833052504218, 11207.831882521768, 11217.0, 38936.61063389386, 39376.0, 39616.80191599023, 39646.0, 39857.60142398568, 3655.670989610921, 4007.3788562772816, 4020.2361551305453, 4035.9798201008807, 4044.635982761536, 29917.10248692543, 30205.413878457966, 30499.23751906198, 30906.690933090606, 31024.0, 2897.6088228089147, 3115.8286294253808, 3145.1979745164836, 3181.3955348483714, 3272.378248132847, 364205.0, 365232.0, 368544.1572792146, 368595.0, 370999.0, 57794.0, 58030.70984645134, 58121.0, 58163.4183658158, 58172.70913645405, 60078.0, 61503.76992460215, 61981.69009154925, 62008.0, 62102.75794484175, 15259.923700381649, 15460.0, 15461.14963676986, 15468.071915685096, 15490.0, 10657.147428205724, 10876.075533579577, 10896.0, 11687.0, 11856.762864742657, 15066.0, 15486.064241969754, 15516.301766420538, 15548.533543993699, 15631.140287284308, 7180.9893229895715, 7185.497015208885, 7215.891761623579, 7263.3826124778925, 7276.527025743416, 806.2340776262574, 814.4461765999177, 814.4461765999177, 822.4530687093136, 840.4075127035426, 29302.560461593093, 29472.410551788846, 29768.255793605334, 30059.0, 30520.542191867138, 825.9504429734235, 986.413084214891, 1022.823752684404, 1032.7573020340214, 1043.139409986749, 13268.469261229635, 14946.626334341729, 15174.848251517564, 15424.0, 15474.613134671497, 623268.0, 653589.0, 666206.0, 844655.0, 845111.0, 51539.71150721187, 52165.217521737206, 52305.476945230825, 52404.21393679098, 52449.0, 1364.2701154882288, 1394.3028485757122, 1408.6971301170297, 1416.4971435140583, 1420.7939848721878, 27146.592801108392, 27336.863315683295, 27544.58683119795, 27942.580861287504, 28297.717022829507, 43249.0, 44153.33769993517, 44184.779076104416, 44193.55806442023, 44227.90404671642, 533415.6658433521, 534666.0, 535124.0, 535668.0, 537127.0, 19250.8074919249, 19654.90172549128, 23273.650895236922, 23494.412639684146, 23871.925763341038, 38002.049948751504, 38370.80814595909, 38723.0, 38915.80542097271, 39135.80432097821, 33161.336773264884, 33316.66683333136, 33332.833335833166, 34253.48619770656, 34508.65491345055, 32504.83747581247, 33120.834395827864, 33910.491342630376, 34002.14994625154, 34385.0, 32531.837340813145, 32951.50572741363, 32956.50565241475, 33083.33833323272, 33135.84024559129, 27143.457130857667, 29560.0, 29760.25599359947, 29817.25456863509, 29970.100896973127, 21124.577508450053, 21239.256626018014, 22507.212247571257, 22837.429064273525, 23106.653400199346], "value": 18999.95611216922, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.6562197156645, 384.33318192935235, 386.18707620458935, 405.16940272441485, 415.2173153605453, 16997.830021699778, 19768.802311976877, 20506.897465512688, 21909.780902190974, 22265.998030088624, 11960.0, 12491.562795302167, 12523.436445359957, 12538.749225015494, 21854.89072554639, 5265.210218467232, 5536.474034966672, 5546.500814926656, 5546.77812887484, 5548.500634942854, 4574.679772415916, 5337.839864804062, 5416.052190866602, 5466.480684335002, 5477.890442191154, 4706.623470122399, 5465.125579907216, 5467.289252397183, 5468.234447177399, 5471.398146203915, 5458.990086833928, 5931.970340148293, 6204.100405441231, 6268.373162683741, 6282.246130464374, 5977.551683623746, 5999.580029397944, 6000.399960004008, 6112.6638034908065, 6139.201903752483, 23113.7688623115, 25028.624570631455, 26319.73680263192, 26800.463990720076, 26956.865215673795, 2566.7561581649716, 2618.109842653507, 2637.2483842104907, 2641.5773476243903, 2643.5109504741686, 10166.9491652542, 10198.898011019868, 10204.23468239885, 10801.513931873005, 10830.72923176919, 1826.2421095245456, 1870.812918708132, 1871.5321169707572, 1878.64305781901, 1881.491997160759, 112094.87905121008, 136393.0, 143382.0, 144001.67994960168, 144890.13324000637, 9866.80266394668, 11689.88310116888, 11728.648140555797, 11914.0, 12049.397530123633, 15790.447334343244, 15799.763003554956, 15801.36794528206, 15829.445969391247, 15835.970661906938, 8303.626336814857, 8328.167183281623, 8337.666493340199, 8695.391322607356, 8805.735827925173, 8272.627731752085, 8288.627011784483, 8296.8340633187, 8446.873296900429, 8454.915450845412, 4268.338407546835, 4510.571495707903, 4742.596879265232, 4758.762061896891, 4764.6664733469015, 1703.9829601704073, 1958.3831093205595, 2013.7784843667184, 2015.2543558883308, 2022.1203776357038, 955.2358113509165, 962.2897154668494, 964.4358050540512, 970.1608108985677, 986.4820968991337, 22087.543432245748, 22523.774762252495, 22588.66117008246, 22606.0, 23005.424864378525, 974.2449601558707, 985.6008316631776, 987.5506644476745, 1002.8094662014194, 1004.0712341084613, 11290.943545282222, 11553.942230288794, 11561.42192890352, 11572.189946703818, 11616.361100139404, 577340.0, 617761.0, 719474.0, 729747.0, 742614.7300425455, 140313.0, 170511.0, 172335.0, 173604.0, 176717.0, 1047.104725459721, 1076.4025965589174, 1119.227732864321, 1126.2735535579504, 1129.757102223026, 17906.91046544811, 19595.0, 20828.0, 20888.895555522126, 20902.58194836125, 45900.77049614864, 57787.711061444425, 58015.70992145012, 59815.70092149511, 61426.0, 92388.61417078615, 95378.0, 95618.52190739002, 96143.76006940141, 96359.51820240854, 8340.916590834015, 8384.455010424303, 8559.443636163629, 8834.072422395557, 8834.867476987722, 13005.284709340698, 13119.803202952155, 13344.199348039148, 13349.599512014654, 13650.180989140683, 11314.773704525698, 11360.0, 11686.766264674829, 11715.824262635897, 11838.467268972745, 11257.49341279628, 11269.042131418593, 11370.772584548427, 11653.76692466163, 11735.0, 11379.943100284445, 11547.191696581158, 11741.530338786695, 11777.764444711229, 11902.940485297519, 6947.930520694728, 7577.583232922242, 7637.389008879165, 7936.6428510718315, 8695.130486951255, 10058.0, 11316.83024754613, 11408.258463200198, 11814.586489472827, 11872.465739041936], "value": 9892.261181348249, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1319.7294554616306, 1421.587739555529, 1425.4013314407948, 1445.125698952134, 1448.8840892728579, 7417.592032438214, 7727.72952946647, 7963.24349186827, 8217.465864718804, 8236.0, 8078.474899131553, 8093.312068474187, 8100.4734692244965, 8131.430799844014, 8192.631331590077, 2017.4250338653487, 2092.0, 2235.4076169815053, 2280.7605201453857, 2291.4844160064017, 2247.3819699582664, 2312.19073324336, 2324.34918222897, 2324.360800779782, 2327.7439481657, 2253.1550668499312, 2272.8409011369213, 2278.168468508999, 2286.588414085464, 2289.496310811619, 1787.49950013995, 2014.335269361122, 2080.6878968154747, 2270.886455677218, 2292.5758734634146, 2199.4061603367163, 2282.589133955887, 2287.874166920819, 2290.4960908600083, 2295.5294164696365, 8172.754817355489, 8224.917750822535, 8230.176982301782, 8240.21717936795, 8245.917540824636, 4953.6037117030555, 5056.393232812049, 5083.694978301277, 5084.237364395333, 5090.54185123337, 2876.2377969838008, 3250.2199472126754, 3422.6064002639764, 3481.582210134775, 3496.423090190141, 1139.2367114033618, 1182.4028865422993, 1190.2739329009298, 1240.6898275431142, 1243.5150291386367, 10135.138513226311, 10235.0788429041, 10257.128144107759, 10262.794744105076, 10266.384016959006, 3717.386631205875, 4370.672199585044, 4560.853011422175, 4574.176648203289, 4624.953750462452, 9268.165865072177, 9273.72178834636, 9285.72142835716, 9286.860697089414, 9297.023812499725, 5106.412762532282, 5203.765830537635, 5206.0, 5207.0366982108435, 5212.530872221519, 4571.588557029883, 4586.6789324747615, 4718.41019872516, 4771.06964141989, 4783.091212669582, 3151.3697260547983, 3734.607866174067, 3759.661630453272, 3788.0908581940134, 3825.6748176404762, 1260.1682889293022, 1261.4512686981193, 1267.790814515613, 1270.1934271737327, 1275.789494733377, 807.7657479330928, 834.561855026104, 838.4340570115148, 853.9615717292737, 888.0187392930718, 5218.556422704112, 5220.634555581073, 5434.728263586805, 5524.58565607573, 5582.218489411485, 558.3355806590145, 871.3029576338904, 915.3135148638521, 947.9715608531756, 955.8709574207533, 3382.864685412605, 3599.6580324869096, 3682.3003629310347, 3763.190913953458, 3802.6957843372466, 9240.491772952551, 9586.568604412818, 9814.607415703293, 9878.950605247072, 9905.900940990497, 9686.273529485317, 9780.315377923476, 9799.559019844124, 9804.313698041069, 9827.508624568743, 7529.736459223901, 7706.460547761715, 7715.305622494003, 7731.729389471341, 7733.0, 4514.954850451453, 5415.187721841755, 5429.67421954684, 5463.754131064032, 5615.634983726045, 9048.773780655536, 9168.31237657165, 9181.989981102208, 9202.309826763154, 9203.907960920305, 9107.681231156877, 9197.448153110834, 9308.95345523268, 9426.95286523563, 9431.764205894908, 6877.277885822116, 6966.616836073862, 7032.542884712682, 7064.364207221375, 7087.255838136922, 7477.513961592479, 7546.509476883985, 7546.849063018599, 7571.3564347031115, 7585.658645361083, 6647.534672572873, 6666.566673166229, 6849.383555480031, 6898.793036208922, 6981.581105133709, 6682.298358672464, 6715.0, 6716.6977486012265, 6729.158855143107, 6744.831379215362, 5973.0, 6005.489533389711, 6018.4583387493585, 6085.9695701521205, 6094.9085763714465, 4791.9281210782565, 4868.026394721141, 4891.192953162688, 4941.209406494943, 5054.74726263678, 4791.5687588117235, 4856.344393506973, 4929.112759703288, 4939.431965324034, 5040.420351659606], "value": 4296.487522226984, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.2626464190928, 351.8152969690912, 355.94482855157446, 362.5884620955216, 371.8456840411229, 4384.561543845613, 4993.625478089144, 5150.665206761567, 5151.8877260323225, 5156.246876062138, 4024.919501609966, 5102.617303702219, 5171.250168725542, 5214.582833373331, 5239.345081864767, 3496.702780263681, 4095.9180816383655, 4114.629683328492, 4129.339305711087, 4155.646770024551, 3938.5667576566557, 4089.7750623715683, 4107.958920410788, 4117.279476091687, 4137.110521237933, 3062.9234269143226, 3686.7972261525606, 3931.3906344516645, 4119.6704263658985, 4138.482689663792, 3555.822208889545, 3582.910427239314, 3761.9278505625766, 3910.569837317893, 4134.3591743279685, 3372.629010808809, 3645.9453108203134, 3650.233450975298, 3658.579263384718, 3666.8349924253207, 4615.2769865779355, 4623.583877451012, 4760.357351757538, 5148.407933087706, 5159.74201289934, 4638.281066434674, 4741.288806679026, 4755.334253204554, 4765.475797662254, 4788.712677239342, 5159.303494028333, 5496.340439147288, 5567.8886422271735, 5588.692621905793, 5629.549636029108, 3368.9663103369144, 3588.102974256436, 4053.716239863211, 4064.2887494688607, 4065.7357271777537, 5771.8456308738005, 5776.9422305776825, 5809.0, 5850.736716847752, 5862.706864656835, 3871.090293780959, 3874.380099184117, 3886.358750806142, 3909.5113110861143, 3936.8031598419966, 3774.5659249186147, 3972.463717398172, 3977.6817854571564, 3996.2606917720314, 4002.479677641888, 4300.247456695066, 4536.977315113403, 4576.954230457719, 4580.862574122782, 4606.401167848158, 5265.183896496049, 5471.890562188813, 5485.122380419114, 5490.258815059996, 5503.7798488060025, 973.090160699748, 1151.441550847838, 1222.2727477151082, 1263.5072321794507, 1275.4057428214733, 821.4309988251373, 859.7463748194298, 872.908344623818, 873.1748497669641, 874.2699845628844, 4329.891752706208, 4815.362776655954, 4830.782614782343, 4878.219379980544, 4939.53074457926, 5061.392632884003, 5232.607554433433, 5342.412334643186, 5389.541888939406, 5443.8094666686475, 3919.0398352403845, 4480.4623445186135, 4484.53788480464, 4867.756612169377, 4876.073950789419, 2362.551115288124, 2488.171194172318, 2809.35652643206, 2879.3809330993936, 2885.5238885584063, 4712.0, 4900.0, 5267.657602255841, 5491.478309560585, 5676.489115979582, 5358.356997160287, 5404.351477822608, 5531.731989884128, 5619.93221287954, 5940.168376427304, 4972.825951091767, 5260.631755777059, 5481.163810123653, 5604.271444712162, 5914.408559144051, 4855.490173531728, 5241.47585241488, 5275.736213189249, 5730.914036289376, 5777.8555536111935, 4572.428446444194, 5326.41409444953, 5548.639338442988, 5735.369109398046, 5744.396838331915, 4978.950210497994, 5071.822486212965, 5942.346341902301, 5963.403659634176, 6020.946334391551, 3298.8185649789493, 3891.5719270879613, 3939.0, 3944.980275098606, 3947.0, 4987.9750601248215, 5149.326468897549, 5593.748281327432, 5769.8557536061935, 5785.942140578541, 5481.451854814646, 5489.258950041853, 5510.641808282448, 5630.704937864232, 5644.6330988485615, 4916.557509824133, 5515.558755299486, 5560.749766260447, 5598.944010559842, 5627.831165065055, 4536.523665015258, 5415.35015798091, 5460.26286451324, 5489.58828087903, 5494.752736126804, 5163.922541161961, 5568.749406276646, 5580.441955804387, 5589.720513974366, 5590.937721832839, 4577.5880170784785, 5398.78404863801, 5483.862903427446, 5503.284573005483, 5505.339359276913], "value": 4026.3954886550328, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.5005071424646, 737.3732327521607, 765.885117232415, 820.4994953078622, 835.3150416658341, 14806.185659788707, 15827.525174244762, 15838.76241856373, 15871.603209919758, 15885.761713574306, 14547.127172369639, 14727.116373017627, 14735.484258050943, 14737.336819843105, 14844.628884277898, 688.3563867783638, 712.9536580122301, 874.2830878679486, 889.1775108025083, 894.8568229083348, 856.1866227084264, 881.3962435731512, 889.968851090212, 891.0421297105605, 894.2532984957568, 942.189716843514, 946.3801210207291, 946.6592026870321, 948.3172116076414, 952.642758965388, 940.9670661526882, 942.6700654770843, 943.2831048403194, 945.6264775413707, 945.7210123013728, 534.4201541327665, 550.9146082357241, 671.1744554198315, 692.4079911675495, 701.638656092113, 15391.461298854487, 15464.845351546453, 15476.922615386964, 15510.30203640839, 15510.767338489932, 13715.657108572366, 13724.65688357789, 13759.0, 13777.448902043907, 13790.65523361914, 12662.936685316514, 12794.104412691115, 12872.42074106658, 12885.549005784753, 13029.543965961146, 4457.710248833815, 4487.887802804923, 4501.7974191161475, 4631.143238500888, 4648.442186937556, 102816.48591757068, 105203.94796052018, 106278.0, 107393.0, 108795.45602271939, 10816.27571094181, 11117.777644447066, 11128.0, 11282.984531392214, 11297.26567773092, 18210.726839097424, 21975.780242197372, 22071.66892496614, 22223.44441388978, 22266.443338916655, 7289.817754556178, 7363.153237377663, 7367.373773229228, 7385.963070184722, 7444.8883266751045, 4167.395727619539, 4187.430717146188, 4250.978745106317, 4253.574642535784, 4358.738475691405, 3360.0, 3447.775894566845, 3448.7067349743847, 3449.034270404291, 3452.5339079224486, 623.2987888625297, 630.9242890853035, 636.6339354870968, 651.2152855808739, 654.443722835586, 562.8030189433665, 563.6167406163848, 600.6936462404205, 627.7727043629693, 663.1975309875105, 7950.363970882316, 9052.321075919332, 10239.539220735085, 10270.6405275815, 10500.317479363817, 535.4484880573016, 574.7554311410265, 591.5474661883662, 638.6897453162815, 700.4466471487468, 4652.209124448852, 4943.4809345018975, 5012.423571289349, 5026.673266237683, 5065.088284108896, 75676.0, 89149.10850891538, 90867.0, 93513.0, 93648.0, 79139.60430197927, 83267.0, 83576.0, 84474.57762711147, 85346.0, 61751.07373389403, 63471.0, 71862.56274874473, 72803.27196728176, 74788.0, 8947.823522649365, 9545.904540954501, 9575.760605984906, 9638.95180524093, 9657.951710241405, 33913.32173356568, 45784.77107614574, 46348.30477542772, 46439.30341044819, 47016.477363621656, 45776.0, 52240.47759522356, 57888.421115789984, 60870.69564652148, 61392.693036536315, 11723.35521546323, 11862.881371186179, 12285.938570307091, 12330.63008109758, 12414.503419863104, 12300.753984920073, 12807.871921280668, 12869.034822388172, 12920.22478651284, 13356.198628082346, 11678.0, 12105.51577936908, 12160.391980400767, 12265.693357665772, 12311.261324320569, 11827.231229970024, 11864.406779661154, 11958.64124076279, 12177.878221217674, 12411.069169812481, 11819.290842549475, 11881.881181188077, 11892.167548271884, 11933.0, 12056.879431205576, 10019.348742331726, 10098.358692343614, 10426.374417534973, 10597.576096956036, 10649.840252396065, 9700.563474643517, 9843.803123937338, 9880.0, 9894.950525247328, 9898.752531186778], "value": 7237.450988122773, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1511.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 113.0, 114.0, 114.0, 114.0, 118.0, 117.0, 91.0, 159.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 110.0, 106.0, 140.0, 111.0, 110.0, 109.0, 110.0, 129.0, 109.0], "value": 117.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 255.0, 203.0, 186.0, 183.0, 180.0, 183.0, 182.0, 185.0, 184.0], "value": 194.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 61.0, 71.0, 112.0, 84.0, 89.0, 83.0, 59.0, 74.0, 59.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 84.0, 70.0, 69.0, 99.0, 117.0, 97.0, 99.0, 112.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 85.0, 87.0, 86.0, 90.0, 87.0, 89.0, 85.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 110.0, 150.0, 148.0, 97.0, 95.0, 95.0, 95.0, 97.0, 95.0], "value": 107.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 84.0, 88.0, 87.0, 88.0, 85.0, 87.0], "value": 86.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 59.0, 58.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 98.0, 100.0, 104.0, 75.0, 71.0, 69.0, 70.0, 70.0, 68.0], "value": 77.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 86.0, 91.0, 88.0, 84.0, 102.0, 90.0, 88.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 140.0, 142.0, 153.0, 147.0, 143.0, 140.0, 186.0, 182.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 174.0, 173.0, 173.0, 172.0, 190.0, 152.0, 231.0, 210.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 70.0, 78.0, 87.0, 85.0, 85.0, 70.0, 65.0], "value": 74.5, "unit": "score"}]}]} 00:02:28 INFO - Return code: 0 00:02:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:02:28 ERROR - # TBPL SUCCESS # 00:02:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:02:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:02:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:02:28 INFO - 2017-01-31 00:02:28.318 screenresolution[1660:10470] starting screenresolution argv=screenresolution get 00:02:28 INFO - 2017-01-31 00:02:28.321 screenresolution[1660:10470] Display 0: 1600x1200x32@60 00:02:28 INFO - 2017-01-31 00:02:28.327 screenresolution[1661:10473] starting screenresolution argv=screenresolution list 00:02:28 INFO - Available Modes on Display 0 00:02:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:02:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:02:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:02:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:02:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:02:28 INFO - Intel Iris: 00:02:28 INFO - Chipset Model: Intel Iris 00:02:28 INFO - Type: GPU 00:02:28 INFO - Bus: Built-In 00:02:28 INFO - VRAM (Dynamic, Max): 1536 MB 00:02:28 INFO - Vendor: Intel (0x8086) 00:02:28 INFO - Device ID: 0x0a2e 00:02:28 INFO - Revision ID: 0x0009 00:02:28 INFO - Displays: 00:02:28 INFO - Display: 00:02:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:02:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:02:28 INFO - Display Serial Number: 1600x1200 60 00:02:28 INFO - Main Display: Yes 00:02:28 INFO - Mirror: Off 00:02:28 INFO - Online: Yes 00:02:28 INFO - Rotation: Supported 00:02:28 INFO - Return code: 0 00:02:28 INFO - Running post-action listener: _resource_record_post_action 00:02:28 INFO - [mozharness: 2017-01-31 08:02:28.569783Z] Finished run-tests step (success) 00:02:28 INFO - Running post-run listener: _resource_record_post_run 00:02:28 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 37022720; Write bytes: 571268096; Read time: 343; Write time: 1755 00:02:28 INFO - TinderboxPrint: CPU usage
53.8% 00:02:28 INFO - TinderboxPrint: I/O read bytes / time
37,022,720 / 343 00:02:28 INFO - TinderboxPrint: I/O write bytes / time
571,268,096 / 1,755 00:02:28 INFO - TinderboxPrint: CPU idle
2,222.1 (46.2%) 00:02:28 INFO - TinderboxPrint: CPU system
962.4 (20.0%) 00:02:28 INFO - TinderboxPrint: CPU user
1,629.3 (33.8%) 00:02:28 INFO - TinderboxPrint: Swap in / out
279,547,904 / 0 00:02:28 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 246600192; Write bytes: 240002560; Read time: 17833; Write time: 523 00:02:28 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 34962432; Write bytes: 315909632; Read time: 325; Write time: 1189 00:02:29 INFO - Running post-run listener: _upload_blobber_files 00:02:29 INFO - Blob upload gear active. 00:02:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:02: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 00:02: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'] 00:02: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 00:02:29 INFO - (blobuploader) - INFO - Open directory for files ... 00:02:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:02:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:02:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:02:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:02:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:02:30 INFO - (blobuploader) - INFO - Done attempting. 00:02:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:02:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:02:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:02:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:02:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:02:31 INFO - (blobuploader) - INFO - Done attempting. 00:02:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:02:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:02:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:02:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:02:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:02:32 INFO - (blobuploader) - INFO - Done attempting. 00:02:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:02:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:02:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:02:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:02:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:02:34 INFO - (blobuploader) - INFO - Done attempting. 00:02:34 INFO - (blobuploader) - INFO - Iteration through files over. 00:02:34 INFO - Return code: 0 00:02:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:02:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:02:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3ab53365cc14d6530a903ec947b49a5be9920dba6e126654675a1c942c2dd912076bf3ebc7d5c8a89a63d0944a8d4e9d8a1fcd78f160029de96dda8b4197212", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb496e1e4a7e94916cc941f8100f8fae080bc2f48720c57bb66340fbe24313ac980d2a1a79c3208d4a531297873a782e8079f794d125e84e902a3f75ea54abf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/502d8dc46086fef1d810f984c03810b4fae9a5da284f6ffdf0069fa97e43b1f815d153a50dc1051ca9b640ce719a3b0cd4a148f45ad647b618fc694cb0d5d8b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a587638edb3f1b908e2b6b6d87cc1d3b074e9ebb76a08f17744c2a4b950c8359e94cf005af2f66ab52d855b7f9bc9fee9a57a3e3a4bd1633c19e539394d22f7a"} 00:02:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:02:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:02:34 INFO - Contents: 00:02:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3ab53365cc14d6530a903ec947b49a5be9920dba6e126654675a1c942c2dd912076bf3ebc7d5c8a89a63d0944a8d4e9d8a1fcd78f160029de96dda8b4197212", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb496e1e4a7e94916cc941f8100f8fae080bc2f48720c57bb66340fbe24313ac980d2a1a79c3208d4a531297873a782e8079f794d125e84e902a3f75ea54abf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/502d8dc46086fef1d810f984c03810b4fae9a5da284f6ffdf0069fa97e43b1f815d153a50dc1051ca9b640ce719a3b0cd4a148f45ad647b618fc694cb0d5d8b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a587638edb3f1b908e2b6b6d87cc1d3b074e9ebb76a08f17744c2a4b950c8359e94cf005af2f66ab52d855b7f9bc9fee9a57a3e3a4bd1633c19e539394d22f7a"} 00:02:34 INFO - Running post-run listener: copy_logs_to_upload_dir 00:02:34 INFO - Copying logs to upload dir... 00:02:34 INFO - mkdir: /builds/slave/test/build/upload/logs 00:02:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.161905 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-31 00:02:34.240979) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 00:02:34.245017) ========= 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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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/e3ab53365cc14d6530a903ec947b49a5be9920dba6e126654675a1c942c2dd912076bf3ebc7d5c8a89a63d0944a8d4e9d8a1fcd78f160029de96dda8b4197212", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb496e1e4a7e94916cc941f8100f8fae080bc2f48720c57bb66340fbe24313ac980d2a1a79c3208d4a531297873a782e8079f794d125e84e902a3f75ea54abf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/502d8dc46086fef1d810f984c03810b4fae9a5da284f6ffdf0069fa97e43b1f815d153a50dc1051ca9b640ce719a3b0cd4a148f45ad647b618fc694cb0d5d8b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a587638edb3f1b908e2b6b6d87cc1d3b074e9ebb76a08f17744c2a4b950c8359e94cf005af2f66ab52d855b7f9bc9fee9a57a3e3a4bd1633c19e539394d22f7a"} build_url:https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011014 build_url: 'https://queue.taskcluster.net/v1/task/Tad3EL3VTu-Qunig9pe0Xw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3ab53365cc14d6530a903ec947b49a5be9920dba6e126654675a1c942c2dd912076bf3ebc7d5c8a89a63d0944a8d4e9d8a1fcd78f160029de96dda8b4197212", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb496e1e4a7e94916cc941f8100f8fae080bc2f48720c57bb66340fbe24313ac980d2a1a79c3208d4a531297873a782e8079f794d125e84e902a3f75ea54abf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/502d8dc46086fef1d810f984c03810b4fae9a5da284f6ffdf0069fa97e43b1f815d153a50dc1051ca9b640ce719a3b0cd4a148f45ad647b618fc694cb0d5d8b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a587638edb3f1b908e2b6b6d87cc1d3b074e9ebb76a08f17744c2a4b950c8359e94cf005af2f66ab52d855b7f9bc9fee9a57a3e3a4bd1633c19e539394d22f7a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 00:02:34.282451) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 00:02:34.282767) ========= 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.mlUvFY8I8Z/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.3HgITWbg0T/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.004927 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 00:02:34.331236) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 00:02:34.331562) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 00:02:34.331916) ========= ========= Total master_lag: 0.20 =========