builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0276 starttime: 1486518063.49 results: success (0) buildid: 20170207163356 builduid: 403638ecb46c4afeb7baa8a88efc36b1 revision: f4f374622111022d41dd8d5eb9220624135c534a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:03.493078) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:03.493603) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:03.493940) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:03.521626) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:03.522043) ========= 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.QT3DIqO1SL/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.dg4SyumCa3/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-07 17:41:03-- 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% 149M=0s 2017-02-07 17:41:03 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119153 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:03.667049) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:03.667443) ========= 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.QT3DIqO1SL/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.dg4SyumCa3/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.026317 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:03.716845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 17:41:03.717207) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f4f374622111022d41dd8d5eb9220624135c534a --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 f4f374622111022d41dd8d5eb9220624135c534a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QT3DIqO1SL/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.dg4SyumCa3/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-07 17:41:03,827 truncating revision to first 12 chars 2017-02-07 17:41:03,827 Setting DEBUG logging. 2017-02-07 17:41:03,827 attempt 1/10 2017-02-07 17:41:03,827 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f4f374622111?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-07 17:41:04,908 unpacking tar archive at: mozilla-central-f4f374622111/testing/mozharness/ program finished with exit code 0 elapsedTime=1.344444 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 17:41:05.085072) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:05.085431) ========= script_repo_revision: f4f374622111022d41dd8d5eb9220624135c534a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:05.085828) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:05.086129) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 17:41:05.105993) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 30 mins, 17 secs) (at 2017-02-07 17:41:05.106381) ========= /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.QT3DIqO1SL/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.dg4SyumCa3/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:41:05 INFO - MultiFileLogger online at 20170207 17:41:05 in /builds/slave/test 17:41:05 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 17:41:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:41:05 INFO - {'append_to_log': False, 17:41:05 INFO - 'base_work_dir': '/builds/slave/test', 17:41:05 INFO - 'blob_upload_branch': 'Firefox', 17:41:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:41:05 INFO - 'branch': 'Firefox', 17:41:05 INFO - 'buildbot_json_path': 'buildprops.json', 17:41:05 INFO - 'config_files': ('talos/mac_config.py',), 17:41:05 INFO - 'default_actions': ('clobber', 17:41:05 INFO - 'read-buildbot-config', 17:41:05 INFO - 'download-and-extract', 17:41:05 INFO - 'populate-webroot', 17:41:05 INFO - 'create-virtualenv', 17:41:05 INFO - 'install', 17:41:05 INFO - 'run-tests'), 17:41:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:41:05 INFO - 'download_minidump_stackwalk': True, 17:41:05 INFO - 'download_symbols': 'ondemand', 17:41:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:41:05 INFO - 'tooltool.py': '/tools/tooltool.py', 17:41:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:41:05 INFO - '/tools/misc-python/virtualenv.py')}, 17:41:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:41:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:41:05 INFO - 'installer_path': 'installer.exe', 17:41:05 INFO - 'log_level': 'info', 17:41:05 INFO - 'log_name': 'talos', 17:41:05 INFO - 'log_to_console': True, 17:41:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:41:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:41:05 INFO - 'opt_config_files': (), 17:41:05 INFO - 'pip_index': False, 17:41:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:41:05 INFO - 'cmd': ('bash', 17:41:05 INFO - '-c', 17:41:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:41:05 INFO - 'enabled': True, 17:41:05 INFO - 'halt_on_failure': False, 17:41:05 INFO - 'name': 'check_screen_resolution'},), 17:41:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:41:05 INFO - 'cmd': ('bash', 17:41:05 INFO - '-c', 17:41:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:41:05 INFO - 'enabled': True, 17:41:05 INFO - 'halt_on_failure': False, 17:41:05 INFO - 'name': 'check_screen_resolution'},), 17:41:05 INFO - 'run_cmd_checks_enabled': True, 17:41:05 INFO - 'sps_profile': False, 17:41:05 INFO - 'sps_profile_interval': 0, 17:41:05 INFO - 'suite': 'dromaeojs', 17:41:05 INFO - 'system_bits': '32', 17:41:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:41:05 INFO - 'title': 't-yosemite-r7-0276', 17:41:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:41:05 INFO - 'use_talos_json': True, 17:41:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:41:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:41:05 INFO - 'work_dir': 'build'} 17:41:05 INFO - [mozharness: 2017-02-08 01:41:05.325548Z] Running clobber step. 17:41:05 INFO - Running pre-action listener: _resource_record_pre_action 17:41:05 INFO - Running main action method: clobber 17:41:05 INFO - rmtree: /builds/slave/test/build 17:41:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:41:08 INFO - Running post-action listener: _resource_record_post_action 17:41:08 INFO - [mozharness: 2017-02-08 01:41:08.166933Z] Finished clobber step (success) 17:41:08 INFO - [mozharness: 2017-02-08 01:41:08.167068Z] Running read-buildbot-config step. 17:41:08 INFO - Running pre-action listener: _resource_record_pre_action 17:41:08 INFO - Running main action method: read_buildbot_config 17:41:08 INFO - Using buildbot properties: 17:41:08 INFO - { 17:41:08 INFO - "project": "", 17:41:08 INFO - "product": "firefox", 17:41:08 INFO - "script_repo_revision": "production", 17:41:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:41:08 INFO - "repository": "", 17:41:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:41:08 INFO - "buildid": "20170207163356", 17:41:08 INFO - "pgo_build": "False", 17:41:08 INFO - "basedir": "/builds/slave/test", 17:41:08 INFO - "buildnumber": 65, 17:41:08 INFO - "slavename": "t-yosemite-r7-0276", 17:41:08 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:41:08 INFO - "platform": "yosemite_r7", 17:41:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:41:08 INFO - "branch": "mozilla-central", 17:41:08 INFO - "repo_path": "mozilla-central", 17:41:08 INFO - "revision": "f4f374622111022d41dd8d5eb9220624135c534a", 17:41:08 INFO - "stage_platform": "macosx64", 17:41:08 INFO - "builduid": "403638ecb46c4afeb7baa8a88efc36b1", 17:41:08 INFO - "slavebuilddir": "test" 17:41:08 INFO - } 17:41:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 17:41:08 INFO - Running post-action listener: _resource_record_post_action 17:41:08 INFO - [mozharness: 2017-02-08 01:41:08.170546Z] Finished read-buildbot-config step (success) 17:41:08 INFO - [mozharness: 2017-02-08 01:41:08.170662Z] Running download-and-extract step. 17:41:08 INFO - Running pre-action listener: _resource_record_pre_action 17:41:08 INFO - Running main action method: download_and_extract 17:41:08 INFO - mkdir: /builds/slave/test/build/tests 17:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:41:08 INFO - trying https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 17:41:08 INFO - Downloading https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 17:41:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 17:41:09 INFO - Downloaded 1199 bytes. 17:41:09 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 17:41:09 INFO - Using the following test package requirements: 17:41:09 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 17:41:09 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:41:09 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 17:41:09 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:41:09 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 17:41:09 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:41:09 INFO - u'jsshell-mac.zip'], 17:41:09 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:41:09 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 17:41:09 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 17:41:09 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:41:09 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 17:41:09 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:41:09 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 17:41:09 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:41:09 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 17:41:09 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:41:09 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 17:41:09 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:41:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 17:41:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:41:09 INFO - Fetch https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 17:48:29 INFO - Expected file size: 34937677 17:48:29 INFO - Obtained file size: 34937677 17:48:36 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:48:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 17:48:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:48:36 INFO - Fetch https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 17:48:51 INFO - Expected file size: 34937677 17:48:51 INFO - Obtained file size: 34937677 17:48:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 17:48:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:48:57 INFO - Fetch https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 17:49:29 INFO - Expected file size: 14403454 17:49:29 INFO - Obtained file size: 14403454 17:49:29 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')]} 17:49:29 INFO - trying https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:49:29 INFO - Downloading https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 17:49:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 17:50:40 INFO - Downloaded 70370512 bytes. 17:50:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:50:40 INFO - mkdir: /builds/slave/test/properties 17:50:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:50:40 INFO - Writing to file /builds/slave/test/properties/build_url 17:50:40 INFO - Contents: 17:50:40 INFO - build_url:https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:50:41 INFO - Running post-action listener: _resource_record_post_action 17:50:41 INFO - Running post-action listener: set_extra_try_arguments 17:50:41 INFO - [mozharness: 2017-02-08 01:50:41.992707Z] Finished download-and-extract step (success) 17:50:41 INFO - [mozharness: 2017-02-08 01:50:41.992861Z] Running populate-webroot step. 17:50:41 INFO - Running pre-action listener: _resource_record_pre_action 17:50:41 INFO - Running main action method: populate_webroot 17:50:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:50:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:50:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:50:42 INFO - '--tppagecycles', 17:50:42 INFO - '7'], 17:50:42 INFO - 'tests': ['tsvgm']}}, 17:50:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:50:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:50:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:50:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:50:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:50:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:50:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:50:42 INFO - 'talos_options': ['--disable-e10s'], 17:50:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:50:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:50:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:50:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:50:42 INFO - 'talos_options': ['--disable-e10s'], 17:50:42 INFO - 'tests': ['damp', 'tps']}, 17:50:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:50:42 INFO - 'tests': ['damp', 'tps']}, 17:50:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:50:42 INFO - 'tests': ['dromaeo_dom']}, 17:50:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:50:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:50:42 INFO - 'tests': ['basic_compositor_video']}, 17:50:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:50:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:50:42 INFO - 'tests': ['a11yr', 17:50:42 INFO - 'ts_paint', 17:50:42 INFO - 'tpaint', 17:50:42 INFO - 'sessionrestore', 17:50:42 INFO - 'sessionrestore_no_auto_restore', 17:50:42 INFO - 'tabpaint']}, 17:50:42 INFO - 'other-e10s': {'tests': ['a11yr', 17:50:42 INFO - 'ts_paint', 17:50:42 INFO - 'tpaint', 17:50:42 INFO - 'sessionrestore', 17:50:42 INFO - 'sessionrestore_no_auto_restore', 17:50:42 INFO - 'tabpaint']}, 17:50:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:50:42 INFO - 'ts_paint', 17:50:42 INFO - 'tpaint', 17:50:42 INFO - 'sessionrestore', 17:50:42 INFO - 'sessionrestore_no_auto_restore', 17:50:42 INFO - 'tabpaint']}, 17:50:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:50:42 INFO - 'ts_paint', 17:50:42 INFO - 'tpaint', 17:50:42 INFO - 'sessionrestore', 17:50:42 INFO - 'sessionrestore_no_auto_restore', 17:50:42 INFO - 'tabpaint']}, 17:50:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:50:42 INFO - 'tests': ['a11yr', 17:50:42 INFO - 'ts_paint', 17:50:42 INFO - 'tpaint', 17:50:42 INFO - 'sessionrestore', 17:50:42 INFO - 'sessionrestore_no_auto_restore', 17:50:42 INFO - 'tabpaint']}, 17:50:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:50:42 INFO - 'tests': ['a11yr', 17:50:42 INFO - 'ts_paint', 17:50:42 INFO - 'tpaint', 17:50:42 INFO - 'sessionrestore', 17:50:42 INFO - 'sessionrestore_no_auto_restore', 17:50:42 INFO - 'tabpaint']}, 17:50:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:50:42 INFO - 'tests': ['tsvgx', 17:50:42 INFO - 'tsvgr_opacity', 17:50:42 INFO - 'tart', 17:50:42 INFO - 'tscrollx', 17:50:42 INFO - 'cart', 17:50:42 INFO - 'tsvg_static']}, 17:50:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:50:42 INFO - 'tsvgr_opacity', 17:50:42 INFO - 'tart', 17:50:42 INFO - 'tscrollx', 17:50:42 INFO - 'cart', 17:50:42 INFO - 'tsvg_static']}, 17:50:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:50:42 INFO - 'talos_options': ['--disable-e10s'], 17:50:42 INFO - 'tests': ['tp5o']}, 17:50:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:50:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:50:42 INFO - 'talos_options': ['--disable-e10s', 17:50:42 INFO - '--xperf_path', 17:50:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:50:42 INFO - 'tests': ['tp5n']}, 17:50:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:50:42 INFO - 'talos_options': ['--xperf_path', 17:50:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:50:42 INFO - 'tests': ['tp5n']}}, 17:50:42 INFO - 'talos.zip': {'path': '', 17:50:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:50:42 INFO - Running post-action listener: _resource_record_post_action 17:50:42 INFO - [mozharness: 2017-02-08 01:50:42.011127Z] Finished populate-webroot step (success) 17:50:42 INFO - [mozharness: 2017-02-08 01:50:42.011240Z] Running create-virtualenv step. 17:50:42 INFO - Running pre-action listener: _resource_record_pre_action 17:50:42 INFO - Running main action method: create_virtualenv 17:50:42 INFO - Creating virtualenv /builds/slave/test/build/venv 17:50:42 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 17:50:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:50:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:50:42 INFO - Using real prefix '/tools/python27' 17:50:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:50:42 INFO - Installing distribute.............................................................................................................................................................................................done. 17:50:45 INFO - Installing pip.................done. 17:50:45 INFO - Return code: 0 17:50:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:50:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:50:45 INFO - Reading from file tmpfile_stdout 17:50:45 INFO - Output received: 17:50:45 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) 17:50:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:50:45 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')]} 17:50:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:46 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')]} 17:50:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:46 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 0x103bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffed24ddda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039d5d30>, '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 0x1038fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039cabe8>, '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.QT3DIqO1SL/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.dg4SyumCa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:50:46 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 17:50:46 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 17:50:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QT3DIqO1SL/Render', 17:50:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:50:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:50:46 INFO - 'HOME': '/Users/cltbld', 17:50:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:50:46 INFO - 'LOGNAME': 'cltbld', 17:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:46 INFO - 'MOZ_NO_REMOTE': '1', 17:50:46 INFO - 'NO_EM_RESTART': '1', 17:50:46 INFO - 'PAGER': '/bin/cat', 17:50:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:50:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:46 INFO - 'PWD': '/builds/slave/test', 17:50:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:50:46 INFO - 'SHELL': '/bin/bash', 17:50:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dg4SyumCa3/Listeners', 17:50:46 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:50:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:50:46 INFO - 'USER': 'cltbld', 17:50:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:50:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:46 INFO - 'XPC_FLAGS': '0x0', 17:50:46 INFO - 'XPC_SERVICE_NAME': '0', 17:50:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:46 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 17:50:46 INFO - Cleaning up... 17:50:46 INFO - Return code: 0 17:50:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:50:46 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')]} 17:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:46 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')]} 17:50:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:46 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 0x103bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffed24ddda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039d5d30>, '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 0x1038fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039cabe8>, '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.QT3DIqO1SL/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.dg4SyumCa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:50:46 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 17:50:46 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 17:50:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QT3DIqO1SL/Render', 17:50:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:50:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:50:46 INFO - 'HOME': '/Users/cltbld', 17:50:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:50:46 INFO - 'LOGNAME': 'cltbld', 17:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:46 INFO - 'MOZ_NO_REMOTE': '1', 17:50:46 INFO - 'NO_EM_RESTART': '1', 17:50:46 INFO - 'PAGER': '/bin/cat', 17:50:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:50:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:46 INFO - 'PWD': '/builds/slave/test', 17:50:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:50:46 INFO - 'SHELL': '/bin/bash', 17:50:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dg4SyumCa3/Listeners', 17:50:46 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:50:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:50:46 INFO - 'USER': 'cltbld', 17:50:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:50:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:46 INFO - 'XPC_FLAGS': '0x0', 17:50:46 INFO - 'XPC_SERVICE_NAME': '0', 17:50:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:46 INFO - Downloading/unpacking psutil>=3.1.1 17:50:46 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 17:50:46 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 17:50:46 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 17:50:46 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 17:50:46 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 17:50:46 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 17:50:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:50:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:50:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:50:50 INFO - Installing collected packages: psutil 17:50:50 INFO - Running setup.py install for psutil 17:50:50 INFO - building 'psutil._psutil_osx' extension 17:50:50 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 17:50:51 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 17:50:51 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 17:50:51 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 17:50:51 INFO - building 'psutil._psutil_posix' extension 17:50:51 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 17:50:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:50:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:50:51 INFO - ^ 17:50:51 INFO - 1 warning generated. 17:50:51 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 17:50:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:50:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:50:51 INFO - Successfully installed psutil 17:50:51 INFO - Cleaning up... 17:50:51 INFO - Return code: 0 17:50:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:50:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:50:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:51 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 0x103bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffed24ddda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039d5d30>, '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 0x1038fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039cabe8>, '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.QT3DIqO1SL/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.dg4SyumCa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:50:51 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 17:50:51 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 17:50:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QT3DIqO1SL/Render', 17:50:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:50:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:50:51 INFO - 'HOME': '/Users/cltbld', 17:50:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:50:51 INFO - 'LOGNAME': 'cltbld', 17:50:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:51 INFO - 'MOZ_NO_REMOTE': '1', 17:50:51 INFO - 'NO_EM_RESTART': '1', 17:50:51 INFO - 'PAGER': '/bin/cat', 17:50:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:50:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:51 INFO - 'PWD': '/builds/slave/test', 17:50:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:50:51 INFO - 'SHELL': '/bin/bash', 17:50:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dg4SyumCa3/Listeners', 17:50:51 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:50:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:50:51 INFO - 'USER': 'cltbld', 17:50:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:50:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:50:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:51 INFO - 'XPC_FLAGS': '0x0', 17:50:51 INFO - 'XPC_SERVICE_NAME': '0', 17:50:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:50:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:50:51 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 17:50:51 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 17:50:51 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 17:50:51 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 17:50:51 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 17:50:51 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 17:50:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:50:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:50:54 INFO - Installing collected packages: mozsystemmonitor 17:50:54 INFO - Running setup.py install for mozsystemmonitor 17:50:54 INFO - Successfully installed mozsystemmonitor 17:50:54 INFO - Cleaning up... 17:50:54 INFO - Return code: 0 17:50:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:54 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 0x103bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffed24ddda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039d5d30>, '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 0x1038fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039cabe8>, '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.QT3DIqO1SL/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.dg4SyumCa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:50:54 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 17:50:54 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 17:50:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QT3DIqO1SL/Render', 17:50:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:50:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:50:54 INFO - 'HOME': '/Users/cltbld', 17:50:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:50:54 INFO - 'LOGNAME': 'cltbld', 17:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:54 INFO - 'MOZ_NO_REMOTE': '1', 17:50:54 INFO - 'NO_EM_RESTART': '1', 17:50:54 INFO - 'PAGER': '/bin/cat', 17:50:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:54 INFO - 'PWD': '/builds/slave/test', 17:50:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:50:54 INFO - 'SHELL': '/bin/bash', 17:50:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dg4SyumCa3/Listeners', 17:50:54 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:50:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:50:54 INFO - 'USER': 'cltbld', 17:50:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:50:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:54 INFO - 'XPC_FLAGS': '0x0', 17:50:54 INFO - 'XPC_SERVICE_NAME': '0', 17:50:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:55 INFO - Downloading/unpacking jsonschema==2.5.1 17:50:55 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 17:50:55 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 17:50:55 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 17:50:55 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 17:50:55 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 17:50:55 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 17:50:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:50:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:50:57 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 17:50:57 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 17:50:57 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 17:50:57 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 17:50:57 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 17:50:57 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 17:50:58 INFO - Downloading functools32-3.2.3-2.tar.gz 17:50:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:50:58 INFO - warning: no files found matching '*.txt' 17:50:58 INFO - no previously-included directories found matching 'build' 17:50:58 INFO - no previously-included directories found matching 'dist' 17:50:58 INFO - no previously-included directories found matching '.git*' 17:50:58 INFO - Installing collected packages: jsonschema, functools32 17:50:58 INFO - Running setup.py install for functools32 17:50:58 INFO - warning: no files found matching '*.txt' 17:50:58 INFO - no previously-included directories found matching 'build' 17:50:58 INFO - no previously-included directories found matching 'dist' 17:50:58 INFO - no previously-included directories found matching '.git*' 17:50:58 INFO - Successfully installed jsonschema functools32 17:50:58 INFO - Cleaning up... 17:50:58 INFO - Return code: 0 17:50:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:50:58 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')]} 17:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:58 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')]} 17:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:58 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 0x103bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffed24ddda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039d5d30>, '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 0x1038fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039cabe8>, '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.QT3DIqO1SL/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.dg4SyumCa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:50:58 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 17:50:58 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 17:50:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QT3DIqO1SL/Render', 17:50:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:50:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:50:58 INFO - 'HOME': '/Users/cltbld', 17:50:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:50:58 INFO - 'LOGNAME': 'cltbld', 17:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:58 INFO - 'MOZ_NO_REMOTE': '1', 17:50:58 INFO - 'NO_EM_RESTART': '1', 17:50:58 INFO - 'PAGER': '/bin/cat', 17:50:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:58 INFO - 'PWD': '/builds/slave/test', 17:50:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:50:58 INFO - 'SHELL': '/bin/bash', 17:50:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dg4SyumCa3/Listeners', 17:50:58 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:50:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:50:58 INFO - 'USER': 'cltbld', 17:50:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:50:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:58 INFO - 'XPC_FLAGS': '0x0', 17:50:58 INFO - 'XPC_SERVICE_NAME': '0', 17:50:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:50:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:50:58 INFO - Cleaning up... 17:50:58 INFO - Return code: 0 17:50:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:50:58 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')]} 17:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:58 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')]} 17:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:58 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 0x103bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffed24ddda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039d5d30>, '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 0x1038fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039cabe8>, '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.QT3DIqO1SL/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.dg4SyumCa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:50:58 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 17:50:58 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 17:50:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QT3DIqO1SL/Render', 17:50:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:50:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:50:58 INFO - 'HOME': '/Users/cltbld', 17:50:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:50:58 INFO - 'LOGNAME': 'cltbld', 17:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:58 INFO - 'MOZ_NO_REMOTE': '1', 17:50:58 INFO - 'NO_EM_RESTART': '1', 17:50:58 INFO - 'PAGER': '/bin/cat', 17:50:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:58 INFO - 'PWD': '/builds/slave/test', 17:50:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:50:58 INFO - 'SHELL': '/bin/bash', 17:50:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dg4SyumCa3/Listeners', 17:50:58 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:50:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:50:58 INFO - 'USER': 'cltbld', 17:50:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:50:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:58 INFO - 'XPC_FLAGS': '0x0', 17:50:58 INFO - 'XPC_SERVICE_NAME': '0', 17:50:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:59 INFO - Downloading/unpacking blobuploader==1.2.4 17:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:01 INFO - Downloading blobuploader-1.2.4.tar.gz 17:51:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:51:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:51:01 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 17:51:01 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 17:51:01 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 17:51:01 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 17:51:01 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 17:51:01 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 17:51:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:51:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:02 INFO - Downloading docopt-0.6.1.tar.gz 17:51:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:51:02 INFO - Installing collected packages: blobuploader, requests, docopt 17:51:02 INFO - Running setup.py install for blobuploader 17:51:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:51:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:51:03 INFO - Running setup.py install for requests 17:51:03 INFO - Running setup.py install for docopt 17:51:03 INFO - Successfully installed blobuploader requests docopt 17:51:03 INFO - Cleaning up... 17:51:03 INFO - Return code: 0 17:51:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:51:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:03 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 0x103bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffed24ddda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039d5d30>, '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 0x1038fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039cabe8>, '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.QT3DIqO1SL/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.dg4SyumCa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:51:03 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 17:51:03 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 17:51:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QT3DIqO1SL/Render', 17:51:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:03 INFO - 'HOME': '/Users/cltbld', 17:51:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:03 INFO - 'LOGNAME': 'cltbld', 17:51:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:03 INFO - 'MOZ_NO_REMOTE': '1', 17:51:03 INFO - 'NO_EM_RESTART': '1', 17:51:03 INFO - 'PAGER': '/bin/cat', 17:51:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:03 INFO - 'PWD': '/builds/slave/test', 17:51:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:03 INFO - 'SHELL': '/bin/bash', 17:51:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dg4SyumCa3/Listeners', 17:51:03 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:51:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:03 INFO - 'USER': 'cltbld', 17:51:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:03 INFO - 'XPC_FLAGS': '0x0', 17:51:03 INFO - 'XPC_SERVICE_NAME': '0', 17:51:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:51:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-YsTRHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:51:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-8P7DUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:51:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-0713j3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:51:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Ll9M9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:51:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-gO9a9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:51:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-N3RQ45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:51:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-jwiNuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:51:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Oh2Iw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:51:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-rzqLJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:51:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-1VDrvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:51:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-2eOCHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:51:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-BmRBiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:51:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-MhpVWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:51:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-vLFJED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-HDNkkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:51:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-xrruHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:51:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Igo3Rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:51:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:51:05 INFO - Running setup.py install for manifestparser 17:51:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:51:06 INFO - Running setup.py install for mozcrash 17:51:06 INFO - Running setup.py install for mozdebug 17:51:06 INFO - Running setup.py install for mozdevice 17:51:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:51:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:51:06 INFO - Running setup.py install for mozfile 17:51:06 INFO - Running setup.py install for mozhttpd 17:51:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:51:06 INFO - Running setup.py install for mozinfo 17:51:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:51:06 INFO - Running setup.py install for mozInstall 17:51:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:51:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:51:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:51:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:51:07 INFO - Running setup.py install for mozleak 17:51:07 INFO - Running setup.py install for mozlog 17:51:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:51:07 INFO - Running setup.py install for moznetwork 17:51:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:51:07 INFO - Running setup.py install for mozprocess 17:51:07 INFO - Running setup.py install for mozprofile 17:51:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:51:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:51:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:51:07 INFO - Running setup.py install for mozrunner 17:51:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:51:08 INFO - Running setup.py install for mozscreenshot 17:51:08 INFO - Running setup.py install for moztest 17:51:08 INFO - Running setup.py install for mozversion 17:51:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:51:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:51:08 INFO - Cleaning up... 17:51:08 INFO - Return code: 0 17:51:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:08 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 0x103bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffed24ddda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039d5d30>, '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 0x1038fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039cabe8>, '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.QT3DIqO1SL/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.dg4SyumCa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:51:08 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 17:51:08 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 17:51:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QT3DIqO1SL/Render', 17:51:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:08 INFO - 'HOME': '/Users/cltbld', 17:51:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:08 INFO - 'LOGNAME': 'cltbld', 17:51:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:08 INFO - 'MOZ_NO_REMOTE': '1', 17:51:08 INFO - 'NO_EM_RESTART': '1', 17:51:08 INFO - 'PAGER': '/bin/cat', 17:51:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:08 INFO - 'PWD': '/builds/slave/test', 17:51:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:08 INFO - 'SHELL': '/bin/bash', 17:51:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dg4SyumCa3/Listeners', 17:51:08 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:51:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:08 INFO - 'USER': 'cltbld', 17:51:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:08 INFO - 'XPC_FLAGS': '0x0', 17:51:08 INFO - 'XPC_SERVICE_NAME': '0', 17:51:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:51:08 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Dr9m4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:51:08 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)) 17:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:51:08 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-_0pWmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:51:09 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)) 17:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:51:09 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-evcHQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:51:09 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)) 17:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:51:09 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-tZjklD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:51:09 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)) 17:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:51:09 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-fbsvk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:51:09 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)) 17:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:51:09 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-oLFuJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:51:09 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)) 17:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:51:09 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-qQ4dk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:51:09 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)) 17:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:51:09 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-9IzjeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:51:09 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)) 17:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:51:09 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-oysoKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:51:09 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)) 17:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:51:09 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-qm1F3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:51:09 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)) 17:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:51:09 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-6hmo4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:51:10 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)) 17:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:51:10 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-o43v3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:51:10 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-H3eYmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:51:10 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)) 17:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:51:10 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-8GWkaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:51:10 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)) 17:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:10 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-h7ZJca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:51:10 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)) 17:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:51:10 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-tJUPwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:51:10 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-p_2EZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:51:10 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)) 17:51:10 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)) 17:51:10 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)) 17:51:10 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)) 17:51:10 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)) 17:51:10 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)) 17:51:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:51:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:51:13 INFO - Downloading blessings-1.6.tar.gz 17:51:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:51:13 INFO - Installing collected packages: blessings 17:51:13 INFO - Running setup.py install for blessings 17:51:13 INFO - Successfully installed blessings 17:51:13 INFO - Cleaning up... 17:51:13 INFO - Return code: 0 17:51:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:51:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:51:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:51:13 INFO - Reading from file tmpfile_stdout 17:51:13 INFO - Current package versions: 17:51:13 INFO - blessings == 1.6 17:51:13 INFO - blobuploader == 1.2.4 17:51:13 INFO - docopt == 0.6.1 17:51:13 INFO - functools32 == 3.2.3-2 17:51:13 INFO - jsonschema == 2.5.1 17:51:13 INFO - manifestparser == 1.1 17:51:13 INFO - mozInstall == 1.12 17:51:13 INFO - mozcrash == 1.0 17:51:13 INFO - mozdebug == 0.1 17:51:13 INFO - mozdevice == 0.49 17:51:13 INFO - mozfile == 1.2 17:51:13 INFO - mozhttpd == 0.7 17:51:13 INFO - mozinfo == 0.9 17:51:13 INFO - mozleak == 0.1 17:51:13 INFO - mozlog == 3.4 17:51:13 INFO - moznetwork == 0.27 17:51:13 INFO - mozprocess == 0.24 17:51:13 INFO - mozprofile == 0.28 17:51:13 INFO - mozrunner == 6.13 17:51:13 INFO - mozscreenshot == 0.1 17:51:13 INFO - mozsystemmonitor == 0.3 17:51:13 INFO - moztest == 0.8 17:51:13 INFO - mozversion == 1.4 17:51:13 INFO - psutil == 3.1.1 17:51:13 INFO - requests == 1.2.3 17:51:13 INFO - wsgiref == 0.1.2 17:51:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:13 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 0x103bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffed24ddda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039d5d30>, '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 0x1038fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039cabe8>, '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.QT3DIqO1SL/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.dg4SyumCa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:51:13 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 17:51:13 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 17:51:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QT3DIqO1SL/Render', 17:51:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:13 INFO - 'HOME': '/Users/cltbld', 17:51:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:13 INFO - 'LOGNAME': 'cltbld', 17:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:13 INFO - 'MOZ_NO_REMOTE': '1', 17:51:13 INFO - 'NO_EM_RESTART': '1', 17:51:13 INFO - 'PAGER': '/bin/cat', 17:51:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:13 INFO - 'PWD': '/builds/slave/test', 17:51:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:13 INFO - 'SHELL': '/bin/bash', 17:51:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dg4SyumCa3/Listeners', 17:51:13 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:51:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:13 INFO - 'USER': 'cltbld', 17:51:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:13 INFO - 'XPC_FLAGS': '0x0', 17:51:13 INFO - 'XPC_SERVICE_NAME': '0', 17:51:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:14 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)) 17:51:14 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)) 17:51:14 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)) 17:51:14 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)) 17:51:14 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)) 17:51:14 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)) 17:51:14 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)) 17:51:14 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)) 17:51:14 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)) 17:51:14 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)) 17:51:14 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)) 17:51:14 INFO - Cleaning up... 17:51:14 INFO - Return code: 0 17:51:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:51: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')]} 17:51:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51: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')]} 17:51:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffed24ddda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039d5d30>, '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 0x1038fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039cabe8>, '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.QT3DIqO1SL/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.dg4SyumCa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:51: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'] in /builds/slave/test/build 17:51: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 17:51:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QT3DIqO1SL/Render', 17:51:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:14 INFO - 'HOME': '/Users/cltbld', 17:51:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:14 INFO - 'LOGNAME': 'cltbld', 17:51:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:14 INFO - 'MOZ_NO_REMOTE': '1', 17:51:14 INFO - 'NO_EM_RESTART': '1', 17:51:14 INFO - 'PAGER': '/bin/cat', 17:51:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:14 INFO - 'PWD': '/builds/slave/test', 17:51:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:14 INFO - 'SHELL': '/bin/bash', 17:51:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dg4SyumCa3/Listeners', 17:51:14 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:51:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:14 INFO - 'USER': 'cltbld', 17:51:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:14 INFO - 'XPC_FLAGS': '0x0', 17:51:14 INFO - 'XPC_SERVICE_NAME': '0', 17:51:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:51:14 INFO - Cleaning up... 17:51:14 INFO - Return code: 0 17:51:14 INFO - Running post-action listener: _resource_record_post_action 17:51:14 INFO - Running post-action listener: _start_resource_monitoring 17:51:14 INFO - Starting resource monitoring. 17:51:14 INFO - [mozharness: 2017-02-08 01:51:14.541902Z] Finished create-virtualenv step (success) 17:51:14 INFO - [mozharness: 2017-02-08 01:51:14.542878Z] Running install step. 17:51:14 INFO - Running pre-action listener: _resource_record_pre_action 17:51:14 INFO - Running main action method: install 17:51:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:51:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:51:14 INFO - Reading from file tmpfile_stdout 17:51:14 INFO - Detecting whether we're running mozinstall >=1.0... 17:51:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:51:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:51:14 INFO - Reading from file tmpfile_stdout 17:51:14 INFO - Output received: 17:51:14 INFO - Usage: mozinstall [options] installer 17:51:14 INFO - Options: 17:51:14 INFO - -h, --help show this help message and exit 17:51:14 INFO - -d DEST, --destination=DEST 17:51:14 INFO - Directory to install application into. [default: 17:51:14 INFO - "/builds/slave/test"] 17:51:14 INFO - --app=APP Application being installed. [default: firefox] 17:51:14 INFO - mkdir: /builds/slave/test/build/application 17:51:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:51:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:51:36 INFO - Reading from file tmpfile_stdout 17:51:36 INFO - Output received: 17:51:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:51:36 INFO - Running post-action listener: _resource_record_post_action 17:51:36 INFO - [mozharness: 2017-02-08 01:51:36.765219Z] Finished install step (success) 17:51:36 INFO - [mozharness: 2017-02-08 01:51:36.765369Z] Running run-tests step. 17:51:36 INFO - Running pre-action listener: _resource_record_pre_action 17:51:36 INFO - Running main action method: run_tests 17:51:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:51:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:51:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:51:36 INFO - 2017-02-07 17:51:36.779 screenresolution[1636:8564] starting screenresolution argv=screenresolution get 17:51:36 INFO - 2017-02-07 17:51:36.782 screenresolution[1636:8564] Display 0: 1600x1200x32@60 17:51:36 INFO - 2017-02-07 17:51:36.788 screenresolution[1637:8566] starting screenresolution argv=screenresolution list 17:51:36 INFO - Available Modes on Display 0 17:51:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:51:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:51:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:51:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:51:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:51:37 INFO - Intel Iris: 17:51:37 INFO - Chipset Model: Intel Iris 17:51:37 INFO - Type: GPU 17:51:37 INFO - Bus: Built-In 17:51:37 INFO - VRAM (Dynamic, Max): 1536 MB 17:51:37 INFO - Vendor: Intel (0x8086) 17:51:37 INFO - Device ID: 0x0a2e 17:51:37 INFO - Revision ID: 0x0009 17:51:37 INFO - Displays: 17:51:37 INFO - Display: 17:51:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:51:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:51:37 INFO - Display Serial Number: 1600x1200 60 17:51:37 INFO - Main Display: Yes 17:51:37 INFO - Mirror: Off 17:51:37 INFO - Online: Yes 17:51:37 INFO - Rotation: Supported 17:51:37 INFO - Return code: 0 17:51:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:51:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:51:37 INFO - Python 2.7.3 17:51:37 INFO - Return code: 0 17:51:37 INFO - grabbing minidump binary from tooltool 17:51:37 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')]} 17:51:37 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 0x1039d5d30>, '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 0x1038fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039cabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:51:37 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 17:51:37 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 17:51:37 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 17:51:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:51:37 INFO - Return code: 0 17:51:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:51:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:51:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:51:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:51:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:51:37 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/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 17:51:37 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/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0276 --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 17:51:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QT3DIqO1SL/Render', 17:51:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:51:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:51:37 INFO - 'HOME': '/Users/cltbld', 17:51:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:51:37 INFO - 'LOGNAME': 'cltbld', 17:51:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:51:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:51:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:37 INFO - 'MOZ_NO_REMOTE': '1', 17:51:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:51:37 INFO - 'NO_EM_RESTART': '1', 17:51:37 INFO - 'PAGER': '/bin/cat', 17:51:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:51:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:51:37 INFO - 'PWD': '/builds/slave/test', 17:51:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:51:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:51:37 INFO - 'SHELL': '/bin/bash', 17:51:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dg4SyumCa3/Listeners', 17:51:37 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:51:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:51:37 INFO - 'USER': 'cltbld', 17:51:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:51:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:51:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:37 INFO - 'XPC_FLAGS': '0x0', 17:51:37 INFO - 'XPC_SERVICE_NAME': '0', 17:51:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:51:37 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/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 17:51:37 INFO - mozversion application_buildid: 20170207163356 17:51:37 INFO - mozversion application_changeset: f4f374622111022d41dd8d5eb9220624135c534a 17:51:37 INFO - mozversion application_display_name: Nightly 17:51:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:51:37 INFO - mozversion application_name: Firefox 17:51:37 INFO - mozversion application_remotingname: firefox 17:51:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:51:37 INFO - mozversion application_vendor: Mozilla 17:51:37 INFO - mozversion application_version: 54.0a1 17:51:37 INFO - mozversion platform_buildid: 20170207163356 17:51:37 INFO - mozversion platform_changeset: f4f374622111022d41dd8d5eb9220624135c534a 17:51:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:51:37 INFO - mozversion platform_version: 54.0a1 17:51:37 INFO - using testdate: 1486518697 17:51:37 INFO - actual date: 1486518697 17:51:37 INFO - starting webserver on 'localhost:49247' 17:51:37 INFO - SUITE-START | Running 2 tests 17:51:37 INFO - TEST-START | dromaeo_css 17:51:37 INFO - operating with platform_type : mac_ 17:51:37 INFO - Initialising browser for dromaeo_css test... 17:51:37 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpLaU9Yw/profile http://localhost:49247/getInfo.html) 17:51:39 INFO - PROCESS | 1647 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 17:51:39 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 17:51:39 INFO - PROCESS | 1647 | colorDepth:24 17:51:39 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 17:51:39 INFO - PROCESS | 1647 | __metrics 17:51:39 INFO - PROCESS | 1647 | 1486518699854 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:51:39 INFO - PROCESS | 1647 | 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" 17:51:39 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:51:40 INFO - TEST-INFO | 1647: exit 0 17:51:40 INFO - Browser initialized. 17:51:40 INFO - Running cycle 1/1 for dromaeo_css test... 17:51:40 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpLaU9Yw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:51:52 INFO - PROCESS | 1650 | RSS: Main: 237871104 17:51:52 INFO - PROCESS | 1650 | 17:54:56 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 17:54:58 INFO - PROCESS | 1650 | RSS: Main: 420524032 17:54:58 INFO - PROCESS | 1650 | 17:56:42 INFO - PROCESS | 1650 | ************************* 17:56:42 INFO - PROCESS | 1650 | A coding exception was thrown and uncaught in a Task. 17:56:42 INFO - PROCESS | 1650 | 17:56:42 ERROR - PROCESS | 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:56:42 INFO - PROCESS | 1650 | Full stack: 17:56:42 INFO - PROCESS | 1650 | ************************* 17:58:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 17:58:01 INFO - PROCESS | 1650 | RSS: Main: 372264960 17:58:01 INFO - PROCESS | 1650 | 18:01:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 18:01:04 INFO - PROCESS | 1650 | RSS: Main: 337424384 18:01:04 INFO - PROCESS | 1650 | 18:04:07 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 18:04:07 INFO - PROCESS | 1650 | RSS: Main: 360308736 18:04:07 INFO - PROCESS | 1650 | 18:07:10 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 18:07:11 INFO - PROCESS | 1650 | RSS: Main: 335777792 18:07:11 INFO - PROCESS | 1650 | 18:10:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 18:10:14 INFO - PROCESS | 1650 | RSS: Main: 523763712 18:10:14 INFO - PROCESS | 1650 | 18:10:14 INFO - PROCESS | 1650 | __start_tp_report 18:10:14 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 18:10:14 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 18:10:14 INFO - PROCESS | 1650 | |i|pagename|runs| 18:10:14 INFO - PROCESS | 1650 | |0;dojo.html;3290.720288775454;3325.0357396355053;3332.1003329101145;3354.7148492378146;3380.4929260610907;5392;5468.125099984003;5577.63745356552;5594.832155035345;5625.1281051437045;5513.09034009388;5561.026820306451;5570.610057295991;5583.553315734742;5629.071203251468;4981.1283025470575;5050.419201791786;5079.873003174923;5184.896302073957;5188.688678679291;4306;5133.30700355451;5156.175011998081;5200.76596553156;5214.217867319894;4968.652194346397;5011.3485246918035;5012.122878496249;5014.047331007097;5122.564582010515;4098.303288440972;5048.3184770056305;5048.9495105048845;5119.692818430906;5128.743562821881;4951.678140920829;5012.949870501285;5053.267276244924;5059.494050594948;5059.822906198266;5359.437259087778;5383.973080134613;5530.198121272393;5530.668159910419;5540.168974653794;6003.549733769984;6079.969600152015;6113.541484388644;6125.540694081788;6129.233845769279;6146.78486252979;6207.441330280251;6224.284207316171;6230.283517395512;6254.530910181755;5672.659640421588;5675.914861277084;5677.37548869624;5687.40282270364;5712.542996560265;6054.54590905675;6153.753849845956;6158.753649854045;6184.134221209034;6214.782482613087;5970.49250813677;6049.334573196945;6058.848528786815;6107.236595425572;6120.908186377208;6166.075088736726;6197.504199664016;6205.844853878689;6206.286277078194;6223.253209614875;5824.446677565625;5868.970655146697;6045.052468553678;6117.105996655267;6134.895838532271;5973.641581505037;6067.059605761114;6113.810786210604;6115.082737589397;6164.568480206342;4911.7544122793715;4928.112939670822;4931.950680493149;4932.827351042696;4959.702417854868;4376.868693939187;4391.8682439526865;4403.4495688039;4414.492333381638;4442.022754993896;5504.256925315111;5512;5526.4197259288;5563.332400112013;5613.550915926716;6139.400144844221;6210.534209934273;6221.595596286227;6227.501799856001;6238.968805155945;4691.648126390534;5643.984082865041;5646.858828529237;5651.604387692904;5692.459216374438;5172.844814655567;5341.172118321666;5351.545118664957;5351.57187425005;5389.003034438641;6218.751249950042;6239.220097487814;6281.994880819138;6283.49732021428;6293.118963345227;5976.252968378953;6030.03519436888;6081.817545473643;6214.782482613087;6239.126522286974;6115.0273862886115;6129.785457508966;6164.352742961924;6173.32093469709;6222.128901953821;5819.505342045804;5851.795187168429;5855.502282306052;5859.355470898283;5893.116032594973;4654.720716757005;5872.354041055395;5945.64326140433;5949.613275137102;6007.128966299774;5793.536517078539;5809.099589563624;5899.528037757055;5902.970485147547;5950.256217972754;5965.73154208053;5974.790882319098;5997.250343707036;5998.070299103646;6020.819375418744;5784.305883293863;5817.127430885232;5836.533077353886;5837.270341207349;5845.152452894392;5743.052396354722;5793.681347525927;5966.19456373384;6007.519398448027;6013.308469525886;5694.971525142513;5698.2592263005545;5744.655320680772;5766.855828604318;5826.388229235869 18:10:14 INFO - PROCESS | 1650 | |1;ext.html;460.8387064527416;500.0798530703505;500.1572350589255;518.2407772613122;518.4815184815185;28037;29767.958121465752;38342.424863627035;40782.388264176065;40913.18173636525;41983.79008104963;42185.789071054605;42220.03369444038;42290.365644515354;42336.78831605837;9083.500407477586;9296.349255552113;9301.395409298373;9312.301577381724;9326.393784403992;7795;7988.520688758664;10811.243212975094;11037.558497660084;11065.225434219608;9289.674861379854;11079.446027698623;11083.612073577426;11087.55649774008;11120.833187502194;7814.960925195338;9514.762130946712;10887.183461240398;10962.287451315638;10973.50619222137;7819.569923654197;9209.309301802392;10612.257142000062;10870.510827012804;11112.388818614972;39195;39230.41154382686;39579.8021009896;39598;39612.40581391282;3712;3729.2355067211156;3746;3771.4719939208535;3803.8858834235016;29261.97583084582;29687.703122968927;29979.550306745416;30359.696403036127;30575.235619109477;2524.825955930501;2915.9125226243245;2976.255936015996;3042.162517885546;3163.841807909618;441770;444073;445098;445884;446066.55068290466;55475.167872481135;55963;55982;56004.71997640067;56098.87802244014;48525;49471;49608.75195624071;57058.29712988777;57869.4213057864;14150.787738183935;14411.279436028157;14450;14466.927665361605;14479.972803807475;11174;11237.662870113909;11251;11255.718607034889;11260.099192064616;13901.721965560631;17331.740023899652;17354.392596259324;17391.391301304393;17396.86856365841;7533.698652053857;7596.430267729942;7601.581912994839;7627.961860190774;7706.614669266514;837.1837458478057;841.2638940926689;868.6178081644057;868.7871471489399;871.7708031675434;25060.373490662878;27012.864935675196;28010.159695209175;28787.136385908456;29402;1010.525053224983;1012.9262981239909;1036.2694300518267;1036.683811437519;1150.9712257193469;15074.246287685572;15085;15090.019148755297;15122.443200167012;15466.767998480032;606575;613621;613926;614118;614634;58425.70787146037;59062.523436914424;59141.408585914454;59171;59247.518812030045;1345.7510360583128;1374.3609221711952;1388.7430825297151;1393.1780249652754;1393.3381643719151;26882.462350753267;28904.42191156123;29148.27129321784;29464.852675736485;29793.10620681383;49894.251586226965;50165.49834501754;50224.748876255384;50236.748816255684;50325.99348013093;513440;517090;517683;518090.0954952313;518868;22795.430114247276;23367.298981030595;25808.48383032291;25964.870175649;26520;45357.31964020476;45533.08933821239;46004.7699761499;46401.83995400142;46813.53186468092;34310.31379372448;37262.25475490529;37949.620503794606;38140.23719525539;38827;35572;35807.104822379646;36324.818375907955;36537;37994.24011519699;36416.08959776027;36455.635443645224;36851.447228292134;37230.627693722716;37264.813675931444;31354.843225783727;31452.68547314589;32940.67059329472;33065.338693226484;33169;20996.68504972396;23926.641100383855;24371.39071523226;24876.502469950137;24919 18:10:14 INFO - PROCESS | 1650 | |2;jquery.html;384.6614978818639;385.1814893351628;389.95111274652703;398.82252397683027;412.9153523527675;13932.721345573082;17626.823731762677;19163.712544311828;20476.69284960727;20534.89732551337;14746.557603271893;17158.742618860728;20090.1963921443;20101.396958091245;20536;5809.3609702932645;5934.851628709273;5953.672548009858;6001.339852616209;6021.759129634809;5696.715164241771;5892.057270836667;5901.3803550627;5978.8804223915495;6001.039833626621;5919.082542205965;5941.257342832146;5949.167116603679;5951.315598706161;5951.5238780897425;5626.071698169838;5734.225879506255;5809.622374545641;5917.52659787216;5947.137665038588;5058.772355244023;5088.135017047074;5159.509846564608;5160.612954028463;5176.948230517684;24726.5054698905;25188;25275;25300;25618.359541011436;2604.361931326818;2735.712750161244;2749.092799376201;2764.7788176945796;2771.6812566554904;14832.925835370754;15358.001729887521;15429.768553471707;15576.065436073872;16302.347906083862;1771.3446024970715;1809.2582041362978;1827.1321122466722;1837.9448616541524;1846.7137593673003;110021;112884.74230515344;120845.39577302057;125688.37155814162;125756;10894.933855757112;11126.554937802397;11200.38397888124;11438.828417573743;11492.655220343404;12612.558560450523;14308;14325.64185895361;14328.856711432752;14357.6410589734;7174.210836807947;7517.248275172548;7535.924640753632;8092.312153467021;8519.957400213083;7914.485558438798;8191.95904020488;8289.544075075928;8295.377846661524;8451.36614753896;6878.140232470942;7022.578645281299;7077.292270772881;7089.0784198053925;7109.537880037781;1644.3011720018944;1873.4473330367302;1908.9809101909082;1909.3794516782102;1909.8567607429497;1004.809300978343;1028.9022542858565;1042.4058286776617;1048.5596049659162;1052.231870734353;22037;22459.32622021342;22534.77465225327;22550.887245563667;22572.20997265088;1053.3311347294523;1057.260805974168;1069.2248120112856;1074.7474343529263;1097.9670609881716;10825.296355736851;11700.824487632692;11703.180777345504;11752.294862308288;11771.823422648667;674305;682847;687711;702855.4857225681;704918;105295.4735226319;122497;122767;125135;126732;998.7715110414231;1019.1133713669155;1032.70051685012;1032.9535170917327;1042.5673345561659;18682.06589670484;18892.33876814305;19168.424947251606;19210.903945480743;19235.615287694447;53937.73031134819;54035.729821350644;54278;54372.18441723298;54937.78091081341;72973;73076;74801;75154;77182;5069.898602028013;5137;7040.964795175992;7547.433942454228;7989.2809647132035;11068.833967490333;12493.375331233221;12812.423440944993;13344.599662010156;13443.126196797177;10757.515911784145;10923.344599324066;11044.447777611247;11147;11190.888091118984;10798.892011079788;10889.618863339745;11005.61480348216;11206.038943185073;11325.037371823488;10305.53625086858;11061.225714199929;11097.889021109686;11374.715632108933;11397;6488.286288508355;7803.7268695595385;7897.4076944230565;8219.178082191973;8257.21556452136;8601.698940537051;10532.210084243865;10854.457277135954;11030.338179709242;11194.160437967348 18:10:14 INFO - PROCESS | 1650 | |3;mootools.html;1428.4643258777958;1436.2603259321447;1440.1431148466672;1451.3468938977462;1458.504108603075;8068.354531637462;8362.03836558796;8504.48973061616;8513.191246831557;8521.659133634646;8080.878786818203;8250.092489826115;8305.252527272543;8305.54319512428;8356.623951922164;2232.598132336179;2268.727752669674;2273.193016479151;2273.465735552144;2280.1905323610044;2176.945576360588;2257.3679369776487;2269.069681430614;2271.216430331533;2272.068451934707;1938.4087853204767;2003.1086166655843;2007.498125468633;2187.267265466071;2251.9324420267417;1933.4393026022508;2242.3721358019698;2269.7503274639776;2269.761675024115;2273.8067264282554;2215.878126703031;2277.0664027748708;2284.1434462076722;2290.862548247094;2294.6328587425933;8399.874001889977;8476.576171191417;8482.872756908651;8488.74533763988;8523.57382130891;4176.561461046576;5215.269862219292;5224.346956630421;5226.477352264743;5227.137522308853;3312.337532493511;3368.5629679562703;3448.2069523685645;3450;3498.1254686328416;1180.1620849197081;1190.8928196462273;1204.2112416367293;1215.4773447417606;1247.0335489995236;10914.345139291589;10951.397673127974;10968.341899486057;11003.17476189281;11010;4166.91666166675;4371.75955322454;4429.712068715523;4449.57729015743;4490.932636010462;8663.133686631287;8664.523451210243;8669.479831210148;8670.436421632574;8682.609282582172;5049.2173713074535;5051.595872330205;5054.21659642756;5054.949450505448;5064.11378008847;4538.432695913011;4577.702449340783;4730;4732.408448943882;4737.597304229179;3576.6780989710533;3611.169431030825;3629.4737263096704;3658.725595580342;3679.981600092036;1136.579465597734;1142.7200335917753;1153.5155234801402;1153.7346410325672;1162.3200427749691;798.3852433626191;882.2412725056446;882.4661080046479;905.9909400906039;915.6814187569878;5266.868328291824;5286.762095705702;5592.804251851166;5603.719814009283;5743.655380677089;540.9594280428984;558.1348909190738;572.1503567202701;580.1095318685881;581.5492992930513;2807.4104438067825;3138.0115263691864;3595.6943659789213;3702.1114932416026;3720.739548231652;9617.326787124834;9890.752731181634;9997.550110245056;10023.298369114238;10080.69757907264;9979.750506237257;9981.650642227632;9992.600295988224;10013.449260290608;10038.498075096217;7211.92788072123;7599.582022988789;7632.160462349138;7751.069871615329;7770.53376797394;5079.822206222764;5433.755481003285;5466.671999680032;5532.197831314517;5638.2670252866865;8889;9067.863982040142;9233.445993240428;9245.07549245097;9365;9152.771180720536;9203.217726493323;9416.387934784218;9519.76200594963;9559.713208603753;6586.967065164644;6746.156730408699;6752.257251702407;6754;6760.391564759196;7699.114601820862;7798.220177982156;7835.49069310493;7851.097123830604;7903.960480197562;5509.256250406304;6958.617276049866;7018.894716579358;7068.787936361918;7225.710971561079;6851.657417129024;6946.097007389007;6956.408705259907;7001.299870013162;7016.158061032709;6212.099245609452;6291.245050593958;6355.237371515448;6357.205349331333;6362.713677884413;4803.759812009315;4903.901921961612;4958.380202474691;4983.875403114951;5113.488651134857;4902.509749025069;4923;4988.700677959334;5075.5509545898185;5075.720835354091 18:10:14 INFO - PROCESS | 1650 | |4;prototype.html;340.38390513171146;353.9593594831196;360.52050772472614;361.7738913179263;365.0181013074828;4610.861674149781;5228.8692782680455;5301.864545654391;5347.946520534799;5375.532781356023;4218.7257828241145;4634.768261586924;4879.364334194002;5219.686818790875;5242.68543887367;3988.6809055275653;4011.074684151609;4022.3363145081025;4054.3107671695884;4059.8173082211224;3374.8650053997935;3995.440638310639;4001;4017.698672599552;4040.444428894882;3718.9442158367647;3910.159315747114;3964.345882929313;4001.9599804002023;4025.2754504189234;3324.9501257481156;3371.51113088602;3661.359262129143;3788.7726736395766;4008.699347548916;3455.736492597353;3459.1697992481877;3459.3222287190692;3461.6365281645567;3461.9653803462147;4562.954370456286;5027.371578552681;5047.570956468705;5190;5250.7112108834;3644.321423753539;4209.726367786084;4448.488423831236;4556.03392302392;4591.864782875979;4555.06621142665;5123.38519377677;5249.923765628076;5597.285316621435;5615.859603509904;3480.6519348065244;3792.3742582474133;3801.7148713846573;3803.4865293185344;3808.980955095207;5373.194020896858;5586.469285417878;5786.739596718157;5948.434898684618;5970.194023806817;3559.359315323267;3860.3051450739135;3872.283627528916;3881.96118038816;3882.242962622318;3414.573178352706;3914.3149948758855;3916.373380259145;3924.7841368724994;3927.56796752357;3674.375356189454;4098.385242213632;4510.924974511696;4543.68194226401;4572.45130584332;4948.752562371867;5609.915851262234;5647.289301135478;5675;5693.516051135617;1163.8428812110426;1191.4221602522769;1194.5759255464359;1262.4508338872436;1270.2759427126634;657.8684263147389;775.8827288704248;808.6563210635456;859.2910848549847;866.6620018192909;5000.749962501933;5024.563086902849;5025.874353141127;5026.748662566857;5031.314509639239;4818.072770891601;5177.068127736969;5238.390216609359;5409.918851217235;5493.434371204187;4503.433043165364;4511.470507220696;4959.628027897922;5060.797568097309;5064.848054558369;2601.7008044075174;2845.758110560584;2861.728135827093;2875.6980517045827;2937.6768555458884;4937.530934561211;5404.7297635118875;5746.71869457257;5758.942410575924;5806.796762113306;4010.939835902464;4780.928286075712;5425.945740542623;5530.972345138249;5704.80033198836;4834.250691142878;5693.459121383462;5970;6069.969650151721;6096.451319381188;5764.377208370265;5764.510016648464;5823.94176058234;5834.453869724442;5872.911906321494;5070.594352451869;5217.452167522356;5659.943400565941;5677.829665110054;5720.141978703228;4833.10587541306;5713.143428285857;5993.580449368503;6026.939730602638;6058.091286307089;3607.603163652049;3881.3983832506005;3891.0077930127636;3894.5910679378258;3894.9805250973563;5102.494764044542;5797.318777554462;5814.418558144152;5853;5901.350851406256;4973.701577905337;5200.6619569727845;5657;5659.349174844946;5666.660000399989;5001.549860512571;5259.763310650953;5601.661823011867;5649.864325299498;5650.333151720334;5547.944520554743;5568.721563921868;5574.5716384424095;5583.812140894329;5587.346010644911;5004.3494345734825;5008.046861723858;5441.591880608891;5694;5717.514011309086;4254.255505286501;4673.696209746356;4957.810125569909;5583.302087239095;5592.468715472024 18:10:14 INFO - PROCESS | 1650 | |5;yui.html;705.4744215559409;739.9112106547212;765.043695380774;792.8374683189945;820.667629610008;13538.661533461656;14362.497312594061;14403.495877644298;14417.567472975801;14421.855781442182;13063.934680326609;13072.542461013867;13149.079564430469;13178.47286108557;13192.802107968364;844.497523973238;879.7580665317057;904.9592768325441;905.2576886952669;912.8950170730351;857.8241460500614;900.7117722328858;901.6979311930478;903.8056817784175;906.3610154840821;898.3846065445191;901.1259078693656;934.523393069536;936.3539157981005;938.995305023474;914.6752902719505;925.2736601767614;930.6044930904338;931.4039015030386;933.8599210118468;697.0763738047108;698.6751160710294;698.8113209433425;700.2612244082491;700.9404200642952;14461.421543138262;14809.185494797783;14951;15003.774943375858;15080.396784128621;13493.51674035707;13668.93165534176;13819.930900345535;13829.712789035331;13837.904212074161;10935.070519005894;11527.538898444052;11551.711207219887;11702.218025013355;12122.757544849143;3636.4545318202217;3657.7439579229463;3660.652238037382;3803.7717736935597;3819.312523745725;94527.68631996529;97815;98092.50953745257;98313;98516.50741746317;10319.690409287734;10443.060124588823;10461.895381046244;10526;10653.467326633792;21255;21547;21624.675629865564;21651.56696866054;21987.23044693428;7342.082239720035;7401.777946661608;7435.330820226098;7597.126330471956;7601.581912994728;3438.209211881281;4103.671706263492;4122.381642753611;4131.338985762323;4143.399207115011;3563.3051554947056;3612.8554857805393;3628.0385697790107;3663.7435379523176;3666.9816650916573;674.1270055278426;674.2785219814751;679.6771533521538;690.8790961581703;692.6467501574233;644.9516286278548;654.8199245207511;677.9389854912982;684.948628852838;717.3507975282273;9551.856722149174;9871.851922221173;9955;9992.400455972665;10188.235882308856;640.9839754006188;695.2352412346479;695.6521739130435;700.0828914122516;711.5517224148857;4611.76941152941;4745.786439610157;4750.572448479654;4764.904701905943;4771.71369717818;96894;97212.51393742986;97799.51100244596;98351;99284;55890.72054639701;58564.7071764647;74489.6275518619;78304.60847695838;78493.60753196197;56215;71584.28415715776;72863.90704139443;72991;74564.88152677714;10172.949135254275;10210.744731381477;10372.11836993864;10483.483963848774;10591;39473.60526394699;45699.771501142284;49330;49369;50993.490065100355;63887.68056159689;64369;64831.02753458608;65178.02232966414;65940.67029664821;12348.365175404622;12572.371381430708;12622.747545049231;12661.556845510362;13147.34263286851;14094.083884547472;14229.857701422854;14298.57104286873;15021.549353519413;15124.243787810785;12944.288064156563;12969.805452918024;13016.479340826261;13562.93218533901;13627.250501222132;11615.76768464643;13112.409941552462;13284.86715132875;13392.531261406193;13742.381592828548;12686.129208733204;12701.4284357206;12806.871931280568;12814.743705125658;12888.22670639727;9957.850632240377;10816.891831081588;10880.587850819004;11328.9433552835;11596.594119205787;8161.224683655043;9668.95165524196;10285.228607854291;10547.52536135891;10555.41945192991 18:10:14 INFO - PROCESS | 1650 | __end_tp_report 18:10:14 INFO - PROCESS | 1650 | __start_cc_report 18:10:14 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1930 18:10:14 INFO - PROCESS | 1650 | __end_cc_report 18:10:14 INFO - PROCESS | 1650 | __startTimestamp1486519814917__endTimestamp 18:10:14 INFO - PROCESS | 1650 | 18:10:14 INFO - PROCESS | 1650 | ------- Summary: start ------- 18:10:14 INFO - PROCESS | 1650 | Number of tests: 6 18:10:14 INFO - PROCESS | 1650 | 18:10:14 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5597.75 Median:5793.54 stddev:639.88 (11.0%) stddev-sans-first:615.72 18:10:14 INFO - PROCESS | 1650 | Values: 3290.7 3325.0 3332.1 3354.7 3380.5 5392.0 5468.1 5577.6 5594.8 5625.1 5513.1 5561.0 5570.6 5583.6 5629.1 4981.1 5050.4 5079.9 5184.9 5188.7 4306.0 5133.3 5156.2 5200.8 5214.2 4968.7 5011.3 5012.1 5014.0 5122.6 4098.3 5048.3 5048.9 5119.7 5128.7 4951.7 5012.9 5053.3 5059.5 5059.8 5359.4 5384.0 5530.2 5530.7 5540.2 6003.5 6080.0 6113.5 6125.5 6129.2 6146.8 6207.4 6224.3 6230.3 6254.5 5672.7 5675.9 5677.4 5687.4 5712.5 6054.5 6153.8 6158.8 6184.1 6214.8 5970.5 6049.3 6058.8 6107.2 6120.9 6166.1 6197.5 6205.8 6206.3 6223.3 5824.4 5869.0 6045.1 6117.1 6134.9 5973.6 6067.1 6113.8 6115.1 6164.6 4911.8 4928.1 4932.0 4932.8 4959.7 4376.9 4391.9 4403.4 4414.5 4442.0 5504.3 5512.0 5526.4 5563.3 5613.6 6139.4 6210.5 6221.6 6227.5 6239.0 4691.6 5644.0 5646.9 5651.6 5692.5 5172.8 5341.2 5351.5 5351.6 5389.0 6218.8 6239.2 6282.0 6283.5 6293.1 5976.3 6030.0 6081.8 6214.8 6239.1 6115.0 6129.8 6164.4 6173.3 6222.1 5819.5 5851.8 5855.5 5859.4 5893.1 4654.7 5872.4 5945.6 5949.6 6007.1 5793.5 5809.1 5899.5 5903.0 5950.3 5965.7 5974.8 5997.3 5998.1 6020.8 5784.3 5817.1 5836.5 5837.3 5845.2 5743.1 5793.7 5966.2 6007.5 6013.3 5695.0 5698.3 5744.7 5766.9 5826.4 18:10:14 INFO - PROCESS | 1650 | 18:10:14 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:65511.46 Median:37263.53 stddev:141286.25 (379.2%) stddev-sans-first:141598.18 18:10:14 INFO - PROCESS | 1650 | Values: 460.8 500.1 500.2 518.2 518.5 28037.0 29768.0 38342.4 40782.4 40913.2 41983.8 42185.8 42220.0 42290.4 42336.8 9083.5 9296.3 9301.4 9312.3 9326.4 7795.0 7988.5 10811.2 11037.6 11065.2 9289.7 11079.4 11083.6 11087.6 11120.8 7815.0 9514.8 10887.2 10962.3 10973.5 7819.6 9209.3 10612.3 10870.5 11112.4 39195.0 39230.4 39579.8 39598.0 39612.4 3712.0 3729.2 3746.0 3771.5 3803.9 29262.0 29687.7 29979.6 30359.7 30575.2 2524.8 2915.9 2976.3 3042.2 3163.8 441770.0 444073.0 445098.0 445884.0 446066.6 55475.2 55963.0 55982.0 56004.7 56098.9 48525.0 49471.0 49608.8 57058.3 57869.4 14150.8 14411.3 14450.0 14466.9 14480.0 11174.0 11237.7 11251.0 11255.7 11260.1 13901.7 17331.7 17354.4 17391.4 17396.9 7533.7 7596.4 7601.6 7628.0 7706.6 837.2 841.3 868.6 868.8 871.8 25060.4 27012.9 28010.2 28787.1 29402.0 1010.5 1012.9 1036.3 1036.7 1151.0 15074.2 15085.0 15090.0 15122.4 15466.8 606575.0 613621.0 613926.0 614118.0 614634.0 58425.7 59062.5 59141.4 59171.0 59247.5 1345.8 1374.4 1388.7 1393.2 1393.3 26882.5 28904.4 29148.3 29464.9 29793.1 49894.3 50165.5 50224.7 50236.7 50326.0 513440.0 517090.0 517683.0 518090.1 518868.0 22795.4 23367.3 25808.5 25964.9 26520.0 45357.3 45533.1 46004.8 46401.8 46813.5 34310.3 37262.3 37949.6 38140.2 38827.0 35572.0 35807.1 36324.8 36537.0 37994.2 36416.1 36455.6 36851.4 37230.6 37264.8 31354.8 31452.7 32940.7 33065.3 33169.0 20996.7 23926.6 24371.4 24876.5 24919.0 18:10:14 INFO - PROCESS | 1650 | 18:10:14 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:37415.81 Median:20505.80 stddev:114319.80 (557.5%) stddev-sans-first:114606.68 18:10:14 INFO - PROCESS | 1650 | Values: 384.7 385.2 390.0 398.8 412.9 13932.7 17626.8 19163.7 20476.7 20534.9 14746.6 17158.7 20090.2 20101.4 20536.0 5809.4 5934.9 5953.7 6001.3 6021.8 5696.7 5892.1 5901.4 5978.9 6001.0 5919.1 5941.3 5949.2 5951.3 5951.5 5626.1 5734.2 5809.6 5917.5 5947.1 5058.8 5088.1 5159.5 5160.6 5176.9 24726.5 25188.0 25275.0 25300.0 25618.4 2604.4 2735.7 2749.1 2764.8 2771.7 14832.9 15358.0 15429.8 15576.1 16302.3 1771.3 1809.3 1827.1 1837.9 1846.7 110021.0 112884.7 120845.4 125688.4 125756.0 10894.9 11126.6 11200.4 11438.8 11492.7 12612.6 14308.0 14325.6 14328.9 14357.6 7174.2 7517.2 7535.9 8092.3 8520.0 7914.5 8192.0 8289.5 8295.4 8451.4 6878.1 7022.6 7077.3 7089.1 7109.5 1644.3 1873.4 1909.0 1909.4 1909.9 1004.8 1028.9 1042.4 1048.6 1052.2 22037.0 22459.3 22534.8 22550.9 22572.2 1053.3 1057.3 1069.2 1074.7 1098.0 10825.3 11700.8 11703.2 11752.3 11771.8 674305.0 682847.0 687711.0 702855.5 704918.0 105295.5 122497.0 122767.0 125135.0 126732.0 998.8 1019.1 1032.7 1033.0 1042.6 18682.1 18892.3 19168.4 19210.9 19235.6 53937.7 54035.7 54278.0 54372.2 54937.8 72973.0 73076.0 74801.0 75154.0 77182.0 5069.9 5137.0 7041.0 7547.4 7989.3 11068.8 12493.4 12812.4 13344.6 13443.1 10757.5 10923.3 11044.4 11147.0 11190.9 10798.9 10889.6 11005.6 11206.0 11325.0 10305.5 11061.2 11097.9 11374.7 11397.0 6488.3 7803.7 7897.4 8219.2 8257.2 8601.7 10532.2 10854.5 11030.3 11194.2 18:10:14 INFO - PROCESS | 1650 | 18:10:14 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5336.46 Median:5164.38 stddev:2977.88 (57.7%) stddev-sans-first:2971.76 18:10:14 INFO - PROCESS | 1650 | Values: 1428.5 1436.3 1440.1 1451.3 1458.5 8068.4 8362.0 8504.5 8513.2 8521.7 8080.9 8250.1 8305.3 8305.5 8356.6 2232.6 2268.7 2273.2 2273.5 2280.2 2176.9 2257.4 2269.1 2271.2 2272.1 1938.4 2003.1 2007.5 2187.3 2251.9 1933.4 2242.4 2269.8 2269.8 2273.8 2215.9 2277.1 2284.1 2290.9 2294.6 8399.9 8476.6 8482.9 8488.7 8523.6 4176.6 5215.3 5224.3 5226.5 5227.1 3312.3 3368.6 3448.2 3450.0 3498.1 1180.2 1190.9 1204.2 1215.5 1247.0 10914.3 10951.4 10968.3 11003.2 11010.0 4166.9 4371.8 4429.7 4449.6 4490.9 8663.1 8664.5 8669.5 8670.4 8682.6 5049.2 5051.6 5054.2 5054.9 5064.1 4538.4 4577.7 4730.0 4732.4 4737.6 3576.7 3611.2 3629.5 3658.7 3680.0 1136.6 1142.7 1153.5 1153.7 1162.3 798.4 882.2 882.5 906.0 915.7 5266.9 5286.8 5592.8 5603.7 5743.7 541.0 558.1 572.2 580.1 581.5 2807.4 3138.0 3595.7 3702.1 3720.7 9617.3 9890.8 9997.6 10023.3 10080.7 9979.8 9981.7 9992.6 10013.4 10038.5 7211.9 7599.6 7632.2 7751.1 7770.5 5079.8 5433.8 5466.7 5532.2 5638.3 8889.0 9067.9 9233.4 9245.1 9365.0 9152.8 9203.2 9416.4 9519.8 9559.7 6587.0 6746.2 6752.3 6754.0 6760.4 7699.1 7798.2 7835.5 7851.1 7904.0 5509.3 6958.6 7018.9 7068.8 7225.7 6851.7 6946.1 6956.4 7001.3 7016.2 6212.1 6291.2 6355.2 6357.2 6362.7 4803.8 4903.9 4958.4 4983.9 5113.5 4902.5 4923.0 4988.7 5075.6 5075.7 18:10:14 INFO - PROCESS | 1650 | 18:10:14 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4453.91 Median:5001.15 stddev:1391.30 (27.8%) stddev-sans-first:1360.51 18:10:14 INFO - PROCESS | 1650 | Values: 340.4 354.0 360.5 361.8 365.0 4610.9 5228.9 5301.9 5347.9 5375.5 4218.7 4634.8 4879.4 5219.7 5242.7 3988.7 4011.1 4022.3 4054.3 4059.8 3374.9 3995.4 4001.0 4017.7 4040.4 3718.9 3910.2 3964.3 4002.0 4025.3 3325.0 3371.5 3661.4 3788.8 4008.7 3455.7 3459.2 3459.3 3461.6 3462.0 4563.0 5027.4 5047.6 5190.0 5250.7 3644.3 4209.7 4448.5 4556.0 4591.9 4555.1 5123.4 5249.9 5597.3 5615.9 3480.7 3792.4 3801.7 3803.5 3809.0 5373.2 5586.5 5786.7 5948.4 5970.2 3559.4 3860.3 3872.3 3882.0 3882.2 3414.6 3914.3 3916.4 3924.8 3927.6 3674.4 4098.4 4510.9 4543.7 4572.5 4948.8 5609.9 5647.3 5675.0 5693.5 1163.8 1191.4 1194.6 1262.5 1270.3 657.9 775.9 808.7 859.3 866.7 5000.7 5024.6 5025.9 5026.7 5031.3 4818.1 5177.1 5238.4 5409.9 5493.4 4503.4 4511.5 4959.6 5060.8 5064.8 2601.7 2845.8 2861.7 2875.7 2937.7 4937.5 5404.7 5746.7 5758.9 5806.8 4010.9 4780.9 5425.9 5531.0 5704.8 4834.3 5693.5 5970.0 6070.0 6096.5 5764.4 5764.5 5823.9 5834.5 5872.9 5070.6 5217.5 5659.9 5677.8 5720.1 4833.1 5713.1 5993.6 6026.9 6058.1 3607.6 3881.4 3891.0 3894.6 3895.0 5102.5 5797.3 5814.4 5853.0 5901.4 4973.7 5200.7 5657.0 5659.3 5666.7 5001.5 5259.8 5601.7 5649.9 5650.3 5547.9 5568.7 5574.6 5583.8 5587.3 5004.3 5008.0 5441.6 5694.0 5717.5 4254.3 4673.7 4957.8 5583.3 5592.5 18:10:14 INFO - PROCESS | 1650 | 18:10:14 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:18987.63 Median:4678.78 stddev:26550.90 (567.5%) stddev-sans-first:26589.89 18:10:14 INFO - PROCESS | 1650 | Values: 705.5 739.9 765.0 792.8 820.7 13538.7 14362.5 14403.5 14417.6 14421.9 13063.9 13072.5 13149.1 13178.5 13192.8 844.5 879.8 905.0 905.3 912.9 857.8 900.7 901.7 903.8 906.4 898.4 901.1 934.5 936.4 939.0 914.7 925.3 930.6 931.4 933.9 697.1 698.7 698.8 700.3 700.9 14461.4 14809.2 14951.0 15003.8 15080.4 13493.5 13668.9 13819.9 13829.7 13837.9 10935.1 11527.5 11551.7 11702.2 12122.8 3636.5 3657.7 3660.7 3803.8 3819.3 94527.7 97815.0 98092.5 98313.0 98516.5 10319.7 10443.1 10461.9 10526.0 10653.5 21255.0 21547.0 21624.7 21651.6 21987.2 7342.1 7401.8 7435.3 7597.1 7601.6 3438.2 4103.7 4122.4 4131.3 4143.4 3563.3 3612.9 3628.0 3663.7 3667.0 674.1 674.3 679.7 690.9 692.6 645.0 654.8 677.9 684.9 717.4 9551.9 9871.9 9955.0 9992.4 10188.2 641.0 695.2 695.7 700.1 711.6 4611.8 4745.8 4750.6 4764.9 4771.7 96894.0 97212.5 97799.5 98351.0 99284.0 55890.7 58564.7 74489.6 78304.6 78493.6 56215.0 71584.3 72863.9 72991.0 74564.9 10172.9 10210.7 10372.1 10483.5 10591.0 39473.6 45699.8 49330.0 49369.0 50993.5 63887.7 64369.0 64831.0 65178.0 65940.7 12348.4 12572.4 12622.7 12661.6 13147.3 14094.1 14229.9 14298.6 15021.5 15124.2 12944.3 12969.8 13016.5 13562.9 13627.3 11615.8 13112.4 13284.9 13392.5 13742.4 12686.1 12701.4 12806.9 12814.7 12888.2 9957.9 10816.9 10880.6 11328.9 11596.6 8161.2 9669.0 10285.2 10547.5 10555.4 18:10:14 INFO - PROCESS | 1650 | -------- Summary: end -------- 18:10:14 INFO - PROCESS | 1650 | 18:10:15 INFO - TEST-INFO | 1650: exit 0 18:10:15 INFO - TEST-OK | dromaeo_css | took 1118252ms 18:10:15 INFO - TEST-START | kraken 18:10:15 INFO - operating with platform_type : mac_ 18:10:15 INFO - Initialising browser for kraken test... 18:10:15 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmp5sHuvc/profile http://localhost:49247/getInfo.html) 18:10:17 INFO - PROCESS | 1656 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 18:10:17 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 18:10:17 INFO - PROCESS | 1656 | colorDepth:24 18:10:17 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 18:10:17 INFO - PROCESS | 1656 | __metrics 18:10:17 INFO - PROCESS | 1656 | 1486519817968 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 18:10:18 INFO - PROCESS | 1656 | 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" 18:10:18 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:10:18 INFO - TEST-INFO | 1656: exit 0 18:10:18 INFO - Browser initialized. 18:10:18 INFO - Running cycle 1/1 for kraken test... 18:10:18 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmp5sHuvc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:10:30 INFO - PROCESS | 1657 | RSS: Main: 236810240 18:10:30 INFO - PROCESS | 1657 | 18:10:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 18:10:34 INFO - PROCESS | 1657 | RSS: Main: 288759808 18:10:34 INFO - PROCESS | 1657 | 18:10:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 18:10:37 INFO - PROCESS | 1657 | RSS: Main: 290480128 18:10:37 INFO - PROCESS | 1657 | 18:10:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 18:10:41 INFO - PROCESS | 1657 | RSS: Main: 379478016 18:10:41 INFO - PROCESS | 1657 | 18:10:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 18:10:44 INFO - PROCESS | 1657 | RSS: Main: 285749248 18:10:44 INFO - PROCESS | 1657 | 18:10:46 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:10:46 INFO - PROCESS | 1657 | RSS: Main: 287727616 18:10:46 INFO - PROCESS | 1657 | 18:10:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 18:10:50 INFO - PROCESS | 1657 | RSS: Main: 329687040 18:10:50 INFO - PROCESS | 1657 | 18:10:53 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 18:10:53 INFO - PROCESS | 1657 | RSS: Main: 332627968 18:10:53 INFO - PROCESS | 1657 | 18:10:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 18:10:57 INFO - PROCESS | 1657 | RSS: Main: 330059776 18:10:57 INFO - PROCESS | 1657 | 18:10:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:10:59 INFO - PROCESS | 1657 | RSS: Main: 305451008 18:10:59 INFO - PROCESS | 1657 | 18:11:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:11:02 INFO - PROCESS | 1657 | RSS: Main: 310689792 18:11:02 INFO - PROCESS | 1657 | 18:11:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:11:05 INFO - PROCESS | 1657 | RSS: Main: 310509568 18:11:05 INFO - PROCESS | 1657 | 18:11:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:11:09 INFO - PROCESS | 1657 | RSS: Main: 323760128 18:11:09 INFO - PROCESS | 1657 | 18:11:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:11:12 INFO - PROCESS | 1657 | RSS: Main: 322793472 18:11:12 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 18:11:14 INFO - PROCESS | 1657 | RSS: Main: 367591424 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | __start_tp_report 18:11:14 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 18:11:14 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 18:11:14 INFO - PROCESS | 1657 | |i|pagename|runs| 18:11:14 INFO - PROCESS | 1657 | |0;ai-astar;114;115;113;114;114;115;114;113;113;114 18:11:14 INFO - PROCESS | 1657 | |1;audio-beat-detection;141;112;111;148;113;109;116;107;131;122 18:11:14 INFO - PROCESS | 1657 | |2;audio-dft;184;184;184;184;183;185;181;181;185;182 18:11:14 INFO - PROCESS | 1657 | |3;audio-fft;80;72;59;88;65;77;85;83;93;59 18:11:14 INFO - PROCESS | 1657 | |4;audio-oscillator;90;70;88;71;69;70;84;70;70;84 18:11:14 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;87;89;86;88;85;90;87;88;80 18:11:14 INFO - PROCESS | 1657 | |6;imaging-darkroom;96;95;96;95;95;95;95;95;96;95 18:11:14 INFO - PROCESS | 1657 | |7;imaging-desaturate;88;86;86;84;85;85;87;84;86;99 18:11:14 INFO - PROCESS | 1657 | |8;json-parse-financial;62;57;58;81;89;88;81;58;57;58 18:11:14 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;65;70;73;75;75;69;68;70;70;70 18:11:14 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;90;84;87;87;89;88;88;90;98;87 18:11:14 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;143;143;145;144;152;145;140;143;230;173 18:11:14 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;173;174;179;226;193;172;176;168;171;174 18:11:14 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;66;67;68;64;67;65;79;79;80 18:11:14 INFO - PROCESS | 1657 | __end_tp_report 18:11:14 INFO - PROCESS | 1657 | __start_cc_report 18:11:14 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2117 18:11:14 INFO - PROCESS | 1657 | __end_cc_report 18:11:14 INFO - PROCESS | 1657 | __startTimestamp1486519874716__endTimestamp 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | ------- Summary: start ------- 18:11:14 INFO - PROCESS | 1657 | Number of tests: 14 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 18:11:14 INFO - PROCESS | 1657 | Values: 114.0 115.0 113.0 114.0 114.0 115.0 114.0 113.0 113.0 114.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:121.00 Median:119.00 stddev:14.30 (12.0%) stddev-sans-first:13.21 18:11:14 INFO - PROCESS | 1657 | Values: 141.0 112.0 111.0 148.0 113.0 109.0 116.0 107.0 131.0 122.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:1.49 (0.8%) stddev-sans-first:1.56 18:11:14 INFO - PROCESS | 1657 | Values: 184.0 184.0 184.0 184.0 183.0 185.0 181.0 181.0 185.0 182.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:76.10 Median:81.50 stddev:11.99 (14.7%) stddev-sans-first:12.64 18:11:14 INFO - PROCESS | 1657 | Values: 80.0 72.0 59.0 88.0 65.0 77.0 85.0 83.0 93.0 59.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:77.50 stddev:8.71 (11.2%) stddev-sans-first:7.77 18:11:14 INFO - PROCESS | 1657 | Values: 90.0 70.0 88.0 71.0 69.0 70.0 84.0 70.0 70.0 84.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:88.00 stddev:2.85 (3.2%) stddev-sans-first:2.92 18:11:14 INFO - PROCESS | 1657 | Values: 89.0 87.0 89.0 86.0 88.0 85.0 90.0 87.0 88.0 80.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 18:11:14 INFO - PROCESS | 1657 | Values: 96.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:87.00 Median:86.00 stddev:4.40 (5.1%) stddev-sans-first:4.65 18:11:14 INFO - PROCESS | 1657 | Values: 88.0 86.0 86.0 84.0 85.0 85.0 87.0 84.0 86.0 99.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:68.90 Median:71.50 stddev:13.94 (19.5%) stddev-sans-first:14.56 18:11:14 INFO - PROCESS | 1657 | Values: 62.0 57.0 58.0 81.0 89.0 88.0 81.0 58.0 57.0 58.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.00 stddev:3.10 (4.4%) stddev-sans-first:2.57 18:11:14 INFO - PROCESS | 1657 | Values: 65.0 70.0 73.0 75.0 75.0 69.0 68.0 70.0 70.0 70.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:88.50 stddev:3.68 (4.2%) stddev-sans-first:3.87 18:11:14 INFO - PROCESS | 1657 | Values: 90.0 84.0 87.0 87.0 89.0 88.0 88.0 90.0 98.0 87.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:155.80 Median:145.00 stddev:27.75 (19.1%) stddev-sans-first:29.04 18:11:14 INFO - PROCESS | 1657 | Values: 143.0 143.0 145.0 144.0 152.0 145.0 140.0 143.0 230.0 173.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.60 Median:175.00 stddev:17.35 (9.9%) stddev-sans-first:18.18 18:11:14 INFO - PROCESS | 1657 | Values: 173.0 174.0 179.0 226.0 193.0 172.0 176.0 168.0 171.0 174.0 18:11:14 INFO - PROCESS | 1657 | 18:11:14 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:67.50 stddev:6.41 (9.5%) stddev-sans-first:6.69 18:11:14 INFO - PROCESS | 1657 | Values: 67.0 66.0 67.0 68.0 64.0 67.0 65.0 79.0 79.0 80.0 18:11:14 INFO - PROCESS | 1657 | -------- Summary: end -------- 18:11:14 INFO - PROCESS | 1657 | 18:11:15 INFO - TEST-INFO | 1657: exit 0 18:11:15 INFO - TEST-OK | kraken | took 60027ms 18:11:15 INFO - SUITE-END | took 1178s 18:11:15 INFO - Completed test suite (00:19:38) 18:11:15 INFO - javascript benchmark 18:11:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7126.173575302238, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3290.720288775454, 3325.0357396355053, 3332.1003329101145, 3354.7148492378146, 3380.4929260610907, 5392.0, 5468.125099984003, 5577.63745356552, 5594.832155035345, 5625.1281051437045, 5513.09034009388, 5561.026820306451, 5570.610057295991, 5583.553315734742, 5629.071203251468, 4981.1283025470575, 5050.419201791786, 5079.873003174923, 5184.896302073957, 5188.688678679291, 4306.0, 5133.30700355451, 5156.175011998081, 5200.76596553156, 5214.217867319894, 4968.652194346397, 5011.3485246918035, 5012.122878496249, 5014.047331007097, 5122.564582010515, 4098.303288440972, 5048.3184770056305, 5048.9495105048845, 5119.692818430906, 5128.743562821881, 4951.678140920829, 5012.949870501285, 5053.267276244924, 5059.494050594948, 5059.822906198266, 5359.437259087778, 5383.973080134613, 5530.198121272393, 5530.668159910419, 5540.168974653794, 6003.549733769984, 6079.969600152015, 6113.541484388644, 6125.540694081788, 6129.233845769279, 6146.78486252979, 6207.441330280251, 6224.284207316171, 6230.283517395512, 6254.530910181755, 5672.659640421588, 5675.914861277084, 5677.37548869624, 5687.40282270364, 5712.542996560265, 6054.54590905675, 6153.753849845956, 6158.753649854045, 6184.134221209034, 6214.782482613087, 5970.49250813677, 6049.334573196945, 6058.848528786815, 6107.236595425572, 6120.908186377208, 6166.075088736726, 6197.504199664016, 6205.844853878689, 6206.286277078194, 6223.253209614875, 5824.446677565625, 5868.970655146697, 6045.052468553678, 6117.105996655267, 6134.895838532271, 5973.641581505037, 6067.059605761114, 6113.810786210604, 6115.082737589397, 6164.568480206342, 4911.7544122793715, 4928.112939670822, 4931.950680493149, 4932.827351042696, 4959.702417854868, 4376.868693939187, 4391.8682439526865, 4403.4495688039, 4414.492333381638, 4442.022754993896, 5504.256925315111, 5512.0, 5526.4197259288, 5563.332400112013, 5613.550915926716, 6139.400144844221, 6210.534209934273, 6221.595596286227, 6227.501799856001, 6238.968805155945, 4691.648126390534, 5643.984082865041, 5646.858828529237, 5651.604387692904, 5692.459216374438, 5172.844814655567, 5341.172118321666, 5351.545118664957, 5351.57187425005, 5389.003034438641, 6218.751249950042, 6239.220097487814, 6281.994880819138, 6283.49732021428, 6293.118963345227, 5976.252968378953, 6030.03519436888, 6081.817545473643, 6214.782482613087, 6239.126522286974, 6115.0273862886115, 6129.785457508966, 6164.352742961924, 6173.32093469709, 6222.128901953821, 5819.505342045804, 5851.795187168429, 5855.502282306052, 5859.355470898283, 5893.116032594973, 4654.720716757005, 5872.354041055395, 5945.64326140433, 5949.613275137102, 6007.128966299774, 5793.536517078539, 5809.099589563624, 5899.528037757055, 5902.970485147547, 5950.256217972754, 5965.73154208053, 5974.790882319098, 5997.250343707036, 5998.070299103646, 6020.819375418744, 5784.305883293863, 5817.127430885232, 5836.533077353886, 5837.270341207349, 5845.152452894392, 5743.052396354722, 5793.681347525927, 5966.19456373384, 6007.519398448027, 6013.308469525886, 5694.971525142513, 5698.2592263005545, 5744.655320680772, 5766.855828604318, 5826.388229235869], "value": 5557.756198705016, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.8387064527416, 500.0798530703505, 500.1572350589255, 518.2407772613122, 518.4815184815185, 28037.0, 29767.958121465752, 38342.424863627035, 40782.388264176065, 40913.18173636525, 41983.79008104963, 42185.789071054605, 42220.03369444038, 42290.365644515354, 42336.78831605837, 9083.500407477586, 9296.349255552113, 9301.395409298373, 9312.301577381724, 9326.393784403992, 7795.0, 7988.520688758664, 10811.243212975094, 11037.558497660084, 11065.225434219608, 9289.674861379854, 11079.446027698623, 11083.612073577426, 11087.55649774008, 11120.833187502194, 7814.960925195338, 9514.762130946712, 10887.183461240398, 10962.287451315638, 10973.50619222137, 7819.569923654197, 9209.309301802392, 10612.257142000062, 10870.510827012804, 11112.388818614972, 39195.0, 39230.41154382686, 39579.8021009896, 39598.0, 39612.40581391282, 3712.0, 3729.2355067211156, 3746.0, 3771.4719939208535, 3803.8858834235016, 29261.97583084582, 29687.703122968927, 29979.550306745416, 30359.696403036127, 30575.235619109477, 2524.825955930501, 2915.9125226243245, 2976.255936015996, 3042.162517885546, 3163.841807909618, 441770.0, 444073.0, 445098.0, 445884.0, 446066.55068290466, 55475.167872481135, 55963.0, 55982.0, 56004.71997640067, 56098.87802244014, 48525.0, 49471.0, 49608.75195624071, 57058.29712988777, 57869.4213057864, 14150.787738183935, 14411.279436028157, 14450.0, 14466.927665361605, 14479.972803807475, 11174.0, 11237.662870113909, 11251.0, 11255.718607034889, 11260.099192064616, 13901.721965560631, 17331.740023899652, 17354.392596259324, 17391.391301304393, 17396.86856365841, 7533.698652053857, 7596.430267729942, 7601.581912994839, 7627.961860190774, 7706.614669266514, 837.1837458478057, 841.2638940926689, 868.6178081644057, 868.7871471489399, 871.7708031675434, 25060.373490662878, 27012.864935675196, 28010.159695209175, 28787.136385908456, 29402.0, 1010.525053224983, 1012.9262981239909, 1036.2694300518267, 1036.683811437519, 1150.9712257193469, 15074.246287685572, 15085.0, 15090.019148755297, 15122.443200167012, 15466.767998480032, 606575.0, 613621.0, 613926.0, 614118.0, 614634.0, 58425.70787146037, 59062.523436914424, 59141.408585914454, 59171.0, 59247.518812030045, 1345.7510360583128, 1374.3609221711952, 1388.7430825297151, 1393.1780249652754, 1393.3381643719151, 26882.462350753267, 28904.42191156123, 29148.27129321784, 29464.852675736485, 29793.10620681383, 49894.251586226965, 50165.49834501754, 50224.748876255384, 50236.748816255684, 50325.99348013093, 513440.0, 517090.0, 517683.0, 518090.0954952313, 518868.0, 22795.430114247276, 23367.298981030595, 25808.48383032291, 25964.870175649, 26520.0, 45357.31964020476, 45533.08933821239, 46004.7699761499, 46401.83995400142, 46813.53186468092, 34310.31379372448, 37262.25475490529, 37949.620503794606, 38140.23719525539, 38827.0, 35572.0, 35807.104822379646, 36324.818375907955, 36537.0, 37994.24011519699, 36416.08959776027, 36455.635443645224, 36851.447228292134, 37230.627693722716, 37264.813675931444, 31354.843225783727, 31452.68547314589, 32940.67059329472, 33065.338693226484, 33169.0, 20996.68504972396, 23926.641100383855, 24371.39071523226, 24876.502469950137, 24919.0], "value": 19197.638584224056, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.6614978818639, 385.1814893351628, 389.95111274652703, 398.82252397683027, 412.9153523527675, 13932.721345573082, 17626.823731762677, 19163.712544311828, 20476.69284960727, 20534.89732551337, 14746.557603271893, 17158.742618860728, 20090.1963921443, 20101.396958091245, 20536.0, 5809.3609702932645, 5934.851628709273, 5953.672548009858, 6001.339852616209, 6021.759129634809, 5696.715164241771, 5892.057270836667, 5901.3803550627, 5978.8804223915495, 6001.039833626621, 5919.082542205965, 5941.257342832146, 5949.167116603679, 5951.315598706161, 5951.5238780897425, 5626.071698169838, 5734.225879506255, 5809.622374545641, 5917.52659787216, 5947.137665038588, 5058.772355244023, 5088.135017047074, 5159.509846564608, 5160.612954028463, 5176.948230517684, 24726.5054698905, 25188.0, 25275.0, 25300.0, 25618.359541011436, 2604.361931326818, 2735.712750161244, 2749.092799376201, 2764.7788176945796, 2771.6812566554904, 14832.925835370754, 15358.001729887521, 15429.768553471707, 15576.065436073872, 16302.347906083862, 1771.3446024970715, 1809.2582041362978, 1827.1321122466722, 1837.9448616541524, 1846.7137593673003, 110021.0, 112884.74230515344, 120845.39577302057, 125688.37155814162, 125756.0, 10894.933855757112, 11126.554937802397, 11200.38397888124, 11438.828417573743, 11492.655220343404, 12612.558560450523, 14308.0, 14325.64185895361, 14328.856711432752, 14357.6410589734, 7174.210836807947, 7517.248275172548, 7535.924640753632, 8092.312153467021, 8519.957400213083, 7914.485558438798, 8191.95904020488, 8289.544075075928, 8295.377846661524, 8451.36614753896, 6878.140232470942, 7022.578645281299, 7077.292270772881, 7089.0784198053925, 7109.537880037781, 1644.3011720018944, 1873.4473330367302, 1908.9809101909082, 1909.3794516782102, 1909.8567607429497, 1004.809300978343, 1028.9022542858565, 1042.4058286776617, 1048.5596049659162, 1052.231870734353, 22037.0, 22459.32622021342, 22534.77465225327, 22550.887245563667, 22572.20997265088, 1053.3311347294523, 1057.260805974168, 1069.2248120112856, 1074.7474343529263, 1097.9670609881716, 10825.296355736851, 11700.824487632692, 11703.180777345504, 11752.294862308288, 11771.823422648667, 674305.0, 682847.0, 687711.0, 702855.4857225681, 704918.0, 105295.4735226319, 122497.0, 122767.0, 125135.0, 126732.0, 998.7715110414231, 1019.1133713669155, 1032.70051685012, 1032.9535170917327, 1042.5673345561659, 18682.06589670484, 18892.33876814305, 19168.424947251606, 19210.903945480743, 19235.615287694447, 53937.73031134819, 54035.729821350644, 54278.0, 54372.18441723298, 54937.78091081341, 72973.0, 73076.0, 74801.0, 75154.0, 77182.0, 5069.898602028013, 5137.0, 7040.964795175992, 7547.433942454228, 7989.2809647132035, 11068.833967490333, 12493.375331233221, 12812.423440944993, 13344.599662010156, 13443.126196797177, 10757.515911784145, 10923.344599324066, 11044.447777611247, 11147.0, 11190.888091118984, 10798.892011079788, 10889.618863339745, 11005.61480348216, 11206.038943185073, 11325.037371823488, 10305.53625086858, 11061.225714199929, 11097.889021109686, 11374.715632108933, 11397.0, 6488.286288508355, 7803.7268695595385, 7897.4076944230565, 8219.178082191973, 8257.21556452136, 8601.698940537051, 10532.210084243865, 10854.457277135954, 11030.338179709242, 11194.160437967348], "value": 9766.900538297821, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.4643258777958, 1436.2603259321447, 1440.1431148466672, 1451.3468938977462, 1458.504108603075, 8068.354531637462, 8362.03836558796, 8504.48973061616, 8513.191246831557, 8521.659133634646, 8080.878786818203, 8250.092489826115, 8305.252527272543, 8305.54319512428, 8356.623951922164, 2232.598132336179, 2268.727752669674, 2273.193016479151, 2273.465735552144, 2280.1905323610044, 2176.945576360588, 2257.3679369776487, 2269.069681430614, 2271.216430331533, 2272.068451934707, 1938.4087853204767, 2003.1086166655843, 2007.498125468633, 2187.267265466071, 2251.9324420267417, 1933.4393026022508, 2242.3721358019698, 2269.7503274639776, 2269.761675024115, 2273.8067264282554, 2215.878126703031, 2277.0664027748708, 2284.1434462076722, 2290.862548247094, 2294.6328587425933, 8399.874001889977, 8476.576171191417, 8482.872756908651, 8488.74533763988, 8523.57382130891, 4176.561461046576, 5215.269862219292, 5224.346956630421, 5226.477352264743, 5227.137522308853, 3312.337532493511, 3368.5629679562703, 3448.2069523685645, 3450.0, 3498.1254686328416, 1180.1620849197081, 1190.8928196462273, 1204.2112416367293, 1215.4773447417606, 1247.0335489995236, 10914.345139291589, 10951.397673127974, 10968.341899486057, 11003.17476189281, 11010.0, 4166.91666166675, 4371.75955322454, 4429.712068715523, 4449.57729015743, 4490.932636010462, 8663.133686631287, 8664.523451210243, 8669.479831210148, 8670.436421632574, 8682.609282582172, 5049.2173713074535, 5051.595872330205, 5054.21659642756, 5054.949450505448, 5064.11378008847, 4538.432695913011, 4577.702449340783, 4730.0, 4732.408448943882, 4737.597304229179, 3576.6780989710533, 3611.169431030825, 3629.4737263096704, 3658.725595580342, 3679.981600092036, 1136.579465597734, 1142.7200335917753, 1153.5155234801402, 1153.7346410325672, 1162.3200427749691, 798.3852433626191, 882.2412725056446, 882.4661080046479, 905.9909400906039, 915.6814187569878, 5266.868328291824, 5286.762095705702, 5592.804251851166, 5603.719814009283, 5743.655380677089, 540.9594280428984, 558.1348909190738, 572.1503567202701, 580.1095318685881, 581.5492992930513, 2807.4104438067825, 3138.0115263691864, 3595.6943659789213, 3702.1114932416026, 3720.739548231652, 9617.326787124834, 9890.752731181634, 9997.550110245056, 10023.298369114238, 10080.69757907264, 9979.750506237257, 9981.650642227632, 9992.600295988224, 10013.449260290608, 10038.498075096217, 7211.92788072123, 7599.582022988789, 7632.160462349138, 7751.069871615329, 7770.53376797394, 5079.822206222764, 5433.755481003285, 5466.671999680032, 5532.197831314517, 5638.2670252866865, 8889.0, 9067.863982040142, 9233.445993240428, 9245.07549245097, 9365.0, 9152.771180720536, 9203.217726493323, 9416.387934784218, 9519.76200594963, 9559.713208603753, 6586.967065164644, 6746.156730408699, 6752.257251702407, 6754.0, 6760.391564759196, 7699.114601820862, 7798.220177982156, 7835.49069310493, 7851.097123830604, 7903.960480197562, 5509.256250406304, 6958.617276049866, 7018.894716579358, 7068.787936361918, 7225.710971561079, 6851.657417129024, 6946.097007389007, 6956.408705259907, 7001.299870013162, 7016.158061032709, 6212.099245609452, 6291.245050593958, 6355.237371515448, 6357.205349331333, 6362.713677884413, 4803.759812009315, 4903.901921961612, 4958.380202474691, 4983.875403114951, 5113.488651134857, 4902.509749025069, 4923.0, 4988.700677959334, 5075.5509545898185, 5075.720835354091], "value": 4265.83823507753, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.38390513171146, 353.9593594831196, 360.52050772472614, 361.7738913179263, 365.0181013074828, 4610.861674149781, 5228.8692782680455, 5301.864545654391, 5347.946520534799, 5375.532781356023, 4218.7257828241145, 4634.768261586924, 4879.364334194002, 5219.686818790875, 5242.68543887367, 3988.6809055275653, 4011.074684151609, 4022.3363145081025, 4054.3107671695884, 4059.8173082211224, 3374.8650053997935, 3995.440638310639, 4001.0, 4017.698672599552, 4040.444428894882, 3718.9442158367647, 3910.159315747114, 3964.345882929313, 4001.9599804002023, 4025.2754504189234, 3324.9501257481156, 3371.51113088602, 3661.359262129143, 3788.7726736395766, 4008.699347548916, 3455.736492597353, 3459.1697992481877, 3459.3222287190692, 3461.6365281645567, 3461.9653803462147, 4562.954370456286, 5027.371578552681, 5047.570956468705, 5190.0, 5250.7112108834, 3644.321423753539, 4209.726367786084, 4448.488423831236, 4556.03392302392, 4591.864782875979, 4555.06621142665, 5123.38519377677, 5249.923765628076, 5597.285316621435, 5615.859603509904, 3480.6519348065244, 3792.3742582474133, 3801.7148713846573, 3803.4865293185344, 3808.980955095207, 5373.194020896858, 5586.469285417878, 5786.739596718157, 5948.434898684618, 5970.194023806817, 3559.359315323267, 3860.3051450739135, 3872.283627528916, 3881.96118038816, 3882.242962622318, 3414.573178352706, 3914.3149948758855, 3916.373380259145, 3924.7841368724994, 3927.56796752357, 3674.375356189454, 4098.385242213632, 4510.924974511696, 4543.68194226401, 4572.45130584332, 4948.752562371867, 5609.915851262234, 5647.289301135478, 5675.0, 5693.516051135617, 1163.8428812110426, 1191.4221602522769, 1194.5759255464359, 1262.4508338872436, 1270.2759427126634, 657.8684263147389, 775.8827288704248, 808.6563210635456, 859.2910848549847, 866.6620018192909, 5000.749962501933, 5024.563086902849, 5025.874353141127, 5026.748662566857, 5031.314509639239, 4818.072770891601, 5177.068127736969, 5238.390216609359, 5409.918851217235, 5493.434371204187, 4503.433043165364, 4511.470507220696, 4959.628027897922, 5060.797568097309, 5064.848054558369, 2601.7008044075174, 2845.758110560584, 2861.728135827093, 2875.6980517045827, 2937.6768555458884, 4937.530934561211, 5404.7297635118875, 5746.71869457257, 5758.942410575924, 5806.796762113306, 4010.939835902464, 4780.928286075712, 5425.945740542623, 5530.972345138249, 5704.80033198836, 4834.250691142878, 5693.459121383462, 5970.0, 6069.969650151721, 6096.451319381188, 5764.377208370265, 5764.510016648464, 5823.94176058234, 5834.453869724442, 5872.911906321494, 5070.594352451869, 5217.452167522356, 5659.943400565941, 5677.829665110054, 5720.141978703228, 4833.10587541306, 5713.143428285857, 5993.580449368503, 6026.939730602638, 6058.091286307089, 3607.603163652049, 3881.3983832506005, 3891.0077930127636, 3894.5910679378258, 3894.9805250973563, 5102.494764044542, 5797.318777554462, 5814.418558144152, 5853.0, 5901.350851406256, 4973.701577905337, 5200.6619569727845, 5657.0, 5659.349174844946, 5666.660000399989, 5001.549860512571, 5259.763310650953, 5601.661823011867, 5649.864325299498, 5650.333151720334, 5547.944520554743, 5568.721563921868, 5574.5716384424095, 5583.812140894329, 5587.346010644911, 5004.3494345734825, 5008.046861723858, 5441.591880608891, 5694.0, 5717.514011309086, 4254.255505286501, 4673.696209746356, 4957.810125569909, 5583.302087239095, 5592.468715472024], "value": 4019.0136181081016, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.4744215559409, 739.9112106547212, 765.043695380774, 792.8374683189945, 820.667629610008, 13538.661533461656, 14362.497312594061, 14403.495877644298, 14417.567472975801, 14421.855781442182, 13063.934680326609, 13072.542461013867, 13149.079564430469, 13178.47286108557, 13192.802107968364, 844.497523973238, 879.7580665317057, 904.9592768325441, 905.2576886952669, 912.8950170730351, 857.8241460500614, 900.7117722328858, 901.6979311930478, 903.8056817784175, 906.3610154840821, 898.3846065445191, 901.1259078693656, 934.523393069536, 936.3539157981005, 938.995305023474, 914.6752902719505, 925.2736601767614, 930.6044930904338, 931.4039015030386, 933.8599210118468, 697.0763738047108, 698.6751160710294, 698.8113209433425, 700.2612244082491, 700.9404200642952, 14461.421543138262, 14809.185494797783, 14951.0, 15003.774943375858, 15080.396784128621, 13493.51674035707, 13668.93165534176, 13819.930900345535, 13829.712789035331, 13837.904212074161, 10935.070519005894, 11527.538898444052, 11551.711207219887, 11702.218025013355, 12122.757544849143, 3636.4545318202217, 3657.7439579229463, 3660.652238037382, 3803.7717736935597, 3819.312523745725, 94527.68631996529, 97815.0, 98092.50953745257, 98313.0, 98516.50741746317, 10319.690409287734, 10443.060124588823, 10461.895381046244, 10526.0, 10653.467326633792, 21255.0, 21547.0, 21624.675629865564, 21651.56696866054, 21987.23044693428, 7342.082239720035, 7401.777946661608, 7435.330820226098, 7597.126330471956, 7601.581912994728, 3438.209211881281, 4103.671706263492, 4122.381642753611, 4131.338985762323, 4143.399207115011, 3563.3051554947056, 3612.8554857805393, 3628.0385697790107, 3663.7435379523176, 3666.9816650916573, 674.1270055278426, 674.2785219814751, 679.6771533521538, 690.8790961581703, 692.6467501574233, 644.9516286278548, 654.8199245207511, 677.9389854912982, 684.948628852838, 717.3507975282273, 9551.856722149174, 9871.851922221173, 9955.0, 9992.400455972665, 10188.235882308856, 640.9839754006188, 695.2352412346479, 695.6521739130435, 700.0828914122516, 711.5517224148857, 4611.76941152941, 4745.786439610157, 4750.572448479654, 4764.904701905943, 4771.71369717818, 96894.0, 97212.51393742986, 97799.51100244596, 98351.0, 99284.0, 55890.72054639701, 58564.7071764647, 74489.6275518619, 78304.60847695838, 78493.60753196197, 56215.0, 71584.28415715776, 72863.90704139443, 72991.0, 74564.88152677714, 10172.949135254275, 10210.744731381477, 10372.11836993864, 10483.483963848774, 10591.0, 39473.60526394699, 45699.771501142284, 49330.0, 49369.0, 50993.490065100355, 63887.68056159689, 64369.0, 64831.02753458608, 65178.02232966414, 65940.67029664821, 12348.365175404622, 12572.371381430708, 12622.747545049231, 12661.556845510362, 13147.34263286851, 14094.083884547472, 14229.857701422854, 14298.57104286873, 15021.549353519413, 15124.243787810785, 12944.288064156563, 12969.805452918024, 13016.479340826261, 13562.93218533901, 13627.250501222132, 11615.76768464643, 13112.409941552462, 13284.86715132875, 13392.531261406193, 13742.381592828548, 12686.129208733204, 12701.4284357206, 12806.871931280568, 12814.743705125658, 12888.22670639727, 9957.850632240377, 10816.891831081588, 10880.587850819004, 11328.9433552835, 11596.594119205787, 8161.224683655043, 9668.95165524196, 10285.228607854291, 10547.52536135891, 10555.41945192991], "value": 7329.287754456153, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1474.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 114.0, 114.0, 115.0, 114.0, 113.0, 113.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 112.0, 111.0, 148.0, 113.0, 109.0, 116.0, 107.0, 131.0, 122.0], "value": 121.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 184.0, 183.0, 185.0, 181.0, 181.0, 185.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 72.0, 59.0, 88.0, 65.0, 77.0, 85.0, 83.0, 93.0, 59.0], "value": 76.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 88.0, 71.0, 69.0, 70.0, 84.0, 70.0, 70.0, 84.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 88.0, 85.0, 90.0, 87.0, 88.0, 80.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 84.0, 85.0, 85.0, 87.0, 84.0, 86.0, 99.0], "value": 87.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 57.0, 58.0, 81.0, 89.0, 88.0, 81.0, 58.0, 57.0, 58.0], "value": 68.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 73.0, 75.0, 75.0, 69.0, 68.0, 70.0, 70.0, 70.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 84.0, 87.0, 87.0, 89.0, 88.0, 88.0, 90.0, 98.0, 87.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 143.0, 145.0, 144.0, 152.0, 145.0, 140.0, 143.0, 230.0, 173.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 179.0, 226.0, 193.0, 172.0, 176.0, 168.0, 171.0, 174.0], "value": 180.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 68.0, 64.0, 67.0, 65.0, 79.0, 79.0, 80.0], "value": 70.2, "unit": "score"}]}]} 18:11:15 INFO - Return code: 0 18:11:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:11:15 ERROR - # TBPL SUCCESS # 18:11:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:11:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:11:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:11:15 INFO - 2017-02-07 18:11:15.992 screenresolution[1661:10801] starting screenresolution argv=screenresolution get 18:11:15 INFO - 2017-02-07 18:11:15.995 screenresolution[1661:10801] Display 0: 1600x1200x32@60 18:11:16 INFO - 2017-02-07 18:11:16.001 screenresolution[1662:10804] starting screenresolution argv=screenresolution list 18:11:16 INFO - Available Modes on Display 0 18:11:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:11:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:11:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:11:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:11:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:11:16 INFO - Intel Iris: 18:11:16 INFO - Chipset Model: Intel Iris 18:11:16 INFO - Type: GPU 18:11:16 INFO - Bus: Built-In 18:11:16 INFO - VRAM (Dynamic, Max): 1536 MB 18:11:16 INFO - Vendor: Intel (0x8086) 18:11:16 INFO - Device ID: 0x0a2e 18:11:16 INFO - Revision ID: 0x0009 18:11:16 INFO - Displays: 18:11:16 INFO - Display: 18:11:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:11:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:11:16 INFO - Display Serial Number: 1600x1200 60 18:11:16 INFO - Main Display: Yes 18:11:16 INFO - Mirror: Off 18:11:16 INFO - Online: Yes 18:11:16 INFO - Rotation: Supported 18:11:16 INFO - Return code: 0 18:11:16 INFO - Running post-action listener: _resource_record_post_action 18:11:16 INFO - [mozharness: 2017-02-08 02:11:16.243173Z] Finished run-tests step (success) 18:11:16 INFO - Running post-run listener: _resource_record_post_run 18:11:16 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 32603136; Write bytes: 555611136; Read time: 195; Write time: 1648 18:11:16 INFO - TinderboxPrint: CPU usage
53.7% 18:11:16 INFO - TinderboxPrint: I/O read bytes / time
32,603,136 / 195 18:11:16 INFO - TinderboxPrint: I/O write bytes / time
555,611,136 / 1,648 18:11:16 INFO - TinderboxPrint: CPU idle
2,223.2 (46.3%) 18:11:16 INFO - TinderboxPrint: CPU system
957.0 (19.9%) 18:11:16 INFO - TinderboxPrint: CPU user
1,625.6 (33.8%) 18:11:16 INFO - TinderboxPrint: Swap in / out
281,034,752 / 0 18:11:16 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250655232; Write bytes: 243861504; Read time: 18189; Write time: 492 18:11:16 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29015040; Write bytes: 299305984; Read time: 172; Write time: 1115 18:11:16 INFO - Running post-run listener: _upload_blobber_files 18:11:16 INFO - Blob upload gear active. 18:11:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:11:16 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 18:11:16 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'] 18:11:16 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 18:11:17 INFO - (blobuploader) - INFO - Open directory for files ... 18:11:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:11:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:11:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:11:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:17 INFO - (blobuploader) - INFO - Done attempting. 18:11:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:11:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:11:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:11:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:18 INFO - (blobuploader) - INFO - Done attempting. 18:11:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:11:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:11:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:11:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:19 INFO - (blobuploader) - INFO - Done attempting. 18:11:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:11:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:11:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:11:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:22 INFO - (blobuploader) - INFO - Done attempting. 18:11:22 INFO - (blobuploader) - INFO - Iteration through files over. 18:11:22 INFO - Return code: 0 18:11:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:11:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:11:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41ac6813663d195f9429e4c1dcda10649fc044b08c6f98d70df820b4734bc4f7320fbaadfd1f674bcc9a29d3d8c270a9cb73389bed0942177f4da8e1162a37aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff93a4f76481ca4f4f7f9a2335a5edbdc78cf3fcfe5780907b0ba011d11bb10af31d9f7550a2b54eb4f442de31451de06a0b644b7453630d8fb52d4340dedffc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a35540766a6f080885de4dee08a4c32ed6f7dba03a59fd31e5b41cc87ef6c1cc79a26830f360c84de4dac4c3b330f2299b149ab57841138f5e1be1f343fa01ab", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9686bbe10dcad95480ea8fa8784f8abfb33865745e47e6ab70eed158ac610c9c7bea60aaa6516c9b9f34d147042ecf8a4f2a149f3dc8e140bbd2c3ce48b5662a"} 18:11:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:11:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:11:22 INFO - Contents: 18:11:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41ac6813663d195f9429e4c1dcda10649fc044b08c6f98d70df820b4734bc4f7320fbaadfd1f674bcc9a29d3d8c270a9cb73389bed0942177f4da8e1162a37aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff93a4f76481ca4f4f7f9a2335a5edbdc78cf3fcfe5780907b0ba011d11bb10af31d9f7550a2b54eb4f442de31451de06a0b644b7453630d8fb52d4340dedffc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a35540766a6f080885de4dee08a4c32ed6f7dba03a59fd31e5b41cc87ef6c1cc79a26830f360c84de4dac4c3b330f2299b149ab57841138f5e1be1f343fa01ab", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9686bbe10dcad95480ea8fa8784f8abfb33865745e47e6ab70eed158ac610c9c7bea60aaa6516c9b9f34d147042ecf8a4f2a149f3dc8e140bbd2c3ce48b5662a"} 18:11:22 INFO - Running post-run listener: copy_logs_to_upload_dir 18:11:22 INFO - Copying logs to upload dir... 18:11:22 INFO - mkdir: /builds/slave/test/build/upload/logs 18:11:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1817.197289 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 30 mins, 17 secs) (at 2017-02-07 18:11:22.344506) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 18:11:22.348280) ========= 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.QT3DIqO1SL/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.dg4SyumCa3/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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/41ac6813663d195f9429e4c1dcda10649fc044b08c6f98d70df820b4734bc4f7320fbaadfd1f674bcc9a29d3d8c270a9cb73389bed0942177f4da8e1162a37aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff93a4f76481ca4f4f7f9a2335a5edbdc78cf3fcfe5780907b0ba011d11bb10af31d9f7550a2b54eb4f442de31451de06a0b644b7453630d8fb52d4340dedffc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a35540766a6f080885de4dee08a4c32ed6f7dba03a59fd31e5b41cc87ef6c1cc79a26830f360c84de4dac4c3b330f2299b149ab57841138f5e1be1f343fa01ab", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9686bbe10dcad95480ea8fa8784f8abfb33865745e47e6ab70eed158ac610c9c7bea60aaa6516c9b9f34d147042ecf8a4f2a149f3dc8e140bbd2c3ce48b5662a"} build_url:https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011373 build_url: 'https://queue.taskcluster.net/v1/task/BB3n7n_VQP-Qop4NuCVY8w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41ac6813663d195f9429e4c1dcda10649fc044b08c6f98d70df820b4734bc4f7320fbaadfd1f674bcc9a29d3d8c270a9cb73389bed0942177f4da8e1162a37aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff93a4f76481ca4f4f7f9a2335a5edbdc78cf3fcfe5780907b0ba011d11bb10af31d9f7550a2b54eb4f442de31451de06a0b644b7453630d8fb52d4340dedffc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a35540766a6f080885de4dee08a4c32ed6f7dba03a59fd31e5b41cc87ef6c1cc79a26830f360c84de4dac4c3b330f2299b149ab57841138f5e1be1f343fa01ab", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9686bbe10dcad95480ea8fa8784f8abfb33865745e47e6ab70eed158ac610c9c7bea60aaa6516c9b9f34d147042ecf8a4f2a149f3dc8e140bbd2c3ce48b5662a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 18:11:22.386123) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 18:11:22.386466) ========= 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.QT3DIqO1SL/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.dg4SyumCa3/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005120 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 18:11:22.434995) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 18:11:22.435305) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 18:11:22.435631) ========= ========= Total master_lag: 0.18 =========