builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0254 starttime: 1482818919.41 results: success (0) buildid: 20161226210137 builduid: ecd3add69c544b2b9c11b43d132d4dd0 revision: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:39.412620) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:39.413062) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:39.413337) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:39.438775) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:39.439208) ========= 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.wuZlWit4mN/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.U2rXAVfYP8/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-26 22:08:39-- 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% 58.1M=0s 2016-12-26 22:08:39 (58.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.480326 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:39.942846) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:39.943211) ========= 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.wuZlWit4mN/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.U2rXAVfYP8/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.019423 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:39.992268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 22:08:39.992622) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad --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 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wuZlWit4mN/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.U2rXAVfYP8/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-26 22:08:40,075 truncating revision to first 12 chars 2016-12-26 22:08:40,075 Setting DEBUG logging. 2016-12-26 22:08:40,075 attempt 1/10 2016-12-26 22:08:40,075 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3119a9a0b5de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 22:08:41,199 unpacking tar archive at: mozilla-central-3119a9a0b5de/testing/mozharness/ program finished with exit code 0 elapsedTime=1.393255 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 22:08:41.409400) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:41.409783) ========= script_repo_revision: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:41.410217) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:41.410492) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 22:08:41.430756) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-26 22:08:41.431192) ========= /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.wuZlWit4mN/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.U2rXAVfYP8/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 22:08:41 INFO - MultiFileLogger online at 20161226 22:08:41 in /builds/slave/test 22:08:41 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 22:08:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:08:41 INFO - {'append_to_log': False, 22:08:41 INFO - 'base_work_dir': '/builds/slave/test', 22:08:41 INFO - 'blob_upload_branch': 'Firefox', 22:08:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:08:41 INFO - 'branch': 'Firefox', 22:08:41 INFO - 'buildbot_json_path': 'buildprops.json', 22:08:41 INFO - 'config_files': ('talos/mac_config.py',), 22:08:41 INFO - 'default_actions': ('clobber', 22:08:41 INFO - 'read-buildbot-config', 22:08:41 INFO - 'download-and-extract', 22:08:41 INFO - 'populate-webroot', 22:08:41 INFO - 'create-virtualenv', 22:08:41 INFO - 'install', 22:08:41 INFO - 'run-tests'), 22:08:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:08:41 INFO - 'download_minidump_stackwalk': True, 22:08:41 INFO - 'download_symbols': 'ondemand', 22:08:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:08:41 INFO - 'tooltool.py': '/tools/tooltool.py', 22:08:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:08:41 INFO - '/tools/misc-python/virtualenv.py')}, 22:08:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:08:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:08:41 INFO - 'installer_path': 'installer.exe', 22:08:41 INFO - 'log_level': 'info', 22:08:41 INFO - 'log_name': 'talos', 22:08:41 INFO - 'log_to_console': True, 22:08:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:08:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:08:41 INFO - 'opt_config_files': (), 22:08:41 INFO - 'pip_index': False, 22:08:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:08:41 INFO - 'cmd': ('bash', 22:08:41 INFO - '-c', 22:08:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:08:41 INFO - 'enabled': True, 22:08:41 INFO - 'halt_on_failure': False, 22:08:41 INFO - 'name': 'check_screen_resolution'},), 22:08:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:08:41 INFO - 'cmd': ('bash', 22:08:41 INFO - '-c', 22:08:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:08:41 INFO - 'enabled': True, 22:08:41 INFO - 'halt_on_failure': False, 22:08:41 INFO - 'name': 'check_screen_resolution'},), 22:08:41 INFO - 'run_cmd_checks_enabled': True, 22:08:41 INFO - 'sps_profile': False, 22:08:41 INFO - 'sps_profile_interval': 0, 22:08:41 INFO - 'suite': 'dromaeojs', 22:08:41 INFO - 'system_bits': '32', 22:08:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:08:41 INFO - 'title': 't-yosemite-r7-0254', 22:08:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:08:41 INFO - 'use_talos_json': True, 22:08:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:08:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:08:41 INFO - 'work_dir': 'build'} 22:08:41 INFO - [mozharness: 2016-12-27 06:08:41.633552Z] Running clobber step. 22:08:41 INFO - Running pre-action listener: _resource_record_pre_action 22:08:41 INFO - Running main action method: clobber 22:08:41 INFO - rmtree: /builds/slave/test/build 22:08:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:08:41 INFO - Running post-action listener: _resource_record_post_action 22:08:41 INFO - [mozharness: 2016-12-27 06:08:41.827510Z] Finished clobber step (success) 22:08:41 INFO - [mozharness: 2016-12-27 06:08:41.827662Z] Running read-buildbot-config step. 22:08:41 INFO - Running pre-action listener: _resource_record_pre_action 22:08:41 INFO - Running main action method: read_buildbot_config 22:08:41 INFO - Using buildbot properties: 22:08:41 INFO - { 22:08:41 INFO - "project": "", 22:08:41 INFO - "product": "firefox", 22:08:41 INFO - "script_repo_revision": "production", 22:08:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 22:08:41 INFO - "repository": "", 22:08:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 22:08:41 INFO - "buildid": "20161226210137", 22:08:41 INFO - "pgo_build": "False", 22:08:41 INFO - "basedir": "/builds/slave/test", 22:08:41 INFO - "buildnumber": 163, 22:08:41 INFO - "slavename": "t-yosemite-r7-0254", 22:08:41 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:08:41 INFO - "platform": "yosemite_r7", 22:08:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:08:41 INFO - "branch": "mozilla-central", 22:08:41 INFO - "repo_path": "mozilla-central", 22:08:41 INFO - "revision": "3119a9a0b5dee60ac77b7596ae5dbe0658f598ad", 22:08:41 INFO - "stage_platform": "macosx64", 22:08:41 INFO - "builduid": "ecd3add69c544b2b9c11b43d132d4dd0", 22:08:41 INFO - "slavebuilddir": "test" 22:08:41 INFO - } 22:08:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 22:08:41 INFO - Running post-action listener: _resource_record_post_action 22:08:41 INFO - [mozharness: 2016-12-27 06:08:41.831151Z] Finished read-buildbot-config step (success) 22:08:41 INFO - [mozharness: 2016-12-27 06:08:41.831287Z] Running download-and-extract step. 22:08:41 INFO - Running pre-action listener: _resource_record_pre_action 22:08:41 INFO - Running main action method: download_and_extract 22:08:41 INFO - mkdir: /builds/slave/test/build/tests 22:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:41 INFO - trying https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 22:08:41 INFO - Downloading https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 22:08:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 22:08:42 INFO - Downloaded 1199 bytes. 22:08:42 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 22:08:42 INFO - Using the following test package requirements: 22:08:42 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:08:42 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:08:42 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 22:08:42 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:08:42 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 22:08:42 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:08:42 INFO - u'jsshell-mac.zip'], 22:08:42 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:08:42 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 22:08:42 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:08:42 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:08:42 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 22:08:42 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:08:42 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 22:08:42 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:08:42 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 22:08:42 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:08:42 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 22:08:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:08:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:08:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:08:42 INFO - Fetch https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:08:43 INFO - Expected file size: 16294314 22:08:43 INFO - Obtained file size: 16294314 22:08:50 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:08:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:08:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:08:50 INFO - Fetch https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:08:51 INFO - Expected file size: 16294314 22:08:51 INFO - Obtained file size: 16294314 22:08:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 22:08:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:08:57 INFO - Fetch https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 22:08:58 INFO - Expected file size: 14409776 22:08:58 INFO - Obtained file size: 14409776 22:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:59 INFO - trying https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:08:59 INFO - Downloading https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 22:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 22:09:01 INFO - Downloaded 69623413 bytes. 22:09:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:09:01 INFO - mkdir: /builds/slave/test/properties 22:09:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:09:01 INFO - Writing to file /builds/slave/test/properties/build_url 22:09:01 INFO - Contents: 22:09:01 INFO - build_url:https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:09:01 INFO - Running post-action listener: _resource_record_post_action 22:09:01 INFO - Running post-action listener: set_extra_try_arguments 22:09:01 INFO - [mozharness: 2016-12-27 06:09:01.766131Z] Finished download-and-extract step (success) 22:09:01 INFO - [mozharness: 2016-12-27 06:09:01.766257Z] Running populate-webroot step. 22:09:01 INFO - Running pre-action listener: _resource_record_pre_action 22:09:01 INFO - Running main action method: populate_webroot 22:09:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:09:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:09:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:09:01 INFO - '--tppagecycles', 22:09:01 INFO - '7'], 22:09:01 INFO - 'tests': ['tsvgm']}}, 22:09:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:09:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:09:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:09:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:09:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:09:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:09:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:09:01 INFO - 'talos_options': ['--disable-e10s'], 22:09:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:09:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:09:01 INFO - 'talos_options': ['--disable-e10s'], 22:09:01 INFO - 'tests': ['damp', 'tps']}, 22:09:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:09:01 INFO - 'tests': ['damp', 'tps']}, 22:09:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:09:01 INFO - 'tests': ['dromaeo_dom']}, 22:09:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:09:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:09:01 INFO - 'tests': ['basic_compositor_video']}, 22:09:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:09:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:09:01 INFO - 'tests': ['a11yr', 22:09:01 INFO - 'ts_paint', 22:09:01 INFO - 'tpaint', 22:09:01 INFO - 'sessionrestore', 22:09:01 INFO - 'sessionrestore_no_auto_restore', 22:09:01 INFO - 'tabpaint']}, 22:09:01 INFO - 'other-e10s': {'tests': ['a11yr', 22:09:01 INFO - 'ts_paint', 22:09:01 INFO - 'tpaint', 22:09:01 INFO - 'sessionrestore', 22:09:01 INFO - 'sessionrestore_no_auto_restore', 22:09:01 INFO - 'tabpaint']}, 22:09:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:09:01 INFO - 'ts_paint', 22:09:01 INFO - 'tpaint', 22:09:01 INFO - 'sessionrestore', 22:09:01 INFO - 'sessionrestore_no_auto_restore', 22:09:01 INFO - 'tabpaint']}, 22:09:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:09:01 INFO - 'ts_paint', 22:09:01 INFO - 'tpaint', 22:09:01 INFO - 'sessionrestore', 22:09:01 INFO - 'sessionrestore_no_auto_restore', 22:09:01 INFO - 'tabpaint']}, 22:09:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:09:01 INFO - 'tests': ['a11yr', 22:09:01 INFO - 'ts_paint', 22:09:01 INFO - 'tpaint', 22:09:01 INFO - 'sessionrestore', 22:09:01 INFO - 'sessionrestore_no_auto_restore', 22:09:01 INFO - 'tabpaint']}, 22:09:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:09:01 INFO - 'tests': ['a11yr', 22:09:01 INFO - 'ts_paint', 22:09:01 INFO - 'tpaint', 22:09:01 INFO - 'sessionrestore', 22:09:01 INFO - 'sessionrestore_no_auto_restore', 22:09:01 INFO - 'tabpaint']}, 22:09:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:09:01 INFO - 'tests': ['tsvgx', 22:09:01 INFO - 'tsvgr_opacity', 22:09:01 INFO - 'tart', 22:09:01 INFO - 'tscrollx', 22:09:01 INFO - 'cart', 22:09:01 INFO - 'tsvg_static']}, 22:09:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:09:01 INFO - 'tsvgr_opacity', 22:09:01 INFO - 'tart', 22:09:01 INFO - 'tscrollx', 22:09:01 INFO - 'cart', 22:09:01 INFO - 'tsvg_static']}, 22:09:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:09:01 INFO - 'talos_options': ['--disable-e10s'], 22:09:01 INFO - 'tests': ['tp5o']}, 22:09:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:09:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:09:01 INFO - 'talos_options': ['--disable-e10s', 22:09:01 INFO - '--xperf_path', 22:09:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:01 INFO - 'tests': ['tp5n']}, 22:09:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:09:01 INFO - 'talos_options': ['--xperf_path', 22:09:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:01 INFO - 'tests': ['tp5n']}}, 22:09:01 INFO - 'talos.zip': {'path': '', 22:09:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:09:01 INFO - Running post-action listener: _resource_record_post_action 22:09:01 INFO - [mozharness: 2016-12-27 06:09:01.784895Z] Finished populate-webroot step (success) 22:09:01 INFO - [mozharness: 2016-12-27 06:09:01.785029Z] Running create-virtualenv step. 22:09:01 INFO - Running pre-action listener: _resource_record_pre_action 22:09:01 INFO - Running main action method: create_virtualenv 22:09:01 INFO - Creating virtualenv /builds/slave/test/build/venv 22:09:01 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 22:09:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:09:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:09:01 INFO - Using real prefix '/tools/python27' 22:09:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:09:02 INFO - Installing distribute.............................................................................................................................................................................................done. 22:09:05 INFO - Installing pip.................done. 22:09:05 INFO - Return code: 0 22:09:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:09:05 INFO - Reading from file tmpfile_stdout 22:09:05 INFO - Output received: 22:09:05 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) 22:09:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:09: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')]} 22:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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')]} 22:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed844d6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea23d30>, '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 0x10e94adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18be8>, '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.wuZlWit4mN/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.U2rXAVfYP8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 22:09: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', 'pip>=1.5'] in /builds/slave/test/build 22:09: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 pip>=1.5 22:09:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuZlWit4mN/Render', 22:09:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:05 INFO - 'HOME': '/Users/cltbld', 22:09:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:05 INFO - 'LOGNAME': 'cltbld', 22:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:05 INFO - 'MOZ_NO_REMOTE': '1', 22:09:05 INFO - 'NO_EM_RESTART': '1', 22:09:05 INFO - 'PAGER': '/bin/cat', 22:09:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:05 INFO - 'PWD': '/builds/slave/test', 22:09:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:05 INFO - 'SHELL': '/bin/bash', 22:09:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U2rXAVfYP8/Listeners', 22:09:05 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 22:09:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:05 INFO - 'USER': 'cltbld', 22:09:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:05 INFO - 'XPC_FLAGS': '0x0', 22:09:05 INFO - 'XPC_SERVICE_NAME': '0', 22:09:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:05 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 22:09:05 INFO - Cleaning up... 22:09:06 INFO - Return code: 0 22:09:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:09:06 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')]} 22:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:06 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')]} 22:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:06 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 0x10ec45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed844d6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea23d30>, '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 0x10e94adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18be8>, '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.wuZlWit4mN/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.U2rXAVfYP8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 22:09:06 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 22:09:06 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 22:09:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuZlWit4mN/Render', 22:09:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:06 INFO - 'HOME': '/Users/cltbld', 22:09:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:06 INFO - 'LOGNAME': 'cltbld', 22:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:06 INFO - 'MOZ_NO_REMOTE': '1', 22:09:06 INFO - 'NO_EM_RESTART': '1', 22:09:06 INFO - 'PAGER': '/bin/cat', 22:09:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:06 INFO - 'PWD': '/builds/slave/test', 22:09:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:06 INFO - 'SHELL': '/bin/bash', 22:09:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U2rXAVfYP8/Listeners', 22:09:06 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 22:09:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:06 INFO - 'USER': 'cltbld', 22:09:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:06 INFO - 'XPC_FLAGS': '0x0', 22:09:06 INFO - 'XPC_SERVICE_NAME': '0', 22:09:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:06 INFO - Downloading/unpacking psutil>=3.1.1 22:09:06 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 22:09:06 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 22:09:06 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 22:09:06 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 22:09:06 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 22:09:06 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 22:09:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:09:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:10 INFO - Installing collected packages: psutil 22:09:10 INFO - Running setup.py install for psutil 22:09:10 INFO - building 'psutil._psutil_osx' extension 22:09:10 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 22:09:10 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 22:09:10 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 22:09:10 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 22:09:10 INFO - building 'psutil._psutil_posix' extension 22:09:10 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 22:09:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:09:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:09:10 INFO - ^ 22:09:10 INFO - 1 warning generated. 22:09:10 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 22:09:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:10 INFO - Successfully installed psutil 22:09:10 INFO - Cleaning up... 22:09:11 INFO - Return code: 0 22:09:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed844d6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea23d30>, '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 0x10e94adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18be8>, '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.wuZlWit4mN/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.U2rXAVfYP8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 22:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuZlWit4mN/Render', 22:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:11 INFO - 'HOME': '/Users/cltbld', 22:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:11 INFO - 'LOGNAME': 'cltbld', 22:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:11 INFO - 'MOZ_NO_REMOTE': '1', 22:09:11 INFO - 'NO_EM_RESTART': '1', 22:09:11 INFO - 'PAGER': '/bin/cat', 22:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:11 INFO - 'PWD': '/builds/slave/test', 22:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:11 INFO - 'SHELL': '/bin/bash', 22:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U2rXAVfYP8/Listeners', 22:09:11 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 22:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:11 INFO - 'USER': 'cltbld', 22:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:11 INFO - 'XPC_FLAGS': '0x0', 22:09:11 INFO - 'XPC_SERVICE_NAME': '0', 22:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:09: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 22:09: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 22:09: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 22:09:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09: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 22:09: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 22:09:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:09:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:09:13 INFO - Installing collected packages: mozsystemmonitor 22:09:13 INFO - Running setup.py install for mozsystemmonitor 22:09:14 INFO - Successfully installed mozsystemmonitor 22:09:14 INFO - Cleaning up... 22:09:14 INFO - Return code: 0 22:09:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:09: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')]} 22:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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')]} 22:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ec45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed844d6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea23d30>, '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 0x10e94adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18be8>, '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.wuZlWit4mN/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.U2rXAVfYP8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 22:09:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:09:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:09:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuZlWit4mN/Render', 22:09:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:14 INFO - 'HOME': '/Users/cltbld', 22:09:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:14 INFO - 'LOGNAME': 'cltbld', 22:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:14 INFO - 'MOZ_NO_REMOTE': '1', 22:09:14 INFO - 'NO_EM_RESTART': '1', 22:09:14 INFO - 'PAGER': '/bin/cat', 22:09:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:14 INFO - 'PWD': '/builds/slave/test', 22:09:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:14 INFO - 'SHELL': '/bin/bash', 22:09:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U2rXAVfYP8/Listeners', 22:09:14 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 22:09:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:14 INFO - 'USER': 'cltbld', 22:09:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:14 INFO - 'XPC_FLAGS': '0x0', 22:09:14 INFO - 'XPC_SERVICE_NAME': '0', 22:09:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:14 INFO - Downloading/unpacking jsonschema==2.5.1 22:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:09:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:17 INFO - Downloading functools32-3.2.3-2.tar.gz 22:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:09:17 INFO - warning: no files found matching '*.txt' 22:09:17 INFO - no previously-included directories found matching 'build' 22:09:17 INFO - no previously-included directories found matching 'dist' 22:09:17 INFO - no previously-included directories found matching '.git*' 22:09:17 INFO - Installing collected packages: jsonschema, functools32 22:09:17 INFO - Running setup.py install for functools32 22:09:17 INFO - warning: no files found matching '*.txt' 22:09:17 INFO - no previously-included directories found matching 'build' 22:09:17 INFO - no previously-included directories found matching 'dist' 22:09:17 INFO - no previously-included directories found matching '.git*' 22:09:17 INFO - Successfully installed jsonschema functools32 22:09:17 INFO - Cleaning up... 22:09:17 INFO - Return code: 0 22:09:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:09:17 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')]} 22:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:17 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')]} 22:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:17 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 0x10ec45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed844d6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea23d30>, '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 0x10e94adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18be8>, '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.wuZlWit4mN/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.U2rXAVfYP8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 22:09:17 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 22:09:17 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 22:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuZlWit4mN/Render', 22:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:17 INFO - 'HOME': '/Users/cltbld', 22:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:17 INFO - 'LOGNAME': 'cltbld', 22:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:17 INFO - 'MOZ_NO_REMOTE': '1', 22:09:17 INFO - 'NO_EM_RESTART': '1', 22:09:17 INFO - 'PAGER': '/bin/cat', 22:09:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:17 INFO - 'PWD': '/builds/slave/test', 22:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:17 INFO - 'SHELL': '/bin/bash', 22:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U2rXAVfYP8/Listeners', 22:09:17 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 22:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:17 INFO - 'USER': 'cltbld', 22:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:17 INFO - 'XPC_FLAGS': '0x0', 22:09:17 INFO - 'XPC_SERVICE_NAME': '0', 22:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:09:18 INFO - Cleaning up... 22:09:18 INFO - Return code: 0 22:09:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:09: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')]} 22:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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')]} 22:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:18 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 0x10ec45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed844d6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea23d30>, '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 0x10e94adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18be8>, '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.wuZlWit4mN/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.U2rXAVfYP8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 22:09:18 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 22:09:18 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 22:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuZlWit4mN/Render', 22:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:18 INFO - 'HOME': '/Users/cltbld', 22:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:18 INFO - 'LOGNAME': 'cltbld', 22:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:18 INFO - 'MOZ_NO_REMOTE': '1', 22:09:18 INFO - 'NO_EM_RESTART': '1', 22:09:18 INFO - 'PAGER': '/bin/cat', 22:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:18 INFO - 'PWD': '/builds/slave/test', 22:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:18 INFO - 'SHELL': '/bin/bash', 22:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U2rXAVfYP8/Listeners', 22:09:18 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 22:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:18 INFO - 'USER': 'cltbld', 22:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:18 INFO - 'XPC_FLAGS': '0x0', 22:09:18 INFO - 'XPC_SERVICE_NAME': '0', 22:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:18 INFO - Downloading/unpacking blobuploader==1.2.4 22:09:18 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 22:09:18 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 22:09:18 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 22:09:18 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 22:09:18 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 22:09:18 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 22:09:20 INFO - Downloading blobuploader-1.2.4.tar.gz 22:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:09:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:09:21 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 22:09:21 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 22:09:21 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 22:09:21 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 22:09:21 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 22:09:21 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 22:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:09:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:09:21 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 22:09:21 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 22:09:21 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 22:09:21 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 22:09:21 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 22:09:21 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 22:09:22 INFO - Downloading docopt-0.6.1.tar.gz 22:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:09:22 INFO - Installing collected packages: blobuploader, requests, docopt 22:09:22 INFO - Running setup.py install for blobuploader 22:09:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:09:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:09:22 INFO - Running setup.py install for requests 22:09:22 INFO - Running setup.py install for docopt 22:09:23 INFO - Successfully installed blobuploader requests docopt 22:09:23 INFO - Cleaning up... 22:09:23 INFO - Return code: 0 22:09:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:23 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 0x10ec45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed844d6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea23d30>, '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 0x10e94adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18be8>, '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.wuZlWit4mN/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.U2rXAVfYP8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 22:09:23 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 22:09:23 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 22:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuZlWit4mN/Render', 22:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:23 INFO - 'HOME': '/Users/cltbld', 22:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:23 INFO - 'LOGNAME': 'cltbld', 22:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:23 INFO - 'MOZ_NO_REMOTE': '1', 22:09:23 INFO - 'NO_EM_RESTART': '1', 22:09:23 INFO - 'PAGER': '/bin/cat', 22:09:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:23 INFO - 'PWD': '/builds/slave/test', 22:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:23 INFO - 'SHELL': '/bin/bash', 22:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U2rXAVfYP8/Listeners', 22:09:23 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 22:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:23 INFO - 'USER': 'cltbld', 22:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:23 INFO - 'XPC_FLAGS': '0x0', 22:09:23 INFO - 'XPC_SERVICE_NAME': '0', 22:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-yshY3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Ot9qs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-q9RrPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-XAmHGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Y3uGsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-zctfEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Q0AUvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-SY_6aW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-VAgB7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-TYCw4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-awXiJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-3eR6n6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-PRgc0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-9tl5on-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-7S4fRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:25 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-YbcHil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:25 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-_qCXgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:09:25 INFO - Running setup.py install for manifestparser 22:09:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:09:25 INFO - Running setup.py install for mozcrash 22:09:25 INFO - Running setup.py install for mozdebug 22:09:25 INFO - Running setup.py install for mozdevice 22:09:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:09:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:09:25 INFO - Running setup.py install for mozfile 22:09:26 INFO - Running setup.py install for mozhttpd 22:09:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:09:26 INFO - Running setup.py install for mozinfo 22:09:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:09:26 INFO - Running setup.py install for mozInstall 22:09:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:09:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:09:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:09:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:09:26 INFO - Running setup.py install for mozleak 22:09:26 INFO - Running setup.py install for mozlog 22:09:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:09:26 INFO - Running setup.py install for moznetwork 22:09:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:09:26 INFO - Running setup.py install for mozprocess 22:09:27 INFO - Running setup.py install for mozprofile 22:09:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:09:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:09:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:09:27 INFO - Running setup.py install for mozrunner 22:09:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:09:27 INFO - Running setup.py install for mozscreenshot 22:09:27 INFO - Running setup.py install for moztest 22:09:27 INFO - Running setup.py install for mozversion 22:09:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:09:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:09:27 INFO - Cleaning up... 22:09:27 INFO - Return code: 0 22:09:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:27 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')]} 22:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:27 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')]} 22:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:27 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 0x10ec45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed844d6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea23d30>, '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 0x10e94adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18be8>, '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.wuZlWit4mN/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.U2rXAVfYP8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 22:09:27 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 22:09:27 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 22:09:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuZlWit4mN/Render', 22:09:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:27 INFO - 'HOME': '/Users/cltbld', 22:09:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:27 INFO - 'LOGNAME': 'cltbld', 22:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:27 INFO - 'MOZ_NO_REMOTE': '1', 22:09:27 INFO - 'NO_EM_RESTART': '1', 22:09:27 INFO - 'PAGER': '/bin/cat', 22:09:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:27 INFO - 'PWD': '/builds/slave/test', 22:09:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:27 INFO - 'SHELL': '/bin/bash', 22:09:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U2rXAVfYP8/Listeners', 22:09:27 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 22:09:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:27 INFO - 'USER': 'cltbld', 22:09:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:27 INFO - 'XPC_FLAGS': '0x0', 22:09:27 INFO - 'XPC_SERVICE_NAME': '0', 22:09:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:28 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-JIWRAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:28 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)) 22:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:28 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-n2UTeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:28 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)) 22:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:28 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-ykYnik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:28 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)) 22:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:28 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-HLr5mO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:28 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)) 22:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:28 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-LbAyto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:28 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)) 22:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:28 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-fTRdqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:28 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)) 22:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:28 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-INewlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:28 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)) 22:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:28 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-EAJDXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:29 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)) 22:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:29 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-vchynf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:29 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)) 22:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:29 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-MVL82n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:29 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)) 22:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:29 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-IZ23TD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:29 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)) 22:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:29 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-3NBWmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:29 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-RqNa4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:29 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)) 22:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:29 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-1_felH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:29 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)) 22:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:29 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-g0PsaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:29 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)) 22:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:29 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-3VNfX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 22:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:29 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-8062_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:29 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)) 22:09:29 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)) 22:09:29 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)) 22:09:29 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)) 22:09:29 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)) 22:09:29 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)) 22:09:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:32 INFO - Downloading blessings-1.6.tar.gz 22:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:09:32 INFO - Installing collected packages: blessings 22:09:32 INFO - Running setup.py install for blessings 22:09:32 INFO - Successfully installed blessings 22:09:32 INFO - Cleaning up... 22:09:32 INFO - Return code: 0 22:09:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:33 INFO - Reading from file tmpfile_stdout 22:09:33 INFO - Current package versions: 22:09:33 INFO - blessings == 1.6 22:09:33 INFO - blobuploader == 1.2.4 22:09:33 INFO - docopt == 0.6.1 22:09:33 INFO - functools32 == 3.2.3-2 22:09:33 INFO - jsonschema == 2.5.1 22:09:33 INFO - manifestparser == 1.1 22:09:33 INFO - mozInstall == 1.12 22:09:33 INFO - mozcrash == 1.0 22:09:33 INFO - mozdebug == 0.1 22:09:33 INFO - mozdevice == 0.49 22:09:33 INFO - mozfile == 1.2 22:09:33 INFO - mozhttpd == 0.7 22:09:33 INFO - mozinfo == 0.9 22:09:33 INFO - mozleak == 0.1 22:09:33 INFO - mozlog == 3.4 22:09:33 INFO - moznetwork == 0.27 22:09:33 INFO - mozprocess == 0.23 22:09:33 INFO - mozprofile == 0.28 22:09:33 INFO - mozrunner == 6.13 22:09:33 INFO - mozscreenshot == 0.1 22:09:33 INFO - mozsystemmonitor == 0.3 22:09:33 INFO - moztest == 0.7 22:09:33 INFO - mozversion == 1.4 22:09:33 INFO - psutil == 3.1.1 22:09:33 INFO - requests == 1.2.3 22:09:33 INFO - wsgiref == 0.1.2 22:09:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:33 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 0x10ec45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed844d6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea23d30>, '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 0x10e94adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18be8>, '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.wuZlWit4mN/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.U2rXAVfYP8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 22:09:33 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 22:09:33 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 22:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuZlWit4mN/Render', 22:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:33 INFO - 'HOME': '/Users/cltbld', 22:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:33 INFO - 'LOGNAME': 'cltbld', 22:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:33 INFO - 'MOZ_NO_REMOTE': '1', 22:09:33 INFO - 'NO_EM_RESTART': '1', 22:09:33 INFO - 'PAGER': '/bin/cat', 22:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:33 INFO - 'PWD': '/builds/slave/test', 22:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:33 INFO - 'SHELL': '/bin/bash', 22:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U2rXAVfYP8/Listeners', 22:09:33 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 22:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:33 INFO - 'USER': 'cltbld', 22:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:33 INFO - 'XPC_FLAGS': '0x0', 22:09:33 INFO - 'XPC_SERVICE_NAME': '0', 22:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:33 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)) 22:09:33 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)) 22:09:33 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)) 22:09:33 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)) 22:09:33 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)) 22:09:33 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)) 22:09:33 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)) 22:09:33 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)) 22:09:33 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)) 22:09:33 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)) 22:09:33 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)) 22:09:33 INFO - Cleaning up... 22:09:33 INFO - Return code: 0 22:09:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:33 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 0x10ec45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed844d6c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea23d30>, '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 0x10e94adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18be8>, '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.wuZlWit4mN/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.U2rXAVfYP8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 22:09:33 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 22:09:33 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 22:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuZlWit4mN/Render', 22:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:33 INFO - 'HOME': '/Users/cltbld', 22:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:33 INFO - 'LOGNAME': 'cltbld', 22:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:33 INFO - 'MOZ_NO_REMOTE': '1', 22:09:33 INFO - 'NO_EM_RESTART': '1', 22:09:33 INFO - 'PAGER': '/bin/cat', 22:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:33 INFO - 'PWD': '/builds/slave/test', 22:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:33 INFO - 'SHELL': '/bin/bash', 22:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U2rXAVfYP8/Listeners', 22:09:33 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 22:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:33 INFO - 'USER': 'cltbld', 22:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:33 INFO - 'XPC_FLAGS': '0x0', 22:09:33 INFO - 'XPC_SERVICE_NAME': '0', 22:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:09:33 INFO - Cleaning up... 22:09:33 INFO - Return code: 0 22:09:33 INFO - Running post-action listener: _resource_record_post_action 22:09:33 INFO - Running post-action listener: _start_resource_monitoring 22:09:33 INFO - Starting resource monitoring. 22:09:33 INFO - [mozharness: 2016-12-27 06:09:33.801703Z] Finished create-virtualenv step (success) 22:09:33 INFO - [mozharness: 2016-12-27 06:09:33.802698Z] Running install step. 22:09:33 INFO - Running pre-action listener: _resource_record_pre_action 22:09:33 INFO - Running main action method: install 22:09:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:34 INFO - Reading from file tmpfile_stdout 22:09:34 INFO - Detecting whether we're running mozinstall >=1.0... 22:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:09:34 INFO - Reading from file tmpfile_stdout 22:09:34 INFO - Output received: 22:09:34 INFO - Usage: mozinstall [options] installer 22:09:34 INFO - Options: 22:09:34 INFO - -h, --help show this help message and exit 22:09:34 INFO - -d DEST, --destination=DEST 22:09:34 INFO - Directory to install application into. [default: 22:09:34 INFO - "/builds/slave/test"] 22:09:34 INFO - --app=APP Application being installed. [default: firefox] 22:09:34 INFO - mkdir: /builds/slave/test/build/application 22:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:09:56 INFO - Reading from file tmpfile_stdout 22:09:56 INFO - Output received: 22:09:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:09:56 INFO - Running post-action listener: _resource_record_post_action 22:09:56 INFO - [mozharness: 2016-12-27 06:09:56.212669Z] Finished install step (success) 22:09:56 INFO - [mozharness: 2016-12-27 06:09:56.212807Z] Running run-tests step. 22:09:56 INFO - Running pre-action listener: _resource_record_pre_action 22:09:56 INFO - Running main action method: run_tests 22:09:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:09:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:09:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:09:56 INFO - 2016-12-26 22:09:56.227 screenresolution[1880:9261] starting screenresolution argv=screenresolution get 22:09:56 INFO - 2016-12-26 22:09:56.229 screenresolution[1880:9261] Display 0: 1600x1200x32@60 22:09:56 INFO - 2016-12-26 22:09:56.235 screenresolution[1881:9263] starting screenresolution argv=screenresolution list 22:09:56 INFO - Available Modes on Display 0 22:09:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:09:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:09:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:09:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:09:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:09:56 INFO - Intel Iris: 22:09:56 INFO - Chipset Model: Intel Iris 22:09:56 INFO - Type: GPU 22:09:56 INFO - Bus: Built-In 22:09:56 INFO - VRAM (Dynamic, Max): 1536 MB 22:09:56 INFO - Vendor: Intel (0x8086) 22:09:56 INFO - Device ID: 0x0a2e 22:09:56 INFO - Revision ID: 0x0009 22:09:56 INFO - Displays: 22:09:56 INFO - Display: 22:09:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:09:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:09:56 INFO - Display Serial Number: 1600x1200 60 22:09:56 INFO - Main Display: Yes 22:09:56 INFO - Mirror: Off 22:09:56 INFO - Online: Yes 22:09:56 INFO - Rotation: Supported 22:09:56 INFO - Return code: 0 22:09:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:09:56 INFO - Python 2.7.3 22:09:56 INFO - Return code: 0 22:09:56 INFO - grabbing minidump binary from tooltool 22:09: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')]} 22:09:56 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 0x10ea23d30>, '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 0x10e94adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:09:56 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 22:09:56 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 22:09:56 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 22:09:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:09:56 INFO - Return code: 0 22:09:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:09:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:09:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:09:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:09:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:09:56 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/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 22:09:56 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/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0254 --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 22:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuZlWit4mN/Render', 22:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:56 INFO - 'HOME': '/Users/cltbld', 22:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:56 INFO - 'LOGNAME': 'cltbld', 22:09:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:09:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:56 INFO - 'MOZ_NO_REMOTE': '1', 22:09:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:09:56 INFO - 'NO_EM_RESTART': '1', 22:09:56 INFO - 'PAGER': '/bin/cat', 22:09:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:56 INFO - 'PWD': '/builds/slave/test', 22:09:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:56 INFO - 'SHELL': '/bin/bash', 22:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U2rXAVfYP8/Listeners', 22:09:56 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 22:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:56 INFO - 'USER': 'cltbld', 22:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:56 INFO - 'XPC_FLAGS': '0x0', 22:09:56 INFO - 'XPC_SERVICE_NAME': '0', 22:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:56 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/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 22:09:56 INFO - mozversion application_buildid: 20161226210137 22:09:56 INFO - mozversion application_changeset: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad 22:09:56 INFO - mozversion application_display_name: Nightly 22:09:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:09:56 INFO - mozversion application_name: Firefox 22:09:56 INFO - mozversion application_remotingname: firefox 22:09:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:09:56 INFO - mozversion application_vendor: Mozilla 22:09:56 INFO - mozversion application_version: 53.0a1 22:09:56 INFO - mozversion platform_buildid: 20161226210137 22:09:56 INFO - mozversion platform_changeset: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad 22:09:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:09:56 INFO - mozversion platform_version: 53.0a1 22:09:56 INFO - using testdate: 1482818996 22:09:56 INFO - actual date: 1482818996 22:09:56 INFO - starting webserver on 'localhost:49380' 22:09:56 INFO - SUITE-START | Running 2 tests 22:09:56 INFO - TEST-START | dromaeo_css 22:09:56 INFO - operating with platform_type : mac_ 22:09:56 INFO - Initialising browser for dromaeo_css test... 22:09:56 INFO - TEST-INFO | started process 1891 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpqrAUtG/profile http://localhost:49380/getInfo.html) 22:09:57 INFO - PROCESS | 1891 | 1482818997332 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 22:09:59 INFO - PROCESS | 1891 | __metrics Screen width/height:1600/1200 22:09:59 INFO - PROCESS | 1891 | colorDepth:24 22:09:59 INFO - PROCESS | 1891 | Browser inner width/height: 1024/689 22:09:59 INFO - PROCESS | 1891 | __metrics 22:09:59 INFO - PROCESS | 1891 | 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" 22:09:59 INFO - PROCESS | 1891 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:10:00 INFO - TEST-INFO | 1891: exit 0 22:10:00 INFO - Browser initialized. 22:10:00 INFO - Running cycle 1/1 for dromaeo_css test... 22:10:00 INFO - TEST-INFO | started process 1893 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpqrAUtG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:10:12 INFO - PROCESS | 1893 | RSS: Main: 233824256 22:10:12 INFO - PROCESS | 1893 | 22:13:15 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:49380/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49380/tests/dromaeo/cssquery-ext.html) 22:13:17 INFO - PROCESS | 1893 | RSS: Main: 382418944 22:13:17 INFO - PROCESS | 1893 | 22:15:01 INFO - PROCESS | 1893 | ************************* 22:15:01 INFO - PROCESS | 1893 | A coding exception was thrown and uncaught in a Task. 22:15:01 INFO - PROCESS | 1893 | 22:15:01 INFO - PROCESS | 1893 | Full message: ReferenceError: fetch is not defined 22:15:01 INFO - PROCESS | 1893 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 22:15:01 INFO - PROCESS | 1893 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 22:15:01 INFO - PROCESS | 1893 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 22:15:01 INFO - PROCESS | 1893 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 22:15:01 INFO - PROCESS | 1893 | TaskImpl@resource://gre/modules/Task.jsm:277:3 22:15:01 INFO - PROCESS | 1893 | asyncFunction@resource://gre/modules/Task.jsm:252:14 22:15:01 INFO - PROCESS | 1893 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 22:15:01 INFO - PROCESS | 1893 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 22:15:01 INFO - PROCESS | 1893 | TaskImpl@resource://gre/modules/Task.jsm:277:3 22:15:01 INFO - PROCESS | 1893 | asyncFunction@resource://gre/modules/Task.jsm:252:14 22:15:01 INFO - PROCESS | 1893 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 22:15:01 INFO - PROCESS | 1893 | 22:15:01 INFO - PROCESS | 1893 | ************************* 22:16:20 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:49380/tests/dromaeo/cssquery-ext.html (next: http://localhost:49380/tests/dromaeo/cssquery-jquery.html) 22:16:20 INFO - PROCESS | 1893 | RSS: Main: 367042560 22:16:20 INFO - PROCESS | 1893 | 22:19:23 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:49380/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49380/tests/dromaeo/cssquery-mootools.html) 22:19:23 INFO - PROCESS | 1893 | RSS: Main: 359350272 22:19:23 INFO - PROCESS | 1893 | 22:22:26 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:49380/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49380/tests/dromaeo/cssquery-prototype.html) 22:22:26 INFO - PROCESS | 1893 | RSS: Main: 340230144 22:22:26 INFO - PROCESS | 1893 | 22:25:30 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:49380/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49380/tests/dromaeo/cssquery-yui.html) 22:25:30 INFO - PROCESS | 1893 | RSS: Main: 335953920 22:25:30 INFO - PROCESS | 1893 | 22:28:33 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:49380/tests/dromaeo/cssquery-yui.html (next: http://localhost:49380/tests/dromaeo/cssquery-dojo.html) 22:28:34 INFO - PROCESS | 1893 | RSS: Main: 548777984 22:28:34 INFO - PROCESS | 1893 | 22:28:34 INFO - PROCESS | 1893 | __start_tp_report 22:28:34 INFO - PROCESS | 1893 | _x_x_mozilla_page_load 22:28:34 INFO - PROCESS | 1893 | _x_x_mozilla_page_load_details 22:28:34 INFO - PROCESS | 1893 | |i|pagename|runs| 22:28:34 INFO - PROCESS | 1893 | |0;dojo.html;3313.3207692423052;3335.382954153482;3340.5657264555603;3349.3971085204685;3387.4749413840864;5630.268065151535;5654.830355089343;5721.914171287429;5725.570582206341;5776.451237132476;5675.3757086720425;5701.173329867167;5740.339860915996;5772.249607551013;5775.653460792355;4282.550332215119;5237.057329680656;5246.842594722164;5256.080185967459;5312.6281160318795;5281.155015197569;5306.230596563496;5310.946890531103;5318.282031925679;5322.308099947021;5269.051570717269;5284.762185701634;5381.273528073699;5385.865353366177;5419.403865574784;5314.202869569576;5321.042212401766;5325.893482130375;5333.679979201261;5337.226102215196;262.02495963918705;5161.303224064741;5255.947440525622;5265.236540701615;5288.206768984644;4441.489228738704;5702.885942281173;5732.971335143297;5766.308043034821;5770.365259821417;6211.943969525191;6256.124142620037;6260.718267677966;6282.591631543981;6290.245170579514;6285.371462853724;6288.371162883721;6311.684415779193;6345.333739957284;6347.270063942613;5635.239242702224;5719.6854173020465;5750.913736293959;5763.913541296842;5797.391273916178;6279.277883043417;6287.339829317947;6290.559660823698;6294.150289710922;6302.212223472066;5672.319321681424;5738.1679656450415;6165.32181460039;6210.161628180138;6212.006079027334;6088.452039316483;6160.075988601656;6176.969115154396;6230.6573138476915;6311.936880631135;5969.343372229051;6152.507799376039;6173.135760993375;6195.19462469876;6251.937480625135;6250.656213908279;6269.310375858652;6272.153259310026;6287.7799277026;6289.465395441439;4821.325014497973;4916.72957987307;4958.776855041532;4959.801607935642;4982.850514484571;4305.84929527465;4416.646668266531;4425.84509542171;4476.26141686618;4501.392312037899;5683.8010669626365;5802.158686990363;5836.153757705109;5856.297244330707;5856.472917437451;6070.939290607037;6109.84725381869;6122.938770612326;6176.104464852571;6244.2819075805955;5619.719014049363;5629.605927585029;5636.492715655529;5641.2384328116;5642.746076426571;5282.1548552232225;5333.439988801198;5344.679319240858;5350.036993341158;5363.03465376236;6225.844353891189;6292.339304372975;6292.937070629235;6293.779717709858;6338.58799178052;6246.812595622138;6275.278342990615;6305.9684701578035;6307.05394190875;6308.589941653778;6206.968965155145;6246.656433895998;6264;6314.273858506331;6317.5893566918;6124.350421260821;6213.689315534115;6219.129321895029;6223.439890409885;6228.5951413158;6215.595986260879;6242.438180563771;6267.122602835698;6307.684615769285;6332.430081292706;5781;6013.518918486423;6157.076438534255;6176.35148309421;6180.938190618216;6015.969920150371;6025.0661147522205;6033.336333003454;6113.449789518964;6140.140380346666;5890.558208134321;5898.852528686817;5908.172855800278;5924.170616113662;5941.287045554388;5896.793612223723;6088.51291896656;6089.695515224133;6107.511399088151;6112.480439162543;5841.620294680833;5871.236739223874;5872.794452194324;5926.318473375617;5952.8809423812145 22:28:34 INFO - PROCESS | 1893 | |1;ext.html;408.2019651581159;460.8847788052986;486.74202672583544;498.5189292332898;526.1581469648565;29550.704492955065;31598.210044748892;38169.618303816955;39781;39800.004999874945;40968.38547421791;41090.38364424536;41120.17759644813;41180.970475738046;41292;9278.628854845798;9505.80988380232;9506;9511;9521.381110227847;8508.149185081504;8765.780855478632;9361.391509551857;11135.554577816878;11434.371109588968;11391.259568128087;11447.942760286187;11456.713582160472;11463.426828658534;11464.31214127155;11302;11392.316461012366;11397.886021139766;11417;11418.885811141865;8365.581720913991;8598.5700714964;10891.618793342273;11216.49525771334;11222.32666040032;38381;38384.42423363652;38904;38946;39270;3974.9006274843073;3980.8407663693706;3996.9200615987515;4009.919801603952;4010.418489319062;28806.279843003882;30090.54864177039;30099.548506772415;30101.698983010327;30209.79160833564;3152.7004934531183;3281.737461003114;3282.097423208613;3320.25294308781;3325.667433256655;323696;325043.9999999976;325253;327431.36284318665;442869;56682.71658641763;57240.427595724344;57246.713766430905;57348.85302293931;57454;59557.70221148953;59559.022048898245;59583;59607;59900;12488.937555312164;15510.767338489932;15513.224338783015;15523.223838808015;15525.922370388076;10794.838077428845;11807.350595717318;11877.22798018126;11901.821472677917;11951.641450756491;13631.727365452834;14021.929890350482;15199.088054716753;15369.231538423257;15514.293427722941;6412.134361861183;6532;6599.868002639921;6606.8017959461295;6645.7040877029485;754.5849782619647;821.8561751693429;845.3955421873391;846.2848892685671;876.5361845717666;22712.772872271395;27193.72806271912;29336;29500.262493437407;29629.851850740608;977.745786095606;1003.0370843989851;1003.5835128421769;1004.5228516454772;1027.075631931253;13215.933920330337;13751;13753.393597660932;13834.515791947435;13985.384823008382;586845;595996;596156;597479;597866;47925;47951.5204847954;48195.75902120537;48207.517924821004;49010.509894901305;1350.372076984207;1369.9315034248248;1379.7309524642799;1383.391307824554;1383.6955869708647;23194.420139496648;24694.629580556666;25735.099271525407;25806.354841129123;25845.22464326073;42342.36486452644;42712.145757084065;42829.571704282556;42833;42844.571554284055;514247;517056;518492;519617;521011;23279.41801454977;23340.949657265122;23599.88200058989;23639.763602363757;23651.40871478227;38379.808100959315;40391;41224.58775412328;41411;41487;33601;34011.82994085014;34103.48844767281;34888.034517065316;35241.64758352384;32725;33393;33803.66196338005;34116;34829.606815727086;29616.555751664175;33098.83450582732;33372;33601.83199083989;33826.3234735299;28467;29223;30056.69943300539;30067.548986765654;30283;22487.21294754676;22828;22972.081116755144;22985.770142298363;23073.423164420354 22:28:34 INFO - PROCESS | 1893 | |2;jquery.html;346.44741637088833;357.3621086360845;358.3674813953371;364.937960546707;374.64752244397727;14815.777763333559;14852.702945941088;16398.918005409956;20665.1733930643;20787.688184677205;19619.411417657495;19743.703844442345;20349.491262718402;20405.693914591295;20808.89595552024;5550.86122846929;5854.151148083525;5854.443827836349;5859.824205273849;5863.237779088712;4577.10050329113;5800.854978625547;5814.389489103625;5821.854453638672;5843.678597677126;5606.187102870082;5755.165501002352;5768.97115514424;5770.682612456313;5777.248957635502;5550.833474995737;5718.941995730762;5731.054376027951;5741.971290143564;5783.277090363684;4804.879878003043;4939.506049395068;5027.522385373383;5035.647504674675;5043;25439.236822895156;25476.36309092269;25546.74453255462;25804;25864.96540138392;3046.36026434449;3086.0433265687707;3135.3102317490343;3135.4826453635123;3227.3545290941906;14914.701705965928;15227;15820.367185312574;15861.603459913478;16055.598610034725;1798.4604618614364;1832.4869036669754;1845.8338749512475;1862.1806405181676;1890.413971668787;106457;108296.4585177077;108946;109207;109778;10745.570177192825;10906.5092070857;10911.454427278763;10950.233483656228;11061.889381106246;13821.170729756246;13823.447062117402;13842.446302148002;13848.723025539433;13857.237851918242;6794.3545363190415;8069.233422824822;8226.958865205756;8288.875666865002;8401.621927013299;8215.753527394068;8219.095899451055;8292.751217463485;8405.621747021398;8419.369346859325;6532.248791388956;6626.469882409396;6627.138471998705;6645.966770166118;6681.164854393201;1438.3294011742012;1780.1722199177445;1806.205269681336;1819.1177279019664;1831.349870795875;697.2678687378233;841.2428814067266;874.4709450782185;876.0844917061686;898.4699027573632;19925.601487970158;21038.368848934555;21235.46911327229;21321.147154113973;21450.74487941807;724.5145752345941;880.6389380354024;889.1508609278123;918.9172974432333;920.2821798996794;9444.574994125282;10884.346939183675;10991.670249892515;11402.087832973342;11504.194706370641;497792;499592;503741;505237;508521;77602.69121813065;112655;114948;115478.35803398989;118806.40596797134;954.1031430455488;1012.792377562598;1022.5807418958192;1025.092792878308;1026.7381817636604;15751.527454176394;20176.39470815878;20234.79765202388;20286.695699564225;20305.49236269105;44864.8783780395;45492.54507455016;48286.275705863736;50262.74868625634;51933;55867.60330991758;69984;70245.64877175582;70276.29723702697;71952.64023679848;6882.965585172042;7167.390771784457;7192.100987376578;7765.72819951299;8041.316488098577;12492.875071249171;12728.427220774902;13105.541306054241;13221.801672975105;13283.402246898719;8942.821143576963;11136;11326.943365283121;11481;11607.883921160681;9604.711858644252;10999.61501347449;11183.217174797686;11368.44190249814;11479.885201147881;11401.087912967108;11495;11550.653480395602;11684.649460516199;11703;7135.179454362815;7140.9285907140265;7338.339549440576;7483.615531126759;7563.184835639517;10268.794624107626;10831.566737330733;10874.619388321371;11307.547698091985;11380.260283081574 22:28:34 INFO - PROCESS | 1893 | |3;mootools.html;1229.1641683655116;1390.6731917999275;1409.4432699083857;1415.6177831985365;1420.0627585793372;6475.579087359324;7491.363234125092;8451.91548084519;8465.830683386328;8484.745457636282;7537.982372379727;7714.537127772338;7956.6817327306835;8104.149064348247;8167;2294.655801629752;2327.8719670418122;2330.4523436992295;2331.825113116515;2331.8484298520625;2254.1772253127574;2339.7660233976635;2357.7760112789256;2365.1485465232504;2366.160013195317;2060.5981833542464;2138.2409244718133;2138.9144434222612;2260.8304377171694;2325.2210509479346;1800.6038671492315;2318.258157389636;2321.1063740459876;2321.454458202321;2324.639680849471;2296.437372843664;2303.1133013789813;2349.7180338359335;2354.164271683545;2355.963376114504;8246.134155913604;8338.499690018618;8347.83304333916;8349.248567628943;8353.582320883932;4988.102141614508;5144.202648589438;5154.175331946871;5161;5163.7676304566385;3271.6401195868434;3288.11838818506;3359.260962588227;3404.5914490261325;3437.8968630941113;1166.597523854269;1183.7632473505248;1193.7135087579006;1220.206865537399;1234.8888600026041;10648.254622176451;10756.946215268874;10763.730906727395;10776.353418794897;10788.838167427495;4381.759003254788;4525.886852828706;4601.74690392025;4609.078184363141;4636.211843986531;9336.766580835398;9353;9353.345265831462;9358.672446464478;9364.157225849705;5152.793888244503;5158.329417175743;5158.4067832199025;5159.638825282194;5161.3032240647035;4585.197590421662;4696.01383709418;4730.290456431494;4732.124556956974;4733.763311834395;3681.687056600219;3710.1466662667735;3734.3464893643504;3774.7923864187183;3788.204477059848;1230.1573422205818;1236.4312416288428;1240.6154092231436;1255.8806913343217;1256.2023115321836;761;845.7547311279783;867.2324992381662;880.264978742757;880.7093659092484;5207.46379817947;5541.362743284493;5613.915791263134;5663.263775709132;5677.829665110054;780.8833368283296;870.7692461497708;896.2964073202505;917.124146440148;934.5233930695395;3063.754899608026;3716.2381711749026;3760.8871733848027;3829.368154254517;3898.902527436837;9564.713058608253;9784.461854598065;9890.406575605486;9899.109080182674;9905.950470247746;8358.414910956175;9674.467904265333;9885.15976142036;9903.158231550398;9906.653267135616;7572.734954276574;7785.209401837609;7788.299053085249;7793.220677932161;7795.869598908126;5477.75350109238;5520.585956053393;5538.584606154474;5718.628289161191;5838.737256823538;7747.457677962489;9216.677416290398;9254.814903702023;9255.120763527455;9284.210842078499;7781.572013539083;9060.501672408082;9184.954075229582;9275.72172834816;9346.953265233631;7045.718171273092;7066.505344626033;7170.426365890612;7194.568325900463;7198.7840364789145;7702.537847729154;7767.961160194162;7862;7864.331531819859;7867.370610351044;6962.268961758942;7028.543144695578;7057.2237053925055;7137.892931605927;7186.568805871664;6907.620080895599;6920.9307906920285;6952.791416257521;6974.337437943387;7028.191757947697;6246.000639897595;6310.116583678197;6367.87264254703;6371.331010244043;6402.743890244338;4289.978550107229;4302.784860757012;4350.869473915787;4381.058072514425;4471.463424389094;4694.319323698113;4850.708957462563;4862.927056094232;4863.319135321129;4917.237828136645 22:28:34 INFO - PROCESS | 1893 | |4;prototype.html;343.390481894637;354.13195394295093;356.6006073198017;363.27345309381235;364.7756629672752;3949.775569573434;5247.973760131199;5267.86740850717;5329.227262047006;5341.866453338664;4384.714993525418;4449.132419178261;5257.789688412459;5286.392064912537;5315.229291752694;3439.793612383265;3902.2975864344407;4018.2365350583295;4060.8578699745517;4138.020625149888;3875.5543112541995;3900.8049597520153;4164.312888373414;4166.500019997605;4179.958200418003;3769.6607305342513;3964.167524819791;3975.403689446577;4108.5275193352845;4125.979370103145;3660.8352624131976;3673.530587764889;3766.585675575671;3840.1743625120735;4067.4712287402745;3030.409035256486;3582.229820588586;3646.361886669849;3648.1609229877013;3650.817459127033;4709.9764501177615;4755.714657120584;5106.974465127688;5117.616178786606;5139.8458046258675;4278.572142785728;4737.620990320801;4745.383100196987;4751.904961900777;4782.210935195689;4387.210302145612;4625.606823420013;4954.2568614707725;5535.311729922372;5536.529395001429;3265.5264986576813;3304.46422328109;3326.8336583170985;3597.3165098631175;3659.652333028358;5029.7736601853;5139.894922591623;5623;5680.318361796528;5797.608573942266;3714.312852122312;3751.306008388457;3792.7724336539345;3796.728096087786;3796.8101594920145;3861.806909654506;3870.2066076454257;3870.651641352292;3877.8254978526033;3885.3589157789215;3765.435184722259;3812.5234345706785;3994.5406278277787;4492.393526873896;4546.590806827401;4832;4873.02539492103;5369.5167434931045;5558.916616250759;5560.388357280696;1068.7221322456217;1143.7255058785997;1146.8853114688632;1238.6407941696987;1265.3108100685504;728.883378659412;761.8590560746279;805.2390490985962;857.3998201259144;860.5740158621397;4514.88712782183;4581.679282450196;4594.494605593382;4875.195592727231;4942.579880710108;4722.976385118052;5186.429492755794;5292.497212764782;5305.283786688748;5315.538226987532;4466.687331886802;4599.43543753587;4632.652551058684;4844.2007068833245;4863.927041094386;2348.9530209396057;2366.396568874926;2462.7167875694527;2783.6659600847624;2847.373577812878;4931.5971979334145;5260.421353651095;5699.886002279931;5701.543876489871;5701.771929122871;5246.842594722164;5290.470952904679;5569.443055694398;5594.356648985337;5631.887362252814;5342.839714808562;5598.860028499157;5630.887382252332;5654.293213348249;5701.258836351248;4493;4654.86035418938;4896.681715688612;5054.620903432184;5415.268938693226;4631.675782695179;4788.7366194859615;5137.383513978347;5243.89512209746;5401.837944861661;4888.486708895514;5039.673815281896;5124.360204734449;5769.53843692512;5772.249607550992;3563.6792688658147;4098.877033688994;4115.650169735486;4136.896577585584;4139.172165566839;4852.2479015752615;5658.886822263449;5725.312962444534;5740.282464691913;5752.626079304831;4548.931001214646;5270.970676289579;5519.806806761905;5534.446555344433;5552.807135896956;5480.561555075664;5531.8893622128135;5542.584306177134;5544.029794785815;5578;4758.333833263415;5000.949800541927;5130.4100028497205;5449.891002179855;5457.290552228185;4914.287428322945;5413.756380962787;5514.558835293086;5583.35791383996;5592;5150.381954165524;5393.568514518751;5407.270018547446;5459.7270136492225;5487.341519017744 22:28:34 INFO - PROCESS | 1893 | |5;yui.html;709.9822504437392;737.3327138939259;754.7660225330148;787.8938228727362;812.1026265976099;14616.926915365422;15219.61950951224;15647.374105035786;15692.45076422324;15693.843061569381;14081.15513069214;14210.431582736677;14229.359678814453;14235.928820355884;14242.359093840776;846.0904527632789;866.1598249697814;866.4411454611771;866.7703058689452;882.1311008656468;841.8905542279496;847.1063028504925;875.8029443375253;880.5729221327651;880.8722735203391;872.5288344294073;873.2490058549643;874.3267683883406;894.2220268366531;910.5993362920327;906;906.0350726476281;907.0566610724838;907.664164259225;908.5457271364285;676.1683129750386;676.2763842688325;676.736072931557;676.8409423785406;677.7322957431787;12199.39003049844;14231.715365692628;15048.322825472771;15051.247437628184;15561.922190388976;12104.757904841941;12412.62762117138;13475.528356507475;13794.65513362164;13822.516211932534;11865.52537898483;12385;12561.56034538796;12688;12838.80741788874;4444.777761111932;4453.131639330332;4537.931931021038;4626.722396656178;4663.463701674317;68118.65940670265;94921.62695932588;95379;97087;98869;10132.34139780912;10351.63769268072;10593.311434756715;10764.3541387517;10911.454427278604;20780.480487987923;21396.78603213979;21402.25092121768;21443.892780535996;21469;7680.923190768132;7752.147263800977;7814.726484573012;7852.921470785219;7995.440319177602;3762.664254189778;4250.362445633118;4340.826366945287;4377.78111094444;4529.116822219701;3456.70617997468;3529.3823580873245;3563.803990780532;3574.428091505347;3690.9630903691154;678.3453966921944;680.7038938061959;686.8351595617016;701.2216439751871;703.3283214530213;618.5422787137486;663.354879897856;663.7975417497613;672.8957011663102;704.5913370245246;8522.275606573387;8970.327225458117;9355.251579873593;9484.336096473318;9637.112456247132;640.814163892466;663.9834004149839;676.090658064902;677.3971165662564;690.9723611055501;4250.784382729247;4360.563943605677;4648.233041548175;4749.743871660933;4749.810007599726;72823.63588182026;78634.2136578627;82136;94201;94778;80600.19399806044;80846;81321;82765.58617206875;83115;72044.91932621015;72986;73296.63351683314;73475.26524734791;75196.62401688065;8803.943526776571;9059.547022648709;9948.801023979335;9986.550605222928;9986.60053597877;49035.5096449031;49116.50883491119;49599.50400496093;49934.50196494111;50006.74996624994;45910.54089459196;52019.739901301764;65140.69718605696;65767;68397;12182.269063856196;12412.028150991597;12511.06167037494;12574.056945728924;12983.415746291628;14068.78896816569;14296.115422905219;14322.355494002586;14406.279686015449;14542.85457145415;11117.11063114965;13159.407826647632;13273.402696878857;13407.128536645087;13699.684599351172;13250.271235081778;13375.598732038054;13405.32973351309;13412.12821166621;13909.860901390857;13191.340432978504;13306.46774129024;13313.600591982256;13671.453141874214;13769;10101.646442374482;10908.890911090788;11425.60010399632;11516.963473287109;11968.880311196777;9446.85829712541;9851.605935762489;10496.265261431627;10756.569737210424;10802.135829133807 22:28:34 INFO - PROCESS | 1893 | __end_tp_report 22:28:34 INFO - PROCESS | 1893 | __start_cc_report 22:28:34 INFO - PROCESS | 1893 | _x_x_mozilla_cycle_collect,1631 22:28:34 INFO - PROCESS | 1893 | __end_cc_report 22:28:34 INFO - PROCESS | 1893 | __startTimestamp1482820114100__endTimestamp 22:28:34 INFO - PROCESS | 1893 | 22:28:34 INFO - PROCESS | 1893 | ------- Summary: start ------- 22:28:34 INFO - PROCESS | 1893 | Number of tests: 6 22:28:34 INFO - PROCESS | 1893 | 22:28:34 INFO - PROCESS | 1893 | [#0] dojo.html Cycles:165 Average:5707.94 Median:5896.79 stddev:773.28 (13.1%) stddev-sans-first:752.48 22:28:34 INFO - PROCESS | 1893 | Values: 3313.3 3335.4 3340.6 3349.4 3387.5 5630.3 5654.8 5721.9 5725.6 5776.5 5675.4 5701.2 5740.3 5772.2 5775.7 4282.6 5237.1 5246.8 5256.1 5312.6 5281.2 5306.2 5310.9 5318.3 5322.3 5269.1 5284.8 5381.3 5385.9 5419.4 5314.2 5321.0 5325.9 5333.7 5337.2 262.0 5161.3 5255.9 5265.2 5288.2 4441.5 5702.9 5733.0 5766.3 5770.4 6211.9 6256.1 6260.7 6282.6 6290.2 6285.4 6288.4 6311.7 6345.3 6347.3 5635.2 5719.7 5750.9 5763.9 5797.4 6279.3 6287.3 6290.6 6294.2 6302.2 5672.3 5738.2 6165.3 6210.2 6212.0 6088.5 6160.1 6177.0 6230.7 6311.9 5969.3 6152.5 6173.1 6195.2 6251.9 6250.7 6269.3 6272.2 6287.8 6289.5 4821.3 4916.7 4958.8 4959.8 4982.9 4305.8 4416.6 4425.8 4476.3 4501.4 5683.8 5802.2 5836.2 5856.3 5856.5 6070.9 6109.8 6122.9 6176.1 6244.3 5619.7 5629.6 5636.5 5641.2 5642.7 5282.2 5333.4 5344.7 5350.0 5363.0 6225.8 6292.3 6292.9 6293.8 6338.6 6246.8 6275.3 6306.0 6307.1 6308.6 6207.0 6246.7 6264.0 6314.3 6317.6 6124.4 6213.7 6219.1 6223.4 6228.6 6215.6 6242.4 6267.1 6307.7 6332.4 5781.0 6013.5 6157.1 6176.4 6180.9 6016.0 6025.1 6033.3 6113.4 6140.1 5890.6 5898.9 5908.2 5924.2 5941.3 5896.8 6088.5 6089.7 6107.5 6112.5 5841.6 5871.2 5872.8 5926.3 5952.9 22:28:34 INFO - PROCESS | 1893 | 22:28:34 INFO - PROCESS | 1893 | [#1] ext.html Cycles:180 Average:61390.26 Median:33601.42 stddev:133359.76 (396.9%) stddev-sans-first:133655.27 22:28:34 INFO - PROCESS | 1893 | Values: 408.2 460.9 486.7 498.5 526.2 29550.7 31598.2 38169.6 39781.0 39800.0 40968.4 41090.4 41120.2 41181.0 41292.0 9278.6 9505.8 9506.0 9511.0 9521.4 8508.1 8765.8 9361.4 11135.6 11434.4 11391.3 11447.9 11456.7 11463.4 11464.3 11302.0 11392.3 11397.9 11417.0 11418.9 8365.6 8598.6 10891.6 11216.5 11222.3 38381.0 38384.4 38904.0 38946.0 39270.0 3974.9 3980.8 3996.9 4009.9 4010.4 28806.3 30090.5 30099.5 30101.7 30209.8 3152.7 3281.7 3282.1 3320.3 3325.7 323696.0 325044.0 325253.0 327431.4 442869.0 56682.7 57240.4 57246.7 57348.9 57454.0 59557.7 59559.0 59583.0 59607.0 59900.0 12488.9 15510.8 15513.2 15523.2 15525.9 10794.8 11807.4 11877.2 11901.8 11951.6 13631.7 14021.9 15199.1 15369.2 15514.3 6412.1 6532.0 6599.9 6606.8 6645.7 754.6 821.9 845.4 846.3 876.5 22712.8 27193.7 29336.0 29500.3 29629.9 977.7 1003.0 1003.6 1004.5 1027.1 13215.9 13751.0 13753.4 13834.5 13985.4 586845.0 595996.0 596156.0 597479.0 597866.0 47925.0 47951.5 48195.8 48207.5 49010.5 1350.4 1369.9 1379.7 1383.4 1383.7 23194.4 24694.6 25735.1 25806.4 25845.2 42342.4 42712.1 42829.6 42833.0 42844.6 514247.0 517056.0 518492.0 519617.0 521011.0 23279.4 23340.9 23599.9 23639.8 23651.4 38379.8 40391.0 41224.6 41411.0 41487.0 33601.0 34011.8 34103.5 34888.0 35241.6 32725.0 33393.0 33803.7 34116.0 34829.6 29616.6 33098.8 33372.0 33601.8 33826.3 28467.0 29223.0 30056.7 30067.5 30283.0 22487.2 22828.0 22972.1 22985.8 23073.4 22:28:34 INFO - PROCESS | 1893 | 22:28:34 INFO - PROCESS | 1893 | [#2] jquery.html Cycles:180 Average:31182.31 Median:3066.20 stddev:83996.48 (2739.4%) stddev-sans-first:84200.21 22:28:34 INFO - PROCESS | 1893 | Values: 346.4 357.4 358.4 364.9 374.6 14815.8 14852.7 16398.9 20665.2 20787.7 19619.4 19743.7 20349.5 20405.7 20808.9 5550.9 5854.2 5854.4 5859.8 5863.2 4577.1 5800.9 5814.4 5821.9 5843.7 5606.2 5755.2 5769.0 5770.7 5777.2 5550.8 5718.9 5731.1 5742.0 5783.3 4804.9 4939.5 5027.5 5035.6 5043.0 25439.2 25476.4 25546.7 25804.0 25865.0 3046.4 3086.0 3135.3 3135.5 3227.4 14914.7 15227.0 15820.4 15861.6 16055.6 1798.5 1832.5 1845.8 1862.2 1890.4 106457.0 108296.5 108946.0 109207.0 109778.0 10745.6 10906.5 10911.5 10950.2 11061.9 13821.2 13823.4 13842.4 13848.7 13857.2 6794.4 8069.2 8227.0 8288.9 8401.6 8215.8 8219.1 8292.8 8405.6 8419.4 6532.2 6626.5 6627.1 6646.0 6681.2 1438.3 1780.2 1806.2 1819.1 1831.3 697.3 841.2 874.5 876.1 898.5 19925.6 21038.4 21235.5 21321.1 21450.7 724.5 880.6 889.2 918.9 920.3 9444.6 10884.3 10991.7 11402.1 11504.2 497792.0 499592.0 503741.0 505237.0 508521.0 77602.7 112655.0 114948.0 115478.4 118806.4 954.1 1012.8 1022.6 1025.1 1026.7 15751.5 20176.4 20234.8 20286.7 20305.5 44864.9 45492.5 48286.3 50262.7 51933.0 55867.6 69984.0 70245.6 70276.3 71952.6 6883.0 7167.4 7192.1 7765.7 8041.3 12492.9 12728.4 13105.5 13221.8 13283.4 8942.8 11136.0 11326.9 11481.0 11607.9 9604.7 10999.6 11183.2 11368.4 11479.9 11401.1 11495.0 11550.7 11684.6 11703.0 7135.2 7140.9 7338.3 7483.6 7563.2 10268.8 10831.6 10874.6 11307.5 11380.3 22:28:34 INFO - PROCESS | 1893 | 22:28:34 INFO - PROCESS | 1893 | [#3] mootools.html Cycles:180 Average:5335.11 Median:5342.61 stddev:2909.33 (54.5%) stddev-sans-first:2901.13 22:28:34 INFO - PROCESS | 1893 | Values: 1229.2 1390.7 1409.4 1415.6 1420.1 6475.6 7491.4 8451.9 8465.8 8484.7 7538.0 7714.5 7956.7 8104.1 8167.0 2294.7 2327.9 2330.5 2331.8 2331.8 2254.2 2339.8 2357.8 2365.1 2366.2 2060.6 2138.2 2138.9 2260.8 2325.2 1800.6 2318.3 2321.1 2321.5 2324.6 2296.4 2303.1 2349.7 2354.2 2356.0 8246.1 8338.5 8347.8 8349.2 8353.6 4988.1 5144.2 5154.2 5161.0 5163.8 3271.6 3288.1 3359.3 3404.6 3437.9 1166.6 1183.8 1193.7 1220.2 1234.9 10648.3 10756.9 10763.7 10776.4 10788.8 4381.8 4525.9 4601.7 4609.1 4636.2 9336.8 9353.0 9353.3 9358.7 9364.2 5152.8 5158.3 5158.4 5159.6 5161.3 4585.2 4696.0 4730.3 4732.1 4733.8 3681.7 3710.1 3734.3 3774.8 3788.2 1230.2 1236.4 1240.6 1255.9 1256.2 761.0 845.8 867.2 880.3 880.7 5207.5 5541.4 5613.9 5663.3 5677.8 780.9 870.8 896.3 917.1 934.5 3063.8 3716.2 3760.9 3829.4 3898.9 9564.7 9784.5 9890.4 9899.1 9906.0 8358.4 9674.5 9885.2 9903.2 9906.7 7572.7 7785.2 7788.3 7793.2 7795.9 5477.8 5520.6 5538.6 5718.6 5838.7 7747.5 9216.7 9254.8 9255.1 9284.2 7781.6 9060.5 9185.0 9275.7 9347.0 7045.7 7066.5 7170.4 7194.6 7198.8 7702.5 7768.0 7862.0 7864.3 7867.4 6962.3 7028.5 7057.2 7137.9 7186.6 6907.6 6920.9 6952.8 6974.3 7028.2 6246.0 6310.1 6367.9 6371.3 6402.7 4290.0 4302.8 4350.9 4381.1 4471.5 4694.3 4850.7 4862.9 4863.3 4917.2 22:28:34 INFO - PROCESS | 1893 | 22:28:34 INFO - PROCESS | 1893 | [#4] prototype.html Cycles:180 Average:4369.31 Median:4838.10 stddev:1337.61 (27.6%) stddev-sans-first:1306.78 22:28:34 INFO - PROCESS | 1893 | Values: 343.4 354.1 356.6 363.3 364.8 3949.8 5248.0 5267.9 5329.2 5341.9 4384.7 4449.1 5257.8 5286.4 5315.2 3439.8 3902.3 4018.2 4060.9 4138.0 3875.6 3900.8 4164.3 4166.5 4180.0 3769.7 3964.2 3975.4 4108.5 4126.0 3660.8 3673.5 3766.6 3840.2 4067.5 3030.4 3582.2 3646.4 3648.2 3650.8 4710.0 4755.7 5107.0 5117.6 5139.8 4278.6 4737.6 4745.4 4751.9 4782.2 4387.2 4625.6 4954.3 5535.3 5536.5 3265.5 3304.5 3326.8 3597.3 3659.7 5029.8 5139.9 5623.0 5680.3 5797.6 3714.3 3751.3 3792.8 3796.7 3796.8 3861.8 3870.2 3870.7 3877.8 3885.4 3765.4 3812.5 3994.5 4492.4 4546.6 4832.0 4873.0 5369.5 5558.9 5560.4 1068.7 1143.7 1146.9 1238.6 1265.3 728.9 761.9 805.2 857.4 860.6 4514.9 4581.7 4594.5 4875.2 4942.6 4723.0 5186.4 5292.5 5305.3 5315.5 4466.7 4599.4 4632.7 4844.2 4863.9 2349.0 2366.4 2462.7 2783.7 2847.4 4931.6 5260.4 5699.9 5701.5 5701.8 5246.8 5290.5 5569.4 5594.4 5631.9 5342.8 5598.9 5630.9 5654.3 5701.3 4493.0 4654.9 4896.7 5054.6 5415.3 4631.7 4788.7 5137.4 5243.9 5401.8 4888.5 5039.7 5124.4 5769.5 5772.2 3563.7 4098.9 4115.7 4136.9 4139.2 4852.2 5658.9 5725.3 5740.3 5752.6 4548.9 5271.0 5519.8 5534.4 5552.8 5480.6 5531.9 5542.6 5544.0 5578.0 4758.3 5000.9 5130.4 5449.9 5457.3 4914.3 5413.8 5514.6 5583.4 5592.0 5150.4 5393.6 5407.3 5459.7 5487.3 22:28:34 INFO - PROCESS | 1893 | 22:28:34 INFO - PROCESS | 1893 | [#5] yui.html Cycles:180 Average:18876.95 Median:49358.01 stddev:25835.44 (52.3%) stddev-sans-first:25871.90 22:28:34 INFO - PROCESS | 1893 | Values: 710.0 737.3 754.8 787.9 812.1 14616.9 15219.6 15647.4 15692.5 15693.8 14081.2 14210.4 14229.4 14235.9 14242.4 846.1 866.2 866.4 866.8 882.1 841.9 847.1 875.8 880.6 880.9 872.5 873.2 874.3 894.2 910.6 906.0 906.0 907.1 907.7 908.5 676.2 676.3 676.7 676.8 677.7 12199.4 14231.7 15048.3 15051.2 15561.9 12104.8 12412.6 13475.5 13794.7 13822.5 11865.5 12385.0 12561.6 12688.0 12838.8 4444.8 4453.1 4537.9 4626.7 4663.5 68118.7 94921.6 95379.0 97087.0 98869.0 10132.3 10351.6 10593.3 10764.4 10911.5 20780.5 21396.8 21402.3 21443.9 21469.0 7680.9 7752.1 7814.7 7852.9 7995.4 3762.7 4250.4 4340.8 4377.8 4529.1 3456.7 3529.4 3563.8 3574.4 3691.0 678.3 680.7 686.8 701.2 703.3 618.5 663.4 663.8 672.9 704.6 8522.3 8970.3 9355.3 9484.3 9637.1 640.8 664.0 676.1 677.4 691.0 4250.8 4360.6 4648.2 4749.7 4749.8 72823.6 78634.2 82136.0 94201.0 94778.0 80600.2 80846.0 81321.0 82765.6 83115.0 72044.9 72986.0 73296.6 73475.3 75196.6 8803.9 9059.5 9948.8 9986.6 9986.6 49035.5 49116.5 49599.5 49934.5 50006.7 45910.5 52019.7 65140.7 65767.0 68397.0 12182.3 12412.0 12511.1 12574.1 12983.4 14068.8 14296.1 14322.4 14406.3 14542.9 11117.1 13159.4 13273.4 13407.1 13699.7 13250.3 13375.6 13405.3 13412.1 13909.9 13191.3 13306.5 13313.6 13671.5 13769.0 10101.6 10908.9 11425.6 11517.0 11968.9 9446.9 9851.6 10496.3 10756.6 10802.1 22:28:34 INFO - PROCESS | 1893 | -------- Summary: end -------- 22:28:34 INFO - PROCESS | 1893 | 22:28:34 INFO - TEST-INFO | 1893: exit 0 22:28:34 INFO - TEST-OK | dromaeo_css | took 1117921ms 22:28:34 INFO - TEST-START | kraken 22:28:34 INFO - operating with platform_type : mac_ 22:28:34 INFO - Initialising browser for kraken test... 22:28:34 INFO - TEST-INFO | started process 1899 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpoXxlp3/profile http://localhost:49380/getInfo.html) 22:28:35 INFO - PROCESS | 1899 | 1482820115232 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 22:28:37 INFO - PROCESS | 1899 | __metrics Screen width/height:1600/1200 22:28:37 INFO - PROCESS | 1899 | colorDepth:24 22:28:37 INFO - PROCESS | 1899 | Browser inner width/height: 1024/689 22:28:37 INFO - PROCESS | 1899 | __metrics 22:28:37 INFO - PROCESS | 1899 | 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" 22:28:37 INFO - PROCESS | 1899 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:28:37 INFO - TEST-INFO | 1899: exit 0 22:28:37 INFO - Browser initialized. 22:28:37 INFO - Running cycle 1/1 for kraken test... 22:28:37 INFO - TEST-INFO | started process 1900 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpoXxlp3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:28:50 INFO - PROCESS | 1900 | RSS: Main: 234434560 22:28:50 INFO - PROCESS | 1900 | 22:28:52 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49380/tests/kraken/driver.html?testName=audio-beat-detection) 22:28:53 INFO - PROCESS | 1900 | RSS: Main: 286355456 22:28:53 INFO - PROCESS | 1900 | 22:28:56 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49380/tests/kraken/driver.html?testName=audio-dft) 22:28:56 INFO - PROCESS | 1900 | RSS: Main: 287461376 22:28:56 INFO - PROCESS | 1900 | 22:29:00 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49380/tests/kraken/driver.html?testName=audio-fft) 22:29:00 INFO - PROCESS | 1900 | RSS: Main: 379658240 22:29:00 INFO - PROCESS | 1900 | 22:29:02 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49380/tests/kraken/driver.html?testName=audio-oscillator) 22:29:03 INFO - PROCESS | 1900 | RSS: Main: 283295744 22:29:03 INFO - PROCESS | 1900 | 22:29:05 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49380/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:29:06 INFO - PROCESS | 1900 | RSS: Main: 286003200 22:29:06 INFO - PROCESS | 1900 | 22:29:08 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49380/tests/kraken/driver.html?testName=imaging-darkroom) 22:29:09 INFO - PROCESS | 1900 | RSS: Main: 326778880 22:29:09 INFO - PROCESS | 1900 | 22:29:12 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49380/tests/kraken/driver.html?testName=imaging-desaturate) 22:29:13 INFO - PROCESS | 1900 | RSS: Main: 327434240 22:29:13 INFO - PROCESS | 1900 | 22:29:16 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49380/tests/kraken/driver.html?testName=json-parse-financial) 22:29:16 INFO - PROCESS | 1900 | RSS: Main: 326213632 22:29:16 INFO - PROCESS | 1900 | 22:29:18 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49380/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:29:18 INFO - PROCESS | 1900 | RSS: Main: 303673344 22:29:18 INFO - PROCESS | 1900 | 22:29:20 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49380/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:29:21 INFO - PROCESS | 1900 | RSS: Main: 308510720 22:29:21 INFO - PROCESS | 1900 | 22:29:23 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49380/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:29:24 INFO - PROCESS | 1900 | RSS: Main: 309977088 22:29:24 INFO - PROCESS | 1900 | 22:29:27 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49380/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:29:27 INFO - PROCESS | 1900 | RSS: Main: 325296128 22:29:27 INFO - PROCESS | 1900 | 22:29:30 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49380/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:29:31 INFO - PROCESS | 1900 | RSS: Main: 319811584 22:29:31 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | Cycle 1(1): loaded http://localhost:49380/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49380/tests/kraken/driver.html?testName=ai-astar) 22:29:33 INFO - PROCESS | 1900 | RSS: Main: 364048384 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | __start_tp_report 22:29:33 INFO - PROCESS | 1900 | _x_x_mozilla_page_load 22:29:33 INFO - PROCESS | 1900 | _x_x_mozilla_page_load_details 22:29:33 INFO - PROCESS | 1900 | |i|pagename|runs| 22:29:33 INFO - PROCESS | 1900 | |0;ai-astar;114;116;114;115;115;114;116;116;115;114 22:29:33 INFO - PROCESS | 1900 | |1;audio-beat-detection;144;110;108;147;110;111;112;136;162;138 22:29:33 INFO - PROCESS | 1900 | |2;audio-dft;186;184;184;184;185;183;183;186;184;186 22:29:33 INFO - PROCESS | 1900 | |3;audio-fft;79;58;58;87;83;85;82;49;91;81 22:29:33 INFO - PROCESS | 1900 | |4;audio-oscillator;93;69;88;68;68;69;85;68;68;85 22:29:33 INFO - PROCESS | 1900 | |5;imaging-gaussian-blur;92;87;91;82;89;71;118;88;90;87 22:29:33 INFO - PROCESS | 1900 | |6;imaging-darkroom;95;97;96;98;95;95;96;106;95;96 22:29:33 INFO - PROCESS | 1900 | |7;imaging-desaturate;86;88;87;86;86;85;84;102;139;139 22:29:33 INFO - PROCESS | 1900 | |8;json-parse-financial;58;58;58;57;57;56;56;56;56;56 22:29:33 INFO - PROCESS | 1900 | |9;json-stringify-tinderbox;66;78;67;68;76;78;73;72;72;74 22:29:33 INFO - PROCESS | 1900 | |10;stanford-crypto-aes;90;87;87;87;87;86;89;87;89;86 22:29:33 INFO - PROCESS | 1900 | |11;stanford-crypto-ccm;142;141;139;140;145;144;154;139;170;183 22:29:33 INFO - PROCESS | 1900 | |12;stanford-crypto-pbkdf2;170;174;177;171;179;175;169;173;173;175 22:29:33 INFO - PROCESS | 1900 | |13;stanford-crypto-sha256-iterative;68;68;70;82;86;85;69;66;67;69 22:29:33 INFO - PROCESS | 1900 | __end_tp_report 22:29:33 INFO - PROCESS | 1900 | __start_cc_report 22:29:33 INFO - PROCESS | 1900 | _x_x_mozilla_cycle_collect,1944 22:29:33 INFO - PROCESS | 1900 | __end_cc_report 22:29:33 INFO - PROCESS | 1900 | __startTimestamp1482820173506__endTimestamp 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | ------- Summary: start ------- 22:29:33 INFO - PROCESS | 1900 | Number of tests: 14 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#0] ai-astar Cycles:10 Average:114.90 Median:115.00 stddev:0.88 (0.8%) stddev-sans-first:0.87 22:29:33 INFO - PROCESS | 1900 | Values: 114.0 116.0 114.0 115.0 115.0 114.0 116.0 116.0 115.0 114.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#1] audio-beat-detection Cycles:10 Average:127.80 Median:137.00 stddev:19.80 (14.5%) stddev-sans-first:20.12 22:29:33 INFO - PROCESS | 1900 | Values: 144.0 110.0 108.0 147.0 110.0 111.0 112.0 136.0 162.0 138.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#2] audio-dft Cycles:10 Average:184.50 Median:184.50 stddev:1.18 (0.6%) stddev-sans-first:1.12 22:29:33 INFO - PROCESS | 1900 | Values: 186.0 184.0 184.0 184.0 185.0 183.0 183.0 186.0 184.0 186.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#3] audio-fft Cycles:10 Average:75.30 Median:82.50 stddev:14.60 (17.7%) stddev-sans-first:15.42 22:29:33 INFO - PROCESS | 1900 | Values: 79.0 58.0 58.0 87.0 83.0 85.0 82.0 49.0 91.0 81.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:77.00 stddev:10.27 (13.3%) stddev-sans-first:8.89 22:29:33 INFO - PROCESS | 1900 | Values: 93.0 69.0 88.0 68.0 68.0 69.0 85.0 68.0 68.0 85.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:88.50 stddev:11.71 (13.2%) stddev-sans-first:12.39 22:29:33 INFO - PROCESS | 1900 | Values: 92.0 87.0 91.0 82.0 89.0 71.0 118.0 88.0 90.0 87.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:96.00 stddev:3.35 (3.5%) stddev-sans-first:3.48 22:29:33 INFO - PROCESS | 1900 | Values: 95.0 97.0 96.0 98.0 95.0 95.0 96.0 106.0 95.0 96.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#7] imaging-desaturate Cycles:10 Average:98.20 Median:86.00 stddev:22.10 (25.7%) stddev-sans-first:23.00 22:29:33 INFO - PROCESS | 1900 | Values: 86.0 88.0 87.0 86.0 86.0 85.0 84.0 102.0 139.0 139.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.00 stddev:0.92 (1.6%) stddev-sans-first:0.87 22:29:33 INFO - PROCESS | 1900 | Values: 58.0 58.0 58.0 57.0 57.0 56.0 56.0 56.0 56.0 56.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:73.50 stddev:4.33 (5.9%) stddev-sans-first:3.92 22:29:33 INFO - PROCESS | 1900 | Values: 66.0 78.0 67.0 68.0 76.0 78.0 73.0 72.0 72.0 74.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:87.00 stddev:1.35 (1.6%) stddev-sans-first:1.09 22:29:33 INFO - PROCESS | 1900 | Values: 90.0 87.0 87.0 87.0 87.0 86.0 89.0 87.0 89.0 86.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:144.50 stddev:15.10 (10.4%) stddev-sans-first:15.76 22:29:33 INFO - PROCESS | 1900 | Values: 142.0 141.0 139.0 140.0 145.0 144.0 154.0 139.0 170.0 183.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:174.50 stddev:3.10 (1.8%) stddev-sans-first:3.00 22:29:33 INFO - PROCESS | 1900 | Values: 170.0 174.0 177.0 171.0 179.0 175.0 169.0 173.0 173.0 175.0 22:29:33 INFO - PROCESS | 1900 | 22:29:33 INFO - PROCESS | 1900 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:69.50 stddev:7.96 (11.5%) stddev-sans-first:8.23 22:29:33 INFO - PROCESS | 1900 | Values: 68.0 68.0 70.0 82.0 86.0 85.0 69.0 66.0 67.0 69.0 22:29:33 INFO - PROCESS | 1900 | -------- Summary: end -------- 22:29:33 INFO - PROCESS | 1900 | 22:29:34 INFO - TEST-INFO | 1900: exit 0 22:29:34 INFO - TEST-OK | kraken | took 59713ms 22:29:34 INFO - SUITE-END | took 1177s 22:29:34 INFO - Completed test suite (00:19:37) 22:29:34 INFO - javascript benchmark 22:29:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.3207692423052, 3335.382954153482, 3340.5657264555603, 3349.3971085204685, 3387.4749413840864, 5630.268065151535, 5654.830355089343, 5721.914171287429, 5725.570582206341, 5776.451237132476, 5675.3757086720425, 5701.173329867167, 5740.339860915996, 5772.249607551013, 5775.653460792355, 4282.550332215119, 5237.057329680656, 5246.842594722164, 5256.080185967459, 5312.6281160318795, 5281.155015197569, 5306.230596563496, 5310.946890531103, 5318.282031925679, 5322.308099947021, 5269.051570717269, 5284.762185701634, 5381.273528073699, 5385.865353366177, 5419.403865574784, 5314.202869569576, 5321.042212401766, 5325.893482130375, 5333.679979201261, 5337.226102215196, 262.02495963918705, 5161.303224064741, 5255.947440525622, 5265.236540701615, 5288.206768984644, 4441.489228738704, 5702.885942281173, 5732.971335143297, 5766.308043034821, 5770.365259821417, 6211.943969525191, 6256.124142620037, 6260.718267677966, 6282.591631543981, 6290.245170579514, 6285.371462853724, 6288.371162883721, 6311.684415779193, 6345.333739957284, 6347.270063942613, 5635.239242702224, 5719.6854173020465, 5750.913736293959, 5763.913541296842, 5797.391273916178, 6279.277883043417, 6287.339829317947, 6290.559660823698, 6294.150289710922, 6302.212223472066, 5672.319321681424, 5738.1679656450415, 6165.32181460039, 6210.161628180138, 6212.006079027334, 6088.452039316483, 6160.075988601656, 6176.969115154396, 6230.6573138476915, 6311.936880631135, 5969.343372229051, 6152.507799376039, 6173.135760993375, 6195.19462469876, 6251.937480625135, 6250.656213908279, 6269.310375858652, 6272.153259310026, 6287.7799277026, 6289.465395441439, 4821.325014497973, 4916.72957987307, 4958.776855041532, 4959.801607935642, 4982.850514484571, 4305.84929527465, 4416.646668266531, 4425.84509542171, 4476.26141686618, 4501.392312037899, 5683.8010669626365, 5802.158686990363, 5836.153757705109, 5856.297244330707, 5856.472917437451, 6070.939290607037, 6109.84725381869, 6122.938770612326, 6176.104464852571, 6244.2819075805955, 5619.719014049363, 5629.605927585029, 5636.492715655529, 5641.2384328116, 5642.746076426571, 5282.1548552232225, 5333.439988801198, 5344.679319240858, 5350.036993341158, 5363.03465376236, 6225.844353891189, 6292.339304372975, 6292.937070629235, 6293.779717709858, 6338.58799178052, 6246.812595622138, 6275.278342990615, 6305.9684701578035, 6307.05394190875, 6308.589941653778, 6206.968965155145, 6246.656433895998, 6264.0, 6314.273858506331, 6317.5893566918, 6124.350421260821, 6213.689315534115, 6219.129321895029, 6223.439890409885, 6228.5951413158, 6215.595986260879, 6242.438180563771, 6267.122602835698, 6307.684615769285, 6332.430081292706, 5781.0, 6013.518918486423, 6157.076438534255, 6176.35148309421, 6180.938190618216, 6015.969920150371, 6025.0661147522205, 6033.336333003454, 6113.449789518964, 6140.140380346666, 5890.558208134321, 5898.852528686817, 5908.172855800278, 5924.170616113662, 5941.287045554388, 5896.793612223723, 6088.51291896656, 6089.695515224133, 6107.511399088151, 6112.480439162543, 5841.620294680833, 5871.236739223874, 5872.794452194324, 5926.318473375617, 5952.8809423812145], "value": 5660.425088775352, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.2019651581159, 460.8847788052986, 486.74202672583544, 498.5189292332898, 526.1581469648565, 29550.704492955065, 31598.210044748892, 38169.618303816955, 39781.0, 39800.004999874945, 40968.38547421791, 41090.38364424536, 41120.17759644813, 41180.970475738046, 41292.0, 9278.628854845798, 9505.80988380232, 9506.0, 9511.0, 9521.381110227847, 8508.149185081504, 8765.780855478632, 9361.391509551857, 11135.554577816878, 11434.371109588968, 11391.259568128087, 11447.942760286187, 11456.713582160472, 11463.426828658534, 11464.31214127155, 11302.0, 11392.316461012366, 11397.886021139766, 11417.0, 11418.885811141865, 8365.581720913991, 8598.5700714964, 10891.618793342273, 11216.49525771334, 11222.32666040032, 38381.0, 38384.42423363652, 38904.0, 38946.0, 39270.0, 3974.9006274843073, 3980.8407663693706, 3996.9200615987515, 4009.919801603952, 4010.418489319062, 28806.279843003882, 30090.54864177039, 30099.548506772415, 30101.698983010327, 30209.79160833564, 3152.7004934531183, 3281.737461003114, 3282.097423208613, 3320.25294308781, 3325.667433256655, 323696.0, 325043.9999999976, 325253.0, 327431.36284318665, 442869.0, 56682.71658641763, 57240.427595724344, 57246.713766430905, 57348.85302293931, 57454.0, 59557.70221148953, 59559.022048898245, 59583.0, 59607.0, 59900.0, 12488.937555312164, 15510.767338489932, 15513.224338783015, 15523.223838808015, 15525.922370388076, 10794.838077428845, 11807.350595717318, 11877.22798018126, 11901.821472677917, 11951.641450756491, 13631.727365452834, 14021.929890350482, 15199.088054716753, 15369.231538423257, 15514.293427722941, 6412.134361861183, 6532.0, 6599.868002639921, 6606.8017959461295, 6645.7040877029485, 754.5849782619647, 821.8561751693429, 845.3955421873391, 846.2848892685671, 876.5361845717666, 22712.772872271395, 27193.72806271912, 29336.0, 29500.262493437407, 29629.851850740608, 977.745786095606, 1003.0370843989851, 1003.5835128421769, 1004.5228516454772, 1027.075631931253, 13215.933920330337, 13751.0, 13753.393597660932, 13834.515791947435, 13985.384823008382, 586845.0, 595996.0, 596156.0, 597479.0, 597866.0, 47925.0, 47951.5204847954, 48195.75902120537, 48207.517924821004, 49010.509894901305, 1350.372076984207, 1369.9315034248248, 1379.7309524642799, 1383.391307824554, 1383.6955869708647, 23194.420139496648, 24694.629580556666, 25735.099271525407, 25806.354841129123, 25845.22464326073, 42342.36486452644, 42712.145757084065, 42829.571704282556, 42833.0, 42844.571554284055, 514247.0, 517056.0, 518492.0, 519617.0, 521011.0, 23279.41801454977, 23340.949657265122, 23599.88200058989, 23639.763602363757, 23651.40871478227, 38379.808100959315, 40391.0, 41224.58775412328, 41411.0, 41487.0, 33601.0, 34011.82994085014, 34103.48844767281, 34888.034517065316, 35241.64758352384, 32725.0, 33393.0, 33803.66196338005, 34116.0, 34829.606815727086, 29616.555751664175, 33098.83450582732, 33372.0, 33601.83199083989, 33826.3234735299, 28467.0, 29223.0, 30056.69943300539, 30067.548986765654, 30283.0, 22487.21294754676, 22828.0, 22972.081116755144, 22985.770142298363, 23073.423164420354], "value": 18530.798984123725, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.44741637088833, 357.3621086360845, 358.3674813953371, 364.937960546707, 374.64752244397727, 14815.777763333559, 14852.702945941088, 16398.918005409956, 20665.1733930643, 20787.688184677205, 19619.411417657495, 19743.703844442345, 20349.491262718402, 20405.693914591295, 20808.89595552024, 5550.86122846929, 5854.151148083525, 5854.443827836349, 5859.824205273849, 5863.237779088712, 4577.10050329113, 5800.854978625547, 5814.389489103625, 5821.854453638672, 5843.678597677126, 5606.187102870082, 5755.165501002352, 5768.97115514424, 5770.682612456313, 5777.248957635502, 5550.833474995737, 5718.941995730762, 5731.054376027951, 5741.971290143564, 5783.277090363684, 4804.879878003043, 4939.506049395068, 5027.522385373383, 5035.647504674675, 5043.0, 25439.236822895156, 25476.36309092269, 25546.74453255462, 25804.0, 25864.96540138392, 3046.36026434449, 3086.0433265687707, 3135.3102317490343, 3135.4826453635123, 3227.3545290941906, 14914.701705965928, 15227.0, 15820.367185312574, 15861.603459913478, 16055.598610034725, 1798.4604618614364, 1832.4869036669754, 1845.8338749512475, 1862.1806405181676, 1890.413971668787, 106457.0, 108296.4585177077, 108946.0, 109207.0, 109778.0, 10745.570177192825, 10906.5092070857, 10911.454427278763, 10950.233483656228, 11061.889381106246, 13821.170729756246, 13823.447062117402, 13842.446302148002, 13848.723025539433, 13857.237851918242, 6794.3545363190415, 8069.233422824822, 8226.958865205756, 8288.875666865002, 8401.621927013299, 8215.753527394068, 8219.095899451055, 8292.751217463485, 8405.621747021398, 8419.369346859325, 6532.248791388956, 6626.469882409396, 6627.138471998705, 6645.966770166118, 6681.164854393201, 1438.3294011742012, 1780.1722199177445, 1806.205269681336, 1819.1177279019664, 1831.349870795875, 697.2678687378233, 841.2428814067266, 874.4709450782185, 876.0844917061686, 898.4699027573632, 19925.601487970158, 21038.368848934555, 21235.46911327229, 21321.147154113973, 21450.74487941807, 724.5145752345941, 880.6389380354024, 889.1508609278123, 918.9172974432333, 920.2821798996794, 9444.574994125282, 10884.346939183675, 10991.670249892515, 11402.087832973342, 11504.194706370641, 497792.0, 499592.0, 503741.0, 505237.0, 508521.0, 77602.69121813065, 112655.0, 114948.0, 115478.35803398989, 118806.40596797134, 954.1031430455488, 1012.792377562598, 1022.5807418958192, 1025.092792878308, 1026.7381817636604, 15751.527454176394, 20176.39470815878, 20234.79765202388, 20286.695699564225, 20305.49236269105, 44864.8783780395, 45492.54507455016, 48286.275705863736, 50262.74868625634, 51933.0, 55867.60330991758, 69984.0, 70245.64877175582, 70276.29723702697, 71952.64023679848, 6882.965585172042, 7167.390771784457, 7192.100987376578, 7765.72819951299, 8041.316488098577, 12492.875071249171, 12728.427220774902, 13105.541306054241, 13221.801672975105, 13283.402246898719, 8942.821143576963, 11136.0, 11326.943365283121, 11481.0, 11607.883921160681, 9604.711858644252, 10999.61501347449, 11183.217174797686, 11368.44190249814, 11479.885201147881, 11401.087912967108, 11495.0, 11550.653480395602, 11684.649460516199, 11703.0, 7135.179454362815, 7140.9285907140265, 7338.339549440576, 7483.615531126759, 7563.184835639517, 10268.794624107626, 10831.566737330733, 10874.619388321371, 11307.547698091985, 11380.260283081574], "value": 9421.068655873214, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1229.1641683655116, 1390.6731917999275, 1409.4432699083857, 1415.6177831985365, 1420.0627585793372, 6475.579087359324, 7491.363234125092, 8451.91548084519, 8465.830683386328, 8484.745457636282, 7537.982372379727, 7714.537127772338, 7956.6817327306835, 8104.149064348247, 8167.0, 2294.655801629752, 2327.8719670418122, 2330.4523436992295, 2331.825113116515, 2331.8484298520625, 2254.1772253127574, 2339.7660233976635, 2357.7760112789256, 2365.1485465232504, 2366.160013195317, 2060.5981833542464, 2138.2409244718133, 2138.9144434222612, 2260.8304377171694, 2325.2210509479346, 1800.6038671492315, 2318.258157389636, 2321.1063740459876, 2321.454458202321, 2324.639680849471, 2296.437372843664, 2303.1133013789813, 2349.7180338359335, 2354.164271683545, 2355.963376114504, 8246.134155913604, 8338.499690018618, 8347.83304333916, 8349.248567628943, 8353.582320883932, 4988.102141614508, 5144.202648589438, 5154.175331946871, 5161.0, 5163.7676304566385, 3271.6401195868434, 3288.11838818506, 3359.260962588227, 3404.5914490261325, 3437.8968630941113, 1166.597523854269, 1183.7632473505248, 1193.7135087579006, 1220.206865537399, 1234.8888600026041, 10648.254622176451, 10756.946215268874, 10763.730906727395, 10776.353418794897, 10788.838167427495, 4381.759003254788, 4525.886852828706, 4601.74690392025, 4609.078184363141, 4636.211843986531, 9336.766580835398, 9353.0, 9353.345265831462, 9358.672446464478, 9364.157225849705, 5152.793888244503, 5158.329417175743, 5158.4067832199025, 5159.638825282194, 5161.3032240647035, 4585.197590421662, 4696.01383709418, 4730.290456431494, 4732.124556956974, 4733.763311834395, 3681.687056600219, 3710.1466662667735, 3734.3464893643504, 3774.7923864187183, 3788.204477059848, 1230.1573422205818, 1236.4312416288428, 1240.6154092231436, 1255.8806913343217, 1256.2023115321836, 761.0, 845.7547311279783, 867.2324992381662, 880.264978742757, 880.7093659092484, 5207.46379817947, 5541.362743284493, 5613.915791263134, 5663.263775709132, 5677.829665110054, 780.8833368283296, 870.7692461497708, 896.2964073202505, 917.124146440148, 934.5233930695395, 3063.754899608026, 3716.2381711749026, 3760.8871733848027, 3829.368154254517, 3898.902527436837, 9564.713058608253, 9784.461854598065, 9890.406575605486, 9899.109080182674, 9905.950470247746, 8358.414910956175, 9674.467904265333, 9885.15976142036, 9903.158231550398, 9906.653267135616, 7572.734954276574, 7785.209401837609, 7788.299053085249, 7793.220677932161, 7795.869598908126, 5477.75350109238, 5520.585956053393, 5538.584606154474, 5718.628289161191, 5838.737256823538, 7747.457677962489, 9216.677416290398, 9254.814903702023, 9255.120763527455, 9284.210842078499, 7781.572013539083, 9060.501672408082, 9184.954075229582, 9275.72172834816, 9346.953265233631, 7045.718171273092, 7066.505344626033, 7170.426365890612, 7194.568325900463, 7198.7840364789145, 7702.537847729154, 7767.961160194162, 7862.0, 7864.331531819859, 7867.370610351044, 6962.268961758942, 7028.543144695578, 7057.2237053925055, 7137.892931605927, 7186.568805871664, 6907.620080895599, 6920.9307906920285, 6952.791416257521, 6974.337437943387, 7028.191757947697, 6246.000639897595, 6310.116583678197, 6367.87264254703, 6371.331010244043, 6402.743890244338, 4289.978550107229, 4302.784860757012, 4350.869473915787, 4381.058072514425, 4471.463424389094, 4694.319323698113, 4850.708957462563, 4862.927056094232, 4863.319135321129, 4917.237828136645], "value": 4332.468880422317, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.390481894637, 354.13195394295093, 356.6006073198017, 363.27345309381235, 364.7756629672752, 3949.775569573434, 5247.973760131199, 5267.86740850717, 5329.227262047006, 5341.866453338664, 4384.714993525418, 4449.132419178261, 5257.789688412459, 5286.392064912537, 5315.229291752694, 3439.793612383265, 3902.2975864344407, 4018.2365350583295, 4060.8578699745517, 4138.020625149888, 3875.5543112541995, 3900.8049597520153, 4164.312888373414, 4166.500019997605, 4179.958200418003, 3769.6607305342513, 3964.167524819791, 3975.403689446577, 4108.5275193352845, 4125.979370103145, 3660.8352624131976, 3673.530587764889, 3766.585675575671, 3840.1743625120735, 4067.4712287402745, 3030.409035256486, 3582.229820588586, 3646.361886669849, 3648.1609229877013, 3650.817459127033, 4709.9764501177615, 4755.714657120584, 5106.974465127688, 5117.616178786606, 5139.8458046258675, 4278.572142785728, 4737.620990320801, 4745.383100196987, 4751.904961900777, 4782.210935195689, 4387.210302145612, 4625.606823420013, 4954.2568614707725, 5535.311729922372, 5536.529395001429, 3265.5264986576813, 3304.46422328109, 3326.8336583170985, 3597.3165098631175, 3659.652333028358, 5029.7736601853, 5139.894922591623, 5623.0, 5680.318361796528, 5797.608573942266, 3714.312852122312, 3751.306008388457, 3792.7724336539345, 3796.728096087786, 3796.8101594920145, 3861.806909654506, 3870.2066076454257, 3870.651641352292, 3877.8254978526033, 3885.3589157789215, 3765.435184722259, 3812.5234345706785, 3994.5406278277787, 4492.393526873896, 4546.590806827401, 4832.0, 4873.02539492103, 5369.5167434931045, 5558.916616250759, 5560.388357280696, 1068.7221322456217, 1143.7255058785997, 1146.8853114688632, 1238.6407941696987, 1265.3108100685504, 728.883378659412, 761.8590560746279, 805.2390490985962, 857.3998201259144, 860.5740158621397, 4514.88712782183, 4581.679282450196, 4594.494605593382, 4875.195592727231, 4942.579880710108, 4722.976385118052, 5186.429492755794, 5292.497212764782, 5305.283786688748, 5315.538226987532, 4466.687331886802, 4599.43543753587, 4632.652551058684, 4844.2007068833245, 4863.927041094386, 2348.9530209396057, 2366.396568874926, 2462.7167875694527, 2783.6659600847624, 2847.373577812878, 4931.5971979334145, 5260.421353651095, 5699.886002279931, 5701.543876489871, 5701.771929122871, 5246.842594722164, 5290.470952904679, 5569.443055694398, 5594.356648985337, 5631.887362252814, 5342.839714808562, 5598.860028499157, 5630.887382252332, 5654.293213348249, 5701.258836351248, 4493.0, 4654.86035418938, 4896.681715688612, 5054.620903432184, 5415.268938693226, 4631.675782695179, 4788.7366194859615, 5137.383513978347, 5243.89512209746, 5401.837944861661, 4888.486708895514, 5039.673815281896, 5124.360204734449, 5769.53843692512, 5772.249607550992, 3563.6792688658147, 4098.877033688994, 4115.650169735486, 4136.896577585584, 4139.172165566839, 4852.2479015752615, 5658.886822263449, 5725.312962444534, 5740.282464691913, 5752.626079304831, 4548.931001214646, 5270.970676289579, 5519.806806761905, 5534.446555344433, 5552.807135896956, 5480.561555075664, 5531.8893622128135, 5542.584306177134, 5544.029794785815, 5578.0, 4758.333833263415, 5000.949800541927, 5130.4100028497205, 5449.891002179855, 5457.290552228185, 4914.287428322945, 5413.756380962787, 5514.558835293086, 5583.35791383996, 5592.0, 5150.381954165524, 5393.568514518751, 5407.270018547446, 5459.7270136492225, 5487.341519017744], "value": 3950.1838917610603, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.9822504437392, 737.3327138939259, 754.7660225330148, 787.8938228727362, 812.1026265976099, 14616.926915365422, 15219.61950951224, 15647.374105035786, 15692.45076422324, 15693.843061569381, 14081.15513069214, 14210.431582736677, 14229.359678814453, 14235.928820355884, 14242.359093840776, 846.0904527632789, 866.1598249697814, 866.4411454611771, 866.7703058689452, 882.1311008656468, 841.8905542279496, 847.1063028504925, 875.8029443375253, 880.5729221327651, 880.8722735203391, 872.5288344294073, 873.2490058549643, 874.3267683883406, 894.2220268366531, 910.5993362920327, 906.0, 906.0350726476281, 907.0566610724838, 907.664164259225, 908.5457271364285, 676.1683129750386, 676.2763842688325, 676.736072931557, 676.8409423785406, 677.7322957431787, 12199.39003049844, 14231.715365692628, 15048.322825472771, 15051.247437628184, 15561.922190388976, 12104.757904841941, 12412.62762117138, 13475.528356507475, 13794.65513362164, 13822.516211932534, 11865.52537898483, 12385.0, 12561.56034538796, 12688.0, 12838.80741788874, 4444.777761111932, 4453.131639330332, 4537.931931021038, 4626.722396656178, 4663.463701674317, 68118.65940670265, 94921.62695932588, 95379.0, 97087.0, 98869.0, 10132.34139780912, 10351.63769268072, 10593.311434756715, 10764.3541387517, 10911.454427278604, 20780.480487987923, 21396.78603213979, 21402.25092121768, 21443.892780535996, 21469.0, 7680.923190768132, 7752.147263800977, 7814.726484573012, 7852.921470785219, 7995.440319177602, 3762.664254189778, 4250.362445633118, 4340.826366945287, 4377.78111094444, 4529.116822219701, 3456.70617997468, 3529.3823580873245, 3563.803990780532, 3574.428091505347, 3690.9630903691154, 678.3453966921944, 680.7038938061959, 686.8351595617016, 701.2216439751871, 703.3283214530213, 618.5422787137486, 663.354879897856, 663.7975417497613, 672.8957011663102, 704.5913370245246, 8522.275606573387, 8970.327225458117, 9355.251579873593, 9484.336096473318, 9637.112456247132, 640.814163892466, 663.9834004149839, 676.090658064902, 677.3971165662564, 690.9723611055501, 4250.784382729247, 4360.563943605677, 4648.233041548175, 4749.743871660933, 4749.810007599726, 72823.63588182026, 78634.2136578627, 82136.0, 94201.0, 94778.0, 80600.19399806044, 80846.0, 81321.0, 82765.58617206875, 83115.0, 72044.91932621015, 72986.0, 73296.63351683314, 73475.26524734791, 75196.62401688065, 8803.943526776571, 9059.547022648709, 9948.801023979335, 9986.550605222928, 9986.60053597877, 49035.5096449031, 49116.50883491119, 49599.50400496093, 49934.50196494111, 50006.74996624994, 45910.54089459196, 52019.739901301764, 65140.69718605696, 65767.0, 68397.0, 12182.269063856196, 12412.028150991597, 12511.06167037494, 12574.056945728924, 12983.415746291628, 14068.78896816569, 14296.115422905219, 14322.355494002586, 14406.279686015449, 14542.85457145415, 11117.11063114965, 13159.407826647632, 13273.402696878857, 13407.128536645087, 13699.684599351172, 13250.271235081778, 13375.598732038054, 13405.32973351309, 13412.12821166621, 13909.860901390857, 13191.340432978504, 13306.46774129024, 13313.600591982256, 13671.453141874214, 13769.0, 10101.646442374482, 10908.890911090788, 11425.60010399632, 11516.963473287109, 11968.880311196777, 9446.85829712541, 9851.605935762489, 10496.265261431627, 10756.569737210424, 10802.135829133807], "value": 7354.192521457264, "unit": "score"}], "name": "dromaeo_css", "value": 7065.2841390645, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 114.0, 115.0, 115.0, 114.0, 116.0, 116.0, 115.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 108.0, 147.0, 110.0, 111.0, 112.0, 136.0, 162.0, 138.0], "value": 127.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 184.0, 184.0, 185.0, 183.0, 183.0, 186.0, 184.0, 186.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 58.0, 87.0, 83.0, 85.0, 82.0, 49.0, 91.0, 81.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 69.0, 88.0, 68.0, 68.0, 69.0, 85.0, 68.0, 68.0, 85.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 87.0, 91.0, 82.0, 89.0, 71.0, 118.0, 88.0, 90.0, 87.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 98.0, 95.0, 95.0, 96.0, 106.0, 95.0, 96.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 87.0, 86.0, 86.0, 85.0, 84.0, 102.0, 139.0, 139.0], "value": 98.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 57.0, 57.0, 56.0, 56.0, 56.0, 56.0, 56.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 78.0, 67.0, 68.0, 76.0, 78.0, 73.0, 72.0, 72.0, 74.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 87.0, 87.0, 87.0, 86.0, 89.0, 87.0, 89.0, 86.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 139.0, 140.0, 145.0, 144.0, 154.0, 139.0, 170.0, 183.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 174.0, 177.0, 171.0, 179.0, 175.0, 169.0, 173.0, 173.0, 175.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 82.0, 86.0, 85.0, 69.0, 66.0, 67.0, 69.0], "value": 73.0, "unit": "score"}], "name": "kraken", "value": 1476.2, "alertThreshold": 2.0}]} 22:29:34 INFO - Return code: 0 22:29:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:29:34 INFO - # TBPL SUCCESS # 22:29:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:29:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:29:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:29:34 INFO - 2016-12-26 22:29:34.792 screenresolution[1904:11329] starting screenresolution argv=screenresolution get 22:29:34 INFO - 2016-12-26 22:29:34.795 screenresolution[1904:11329] Display 0: 1600x1200x32@60 22:29:34 INFO - 2016-12-26 22:29:34.801 screenresolution[1905:11332] starting screenresolution argv=screenresolution list 22:29:34 INFO - Available Modes on Display 0 22:29:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:29:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:29:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:29:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:29:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:29:35 INFO - Intel Iris: 22:29:35 INFO - Chipset Model: Intel Iris 22:29:35 INFO - Type: GPU 22:29:35 INFO - Bus: Built-In 22:29:35 INFO - VRAM (Dynamic, Max): 1536 MB 22:29:35 INFO - Vendor: Intel (0x8086) 22:29:35 INFO - Device ID: 0x0a2e 22:29:35 INFO - Revision ID: 0x0009 22:29:35 INFO - Displays: 22:29:35 INFO - Display: 22:29:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:29:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:29:35 INFO - Display Serial Number: 1600x1200 60 22:29:35 INFO - Main Display: Yes 22:29:35 INFO - Mirror: Off 22:29:35 INFO - Online: Yes 22:29:35 INFO - Rotation: Supported 22:29:35 INFO - Return code: 0 22:29:35 INFO - Running post-action listener: _resource_record_post_action 22:29:35 INFO - [mozharness: 2016-12-27 06:29:35.053189Z] Finished run-tests step (success) 22:29:35 INFO - Running post-run listener: _resource_record_post_run 22:29:35 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 2236416; Write bytes: 547908096; Read time: 22; Write time: 1668 22:29:35 INFO - TinderboxPrint: CPU usage
53.7% 22:29:35 INFO - TinderboxPrint: I/O read bytes / time
2,236,416 / 22 22:29:35 INFO - TinderboxPrint: I/O write bytes / time
547,908,096 / 1,668 22:29:35 INFO - TinderboxPrint: CPU idle
2,221.7 (46.3%) 22:29:35 INFO - TinderboxPrint: CPU system
959.3 (20.0%) 22:29:35 INFO - TinderboxPrint: CPU user
1,620.7 (33.8%) 22:29:35 INFO - TinderboxPrint: Swap in / out
252,203,008 / 0 22:29:35 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 243161600; Write bytes: 237635584; Read time: 17982; Write time: 525 22:29:35 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1372160; Write bytes: 294822400; Read time: 19; Write time: 1103 22:29:35 INFO - Running post-run listener: _upload_blobber_files 22:29:35 INFO - Blob upload gear active. 22:29:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:29:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:29:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:29:35 INFO - (blobuploader) - INFO - Open directory for files ... 22:29:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:29:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:29:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:37 INFO - (blobuploader) - INFO - Done attempting. 22:29:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:29:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:29:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:38 INFO - (blobuploader) - INFO - Done attempting. 22:29:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:29:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:29:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:39 INFO - (blobuploader) - INFO - Done attempting. 22:29:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:29:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:29:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:41 INFO - (blobuploader) - INFO - Done attempting. 22:29:41 INFO - (blobuploader) - INFO - Iteration through files over. 22:29:41 INFO - Return code: 0 22:29:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:29:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:29:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74c003d2a7bccf1cb39a174e672cd2295ddf513eee71dd7825a64a4c2b5551ee554adbc4f7af1123d4a03e08da4ea923a15559205431ab0b430a21c01e1b1969", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f21ee8ff0d9f6b0fcd3d9cf80af04ae3dc6d51394a5874dedca23737ccce4ac38affdfe75fa2cd4a17248ccf8ba758d6926096604ab3045bc3f65ebcdfc9cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca8a6028e150528f53c02bc6b2386993bccd7f9173d28d48610407eeb592454b848cb23c356a1acbe3b4c726068df458b39d8ea687db0d5e83378d53419bfdfd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/895c320d2db79ccd68a40b02737e96f09ee7cf31ef6d0e7f1d6ac48e19699df046377b112fa4558f79f2acfe35034dd05f25f672d65cfa5486662a999c78aa26"} 22:29:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:29:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:29:41 INFO - Contents: 22:29:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74c003d2a7bccf1cb39a174e672cd2295ddf513eee71dd7825a64a4c2b5551ee554adbc4f7af1123d4a03e08da4ea923a15559205431ab0b430a21c01e1b1969", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f21ee8ff0d9f6b0fcd3d9cf80af04ae3dc6d51394a5874dedca23737ccce4ac38affdfe75fa2cd4a17248ccf8ba758d6926096604ab3045bc3f65ebcdfc9cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca8a6028e150528f53c02bc6b2386993bccd7f9173d28d48610407eeb592454b848cb23c356a1acbe3b4c726068df458b39d8ea687db0d5e83378d53419bfdfd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/895c320d2db79ccd68a40b02737e96f09ee7cf31ef6d0e7f1d6ac48e19699df046377b112fa4558f79f2acfe35034dd05f25f672d65cfa5486662a999c78aa26"} 22:29:41 INFO - Running post-run listener: copy_logs_to_upload_dir 22:29:41 INFO - Copying logs to upload dir... 22:29:41 INFO - mkdir: /builds/slave/test/build/upload/logs 22:29:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.182276 ========= master_lag: 1.01 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-26 22:29:42.626890) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 22:29:42.630482) ========= 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.wuZlWit4mN/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.U2rXAVfYP8/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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/74c003d2a7bccf1cb39a174e672cd2295ddf513eee71dd7825a64a4c2b5551ee554adbc4f7af1123d4a03e08da4ea923a15559205431ab0b430a21c01e1b1969", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f21ee8ff0d9f6b0fcd3d9cf80af04ae3dc6d51394a5874dedca23737ccce4ac38affdfe75fa2cd4a17248ccf8ba758d6926096604ab3045bc3f65ebcdfc9cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca8a6028e150528f53c02bc6b2386993bccd7f9173d28d48610407eeb592454b848cb23c356a1acbe3b4c726068df458b39d8ea687db0d5e83378d53419bfdfd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/895c320d2db79ccd68a40b02737e96f09ee7cf31ef6d0e7f1d6ac48e19699df046377b112fa4558f79f2acfe35034dd05f25f672d65cfa5486662a999c78aa26"} build_url:https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011277 build_url: 'https://queue.taskcluster.net/v1/task/QGGahPTxRN2E4gYmCn6q-w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74c003d2a7bccf1cb39a174e672cd2295ddf513eee71dd7825a64a4c2b5551ee554adbc4f7af1123d4a03e08da4ea923a15559205431ab0b430a21c01e1b1969", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f21ee8ff0d9f6b0fcd3d9cf80af04ae3dc6d51394a5874dedca23737ccce4ac38affdfe75fa2cd4a17248ccf8ba758d6926096604ab3045bc3f65ebcdfc9cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca8a6028e150528f53c02bc6b2386993bccd7f9173d28d48610407eeb592454b848cb23c356a1acbe3b4c726068df458b39d8ea687db0d5e83378d53419bfdfd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/895c320d2db79ccd68a40b02737e96f09ee7cf31ef6d0e7f1d6ac48e19699df046377b112fa4558f79f2acfe35034dd05f25f672d65cfa5486662a999c78aa26"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 22:29:42.665994) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 22:29:42.666335) ========= 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.wuZlWit4mN/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.U2rXAVfYP8/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.005303 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 22:29:42.716732) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 22:29:42.717171) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 22:29:42.717698) ========= ========= Total master_lag: 1.16 =========