builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0147 starttime: 1471917631.83 results: success (0) buildid: 20160822153014 builduid: bc63e52bc197498d974eb26243b8c472 revision: d2339a760bc96d8e8b2cbf819b08872e92e2ffaf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:31.832126) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:31.832523) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:31.832792) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:31.865874) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:31.866257) ========= 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.NLWwB20CZD/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.boPrnoroIq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-22 19:00:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 34.3M=0s 2016-08-22 19:00:31 (34.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:32.002848) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:32.003161) ========= 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.NLWwB20CZD/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.boPrnoroIq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.020695 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:32.051007) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-08-22 19:00:32.051342) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev d2339a760bc96d8e8b2cbf819b08872e92e2ffaf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev d2339a760bc96d8e8b2cbf819b08872e92e2ffaf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NLWwB20CZD/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.boPrnoroIq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-22 19:00:32,127 truncating revision to first 12 chars 2016-08-22 19:00:32,128 Setting DEBUG logging. 2016-08-22 19:00:32,128 attempt 1/10 2016-08-22 19:00:32,128 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/d2339a760bc9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 19:00:34,128 unpacking tar archive at: larch-d2339a760bc9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.258051 ========= master_lag: 2.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-08-22 19:00:36.472503) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:36.472819) ========= script_repo_revision: d2339a760bc96d8e8b2cbf819b08872e92e2ffaf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:36.473220) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:36.473496) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 19:00:36.497036) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-22 19:00:36.497371) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NLWwB20CZD/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.boPrnoroIq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 19:00:36 INFO - MultiFileLogger online at 20160822 19:00:36 in /builds/slave/test 19:00:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 19:00:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:00:36 INFO - {'append_to_log': False, 19:00:36 INFO - 'base_work_dir': '/builds/slave/test', 19:00:36 INFO - 'blob_upload_branch': 'Larch', 19:00:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:00:36 INFO - 'branch': 'Larch', 19:00:36 INFO - 'buildbot_json_path': 'buildprops.json', 19:00:36 INFO - 'config_files': ('talos/mac_config.py',), 19:00:36 INFO - 'default_actions': ('clobber', 19:00:36 INFO - 'read-buildbot-config', 19:00:36 INFO - 'download-and-extract', 19:00:36 INFO - 'populate-webroot', 19:00:36 INFO - 'create-virtualenv', 19:00:36 INFO - 'install', 19:00:36 INFO - 'run-tests'), 19:00:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:00:36 INFO - 'download_minidump_stackwalk': True, 19:00:36 INFO - 'download_symbols': 'ondemand', 19:00:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:00:36 INFO - 'tooltool.py': '/tools/tooltool.py', 19:00:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:00:36 INFO - '/tools/misc-python/virtualenv.py')}, 19:00:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:00:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:00:36 INFO - 'installer_path': 'installer.exe', 19:00:36 INFO - 'log_level': 'info', 19:00:36 INFO - 'log_name': 'talos', 19:00:36 INFO - 'log_to_console': True, 19:00:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:00:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:00:36 INFO - 'opt_config_files': (), 19:00:36 INFO - 'pip_index': False, 19:00:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:00:36 INFO - 'cmd': ('bash', 19:00:36 INFO - '-c', 19:00:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:00:36 INFO - 'enabled': True, 19:00:36 INFO - 'halt_on_failure': False, 19:00:36 INFO - 'name': 'check_screen_resolution'},), 19:00:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:00:36 INFO - 'cmd': ('bash', 19:00:36 INFO - '-c', 19:00:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:00:36 INFO - 'enabled': True, 19:00:36 INFO - 'halt_on_failure': False, 19:00:36 INFO - 'name': 'check_screen_resolution'},), 19:00:36 INFO - 'run_cmd_checks_enabled': True, 19:00:36 INFO - 'sps_profile': False, 19:00:36 INFO - 'sps_profile_interval': 0, 19:00:36 INFO - 'suite': 'dromaeojs', 19:00:36 INFO - 'system_bits': '32', 19:00:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:00:36 INFO - 'title': 't-yosemite-r7-0147', 19:00:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:00:36 INFO - 'use_talos_json': True, 19:00:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:00:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:00:36 INFO - 'work_dir': 'build'} 19:00:36 INFO - [mozharness: 2016-08-23 02:00:36.691695Z] Running clobber step. 19:00:36 INFO - Running pre-action listener: _resource_record_pre_action 19:00:36 INFO - Running main action method: clobber 19:00:36 INFO - rmtree: /builds/slave/test/build 19:00:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:00:37 INFO - Running post-action listener: _resource_record_post_action 19:00:37 INFO - [mozharness: 2016-08-23 02:00:37.669454Z] Finished clobber step (success) 19:00:37 INFO - [mozharness: 2016-08-23 02:00:37.669610Z] Running read-buildbot-config step. 19:00:37 INFO - Running pre-action listener: _resource_record_pre_action 19:00:37 INFO - Running main action method: read_buildbot_config 19:00:37 INFO - Using buildbot properties: 19:00:37 INFO - { 19:00:37 INFO - "project": "", 19:00:37 INFO - "product": "firefox", 19:00:37 INFO - "script_repo_revision": "production", 19:00:37 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 19:00:37 INFO - "repository": "", 19:00:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 19:00:37 INFO - "buildid": "20160822153014", 19:00:37 INFO - "pgo_build": "False", 19:00:37 INFO - "basedir": "/builds/slave/test", 19:00:37 INFO - "buildnumber": 1, 19:00:37 INFO - "slavename": "t-yosemite-r7-0147", 19:00:37 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:00:37 INFO - "platform": "yosemite_r7", 19:00:37 INFO - "scheduler": "tests-larch-macosx64-talos", 19:00:37 INFO - "branch": "larch", 19:00:37 INFO - "repo_path": "projects/larch", 19:00:37 INFO - "revision": "d2339a760bc96d8e8b2cbf819b08872e92e2ffaf", 19:00:37 INFO - "stage_platform": "macosx64", 19:00:37 INFO - "builduid": "bc63e52bc197498d974eb26243b8c472", 19:00:37 INFO - "slavebuilddir": "test" 19:00:37 INFO - } 19:00:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:00:37 INFO - Running post-action listener: _resource_record_post_action 19:00:37 INFO - [mozharness: 2016-08-23 02:00:37.672539Z] Finished read-buildbot-config step (success) 19:00:37 INFO - [mozharness: 2016-08-23 02:00:37.672640Z] Running download-and-extract step. 19:00:37 INFO - Running pre-action listener: _resource_record_pre_action 19:00:37 INFO - Running main action method: download_and_extract 19:00:37 INFO - mkdir: /builds/slave/test/build/tests 19:00: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')]} 19:00:37 INFO - trying https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:00:37 INFO - Downloading https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:00:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 19:00:38 INFO - Downloaded 1199 bytes. 19:00:38 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:00:38 INFO - Using the following test package requirements: 19:00:38 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:00:38 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:00:38 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:00:38 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:00:38 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:00:38 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:00:38 INFO - u'jsshell-mac.zip'], 19:00:38 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:00:38 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:00:38 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:00:38 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:00:38 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:00:38 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:00:38 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:00:38 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:00:38 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:00:38 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:00:38 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:00:38 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:00:38 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')]} 19:00:38 INFO - trying https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:00:38 INFO - Downloading https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:00:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:00:39 INFO - Downloaded 16352384 bytes. 19:00:39 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:00:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:00: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')]} 19:00:46 INFO - trying https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:00:46 INFO - Downloading https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:00:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:00:47 INFO - Downloaded 16352384 bytes. 19:00:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:00: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')]} 19:00:54 INFO - trying https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:00:54 INFO - Downloading https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:00:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:00:55 INFO - Downloaded 14413981 bytes. 19:00:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:00:55 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')]} 19:00:55 INFO - trying https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:00:55 INFO - Downloading https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:00:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 19:00:58 INFO - Downloaded 111545673 bytes. 19:00:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:00:58 INFO - mkdir: /builds/slave/test/properties 19:00:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:00:58 INFO - Writing to file /builds/slave/test/properties/build_url 19:00:58 INFO - Contents: 19:00:58 INFO - build_url:https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:00:59 INFO - Running post-action listener: _resource_record_post_action 19:00:59 INFO - Running post-action listener: set_extra_try_arguments 19:00:59 INFO - [mozharness: 2016-08-23 02:00:59.305671Z] Finished download-and-extract step (success) 19:00:59 INFO - [mozharness: 2016-08-23 02:00:59.305800Z] Running populate-webroot step. 19:00:59 INFO - Running pre-action listener: _resource_record_pre_action 19:00:59 INFO - Running main action method: populate_webroot 19:00:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:00:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:00:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:00:59 INFO - '--tppagecycles', 19:00:59 INFO - '7'], 19:00:59 INFO - 'tests': ['tsvgm']}}, 19:00:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:00:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:00:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:00:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:00:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:59 INFO - 'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:59 INFO - 'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['damp', 'tps']}, 19:00:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:59 INFO - 'tests': ['damp', 'tps']}, 19:00:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['dromaeo_dom']}, 19:00:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:00:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['basic_compositor_video']}, 19:00:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:00:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'other-e10s': {'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['a11yr', 19:00:59 INFO - 'ts_paint', 19:00:59 INFO - 'tpaint', 19:00:59 INFO - 'sessionrestore', 19:00:59 INFO - 'sessionrestore_no_auto_restore', 19:00:59 INFO - 'tabpaint']}, 19:00:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['tsvgx', 19:00:59 INFO - 'tsvgr_opacity', 19:00:59 INFO - 'tart', 19:00:59 INFO - 'tscrollx', 19:00:59 INFO - 'cart']}, 19:00:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:00:59 INFO - 'tsvgr_opacity', 19:00:59 INFO - 'tart', 19:00:59 INFO - 'tscrollx', 19:00:59 INFO - 'cart']}, 19:00:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:59 INFO - 'talos_options': ['--disable-e10s'], 19:00:59 INFO - 'tests': ['tp5o']}, 19:00:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:59 INFO - 'tests': ['tp5o']}, 19:00:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:59 INFO - 'talos_options': ['--disable-e10s', 19:00:59 INFO - '--xperf_path', 19:00:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:59 INFO - 'tests': ['tp5n']}, 19:00:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:59 INFO - 'talos_options': ['--xperf_path', 19:00:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:59 INFO - 'tests': ['tp5n']}}, 19:00:59 INFO - 'talos.zip': {'path': '', 19:00:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:00:59 INFO - Running post-action listener: _resource_record_post_action 19:00:59 INFO - [mozharness: 2016-08-23 02:00:59.324515Z] Finished populate-webroot step (success) 19:00:59 INFO - [mozharness: 2016-08-23 02:00:59.324643Z] Running create-virtualenv step. 19:00:59 INFO - Running pre-action listener: _resource_record_pre_action 19:00:59 INFO - Running main action method: create_virtualenv 19:00:59 INFO - Creating virtualenv /builds/slave/test/build/venv 19:00:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:00:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:00:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:00:59 INFO - Using real prefix '/tools/python27' 19:00:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:01:00 INFO - Installing distribute.............................................................................................................................................................................................done. 19:01:02 INFO - Installing pip.................done. 19:01:02 INFO - Return code: 0 19:01:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ccbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc419d898f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc20d30>, '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 0x10c985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc18b20>, '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.NLWwB20CZD/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.boPrnoroIq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 19:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:01:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLWwB20CZD/Render', 19:01:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:02 INFO - 'HOME': '/Users/cltbld', 19:01:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:02 INFO - 'LOGNAME': 'cltbld', 19:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:02 INFO - 'MOZ_NO_REMOTE': '1', 19:01:02 INFO - 'NO_EM_RESTART': '1', 19:01:02 INFO - 'PAGER': '/bin/cat', 19:01:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:02 INFO - 'PWD': '/builds/slave/test', 19:01:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:02 INFO - 'SHELL': '/bin/bash', 19:01:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.boPrnoroIq/Listeners', 19:01:02 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 19:01:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:02 INFO - 'USER': 'cltbld', 19:01:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:02 INFO - 'XPC_FLAGS': '0x0', 19:01:02 INFO - 'XPC_SERVICE_NAME': '0', 19:01:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:03 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 19:01:03 INFO - Cleaning up... 19:01:03 INFO - Return code: 0 19:01:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:01: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')]} 19:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01: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')]} 19:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ccbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc419d898f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc20d30>, '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 0x10c985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc18b20>, '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.NLWwB20CZD/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.boPrnoroIq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 19:01:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:01:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:01:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLWwB20CZD/Render', 19:01:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:03 INFO - 'HOME': '/Users/cltbld', 19:01:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:03 INFO - 'LOGNAME': 'cltbld', 19:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:03 INFO - 'MOZ_NO_REMOTE': '1', 19:01:03 INFO - 'NO_EM_RESTART': '1', 19:01:03 INFO - 'PAGER': '/bin/cat', 19:01:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:03 INFO - 'PWD': '/builds/slave/test', 19:01:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:03 INFO - 'SHELL': '/bin/bash', 19:01:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.boPrnoroIq/Listeners', 19:01:03 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 19:01:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:03 INFO - 'USER': 'cltbld', 19:01:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:03 INFO - 'XPC_FLAGS': '0x0', 19:01:03 INFO - 'XPC_SERVICE_NAME': '0', 19:01:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:03 INFO - Downloading/unpacking psutil>=3.1.1 19:01:03 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 19:01:03 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 19:01:03 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 19:01:03 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 19:01:03 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 19:01:03 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 19:01:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:01:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:07 INFO - Installing collected packages: psutil 19:01:07 INFO - Running setup.py install for psutil 19:01:07 INFO - building 'psutil._psutil_osx' extension 19:01:07 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 19:01:07 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 19:01:07 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 19:01:07 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 19:01:07 INFO - building 'psutil._psutil_posix' extension 19:01:07 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 19:01:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:01:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:01:07 INFO - ^ 19:01:07 INFO - 1 warning generated. 19:01:07 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 19:01:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:07 INFO - Successfully installed psutil 19:01:07 INFO - Cleaning up... 19:01:07 INFO - Return code: 0 19:01:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ccbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc419d898f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc20d30>, '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 0x10c985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc18b20>, '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.NLWwB20CZD/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.boPrnoroIq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 19:01:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:01:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:01:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLWwB20CZD/Render', 19:01:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:07 INFO - 'HOME': '/Users/cltbld', 19:01:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:07 INFO - 'LOGNAME': 'cltbld', 19:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:07 INFO - 'MOZ_NO_REMOTE': '1', 19:01:07 INFO - 'NO_EM_RESTART': '1', 19:01:07 INFO - 'PAGER': '/bin/cat', 19:01:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:07 INFO - 'PWD': '/builds/slave/test', 19:01:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:07 INFO - 'SHELL': '/bin/bash', 19:01:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.boPrnoroIq/Listeners', 19:01:07 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 19:01:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:07 INFO - 'USER': 'cltbld', 19:01:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:07 INFO - 'XPC_FLAGS': '0x0', 19:01:07 INFO - 'XPC_SERVICE_NAME': '0', 19:01:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:01:08 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 19:01:08 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 19:01:08 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 19:01:08 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 19:01:08 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 19:01:08 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 19:01:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:01:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:01:10 INFO - Installing collected packages: mozsystemmonitor 19:01:10 INFO - Running setup.py install for mozsystemmonitor 19:01:10 INFO - Successfully installed mozsystemmonitor 19:01:10 INFO - Cleaning up... 19:01:10 INFO - Return code: 0 19:01:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:01:10 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')]} 19:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:10 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')]} 19:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:10 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 0x10ccbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc419d898f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc20d30>, '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 0x10c985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc18b20>, '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.NLWwB20CZD/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.boPrnoroIq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 19:01:10 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 19:01:10 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 19:01:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLWwB20CZD/Render', 19:01:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:10 INFO - 'HOME': '/Users/cltbld', 19:01:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:10 INFO - 'LOGNAME': 'cltbld', 19:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:10 INFO - 'MOZ_NO_REMOTE': '1', 19:01:10 INFO - 'NO_EM_RESTART': '1', 19:01:10 INFO - 'PAGER': '/bin/cat', 19:01:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:10 INFO - 'PWD': '/builds/slave/test', 19:01:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:10 INFO - 'SHELL': '/bin/bash', 19:01:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.boPrnoroIq/Listeners', 19:01:10 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 19:01:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:10 INFO - 'USER': 'cltbld', 19:01:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:10 INFO - 'XPC_FLAGS': '0x0', 19:01:10 INFO - 'XPC_SERVICE_NAME': '0', 19:01:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:11 INFO - Downloading/unpacking jsonschema==2.5.1 19:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:01:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:01:13 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 19:01:13 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 19:01:13 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 19:01:13 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 19:01:13 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 19:01:13 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 19:01:13 INFO - Downloading functools32-3.2.3-2.tar.gz 19:01:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:01:14 INFO - warning: no files found matching '*.txt' 19:01:14 INFO - no previously-included directories found matching 'build' 19:01:14 INFO - no previously-included directories found matching 'dist' 19:01:14 INFO - no previously-included directories found matching '.git*' 19:01:14 INFO - Installing collected packages: jsonschema, functools32 19:01:14 INFO - Running setup.py install for functools32 19:01:14 INFO - warning: no files found matching '*.txt' 19:01:14 INFO - no previously-included directories found matching 'build' 19:01:14 INFO - no previously-included directories found matching 'dist' 19:01:14 INFO - no previously-included directories found matching '.git*' 19:01:14 INFO - Successfully installed jsonschema functools32 19:01:14 INFO - Cleaning up... 19:01:14 INFO - Return code: 0 19:01:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:01: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')]} 19:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01: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')]} 19:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01: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', '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 0x10ccbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc419d898f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc20d30>, '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 0x10c985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc18b20>, '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.NLWwB20CZD/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.boPrnoroIq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 19:01: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:01: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 functools32==3.2.3-2 19:01:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLWwB20CZD/Render', 19:01:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:14 INFO - 'HOME': '/Users/cltbld', 19:01:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:14 INFO - 'LOGNAME': 'cltbld', 19:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:14 INFO - 'MOZ_NO_REMOTE': '1', 19:01:14 INFO - 'NO_EM_RESTART': '1', 19:01:14 INFO - 'PAGER': '/bin/cat', 19:01:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:14 INFO - 'PWD': '/builds/slave/test', 19:01:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:14 INFO - 'SHELL': '/bin/bash', 19:01:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.boPrnoroIq/Listeners', 19:01:14 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 19:01:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:14 INFO - 'USER': 'cltbld', 19:01:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:14 INFO - 'XPC_FLAGS': '0x0', 19:01:14 INFO - 'XPC_SERVICE_NAME': '0', 19:01:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:01:14 INFO - Cleaning up... 19:01:14 INFO - Return code: 0 19:01:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:01: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')]} 19:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01: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')]} 19:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01: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', '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 0x10ccbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc419d898f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc20d30>, '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 0x10c985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc18b20>, '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.NLWwB20CZD/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.boPrnoroIq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 19:01: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:01: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 blobuploader==1.2.4 19:01:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLWwB20CZD/Render', 19:01:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:14 INFO - 'HOME': '/Users/cltbld', 19:01:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:14 INFO - 'LOGNAME': 'cltbld', 19:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:14 INFO - 'MOZ_NO_REMOTE': '1', 19:01:14 INFO - 'NO_EM_RESTART': '1', 19:01:14 INFO - 'PAGER': '/bin/cat', 19:01:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:14 INFO - 'PWD': '/builds/slave/test', 19:01:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:14 INFO - 'SHELL': '/bin/bash', 19:01:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.boPrnoroIq/Listeners', 19:01:14 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 19:01:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:14 INFO - 'USER': 'cltbld', 19:01:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:14 INFO - 'XPC_FLAGS': '0x0', 19:01:14 INFO - 'XPC_SERVICE_NAME': '0', 19:01:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:14 INFO - Downloading/unpacking blobuploader==1.2.4 19:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:17 INFO - Downloading blobuploader-1.2.4.tar.gz 19:01:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:01:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:01:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:18 INFO - Downloading docopt-0.6.1.tar.gz 19:01:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:01:18 INFO - Installing collected packages: blobuploader, requests, docopt 19:01:18 INFO - Running setup.py install for blobuploader 19:01:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:01:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:01:18 INFO - Running setup.py install for requests 19:01:19 INFO - Running setup.py install for docopt 19:01:19 INFO - Successfully installed blobuploader requests docopt 19:01:19 INFO - Cleaning up... 19:01:19 INFO - Return code: 0 19:01:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01:19 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')]} 19:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:19 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')]} 19:01:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:19 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 0x10ccbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc419d898f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc20d30>, '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 0x10c985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc18b20>, '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.NLWwB20CZD/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.boPrnoroIq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 19:01:19 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 19:01:19 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 19:01:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLWwB20CZD/Render', 19:01:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:19 INFO - 'HOME': '/Users/cltbld', 19:01:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:19 INFO - 'LOGNAME': 'cltbld', 19:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:19 INFO - 'MOZ_NO_REMOTE': '1', 19:01:19 INFO - 'NO_EM_RESTART': '1', 19:01:19 INFO - 'PAGER': '/bin/cat', 19:01:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:19 INFO - 'PWD': '/builds/slave/test', 19:01:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:19 INFO - 'SHELL': '/bin/bash', 19:01:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.boPrnoroIq/Listeners', 19:01:19 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 19:01:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:19 INFO - 'USER': 'cltbld', 19:01:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:19 INFO - 'XPC_FLAGS': '0x0', 19:01:19 INFO - 'XPC_SERVICE_NAME': '0', 19:01:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:01:19 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-cIgwcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:01:19 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-PDwSxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:01:19 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-q4bNQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:01:19 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Pq7EFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:01:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-jk7gxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:01:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-sy9Fd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:01:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-9AmTBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:01:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-WaV2Gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:01:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-aCCzgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:01:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-5m69x4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:01:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Fql3cj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:01:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-882HRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:01:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-XmpK1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:01:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-vYnuaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-n2PzDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:01:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-nguDiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:01:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-ejy8vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:01:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:01:21 INFO - Running setup.py install for manifestparser 19:01:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:01:21 INFO - Running setup.py install for mozcrash 19:01:21 INFO - Running setup.py install for mozdebug 19:01:21 INFO - Running setup.py install for mozdevice 19:01:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:01:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:01:22 INFO - Running setup.py install for mozfile 19:01:22 INFO - Running setup.py install for mozhttpd 19:01:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:01:22 INFO - Running setup.py install for mozinfo 19:01:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:01:22 INFO - Running setup.py install for mozInstall 19:01:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:01:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:01:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:01:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:01:22 INFO - Running setup.py install for mozleak 19:01:22 INFO - Running setup.py install for mozlog 19:01:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:01:22 INFO - Running setup.py install for moznetwork 19:01:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:01:23 INFO - Running setup.py install for mozprocess 19:01:23 INFO - Running setup.py install for mozprofile 19:01:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:01:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:01:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:01:23 INFO - Running setup.py install for mozrunner 19:01:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:01:23 INFO - Running setup.py install for mozscreenshot 19:01:23 INFO - Running setup.py install for moztest 19:01:23 INFO - Running setup.py install for mozversion 19:01:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:01:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:01:24 INFO - Cleaning up... 19:01:24 INFO - Return code: 0 19:01:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:24 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 0x10ccbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc419d898f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc20d30>, '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 0x10c985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc18b20>, '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.NLWwB20CZD/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.boPrnoroIq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 19:01:24 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 19:01:24 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 19:01:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLWwB20CZD/Render', 19:01:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:24 INFO - 'HOME': '/Users/cltbld', 19:01:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:24 INFO - 'LOGNAME': 'cltbld', 19:01:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:24 INFO - 'MOZ_NO_REMOTE': '1', 19:01:24 INFO - 'NO_EM_RESTART': '1', 19:01:24 INFO - 'PAGER': '/bin/cat', 19:01:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:24 INFO - 'PWD': '/builds/slave/test', 19:01:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:24 INFO - 'SHELL': '/bin/bash', 19:01:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.boPrnoroIq/Listeners', 19:01:24 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 19:01:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:24 INFO - 'USER': 'cltbld', 19:01:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:24 INFO - 'XPC_FLAGS': '0x0', 19:01:24 INFO - 'XPC_SERVICE_NAME': '0', 19:01:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:01:24 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-rxMZEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:01:24 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)) 19:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:01:24 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-JiE5s4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:01:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 19:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:01:24 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-hyPnrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:01:24 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)) 19:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:01:24 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-NCrqRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:01:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 19:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:01:24 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-7_zUEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:01:24 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)) 19:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:01:24 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-VRz5KP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:01:24 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)) 19:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:01:24 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-3y5qeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:01:25 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)) 19:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:01:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-0rdUZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:01:25 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)) 19:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:01:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-wpKWMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:01:25 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)) 19:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:01:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-COGnML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:01:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 19:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:01:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-UIDaP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:01:25 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)) 19:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:01:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-FwSbNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:01:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:01:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-nSgFb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:01:25 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)) 19:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:01:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-b5FlrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:01:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 19:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-PN14mO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:25 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)) 19:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:01:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-lw8OA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:01:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:01:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xy5EAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:01:26 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)) 19:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:01:26 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)) 19:01:26 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:01:26 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:01:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:01:26 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 19:01:26 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 19:01:26 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 19:01:26 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 19:01:26 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 19:01:26 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 19:01:28 INFO - Downloading blessings-1.6.tar.gz 19:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:01:29 INFO - Installing collected packages: blessings 19:01:29 INFO - Running setup.py install for blessings 19:01:29 INFO - Successfully installed blessings 19:01:29 INFO - Cleaning up... 19:01:29 INFO - Return code: 0 19:01:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:01:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:01:29 INFO - Reading from file tmpfile_stdout 19:01:29 INFO - Current package versions: 19:01:29 INFO - blessings == 1.6 19:01:29 INFO - blobuploader == 1.2.4 19:01:29 INFO - docopt == 0.6.1 19:01:29 INFO - functools32 == 3.2.3-2 19:01:29 INFO - jsonschema == 2.5.1 19:01:29 INFO - manifestparser == 1.1 19:01:29 INFO - mozInstall == 1.12 19:01:29 INFO - mozcrash == 0.17 19:01:29 INFO - mozdebug == 0.1 19:01:29 INFO - mozdevice == 0.48 19:01:29 INFO - mozfile == 1.2 19:01:29 INFO - mozhttpd == 0.7 19:01:29 INFO - mozinfo == 0.9 19:01:29 INFO - mozleak == 0.1 19:01:29 INFO - mozlog == 3.3 19:01:29 INFO - moznetwork == 0.27 19:01:29 INFO - mozprocess == 0.23 19:01:29 INFO - mozprofile == 0.28 19:01:29 INFO - mozrunner == 6.12 19:01:29 INFO - mozscreenshot == 0.1 19:01:29 INFO - mozsystemmonitor == 0.3 19:01:29 INFO - moztest == 0.7 19:01:29 INFO - mozversion == 1.4 19:01:29 INFO - psutil == 3.1.1 19:01:29 INFO - requests == 1.2.3 19:01:29 INFO - wsgiref == 0.1.2 19:01:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01: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')]} 19:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01: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')]} 19:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:29 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 0x10ccbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc419d898f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc20d30>, '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 0x10c985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc18b20>, '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.NLWwB20CZD/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.boPrnoroIq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 19:01:29 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 19:01:29 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 19:01:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLWwB20CZD/Render', 19:01:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:29 INFO - 'HOME': '/Users/cltbld', 19:01:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:29 INFO - 'LOGNAME': 'cltbld', 19:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:29 INFO - 'MOZ_NO_REMOTE': '1', 19:01:29 INFO - 'NO_EM_RESTART': '1', 19:01:29 INFO - 'PAGER': '/bin/cat', 19:01:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:29 INFO - 'PWD': '/builds/slave/test', 19:01:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:29 INFO - 'SHELL': '/bin/bash', 19:01:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.boPrnoroIq/Listeners', 19:01:29 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 19:01:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:29 INFO - 'USER': 'cltbld', 19:01:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:29 INFO - 'XPC_FLAGS': '0x0', 19:01:29 INFO - 'XPC_SERVICE_NAME': '0', 19:01:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 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)) 19:01:29 INFO - Cleaning up... 19:01:29 INFO - Return code: 0 19:01:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:01: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')]} 19:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01: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')]} 19:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:29 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 0x10ccbca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd51618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc419d898f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc20d30>, '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 0x10c985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc18b20>, '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.NLWwB20CZD/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.boPrnoroIq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 19:01:29 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 19:01:29 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 19:01:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLWwB20CZD/Render', 19:01:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:29 INFO - 'HOME': '/Users/cltbld', 19:01:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:29 INFO - 'LOGNAME': 'cltbld', 19:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:29 INFO - 'MOZ_NO_REMOTE': '1', 19:01:29 INFO - 'NO_EM_RESTART': '1', 19:01:29 INFO - 'PAGER': '/bin/cat', 19:01:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:29 INFO - 'PWD': '/builds/slave/test', 19:01:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:29 INFO - 'SHELL': '/bin/bash', 19:01:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.boPrnoroIq/Listeners', 19:01:29 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 19:01:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:29 INFO - 'USER': 'cltbld', 19:01:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:29 INFO - 'XPC_FLAGS': '0x0', 19:01:29 INFO - 'XPC_SERVICE_NAME': '0', 19:01:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:01:30 INFO - Cleaning up... 19:01:30 INFO - Return code: 0 19:01:30 INFO - Running post-action listener: _resource_record_post_action 19:01:30 INFO - Running post-action listener: _start_resource_monitoring 19:01:30 INFO - Starting resource monitoring. 19:01:30 INFO - [mozharness: 2016-08-23 02:01:30.184039Z] Finished create-virtualenv step (success) 19:01:30 INFO - [mozharness: 2016-08-23 02:01:30.184987Z] Running install step. 19:01:30 INFO - Running pre-action listener: _resource_record_pre_action 19:01:30 INFO - Running main action method: install 19:01:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:01:30 INFO - Reading from file tmpfile_stdout 19:01:30 INFO - Detecting whether we're running mozinstall >=1.0... 19:01:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:01:30 INFO - Reading from file tmpfile_stdout 19:01:30 INFO - Output received: 19:01:30 INFO - Usage: mozinstall [options] installer 19:01:30 INFO - Options: 19:01:30 INFO - -h, --help show this help message and exit 19:01:30 INFO - -d DEST, --destination=DEST 19:01:30 INFO - Directory to install application into. [default: 19:01:30 INFO - "/builds/slave/test"] 19:01:30 INFO - --app=APP Application being installed. [default: firefox] 19:01:30 INFO - mkdir: /builds/slave/test/build/application 19:01:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:02:04 INFO - Reading from file tmpfile_stdout 19:02:04 INFO - Output received: 19:02:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:02:04 INFO - Running post-action listener: _resource_record_post_action 19:02:04 INFO - [mozharness: 2016-08-23 02:02:04.402095Z] Finished install step (success) 19:02:04 INFO - [mozharness: 2016-08-23 02:02:04.402229Z] Running run-tests step. 19:02:04 INFO - Running pre-action listener: _resource_record_pre_action 19:02:04 INFO - Running main action method: run_tests 19:02:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:02:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:02:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:02:04 INFO - 2016-08-22 19:02:04.415 screenresolution[1954:13367] starting screenresolution argv=screenresolution get 19:02:04 INFO - 2016-08-22 19:02:04.418 screenresolution[1954:13367] Display 0: 1600x1200x32@60 19:02:04 INFO - 2016-08-22 19:02:04.423 screenresolution[1955:13370] starting screenresolution argv=screenresolution list 19:02:04 INFO - Available Modes on Display 0 19:02:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:02:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:02:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:02:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:02:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:02:04 INFO - Intel Iris: 19:02:04 INFO - Chipset Model: Intel Iris 19:02:04 INFO - Type: GPU 19:02:04 INFO - Bus: Built-In 19:02:04 INFO - VRAM (Dynamic, Max): 1536 MB 19:02:04 INFO - Vendor: Intel (0x8086) 19:02:04 INFO - Device ID: 0x0a2e 19:02:04 INFO - Revision ID: 0x0009 19:02:04 INFO - Displays: 19:02:04 INFO - Display: 19:02:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:02:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:02:04 INFO - Display Serial Number: 1600x1200 60 19:02:04 INFO - Main Display: Yes 19:02:04 INFO - Mirror: Off 19:02:04 INFO - Online: Yes 19:02:04 INFO - Rotation: Supported 19:02:04 INFO - Return code: 0 19:02:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:02:04 INFO - Python 2.7.3 19:02:04 INFO - Return code: 0 19:02:04 INFO - grabbing minidump binary from tooltool 19:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:04 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 0x10cc20d30>, '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 0x10c985db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc18b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:02:04 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 19:02:04 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 19:02:04 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 19:02:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:02:04 INFO - Return code: 0 19:02:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:02:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:02:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:02:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:02:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:02:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 19:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0147 --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 19:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLWwB20CZD/Render', 19:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:04 INFO - 'HOME': '/Users/cltbld', 19:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:04 INFO - 'LOGNAME': 'cltbld', 19:02:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:02:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:04 INFO - 'MOZ_NO_REMOTE': '1', 19:02:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:02:04 INFO - 'NO_EM_RESTART': '1', 19:02:04 INFO - 'PAGER': '/bin/cat', 19:02:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:04 INFO - 'PWD': '/builds/slave/test', 19:02:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:04 INFO - 'SHELL': '/bin/bash', 19:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.boPrnoroIq/Listeners', 19:02:04 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 19:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:04 INFO - 'USER': 'cltbld', 19:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:04 INFO - 'XPC_FLAGS': '0x0', 19:02:04 INFO - 'XPC_SERVICE_NAME': '0', 19:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 19:02:05 INFO - mozversion application_buildid: 20160822153014 19:02:05 INFO - mozversion application_changeset: d2339a760bc96d8e8b2cbf819b08872e92e2ffaf 19:02:05 INFO - mozversion application_display_name: Nightly 19:02:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:02:05 INFO - mozversion application_name: Firefox 19:02:05 INFO - mozversion application_remotingname: firefox 19:02:05 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 19:02:05 INFO - mozversion application_vendor: Mozilla 19:02:05 INFO - mozversion application_version: 51.0a1 19:02:05 INFO - mozversion platform_buildid: 20160822153014 19:02:05 INFO - mozversion platform_changeset: d2339a760bc96d8e8b2cbf819b08872e92e2ffaf 19:02:05 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 19:02:05 INFO - mozversion platform_version: 51.0a1 19:02:05 INFO - using testdate: 1471917725 19:02:05 INFO - actual date: 1471917725 19:02:05 INFO - starting webserver on 'localhost:51368' 19:02:05 INFO - SUITE-START | Running 2 tests 19:02:05 INFO - TEST-START | dromaeo_css 19:02:05 INFO - operating with platform_type : mac_ 19:02:05 INFO - Initialising browser for dromaeo_css test... 19:02:05 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmphibGoZ/profile http://localhost:51368/getInfo.html) 19:02:07 INFO - PROCESS | 1965 | __metrics Screen width/height:1600/1200 19:02:07 INFO - PROCESS | 1965 | colorDepth:24 19:02:07 INFO - PROCESS | 1965 | Browser inner width/height: 1024/689 19:02:07 INFO - PROCESS | 1965 | __metrics 19:02:07 INFO - PROCESS | 1965 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:02:07 INFO - PROCESS | 1965 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:02:08 INFO - TEST-INFO | 1965: exit 0 19:02:08 INFO - Browser initialized. 19:02:08 INFO - Running cycle 1/1 for dromaeo_css test... 19:02:08 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmphibGoZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:02:20 INFO - PROCESS | 1967 | RSS: Main: 231575552 19:02:20 INFO - PROCESS | 1967 | 19:03:11 INFO - PROCESS | 1967 | 1471917791455 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:05:23 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:51368/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51368/tests/dromaeo/cssquery-ext.html) 19:05:24 INFO - PROCESS | 1967 | RSS: Main: 341544960 19:05:24 INFO - PROCESS | 1967 | 19:08:27 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:51368/tests/dromaeo/cssquery-ext.html (next: http://localhost:51368/tests/dromaeo/cssquery-jquery.html) 19:08:28 INFO - PROCESS | 1967 | RSS: Main: 374972416 19:08:28 INFO - PROCESS | 1967 | 19:11:30 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:51368/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51368/tests/dromaeo/cssquery-mootools.html) 19:11:31 INFO - PROCESS | 1967 | RSS: Main: 346599424 19:11:31 INFO - PROCESS | 1967 | 19:14:33 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:51368/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51368/tests/dromaeo/cssquery-prototype.html) 19:14:34 INFO - PROCESS | 1967 | RSS: Main: 348549120 19:14:34 INFO - PROCESS | 1967 | 19:17:37 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:51368/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51368/tests/dromaeo/cssquery-yui.html) 19:17:38 INFO - PROCESS | 1967 | RSS: Main: 331042816 19:17:38 INFO - PROCESS | 1967 | 19:20:41 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:51368/tests/dromaeo/cssquery-yui.html (next: http://localhost:51368/tests/dromaeo/cssquery-dojo.html) 19:20:41 INFO - PROCESS | 1967 | RSS: Main: 508833792 19:20:41 INFO - PROCESS | 1967 | 19:20:41 INFO - PROCESS | 1967 | __start_tp_report 19:20:41 INFO - PROCESS | 1967 | _x_x_mozilla_page_load 19:20:41 INFO - PROCESS | 1967 | _x_x_mozilla_page_load_details 19:20:41 INFO - PROCESS | 1967 | |i|pagename|runs| 19:20:41 INFO - PROCESS | 1967 | |0;dojo.html;2963.74808141308;3326.783759055661;3341.097903566037;3341.3818443587925;3381.509681096241;5487.945120548803;5595.104783234673;5596.944030559694;5664.801731939383;5695.572832037598;5580.693061881605;5601.8599535011645;5675.659460432377;5718;5719.370869204384;5108.157154069574;5116.951025039876;5209.453007434222;5212.76542555584;5227.947720522784;5299.364076310848;5308.23030660554;5310.681359118446;5313.362396512424;5326.866828329284;5068.467810879878;5120.359955005624;5136.998285334361;5216.269722238909;5229.875576750978;5105.489451054865;5127.333446651949;5163.199704045879;5173.508516690908;5178.14560597501;5119.66722163062;5149.0731668299695;5187.922181167285;5201.55786758126;5201.713905735183;5456.563474922036;5707.429257074301;5708.40061793514;5725.456081672276;5749.022666146723;6133.693315334215;6198.969005154945;6203;6235.376462353728;6240.475655150025;6211.720472578745;6239;6281.497480201573;6284.497240220771;6305.747770089191;5604.607677462538;5784.16129661201;5793.073108302651;5798.971005144989;5804.390538993345;6259.749610015549;6285.994240921431;6288.654124023223;6288.968555157195;6304.338044505353;6093.025115981422;6102.542309326818;6106.6946652668075;6125;6125.479334256549;5969.761209551656;6219.782307619212;6226.782062627786;6230.750769969241;6232.220972378453;5993.130996005642;6057.757689692451;6069.635821850703;6071.089336599545;6147.907781383283;6127.601705889103;6128.111423843607;6133.84665383369;6171.84570385735;6177.876442471125;4790.329353890458;4863.392075990501;4863.659543831968;4871.683340582851;4887.560119589254;4333.761643109659;4355.934660980088;4384.145091707086;4399.6920215584605;4433.334999750064;5721.170430287668;5741.540676745851;5745.195672605839;5745.482906538432;5746.5402767779315;6130;6230.875382492325;6276.372362763778;6290.150829638032;6307.400796924285;4751.429828420612;5609.551235901118;5676.148577713294;5699.031164702011;5730.627509211888;5390.137577987503;5392.838214853561;5398.595105367113;5407.053765591085;5411.891762164734;6291.402316779899;6296.937030629727;6299.338569450325;6308.463780578702;6344.777932772516;6129.233845769279;6218.689065546614;6237.937620623918;6268.811935641938;6317.557770955989;6274.498040156685;6277.2153480814895;6280.528960328085;6300.243970723543;6310.211223597051;6122.418370254997;6166.41419065206;6171.444569988723;6198.566100372931;6217.4715149212825;6089.330173680803;6121.602095863755;6133.233345831771;6197.349278325711;6246.750129994749;6085.269767627913;6086.78696245647;6093.025115981422;6105.175801266772;6121.265448146251;6049.727762250621;6055.364186760326;6062.575619706755;6081.391860813883;6084.178635884276;5745.080787074048;5802.82591522255;5858.384869588632;5868.853278668067;5962.135490353961;6082.118092876596;6098.29869564988;6133.723982420713;6145.631262124287;6150.04674275488;5877.35349111606;5941.554383421174;5947.9405205949115;5963.463288304073;5980.760769569342 19:20:41 INFO - PROCESS | 1967 | |1;ext.html;463.97680115994194;481.7663433234883;490.10066527921265;514.0413129513457;523.573287770467;30631.540526892113;32147;40075;41096.58903410965;41221.793891030575;42073.78963105188;42361.364579531255;42458;42505.78747106268;42512.787436062856;9570.85643715343;9781.706548803548;9785.657501987433;9796.657117000907;9803.166730827852;8650.567471626426;8847.867281990775;11749.765004699944;11996.64010079699;12001.51993920242;11853.466594003248;11933.28400295985;11959.0432765379;11967.760644787098;11982.281063136195;10964.56141754329;11552.613686357607;11965.341906195155;11976.760464790656;11979.22135061227;9783.755406114833;11803.468843902045;11806.763864722658;11812.173147879652;11863.406829658568;39978.60021399778;40205.386649867665;40258.597414025775;40275.194496110205;40370.394444083366;3427.2288735034417;3499.3526197653514;3504.4392897136336;3597.9100522487147;3939.72421930465;30664.8466757667;31777;31837.204069898205;31860.362792744014;31912.680873191435;3226.58054452922;3284.868605255787;3385.86456541738;3420.2304481491465;3430.811305378203;503893.4805325987;504200;504652;505409;506412.4679376616;57534.84930301454;58093.709531452914;58117;58132.709336453045;58203.70898145567;60730.39269607336;60763.69618151881;60859;60917.69541152354;60947.390526095056;16034.037957722574;16192.109433981008;16209.35162593483;16225.51323460298;16230.269637866324;11539.48072336747;11712.297262164297;12740.554080607135;12803.231806091664;12840.972722182203;14331.283435828167;14405.78391324131;14412.711745765026;14431.278436078153;15734.055956642638;5278.287431196739;5988;6003.129546215774;6036.094585812163;6063.8478689049825;850.9404341696022;929.5584597316221;978.500964508106;1009.8131845608586;1132.943352832355;28137.43725125486;29603;29968.100956971324;30002.84998574993;30282.697173028428;1000.5047501486665;1054.1303424674675;1095.906847917936;1119.8320251962107;1157.8494795676509;13188.406521706545;15588.220588970733;15653.37386504527;15883.444079457395;15908.840911590967;185432;185618;186446;186906;186958;50017.49982500129;56735.14897276544;56906.14640780391;56938.86122277614;61453.69273153606;1354.7155097429454;1371.4171477122184;1400.9159450433006;1409.5207629406052;1414.3635364085997;27907.440853347878;30278.243043924078;31167.688323117385;31490.527642085846;31659.366812664077;43888.561114389726;50308;50375.74812125916;50400;50484;607441;609970;610804;611772;612677;25690;26257.81839817251;39764.80705578837;39962.80018599889;40018.39972400475;47115.82210444766;47938;48243.27635085407;48735.512644873095;48983.510164897896;38786.4182037264;39053.80473097616;39233.803830980665;39390.80304598458;40496.3925541123;37585.624143758214;38127.42808857814;39059.632912849054;39633;40347;37174.07064823401;37741.86774396773;37850.24299514049;37963;37998.62001379951;30129.548056779604;32036.19909502281;32868.671313287516;34677.306453871286;34749;25061.87469062643;25145.497090057732;25189.496210076064;25256.494870102128;25710.10014649553 19:20:41 INFO - PROCESS | 1967 | |2;jquery.html;347.7583079759565;361.59140171606083;364.9781013139212;374.7133442916168;397.28687006822753;15368;16602.335906563738;20045.298414555495;20461.795382046173;20536.89731551342;20726.37820865373;21197.152113915465;21419.196346760586;21442.24952126676;21927.451813704665;5741.655500669973;5780.82657520274;6043.123747056674;6053.424924632153;6067.180930574382;6081.118237855509;6111.144439778434;6116.235470566179;6119.785807496739;6130.1111338855835;3808.9238215235678;3960.0297927007923;6141.723622437001;6756.02037704535;6768.796936091925;5082.74586270685;6816.522843400964;6819.761308354184;6825.965870170618;6826.453883689318;5840.299164100293;5899.793507227227;5907.645541267538;5908.911366329466;5916.704164791743;13187.86812131876;13261.801072983913;13357.666058348523;13415.060945733803;13494.392752326168;2193.890305484719;2238.9888050559807;2285.074544809355;2331.6269396896414;2406.4946361264156;9603.087706667855;9848.06443387877;9923.255755818342;10073.848892266622;10455.634052808191;1783.5273652482103;1857.6841936870628;1880.2666959885655;1890.5557194059386;1915.2530513099903;117124.41437792756;125357;125913.37043314817;128879.35560322138;129799;9233.722988310361;10960.451977401099;11327.830082548768;11430.1998860079;11631.941840290914;14390.208538530482;14396.712065758835;14399.712005760035;14418.495352662816;14418.927905360406;7177.318154775288;8210.630521626541;8304.294134998472;8476.618552165168;8583.828323433496;8353.289970352467;8371.497710137412;8384.580770961427;8497.532635705096;8561.22948934599;4481.731096134177;4488.977555112268;4495.415595972503;4511;4579.335996280587;1835.862310326731;1881.5766452548066;1882.9246830126915;1890.2816929566677;1893.5644801695687;825.7275099217244;893.3389291923929;919.9356045076914;922.2714055895767;922.9616681233612;20649.793502065088;21389.144434222457;21446.57106857854;21495.89252053761;21564.46088847791;925.5372313843078;933.1741408853214;942.9434233945848;976.1458723616835;1021.6985989133224;10713.732156696145;11115.666530004113;11136.33182009082;11211.943940280247;11240.100791936626;591237;593610;596995;613849;614123;127055.7533096016;134885.32557337152;139024.30487847497;143510.2824485892;145246;974.4543055888724;1007.652359935821;1015.8323876560432;1016.187050359724;1034.4879284754095;16961.067141306852;18869.90565047166;19633.70549441788;19837.900810495856;19969.001549922155;41821.16357672769;46472.83817904579;55632.887342253736;55912;57022.7148864253;78002.82995755182;78832.63502094947;79385.60307198427;80658.59670701608;81022.59488702755;7666.348360389206;7962.363010958993;7989.400794940287;8389.077201507951;8397.118302578141;9414.15272625467;9949.403035817584;11851.111166662362;12513.812292815797;13743.037987340791;11136.38749868765;11433.942830285796;11451.942740286246;11529.463518026021;11653.184277100521;11179.720506987065;11451.25566838153;11518.884811151782;11704.122190835891;11723.944844963995;11451.370174640475;11575.536978521102;11857.466414011562;11866.703332416759;11961.760764784482;8200.138985406445;8246.3815213858;8315;8332.458390204618;8343.541105239028;9792.902070979198;10689.412082335546;11106.278091924;11511.136664750009;11570.826437603275 19:20:41 INFO - PROCESS | 1967 | |3;mootools.html;1350.6758377989274;1497.8502149785022;1523.4363285584332;1678.4545022867571;1845.8984755838433;7576.54540727556;8402.495850248983;8626.18051285127;8637.438566493181;8744.43161194522;8506.829863402729;8594.613242404092;8606.483610983345;8609.870851937227;8610.741677749678;2355.2934119764086;2383.0586918167314;2385.3798012516786;2387.415083304593;2389.4026493376655;2331.8134549236106;2364.9408764780933;2382.60686986647;2382.7736365045293;2383.1182462488905;2353.599888019041;2374.2402431222017;2384.0940442631777;2384.654225137354;2388.7611238876148;2327.9650805237934;2350.811935045192;2356.1871154451774;2359.775821296974;2367.5501654685554;2314.4676724353326;2341.637046257833;2344.0740907341674;2348.2368230325214;2351.047825630605;8449.619767110495;8561.614727337223;8604.698835540788;8656.956715216384;8685.218330350235;5147.510986456318;5366.70483123428;5368.731563421852;5369.62412631116;5379.246905433242;2953.5569664550276;3493.94759078614;3554;3565.16218688608;3568.3398571264397;1030.567161792049;1031.9174466042773;1037.6057098302672;1055.5408397347178;1059.9894001059968;11398.430078496125;11472.426378681115;11539.596114135966;11567.826482602768;11573.652790416385;3406.3527929693278;4372.147431250875;4502.617277531381;4513.50351461339;4561.794719237642;9029.909700902906;9032.232260257952;9036.81926361469;9039.276857851357;9045.954770226239;5173.896522069537;5186.066508028515;5188.92216616751;5190.844274671766;5208.869778255498;3411.914702132417;4352.7823608819435;4527.320901864681;4798.7840547174765;4834.444038935497;3180.6501284858646;3930.1157239620857;3995.360742281221;4013.3979903014197;4024.4969378827645;1267.4486598329756;1304.0741073837492;1304.1848844472204;1314.4873499335076;1316.4668309334734;696.3245651717825;894.7673604862782;909.622506659742;909.7316291693966;914.5655813488539;5489.835304940858;5558.471945165203;5797.043487824461;5877.882442351129;5909.704514774244;982.9115379615869;1007.6799392795461;1024.2164743970868;1042.3276986343765;1070.4380200394855;3695.6858667013066;3740.4763333133383;3740.5137332146646;3868.9226215475533;3886.5336159661024;10279.383237005653;10461.9476902615;10599.788004239872;10607.310524815863;10613.25707200504;10473.21450891171;10561.735956600993;10562.419066951237;10565.577376904992;10629.84055239172;6628.701708423133;7769.339606133542;7946.801329966797;8077.394195435366;8120.75637730869;5782.710864456844;5876.324222714443;5905.114232865105;5920.437558431942;5985.132155837467;9485.098915603006;9566.426014439156;9808.970058143792;9873;9935.155511781579;9107.043760405062;9857.704268871945;9991.750206244902;10117.645882394081;10134.847977280198;6879;6986.6157361343585;7104.751333703295;7113.75101871432;7408.62956852166;8040.195980401913;8063.072746634212;8080.474769140223;8130;8163.387745919199;7320.524165929411;7338.96330518344;7406.629668516445;7425.8143546411775;7443.813904652427;7204.279572042754;7255.746048888263;7281.089863767029;7320.15818180916;7362.77911662651;6760.492963027694;6868.519203655696;6875.5874647521305;6876.553024053421;6883.242843287134;4665.2768820832825;4840.467548569725;4863.513648635108;4947.307376967299;5051.368578927634;4804.159272127294;4907.2393778964315;4974.801007959641;4997.875053123701;5085.389753229636 19:20:41 INFO - PROCESS | 1967 | |4;prototype.html;251.19867622284914;289.9797014209006;302.09070697201423;304.4064075053645;304.58120084883285;4541.591256786888;4850.811551169968;5174.430812610614;5204.687718736874;5217.704210147537;4970.353853998984;5017.824376146827;5146.716930568827;5158.587313014959;5239.161734122532;3506.8071256080907;4008.5347511280497;4013.779242141681;4029.37544680575;4032.717709760318;3317.800931944079;3702.685271751904;4032.213718324928;4052.2098190852794;4054.452648892406;3058.72471477567;3604.6395360464007;3987.7208595398333;3988.8603898863544;4009.2983727847654;3956;3992.2414741199223;3993.103275943918;4045.9595404045876;4046;3493.0917961329983;3494.9864539283576;3525.8589656413715;3541.9905326981943;3572.499850020999;4228.386883901848;4472.46330440346;4688.249880019214;4862.252827152683;5089.796408143669;4528.818847246106;4532.592066713979;4535.523770004135;4556.567126123013;4607.769611519444;4922.78474686389;5395.839894422718;5396.676199428047;5421.376541697716;5425.267588875491;3214.605119186198;3377.5476545085603;3842.3660096084113;3861.4980052593264;3882.650561449455;4214.957850421487;5686.431356864322;5700.572457065737;5729.427057294279;5805.912911306292;3268.2156282492033;3610.151614370621;3687.446882967576;3858.344081506151;3886.3587508060855;3547.4324108142573;3765.755225910362;3983.6215559521797;4006.1787333597126;4021.7989100544855;3856.8842934712;3933.7443066200603;4317.201317756162;4495.685302028892;4611.677182597253;4948.678335908155;5407.728324363782;5435.864103397446;5527;5592.273004509388;1318.5253308808822;1345.825042744437;1355.4239448234657;1360.9928652797057;1365.4674676876027;733.3106879533221;759.7454852624322;847.1528471528471;881.2641444394003;900.2617853360313;4340.978295108504;4652;4740.691855029412;4979.278004689299;5175.792968281302;5382.327209098863;5576.9163462548095;5582.771790206149;5611.9438805612235;5613.831585052455;4666.533346665306;4723.574878260904;4984.028114517707;5034.496550345009;5066.4680208577565;2328.895199716017;2861.7265316933763;2870.62681851358;2875.7555607773575;2915.9125226243245;4670.929936050962;5133.281340612317;5465.07093794056;5703.74517606126;5740.225069615632;4920.450058231646;5157.277981082651;5458.590605704588;5586.413426590231;5719.3136823581435;5008.29883816266;5655.614374478279;5718.828435146952;5764.944579372939;5785.334686511105;4875.951670390883;5005.224190250517;5316.574674026146;5519.834404967857;5528.06022976087;4523.4797998229305;4603.493615702337;4734.4081989751285;5263.473652634706;5432.29380180574;4808.9519104808505;4900;5642.266505354278;5692.857678558069;5696.943030569641;3638.689432210512;3825.5218097737784;3832.6358995895343;3832.965099423084;3869.3228684980577;5401.783928642811;5428.123094759626;5473.233747275305;5490.313710786152;5491.732419210563;5318.627696061393;5352.839414817562;5363.460116323364;5381.246625472359;5386.599484134232;4643.767811609474;5099.847004589868;5218.4259731430275;5333.973330133324;5340.252364668871;5066.392032956069;5185.377754669313;5301.5010093338215;5320.600954928318;5371.555942046281;5353.22378255143;5375.81184658535;5495.6153069284765;5577.888442231214;5610.120609595831;4804.543568361;5418.45815418455;5426.906663201937;5466.262054622575;5517.868836888348 19:20:41 INFO - PROCESS | 1967 | |5;yui.html;709.3225969199415;724.0623392706448;758.0902916500195;785.5561607691653;831.1937420701919;15221.619459513504;16412.75380869285;16413.261403236855;16430.917845410786;16438.75341869873;14779.113253204816;14873;14956.925215373934;14965.775513367282;14970.775438368433;669.5580916595048;685.95541289816;832.2883934236216;844.6832437835811;869.9130086991282;853.2747164909808;866.679328648401;867.8524650809378;868.8957325120995;870.8867847179857;892.1301730812438;895.8835351404341;898.5372533145403;899.0964081098487;899.4962820820328;706.3642721550646;872.4852337121066;891.8706787515806;892.5269607108276;902.5757893789909;675.2673349415841;678.4707927816311;679.2867489136387;680.3162821364497;681.2710399872138;14724.631884202874;14765.18791466469;14791.70416591673;14792.778108328384;14801.037932534458;13651.180929144184;13850.653733656638;14090.647733806634;14154.292285385689;14216.502422415166;11529.250598711056;12573.559925402567;12613.558525451564;12710.04674649405;13282.91536439574;4283.614474697292;4310.267254566731;4536.72779633223;4569.360289559464;4748.3589715388325;102249;103748;103892;104194.47902760514;107342.92657073407;8558.657653693783;10344.896551034393;10374.688759337232;10429.478526073666;10495.527701253463;19578.51053723668;19600.509987250432;19613.50966225856;19658.311959081362;19694.409167724993;7772.106207786064;8048.315893149031;8053.395995300376;8132.959335203286;8133.024037115584;4403;4610.585047345755;4614.123316569841;4655.650826188049;4707.270373092176;3399.5410619566537;3516.4549494828343;3519.6480351964597;3521.5774107107313;3523.823808809549;641.1376698340724;650.6661344244375;676.7868121541699;682.4506272450626;683.5010442376993;593.77139801176;625.8842114208795;633.3316814510061;656.6445463680589;662.7746566167526;8524.488530688179;8574.742757717278;8715.956420217859;9184.12750788674;9264.536773161315;558.0596694569582;572.236064853417;593.7832691067815;595.7438301530384;632.0708558419117;3898.9805050974564;4419.447569053868;4508.954910450919;4522.0304045348585;4523.185826551252;145587.27206363901;159111.33774265007;160892;164797;167529;137240;139090;140586;142735;147779.09424039075;80081.98975128109;80499;117896.41051794858;117987.41006295085;119146;8619.95690021571;8664.35017373712;8669.306455483673;8710.346723995599;8958.507282099547;52915.20627190518;63799.3620063812;63836;63931;64518.67740661267;58026.419735802105;61882.69058654678;75802.24197757951;87712;96622;11464.770704586028;11517;11565;11618.76762464763;11807.23252988553;9320.767045173427;11391.715207119889;12326.506939722669;12497;12543.811842822548;11846.348450834943;11864.347460889734;11969.700757481132;11992;12367.34369410566;11551.104390292207;11911.76176476483;11955.103367247317;12007;12297.56958506448;11590;11846.526138954345;11858.822117668415;11938;12146.635600931986;9588.169820321284;9850.458224797705;10119.065381516353;10217.897821021694;10327.173826094046;8052.194780521901;9111.726648200565;9134.543272836465;9394.436333819993;10149.746256343651 19:20:41 INFO - PROCESS | 1967 | __end_tp_report 19:20:41 INFO - PROCESS | 1967 | __start_cc_report 19:20:41 INFO - PROCESS | 1967 | _x_x_mozilla_cycle_collect,1269 19:20:41 INFO - PROCESS | 1967 | __end_cc_report 19:20:41 INFO - PROCESS | 1967 | __startTimestamp1471918841838__endTimestamp 19:20:41 INFO - PROCESS | 1967 | 19:20:41 INFO - PROCESS | 1967 | ------- Summary: start ------- 19:20:41 INFO - PROCESS | 1967 | Number of tests: 6 19:20:41 INFO - PROCESS | 1967 | 19:20:41 INFO - PROCESS | 1967 | [#0] dojo.html Cycles:165 Average:5713.32 Median:5962.14 stddev:652.64 (10.9%) stddev-sans-first:617.97 19:20:41 INFO - PROCESS | 1967 | Values: 2963.7 3326.8 3341.1 3341.4 3381.5 5487.9 5595.1 5596.9 5664.8 5695.6 5580.7 5601.9 5675.7 5718.0 5719.4 5108.2 5117.0 5209.5 5212.8 5227.9 5299.4 5308.2 5310.7 5313.4 5326.9 5068.5 5120.4 5137.0 5216.3 5229.9 5105.5 5127.3 5163.2 5173.5 5178.1 5119.7 5149.1 5187.9 5201.6 5201.7 5456.6 5707.4 5708.4 5725.5 5749.0 6133.7 6199.0 6203.0 6235.4 6240.5 6211.7 6239.0 6281.5 6284.5 6305.7 5604.6 5784.2 5793.1 5799.0 5804.4 6259.7 6286.0 6288.7 6289.0 6304.3 6093.0 6102.5 6106.7 6125.0 6125.5 5969.8 6219.8 6226.8 6230.8 6232.2 5993.1 6057.8 6069.6 6071.1 6147.9 6127.6 6128.1 6133.8 6171.8 6177.9 4790.3 4863.4 4863.7 4871.7 4887.6 4333.8 4355.9 4384.1 4399.7 4433.3 5721.2 5741.5 5745.2 5745.5 5746.5 6130.0 6230.9 6276.4 6290.2 6307.4 4751.4 5609.6 5676.1 5699.0 5730.6 5390.1 5392.8 5398.6 5407.1 5411.9 6291.4 6296.9 6299.3 6308.5 6344.8 6129.2 6218.7 6237.9 6268.8 6317.6 6274.5 6277.2 6280.5 6300.2 6310.2 6122.4 6166.4 6171.4 6198.6 6217.5 6089.3 6121.6 6133.2 6197.3 6246.8 6085.3 6086.8 6093.0 6105.2 6121.3 6049.7 6055.4 6062.6 6081.4 6084.2 5745.1 5802.8 5858.4 5868.9 5962.1 6082.1 6098.3 6133.7 6145.6 6150.0 5877.4 5941.6 5947.9 5963.5 5980.8 19:20:41 INFO - PROCESS | 1967 | 19:20:41 INFO - PROCESS | 1967 | [#1] ext.html Cycles:180 Average:59023.28 Median:3551.17 stddev:126024.91 (3548.8%) stddev-sans-first:126301.75 19:20:41 INFO - PROCESS | 1967 | Values: 464.0 481.8 490.1 514.0 523.6 30631.5 32147.0 40075.0 41096.6 41221.8 42073.8 42361.4 42458.0 42505.8 42512.8 9570.9 9781.7 9785.7 9796.7 9803.2 8650.6 8847.9 11749.8 11996.6 12001.5 11853.5 11933.3 11959.0 11967.8 11982.3 10964.6 11552.6 11965.3 11976.8 11979.2 9783.8 11803.5 11806.8 11812.2 11863.4 39978.6 40205.4 40258.6 40275.2 40370.4 3427.2 3499.4 3504.4 3597.9 3939.7 30664.8 31777.0 31837.2 31860.4 31912.7 3226.6 3284.9 3385.9 3420.2 3430.8 503893.5 504200.0 504652.0 505409.0 506412.5 57534.8 58093.7 58117.0 58132.7 58203.7 60730.4 60763.7 60859.0 60917.7 60947.4 16034.0 16192.1 16209.4 16225.5 16230.3 11539.5 11712.3 12740.6 12803.2 12841.0 14331.3 14405.8 14412.7 14431.3 15734.1 5278.3 5988.0 6003.1 6036.1 6063.8 850.9 929.6 978.5 1009.8 1132.9 28137.4 29603.0 29968.1 30002.8 30282.7 1000.5 1054.1 1095.9 1119.8 1157.8 13188.4 15588.2 15653.4 15883.4 15908.8 185432.0 185618.0 186446.0 186906.0 186958.0 50017.5 56735.1 56906.1 56938.9 61453.7 1354.7 1371.4 1400.9 1409.5 1414.4 27907.4 30278.2 31167.7 31490.5 31659.4 43888.6 50308.0 50375.7 50400.0 50484.0 607441.0 609970.0 610804.0 611772.0 612677.0 25690.0 26257.8 39764.8 39962.8 40018.4 47115.8 47938.0 48243.3 48735.5 48983.5 38786.4 39053.8 39233.8 39390.8 40496.4 37585.6 38127.4 39059.6 39633.0 40347.0 37174.1 37741.9 37850.2 37963.0 37998.6 30129.5 32036.2 32868.7 34677.3 34749.0 25061.9 25145.5 25189.5 25256.5 25710.1 19:20:41 INFO - PROCESS | 1967 | 19:20:41 INFO - PROCESS | 1967 | [#2] jquery.html Cycles:180 Average:35279.95 Median:2308.35 stddev:100970.11 (4374.1%) stddev-sans-first:101219.29 19:20:41 INFO - PROCESS | 1967 | Values: 347.8 361.6 365.0 374.7 397.3 15368.0 16602.3 20045.3 20461.8 20536.9 20726.4 21197.2 21419.2 21442.2 21927.5 5741.7 5780.8 6043.1 6053.4 6067.2 6081.1 6111.1 6116.2 6119.8 6130.1 3808.9 3960.0 6141.7 6756.0 6768.8 5082.7 6816.5 6819.8 6826.0 6826.5 5840.3 5899.8 5907.6 5908.9 5916.7 13187.9 13261.8 13357.7 13415.1 13494.4 2193.9 2239.0 2285.1 2331.6 2406.5 9603.1 9848.1 9923.3 10073.8 10455.6 1783.5 1857.7 1880.3 1890.6 1915.3 117124.4 125357.0 125913.4 128879.4 129799.0 9233.7 10960.5 11327.8 11430.2 11631.9 14390.2 14396.7 14399.7 14418.5 14418.9 7177.3 8210.6 8304.3 8476.6 8583.8 8353.3 8371.5 8384.6 8497.5 8561.2 4481.7 4489.0 4495.4 4511.0 4579.3 1835.9 1881.6 1882.9 1890.3 1893.6 825.7 893.3 919.9 922.3 923.0 20649.8 21389.1 21446.6 21495.9 21564.5 925.5 933.2 942.9 976.1 1021.7 10713.7 11115.7 11136.3 11211.9 11240.1 591237.0 593610.0 596995.0 613849.0 614123.0 127055.8 134885.3 139024.3 143510.3 145246.0 974.5 1007.7 1015.8 1016.2 1034.5 16961.1 18869.9 19633.7 19837.9 19969.0 41821.2 46472.8 55632.9 55912.0 57022.7 78002.8 78832.6 79385.6 80658.6 81022.6 7666.3 7962.4 7989.4 8389.1 8397.1 9414.2 9949.4 11851.1 12513.8 13743.0 11136.4 11433.9 11451.9 11529.5 11653.2 11179.7 11451.3 11518.9 11704.1 11723.9 11451.4 11575.5 11857.5 11866.7 11961.8 8200.1 8246.4 8315.0 8332.5 8343.5 9792.9 10689.4 11106.3 11511.1 11570.8 19:20:41 INFO - PROCESS | 1967 | 19:20:41 INFO - PROCESS | 1967 | [#3] mootools.html Cycles:180 Average:5569.20 Median:4927.27 stddev:3104.31 (63.0%) stddev-sans-first:3096.83 19:20:41 INFO - PROCESS | 1967 | Values: 1350.7 1497.9 1523.4 1678.5 1845.9 7576.5 8402.5 8626.2 8637.4 8744.4 8506.8 8594.6 8606.5 8609.9 8610.7 2355.3 2383.1 2385.4 2387.4 2389.4 2331.8 2364.9 2382.6 2382.8 2383.1 2353.6 2374.2 2384.1 2384.7 2388.8 2328.0 2350.8 2356.2 2359.8 2367.6 2314.5 2341.6 2344.1 2348.2 2351.0 8449.6 8561.6 8604.7 8657.0 8685.2 5147.5 5366.7 5368.7 5369.6 5379.2 2953.6 3493.9 3554.0 3565.2 3568.3 1030.6 1031.9 1037.6 1055.5 1060.0 11398.4 11472.4 11539.6 11567.8 11573.7 3406.4 4372.1 4502.6 4513.5 4561.8 9029.9 9032.2 9036.8 9039.3 9046.0 5173.9 5186.1 5188.9 5190.8 5208.9 3411.9 4352.8 4527.3 4798.8 4834.4 3180.7 3930.1 3995.4 4013.4 4024.5 1267.4 1304.1 1304.2 1314.5 1316.5 696.3 894.8 909.6 909.7 914.6 5489.8 5558.5 5797.0 5877.9 5909.7 982.9 1007.7 1024.2 1042.3 1070.4 3695.7 3740.5 3740.5 3868.9 3886.5 10279.4 10461.9 10599.8 10607.3 10613.3 10473.2 10561.7 10562.4 10565.6 10629.8 6628.7 7769.3 7946.8 8077.4 8120.8 5782.7 5876.3 5905.1 5920.4 5985.1 9485.1 9566.4 9809.0 9873.0 9935.2 9107.0 9857.7 9991.8 10117.6 10134.8 6879.0 6986.6 7104.8 7113.8 7408.6 8040.2 8063.1 8080.5 8130.0 8163.4 7320.5 7339.0 7406.6 7425.8 7443.8 7204.3 7255.7 7281.1 7320.2 7362.8 6760.5 6868.5 6875.6 6876.6 6883.2 4665.3 4840.5 4863.5 4947.3 5051.4 4804.2 4907.2 4974.8 4997.9 5085.4 19:20:41 INFO - PROCESS | 1967 | 19:20:41 INFO - PROCESS | 1967 | [#4] prototype.html Cycles:180 Average:4387.10 Median:4910.23 stddev:1331.77 (27.1%) stddev-sans-first:1298.83 19:20:41 INFO - PROCESS | 1967 | Values: 251.2 290.0 302.1 304.4 304.6 4541.6 4850.8 5174.4 5204.7 5217.7 4970.4 5017.8 5146.7 5158.6 5239.2 3506.8 4008.5 4013.8 4029.4 4032.7 3317.8 3702.7 4032.2 4052.2 4054.5 3058.7 3604.6 3987.7 3988.9 4009.3 3956.0 3992.2 3993.1 4046.0 4046.0 3493.1 3495.0 3525.9 3542.0 3572.5 4228.4 4472.5 4688.2 4862.3 5089.8 4528.8 4532.6 4535.5 4556.6 4607.8 4922.8 5395.8 5396.7 5421.4 5425.3 3214.6 3377.5 3842.4 3861.5 3882.7 4215.0 5686.4 5700.6 5729.4 5805.9 3268.2 3610.2 3687.4 3858.3 3886.4 3547.4 3765.8 3983.6 4006.2 4021.8 3856.9 3933.7 4317.2 4495.7 4611.7 4948.7 5407.7 5435.9 5527.0 5592.3 1318.5 1345.8 1355.4 1361.0 1365.5 733.3 759.7 847.2 881.3 900.3 4341.0 4652.0 4740.7 4979.3 5175.8 5382.3 5576.9 5582.8 5611.9 5613.8 4666.5 4723.6 4984.0 5034.5 5066.5 2328.9 2861.7 2870.6 2875.8 2915.9 4670.9 5133.3 5465.1 5703.7 5740.2 4920.5 5157.3 5458.6 5586.4 5719.3 5008.3 5655.6 5718.8 5764.9 5785.3 4876.0 5005.2 5316.6 5519.8 5528.1 4523.5 4603.5 4734.4 5263.5 5432.3 4809.0 4900.0 5642.3 5692.9 5696.9 3638.7 3825.5 3832.6 3833.0 3869.3 5401.8 5428.1 5473.2 5490.3 5491.7 5318.6 5352.8 5363.5 5381.2 5386.6 4643.8 5099.8 5218.4 5334.0 5340.3 5066.4 5185.4 5301.5 5320.6 5371.6 5353.2 5375.8 5495.6 5577.9 5610.1 4804.5 5418.5 5426.9 5466.3 5517.9 19:20:41 INFO - PROCESS | 1967 | 19:20:41 INFO - PROCESS | 1967 | [#5] yui.html Cycles:180 Average:24329.95 Median:4515.49 stddev:40480.04 (896.5%) stddev-sans-first:40554.74 19:20:41 INFO - PROCESS | 1967 | Values: 709.3 724.1 758.1 785.6 831.2 15221.6 16412.8 16413.3 16430.9 16438.8 14779.1 14873.0 14956.9 14965.8 14970.8 669.6 686.0 832.3 844.7 869.9 853.3 866.7 867.9 868.9 870.9 892.1 895.9 898.5 899.1 899.5 706.4 872.5 891.9 892.5 902.6 675.3 678.5 679.3 680.3 681.3 14724.6 14765.2 14791.7 14792.8 14801.0 13651.2 13850.7 14090.6 14154.3 14216.5 11529.3 12573.6 12613.6 12710.0 13282.9 4283.6 4310.3 4536.7 4569.4 4748.4 102249.0 103748.0 103892.0 104194.5 107342.9 8558.7 10344.9 10374.7 10429.5 10495.5 19578.5 19600.5 19613.5 19658.3 19694.4 7772.1 8048.3 8053.4 8133.0 8133.0 4403.0 4610.6 4614.1 4655.7 4707.3 3399.5 3516.5 3519.6 3521.6 3523.8 641.1 650.7 676.8 682.5 683.5 593.8 625.9 633.3 656.6 662.8 8524.5 8574.7 8716.0 9184.1 9264.5 558.1 572.2 593.8 595.7 632.1 3899.0 4419.4 4509.0 4522.0 4523.2 145587.3 159111.3 160892.0 164797.0 167529.0 137240.0 139090.0 140586.0 142735.0 147779.1 80082.0 80499.0 117896.4 117987.4 119146.0 8620.0 8664.4 8669.3 8710.3 8958.5 52915.2 63799.4 63836.0 63931.0 64518.7 58026.4 61882.7 75802.2 87712.0 96622.0 11464.8 11517.0 11565.0 11618.8 11807.2 9320.8 11391.7 12326.5 12497.0 12543.8 11846.3 11864.3 11969.7 11992.0 12367.3 11551.1 11911.8 11955.1 12007.0 12297.6 11590.0 11846.5 11858.8 11938.0 12146.6 9588.2 9850.5 10119.1 10217.9 10327.2 8052.2 9111.7 9134.5 9394.4 10149.7 19:20:41 INFO - PROCESS | 1967 | -------- Summary: end -------- 19:20:41 INFO - PROCESS | 1967 | 19:20:42 INFO - TEST-INFO | 1967: exit 0 19:20:42 INFO - TEST-OK | dromaeo_css | took 1117547ms 19:20:42 INFO - TEST-START | kraken 19:20:42 INFO - operating with platform_type : mac_ 19:20:42 INFO - Initialising browser for kraken test... 19:20:42 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmp_Pzm2R/profile http://localhost:51368/getInfo.html) 19:20:45 INFO - PROCESS | 1970 | __metrics Screen width/height:1600/1200 19:20:45 INFO - PROCESS | 1970 | colorDepth:24 19:20:45 INFO - PROCESS | 1970 | Browser inner width/height: 1024/689 19:20:45 INFO - PROCESS | 1970 | __metrics 19:20:45 INFO - PROCESS | 1970 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:20:45 INFO - PROCESS | 1970 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:20:45 INFO - TEST-INFO | 1970: exit 0 19:20:45 INFO - Browser initialized. 19:20:45 INFO - Running cycle 1/1 for kraken test... 19:20:45 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmp_Pzm2R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:20:57 INFO - PROCESS | 1971 | RSS: Main: 231514112 19:20:57 INFO - PROCESS | 1971 | 19:21:00 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51368/tests/kraken/driver.html?testName=audio-beat-detection) 19:21:01 INFO - PROCESS | 1971 | RSS: Main: 297369600 19:21:01 INFO - PROCESS | 1971 | 19:21:03 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51368/tests/kraken/driver.html?testName=audio-dft) 19:21:04 INFO - PROCESS | 1971 | RSS: Main: 297877504 19:21:04 INFO - PROCESS | 1971 | 19:21:08 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51368/tests/kraken/driver.html?testName=audio-fft) 19:21:08 INFO - PROCESS | 1971 | RSS: Main: 386494464 19:21:08 INFO - PROCESS | 1971 | 19:21:10 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51368/tests/kraken/driver.html?testName=audio-oscillator) 19:21:11 INFO - PROCESS | 1971 | RSS: Main: 283537408 19:21:11 INFO - PROCESS | 1971 | 19:21:13 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51368/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:21:14 INFO - PROCESS | 1971 | RSS: Main: 287408128 19:21:14 INFO - PROCESS | 1971 | 19:21:16 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51368/tests/kraken/driver.html?testName=imaging-darkroom) 19:21:17 INFO - PROCESS | 1971 | RSS: Main: 327446528 19:21:17 INFO - PROCESS | 1971 | 19:21:20 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51368/tests/kraken/driver.html?testName=imaging-desaturate) 19:21:21 INFO - PROCESS | 1971 | RSS: Main: 327991296 19:21:21 INFO - PROCESS | 1971 | 19:21:24 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51368/tests/kraken/driver.html?testName=json-parse-financial) 19:21:24 INFO - PROCESS | 1971 | RSS: Main: 327462912 19:21:24 INFO - PROCESS | 1971 | 19:21:26 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51368/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:21:27 INFO - PROCESS | 1971 | RSS: Main: 305659904 19:21:27 INFO - PROCESS | 1971 | 19:21:28 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51368/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:21:29 INFO - PROCESS | 1971 | RSS: Main: 305782784 19:21:29 INFO - PROCESS | 1971 | 19:21:31 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51368/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:21:32 INFO - PROCESS | 1971 | RSS: Main: 305569792 19:21:32 INFO - PROCESS | 1971 | 19:21:35 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51368/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:21:36 INFO - PROCESS | 1971 | RSS: Main: 313638912 19:21:36 INFO - PROCESS | 1971 | 19:21:38 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51368/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:21:39 INFO - PROCESS | 1971 | RSS: Main: 314941440 19:21:39 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:51368/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51368/tests/kraken/driver.html?testName=ai-astar) 19:21:41 INFO - PROCESS | 1971 | RSS: Main: 357826560 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | __start_tp_report 19:21:41 INFO - PROCESS | 1971 | _x_x_mozilla_page_load 19:21:41 INFO - PROCESS | 1971 | _x_x_mozilla_page_load_details 19:21:41 INFO - PROCESS | 1971 | |i|pagename|runs| 19:21:41 INFO - PROCESS | 1971 | |0;ai-astar;97;112;106;111;113;114;117;112;112;112 19:21:41 INFO - PROCESS | 1971 | |1;audio-beat-detection;144;108;132;166;107;124;126;107;157;106 19:21:41 INFO - PROCESS | 1971 | |2;audio-dft;185;185;185;183;186;184;182;182;181;185 19:21:41 INFO - PROCESS | 1971 | |3;audio-fft;79;73;107;96;56;63;64;86;88;55 19:21:41 INFO - PROCESS | 1971 | |4;audio-oscillator;84;85;130;70;72;75;114;70;69;114 19:21:41 INFO - PROCESS | 1971 | |5;imaging-gaussian-blur;87;83;86;87;135;116;87;83;86;87 19:21:41 INFO - PROCESS | 1971 | |6;imaging-darkroom;92;93;92;93;97;92;92;135;130;93 19:21:41 INFO - PROCESS | 1971 | |7;imaging-desaturate;85;84;87;87;82;133;69;123;140;140 19:21:41 INFO - PROCESS | 1971 | |8;json-parse-financial;56;53;52;56;55;55;54;54;53;54 19:21:41 INFO - PROCESS | 1971 | |9;json-stringify-tinderbox;66;66;64;82;73;75;73;71;71;71 19:21:41 INFO - PROCESS | 1971 | |10;stanford-crypto-aes;88;85;84;86;88;81;84;87;82;97 19:21:41 INFO - PROCESS | 1971 | |11;stanford-crypto-ccm;134;144;150;150;151;141;134;138;174;149 19:21:41 INFO - PROCESS | 1971 | |12;stanford-crypto-pbkdf2;166;166;167;169;175;200;229;168;168;168 19:21:41 INFO - PROCESS | 1971 | |13;stanford-crypto-sha256-iterative;68;67;70;84;88;84;85;66;67;67 19:21:41 INFO - PROCESS | 1971 | __end_tp_report 19:21:41 INFO - PROCESS | 1971 | __start_cc_report 19:21:41 INFO - PROCESS | 1971 | _x_x_mozilla_cycle_collect,2066 19:21:41 INFO - PROCESS | 1971 | __end_cc_report 19:21:41 INFO - PROCESS | 1971 | __startTimestamp1471918901698__endTimestamp 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | ------- Summary: start ------- 19:21:41 INFO - PROCESS | 1971 | Number of tests: 14 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.50 stddev:5.50 (4.9%) stddev-sans-first:2.89 19:21:41 INFO - PROCESS | 1971 | Values: 97.0 112.0 106.0 111.0 113.0 114.0 117.0 112.0 112.0 112.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:129.00 stddev:21.96 (17.0%) stddev-sans-first:22.49 19:21:41 INFO - PROCESS | 1971 | Values: 144.0 108.0 132.0 166.0 107.0 124.0 126.0 107.0 157.0 106.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#2] audio-dft Cycles:10 Average:183.80 Median:185.00 stddev:1.69 (0.9%) stddev-sans-first:1.73 19:21:41 INFO - PROCESS | 1971 | Values: 185.0 185.0 185.0 183.0 186.0 184.0 182.0 182.0 181.0 185.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#3] audio-fft Cycles:10 Average:76.70 Median:76.00 stddev:17.55 (23.1%) stddev-sans-first:18.60 19:21:41 INFO - PROCESS | 1971 | Values: 79.0 73.0 107.0 96.0 56.0 63.0 64.0 86.0 88.0 55.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#4] audio-oscillator Cycles:10 Average:88.30 Median:71.00 stddev:22.54 (31.8%) stddev-sans-first:23.86 19:21:41 INFO - PROCESS | 1971 | Values: 84.0 85.0 130.0 70.0 72.0 75.0 114.0 70.0 69.0 114.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:86.50 stddev:17.42 (20.1%) stddev-sans-first:18.30 19:21:41 INFO - PROCESS | 1971 | Values: 87.0 83.0 86.0 87.0 135.0 116.0 87.0 83.0 86.0 87.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#6] imaging-darkroom Cycles:10 Average:100.90 Median:92.50 stddev:16.76 (18.1%) stddev-sans-first:17.47 19:21:41 INFO - PROCESS | 1971 | Values: 92.0 93.0 92.0 93.0 97.0 92.0 92.0 135.0 130.0 93.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#7] imaging-desaturate Cycles:10 Average:103.00 Median:83.00 stddev:27.55 (33.2%) stddev-sans-first:28.44 19:21:41 INFO - PROCESS | 1971 | Values: 85.0 84.0 87.0 87.0 82.0 133.0 69.0 123.0 140.0 140.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.50 stddev:1.32 (2.4%) stddev-sans-first:1.22 19:21:41 INFO - PROCESS | 1971 | Values: 56.0 53.0 52.0 56.0 55.0 55.0 54.0 54.0 53.0 54.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:72.00 stddev:5.20 (7.2%) stddev-sans-first:5.17 19:21:41 INFO - PROCESS | 1971 | Values: 66.0 66.0 64.0 82.0 73.0 75.0 73.0 71.0 71.0 71.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:86.50 stddev:4.47 (5.2%) stddev-sans-first:4.69 19:21:41 INFO - PROCESS | 1971 | Values: 88.0 85.0 84.0 86.0 88.0 81.0 84.0 87.0 82.0 97.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:149.50 stddev:11.68 (7.8%) stddev-sans-first:11.48 19:21:41 INFO - PROCESS | 1971 | Values: 134.0 144.0 150.0 150.0 151.0 141.0 134.0 138.0 174.0 149.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:168.50 stddev:20.77 (12.3%) stddev-sans-first:21.60 19:21:41 INFO - PROCESS | 1971 | Values: 166.0 166.0 167.0 169.0 175.0 200.0 229.0 168.0 168.0 168.0 19:21:41 INFO - PROCESS | 1971 | 19:21:41 INFO - PROCESS | 1971 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:77.00 stddev:9.29 (12.1%) stddev-sans-first:9.54 19:21:41 INFO - PROCESS | 1971 | Values: 68.0 67.0 70.0 84.0 88.0 84.0 85.0 66.0 67.0 67.0 19:21:41 INFO - PROCESS | 1971 | -------- Summary: end -------- 19:21:41 INFO - PROCESS | 1971 | 19:21:42 INFO - TEST-INFO | 1971: exit 0 19:21:42 INFO - TEST-OK | kraken | took 59996ms 19:21:42 INFO - SUITE-END | took 1177s 19:21:42 INFO - Completed test suite (00:19:37) 19:21:42 INFO - javascript benchmark 19:21:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2963.74808141308, 3326.783759055661, 3341.097903566037, 3341.3818443587925, 3381.509681096241, 5487.945120548803, 5595.104783234673, 5596.944030559694, 5664.801731939383, 5695.572832037598, 5580.693061881605, 5601.8599535011645, 5675.659460432377, 5718.0, 5719.370869204384, 5108.157154069574, 5116.951025039876, 5209.453007434222, 5212.76542555584, 5227.947720522784, 5299.364076310848, 5308.23030660554, 5310.681359118446, 5313.362396512424, 5326.866828329284, 5068.467810879878, 5120.359955005624, 5136.998285334361, 5216.269722238909, 5229.875576750978, 5105.489451054865, 5127.333446651949, 5163.199704045879, 5173.508516690908, 5178.14560597501, 5119.66722163062, 5149.0731668299695, 5187.922181167285, 5201.55786758126, 5201.713905735183, 5456.563474922036, 5707.429257074301, 5708.40061793514, 5725.456081672276, 5749.022666146723, 6133.693315334215, 6198.969005154945, 6203.0, 6235.376462353728, 6240.475655150025, 6211.720472578745, 6239.0, 6281.497480201573, 6284.497240220771, 6305.747770089191, 5604.607677462538, 5784.16129661201, 5793.073108302651, 5798.971005144989, 5804.390538993345, 6259.749610015549, 6285.994240921431, 6288.654124023223, 6288.968555157195, 6304.338044505353, 6093.025115981422, 6102.542309326818, 6106.6946652668075, 6125.0, 6125.479334256549, 5969.761209551656, 6219.782307619212, 6226.782062627786, 6230.750769969241, 6232.220972378453, 5993.130996005642, 6057.757689692451, 6069.635821850703, 6071.089336599545, 6147.907781383283, 6127.601705889103, 6128.111423843607, 6133.84665383369, 6171.84570385735, 6177.876442471125, 4790.329353890458, 4863.392075990501, 4863.659543831968, 4871.683340582851, 4887.560119589254, 4333.761643109659, 4355.934660980088, 4384.145091707086, 4399.6920215584605, 4433.334999750064, 5721.170430287668, 5741.540676745851, 5745.195672605839, 5745.482906538432, 5746.5402767779315, 6130.0, 6230.875382492325, 6276.372362763778, 6290.150829638032, 6307.400796924285, 4751.429828420612, 5609.551235901118, 5676.148577713294, 5699.031164702011, 5730.627509211888, 5390.137577987503, 5392.838214853561, 5398.595105367113, 5407.053765591085, 5411.891762164734, 6291.402316779899, 6296.937030629727, 6299.338569450325, 6308.463780578702, 6344.777932772516, 6129.233845769279, 6218.689065546614, 6237.937620623918, 6268.811935641938, 6317.557770955989, 6274.498040156685, 6277.2153480814895, 6280.528960328085, 6300.243970723543, 6310.211223597051, 6122.418370254997, 6166.41419065206, 6171.444569988723, 6198.566100372931, 6217.4715149212825, 6089.330173680803, 6121.602095863755, 6133.233345831771, 6197.349278325711, 6246.750129994749, 6085.269767627913, 6086.78696245647, 6093.025115981422, 6105.175801266772, 6121.265448146251, 6049.727762250621, 6055.364186760326, 6062.575619706755, 6081.391860813883, 6084.178635884276, 5745.080787074048, 5802.82591522255, 5858.384869588632, 5868.853278668067, 5962.135490353961, 6082.118092876596, 6098.29869564988, 6133.723982420713, 6145.631262124287, 6150.04674275488, 5877.35349111606, 5941.554383421174, 5947.9405205949115, 5963.463288304073, 5980.760769569342], "value": 5669.507779323668, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.97680115994194, 481.7663433234883, 490.10066527921265, 514.0413129513457, 523.573287770467, 30631.540526892113, 32147.0, 40075.0, 41096.58903410965, 41221.793891030575, 42073.78963105188, 42361.364579531255, 42458.0, 42505.78747106268, 42512.787436062856, 9570.85643715343, 9781.706548803548, 9785.657501987433, 9796.657117000907, 9803.166730827852, 8650.567471626426, 8847.867281990775, 11749.765004699944, 11996.64010079699, 12001.51993920242, 11853.466594003248, 11933.28400295985, 11959.0432765379, 11967.760644787098, 11982.281063136195, 10964.56141754329, 11552.613686357607, 11965.341906195155, 11976.760464790656, 11979.22135061227, 9783.755406114833, 11803.468843902045, 11806.763864722658, 11812.173147879652, 11863.406829658568, 39978.60021399778, 40205.386649867665, 40258.597414025775, 40275.194496110205, 40370.394444083366, 3427.2288735034417, 3499.3526197653514, 3504.4392897136336, 3597.9100522487147, 3939.72421930465, 30664.8466757667, 31777.0, 31837.204069898205, 31860.362792744014, 31912.680873191435, 3226.58054452922, 3284.868605255787, 3385.86456541738, 3420.2304481491465, 3430.811305378203, 503893.4805325987, 504200.0, 504652.0, 505409.0, 506412.4679376616, 57534.84930301454, 58093.709531452914, 58117.0, 58132.709336453045, 58203.70898145567, 60730.39269607336, 60763.69618151881, 60859.0, 60917.69541152354, 60947.390526095056, 16034.037957722574, 16192.109433981008, 16209.35162593483, 16225.51323460298, 16230.269637866324, 11539.48072336747, 11712.297262164297, 12740.554080607135, 12803.231806091664, 12840.972722182203, 14331.283435828167, 14405.78391324131, 14412.711745765026, 14431.278436078153, 15734.055956642638, 5278.287431196739, 5988.0, 6003.129546215774, 6036.094585812163, 6063.8478689049825, 850.9404341696022, 929.5584597316221, 978.500964508106, 1009.8131845608586, 1132.943352832355, 28137.43725125486, 29603.0, 29968.100956971324, 30002.84998574993, 30282.697173028428, 1000.5047501486665, 1054.1303424674675, 1095.906847917936, 1119.8320251962107, 1157.8494795676509, 13188.406521706545, 15588.220588970733, 15653.37386504527, 15883.444079457395, 15908.840911590967, 185432.0, 185618.0, 186446.0, 186906.0, 186958.0, 50017.49982500129, 56735.14897276544, 56906.14640780391, 56938.86122277614, 61453.69273153606, 1354.7155097429454, 1371.4171477122184, 1400.9159450433006, 1409.5207629406052, 1414.3635364085997, 27907.440853347878, 30278.243043924078, 31167.688323117385, 31490.527642085846, 31659.366812664077, 43888.561114389726, 50308.0, 50375.74812125916, 50400.0, 50484.0, 607441.0, 609970.0, 610804.0, 611772.0, 612677.0, 25690.0, 26257.81839817251, 39764.80705578837, 39962.80018599889, 40018.39972400475, 47115.82210444766, 47938.0, 48243.27635085407, 48735.512644873095, 48983.510164897896, 38786.4182037264, 39053.80473097616, 39233.803830980665, 39390.80304598458, 40496.3925541123, 37585.624143758214, 38127.42808857814, 39059.632912849054, 39633.0, 40347.0, 37174.07064823401, 37741.86774396773, 37850.24299514049, 37963.0, 37998.62001379951, 30129.548056779604, 32036.19909502281, 32868.671313287516, 34677.306453871286, 34749.0, 25061.87469062643, 25145.497090057732, 25189.496210076064, 25256.494870102128, 25710.10014649553], "value": 19560.56150858143, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.7583079759565, 361.59140171606083, 364.9781013139212, 374.7133442916168, 397.28687006822753, 15368.0, 16602.335906563738, 20045.298414555495, 20461.795382046173, 20536.89731551342, 20726.37820865373, 21197.152113915465, 21419.196346760586, 21442.24952126676, 21927.451813704665, 5741.655500669973, 5780.82657520274, 6043.123747056674, 6053.424924632153, 6067.180930574382, 6081.118237855509, 6111.144439778434, 6116.235470566179, 6119.785807496739, 6130.1111338855835, 3808.9238215235678, 3960.0297927007923, 6141.723622437001, 6756.02037704535, 6768.796936091925, 5082.74586270685, 6816.522843400964, 6819.761308354184, 6825.965870170618, 6826.453883689318, 5840.299164100293, 5899.793507227227, 5907.645541267538, 5908.911366329466, 5916.704164791743, 13187.86812131876, 13261.801072983913, 13357.666058348523, 13415.060945733803, 13494.392752326168, 2193.890305484719, 2238.9888050559807, 2285.074544809355, 2331.6269396896414, 2406.4946361264156, 9603.087706667855, 9848.06443387877, 9923.255755818342, 10073.848892266622, 10455.634052808191, 1783.5273652482103, 1857.6841936870628, 1880.2666959885655, 1890.5557194059386, 1915.2530513099903, 117124.41437792756, 125357.0, 125913.37043314817, 128879.35560322138, 129799.0, 9233.722988310361, 10960.451977401099, 11327.830082548768, 11430.1998860079, 11631.941840290914, 14390.208538530482, 14396.712065758835, 14399.712005760035, 14418.495352662816, 14418.927905360406, 7177.318154775288, 8210.630521626541, 8304.294134998472, 8476.618552165168, 8583.828323433496, 8353.289970352467, 8371.497710137412, 8384.580770961427, 8497.532635705096, 8561.22948934599, 4481.731096134177, 4488.977555112268, 4495.415595972503, 4511.0, 4579.335996280587, 1835.862310326731, 1881.5766452548066, 1882.9246830126915, 1890.2816929566677, 1893.5644801695687, 825.7275099217244, 893.3389291923929, 919.9356045076914, 922.2714055895767, 922.9616681233612, 20649.793502065088, 21389.144434222457, 21446.57106857854, 21495.89252053761, 21564.46088847791, 925.5372313843078, 933.1741408853214, 942.9434233945848, 976.1458723616835, 1021.6985989133224, 10713.732156696145, 11115.666530004113, 11136.33182009082, 11211.943940280247, 11240.100791936626, 591237.0, 593610.0, 596995.0, 613849.0, 614123.0, 127055.7533096016, 134885.32557337152, 139024.30487847497, 143510.2824485892, 145246.0, 974.4543055888724, 1007.652359935821, 1015.8323876560432, 1016.187050359724, 1034.4879284754095, 16961.067141306852, 18869.90565047166, 19633.70549441788, 19837.900810495856, 19969.001549922155, 41821.16357672769, 46472.83817904579, 55632.887342253736, 55912.0, 57022.7148864253, 78002.82995755182, 78832.63502094947, 79385.60307198427, 80658.59670701608, 81022.59488702755, 7666.348360389206, 7962.363010958993, 7989.400794940287, 8389.077201507951, 8397.118302578141, 9414.15272625467, 9949.403035817584, 11851.111166662362, 12513.812292815797, 13743.037987340791, 11136.38749868765, 11433.942830285796, 11451.942740286246, 11529.463518026021, 11653.184277100521, 11179.720506987065, 11451.25566838153, 11518.884811151782, 11704.122190835891, 11723.944844963995, 11451.370174640475, 11575.536978521102, 11857.466414011562, 11866.703332416759, 11961.760764784482, 8200.138985406445, 8246.3815213858, 8315.0, 8332.458390204618, 8343.541105239028, 9792.902070979198, 10689.412082335546, 11106.278091924, 11511.136664750009, 11570.826437603275], "value": 9442.18701817849, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.6758377989274, 1497.8502149785022, 1523.4363285584332, 1678.4545022867571, 1845.8984755838433, 7576.54540727556, 8402.495850248983, 8626.18051285127, 8637.438566493181, 8744.43161194522, 8506.829863402729, 8594.613242404092, 8606.483610983345, 8609.870851937227, 8610.741677749678, 2355.2934119764086, 2383.0586918167314, 2385.3798012516786, 2387.415083304593, 2389.4026493376655, 2331.8134549236106, 2364.9408764780933, 2382.60686986647, 2382.7736365045293, 2383.1182462488905, 2353.599888019041, 2374.2402431222017, 2384.0940442631777, 2384.654225137354, 2388.7611238876148, 2327.9650805237934, 2350.811935045192, 2356.1871154451774, 2359.775821296974, 2367.5501654685554, 2314.4676724353326, 2341.637046257833, 2344.0740907341674, 2348.2368230325214, 2351.047825630605, 8449.619767110495, 8561.614727337223, 8604.698835540788, 8656.956715216384, 8685.218330350235, 5147.510986456318, 5366.70483123428, 5368.731563421852, 5369.62412631116, 5379.246905433242, 2953.5569664550276, 3493.94759078614, 3554.0, 3565.16218688608, 3568.3398571264397, 1030.567161792049, 1031.9174466042773, 1037.6057098302672, 1055.5408397347178, 1059.9894001059968, 11398.430078496125, 11472.426378681115, 11539.596114135966, 11567.826482602768, 11573.652790416385, 3406.3527929693278, 4372.147431250875, 4502.617277531381, 4513.50351461339, 4561.794719237642, 9029.909700902906, 9032.232260257952, 9036.81926361469, 9039.276857851357, 9045.954770226239, 5173.896522069537, 5186.066508028515, 5188.92216616751, 5190.844274671766, 5208.869778255498, 3411.914702132417, 4352.7823608819435, 4527.320901864681, 4798.7840547174765, 4834.444038935497, 3180.6501284858646, 3930.1157239620857, 3995.360742281221, 4013.3979903014197, 4024.4969378827645, 1267.4486598329756, 1304.0741073837492, 1304.1848844472204, 1314.4873499335076, 1316.4668309334734, 696.3245651717825, 894.7673604862782, 909.622506659742, 909.7316291693966, 914.5655813488539, 5489.835304940858, 5558.471945165203, 5797.043487824461, 5877.882442351129, 5909.704514774244, 982.9115379615869, 1007.6799392795461, 1024.2164743970868, 1042.3276986343765, 1070.4380200394855, 3695.6858667013066, 3740.4763333133383, 3740.5137332146646, 3868.9226215475533, 3886.5336159661024, 10279.383237005653, 10461.9476902615, 10599.788004239872, 10607.310524815863, 10613.25707200504, 10473.21450891171, 10561.735956600993, 10562.419066951237, 10565.577376904992, 10629.84055239172, 6628.701708423133, 7769.339606133542, 7946.801329966797, 8077.394195435366, 8120.75637730869, 5782.710864456844, 5876.324222714443, 5905.114232865105, 5920.437558431942, 5985.132155837467, 9485.098915603006, 9566.426014439156, 9808.970058143792, 9873.0, 9935.155511781579, 9107.043760405062, 9857.704268871945, 9991.750206244902, 10117.645882394081, 10134.847977280198, 6879.0, 6986.6157361343585, 7104.751333703295, 7113.75101871432, 7408.62956852166, 8040.195980401913, 8063.072746634212, 8080.474769140223, 8130.0, 8163.387745919199, 7320.524165929411, 7338.96330518344, 7406.629668516445, 7425.8143546411775, 7443.813904652427, 7204.279572042754, 7255.746048888263, 7281.089863767029, 7320.15818180916, 7362.77911662651, 6760.492963027694, 6868.519203655696, 6875.5874647521305, 6876.553024053421, 6883.242843287134, 4665.2768820832825, 4840.467548569725, 4863.513648635108, 4947.307376967299, 5051.368578927634, 4804.159272127294, 4907.2393778964315, 4974.801007959641, 4997.875053123701, 5085.389753229636], "value": 4500.848859194465, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [251.19867622284914, 289.9797014209006, 302.09070697201423, 304.4064075053645, 304.58120084883285, 4541.591256786888, 4850.811551169968, 5174.430812610614, 5204.687718736874, 5217.704210147537, 4970.353853998984, 5017.824376146827, 5146.716930568827, 5158.587313014959, 5239.161734122532, 3506.8071256080907, 4008.5347511280497, 4013.779242141681, 4029.37544680575, 4032.717709760318, 3317.800931944079, 3702.685271751904, 4032.213718324928, 4052.2098190852794, 4054.452648892406, 3058.72471477567, 3604.6395360464007, 3987.7208595398333, 3988.8603898863544, 4009.2983727847654, 3956.0, 3992.2414741199223, 3993.103275943918, 4045.9595404045876, 4046.0, 3493.0917961329983, 3494.9864539283576, 3525.8589656413715, 3541.9905326981943, 3572.499850020999, 4228.386883901848, 4472.46330440346, 4688.249880019214, 4862.252827152683, 5089.796408143669, 4528.818847246106, 4532.592066713979, 4535.523770004135, 4556.567126123013, 4607.769611519444, 4922.78474686389, 5395.839894422718, 5396.676199428047, 5421.376541697716, 5425.267588875491, 3214.605119186198, 3377.5476545085603, 3842.3660096084113, 3861.4980052593264, 3882.650561449455, 4214.957850421487, 5686.431356864322, 5700.572457065737, 5729.427057294279, 5805.912911306292, 3268.2156282492033, 3610.151614370621, 3687.446882967576, 3858.344081506151, 3886.3587508060855, 3547.4324108142573, 3765.755225910362, 3983.6215559521797, 4006.1787333597126, 4021.7989100544855, 3856.8842934712, 3933.7443066200603, 4317.201317756162, 4495.685302028892, 4611.677182597253, 4948.678335908155, 5407.728324363782, 5435.864103397446, 5527.0, 5592.273004509388, 1318.5253308808822, 1345.825042744437, 1355.4239448234657, 1360.9928652797057, 1365.4674676876027, 733.3106879533221, 759.7454852624322, 847.1528471528471, 881.2641444394003, 900.2617853360313, 4340.978295108504, 4652.0, 4740.691855029412, 4979.278004689299, 5175.792968281302, 5382.327209098863, 5576.9163462548095, 5582.771790206149, 5611.9438805612235, 5613.831585052455, 4666.533346665306, 4723.574878260904, 4984.028114517707, 5034.496550345009, 5066.4680208577565, 2328.895199716017, 2861.7265316933763, 2870.62681851358, 2875.7555607773575, 2915.9125226243245, 4670.929936050962, 5133.281340612317, 5465.07093794056, 5703.74517606126, 5740.225069615632, 4920.450058231646, 5157.277981082651, 5458.590605704588, 5586.413426590231, 5719.3136823581435, 5008.29883816266, 5655.614374478279, 5718.828435146952, 5764.944579372939, 5785.334686511105, 4875.951670390883, 5005.224190250517, 5316.574674026146, 5519.834404967857, 5528.06022976087, 4523.4797998229305, 4603.493615702337, 4734.4081989751285, 5263.473652634706, 5432.29380180574, 4808.9519104808505, 4900.0, 5642.266505354278, 5692.857678558069, 5696.943030569641, 3638.689432210512, 3825.5218097737784, 3832.6358995895343, 3832.965099423084, 3869.3228684980577, 5401.783928642811, 5428.123094759626, 5473.233747275305, 5490.313710786152, 5491.732419210563, 5318.627696061393, 5352.839414817562, 5363.460116323364, 5381.246625472359, 5386.599484134232, 4643.767811609474, 5099.847004589868, 5218.4259731430275, 5333.973330133324, 5340.252364668871, 5066.392032956069, 5185.377754669313, 5301.5010093338215, 5320.600954928318, 5371.555942046281, 5353.22378255143, 5375.81184658535, 5495.6153069284765, 5577.888442231214, 5610.120609595831, 4804.543568361, 5418.45815418455, 5426.906663201937, 5466.262054622575, 5517.868836888348], "value": 3959.494918122105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.3225969199415, 724.0623392706448, 758.0902916500195, 785.5561607691653, 831.1937420701919, 15221.619459513504, 16412.75380869285, 16413.261403236855, 16430.917845410786, 16438.75341869873, 14779.113253204816, 14873.0, 14956.925215373934, 14965.775513367282, 14970.775438368433, 669.5580916595048, 685.95541289816, 832.2883934236216, 844.6832437835811, 869.9130086991282, 853.2747164909808, 866.679328648401, 867.8524650809378, 868.8957325120995, 870.8867847179857, 892.1301730812438, 895.8835351404341, 898.5372533145403, 899.0964081098487, 899.4962820820328, 706.3642721550646, 872.4852337121066, 891.8706787515806, 892.5269607108276, 902.5757893789909, 675.2673349415841, 678.4707927816311, 679.2867489136387, 680.3162821364497, 681.2710399872138, 14724.631884202874, 14765.18791466469, 14791.70416591673, 14792.778108328384, 14801.037932534458, 13651.180929144184, 13850.653733656638, 14090.647733806634, 14154.292285385689, 14216.502422415166, 11529.250598711056, 12573.559925402567, 12613.558525451564, 12710.04674649405, 13282.91536439574, 4283.614474697292, 4310.267254566731, 4536.72779633223, 4569.360289559464, 4748.3589715388325, 102249.0, 103748.0, 103892.0, 104194.47902760514, 107342.92657073407, 8558.657653693783, 10344.896551034393, 10374.688759337232, 10429.478526073666, 10495.527701253463, 19578.51053723668, 19600.509987250432, 19613.50966225856, 19658.311959081362, 19694.409167724993, 7772.106207786064, 8048.315893149031, 8053.395995300376, 8132.959335203286, 8133.024037115584, 4403.0, 4610.585047345755, 4614.123316569841, 4655.650826188049, 4707.270373092176, 3399.5410619566537, 3516.4549494828343, 3519.6480351964597, 3521.5774107107313, 3523.823808809549, 641.1376698340724, 650.6661344244375, 676.7868121541699, 682.4506272450626, 683.5010442376993, 593.77139801176, 625.8842114208795, 633.3316814510061, 656.6445463680589, 662.7746566167526, 8524.488530688179, 8574.742757717278, 8715.956420217859, 9184.12750788674, 9264.536773161315, 558.0596694569582, 572.236064853417, 593.7832691067815, 595.7438301530384, 632.0708558419117, 3898.9805050974564, 4419.447569053868, 4508.954910450919, 4522.0304045348585, 4523.185826551252, 145587.27206363901, 159111.33774265007, 160892.0, 164797.0, 167529.0, 137240.0, 139090.0, 140586.0, 142735.0, 147779.09424039075, 80081.98975128109, 80499.0, 117896.41051794858, 117987.41006295085, 119146.0, 8619.95690021571, 8664.35017373712, 8669.306455483673, 8710.346723995599, 8958.507282099547, 52915.20627190518, 63799.3620063812, 63836.0, 63931.0, 64518.67740661267, 58026.419735802105, 61882.69058654678, 75802.24197757951, 87712.0, 96622.0, 11464.770704586028, 11517.0, 11565.0, 11618.76762464763, 11807.23252988553, 9320.767045173427, 11391.715207119889, 12326.506939722669, 12497.0, 12543.811842822548, 11846.348450834943, 11864.347460889734, 11969.700757481132, 11992.0, 12367.34369410566, 11551.104390292207, 11911.76176476483, 11955.103367247317, 12007.0, 12297.56958506448, 11590.0, 11846.526138954345, 11858.822117668415, 11938.0, 12146.635600931986, 9588.169820321284, 9850.458224797705, 10119.065381516353, 10217.897821021694, 10327.173826094046, 8052.194780521901, 9111.726648200565, 9134.543272836465, 9394.436333819993, 10149.746256343651], "value": 7543.521493389978, "unit": "score"}], "name": "dromaeo_css", "value": 7212.610496706, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 112.0, 106.0, 111.0, 113.0, 114.0, 117.0, 112.0, 112.0, 112.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 132.0, 166.0, 107.0, 124.0, 126.0, 107.0, 157.0, 106.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 185.0, 183.0, 186.0, 184.0, 182.0, 182.0, 181.0, 185.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 107.0, 96.0, 56.0, 63.0, 64.0, 86.0, 88.0, 55.0], "value": 76.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 130.0, 70.0, 72.0, 75.0, 114.0, 70.0, 69.0, 114.0], "value": 88.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 87.0, 135.0, 116.0, 87.0, 83.0, 86.0, 87.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 97.0, 92.0, 92.0, 135.0, 130.0, 93.0], "value": 100.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 87.0, 87.0, 82.0, 133.0, 69.0, 123.0, 140.0, 140.0], "value": 103.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 52.0, 56.0, 55.0, 55.0, 54.0, 54.0, 53.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 64.0, 82.0, 73.0, 75.0, 73.0, 71.0, 71.0, 71.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 84.0, 86.0, 88.0, 81.0, 84.0, 87.0, 82.0, 97.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 144.0, 150.0, 150.0, 151.0, 141.0, 134.0, 138.0, 174.0, 149.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 167.0, 169.0, 175.0, 200.0, 229.0, 168.0, 168.0, 168.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 70.0, 84.0, 88.0, 84.0, 85.0, 66.0, 67.0, 67.0], "value": 74.6, "unit": "score"}], "name": "kraken", "value": 1495.0, "alertThreshold": 2.0}]} 19:21:42 INFO - Return code: 0 19:21:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:21:42 INFO - # TBPL SUCCESS # 19:21:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:21:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:21:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:21:42 INFO - 2016-08-22 19:21:42.996 screenresolution[1975:15294] starting screenresolution argv=screenresolution get 19:21:42 INFO - 2016-08-22 19:21:42.999 screenresolution[1975:15294] Display 0: 1600x1200x32@60 19:21:43 INFO - 2016-08-22 19:21:43.005 screenresolution[1976:15297] starting screenresolution argv=screenresolution list 19:21:43 INFO - Available Modes on Display 0 19:21:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:21:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:21:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:21:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:21:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:21:43 INFO - Intel Iris: 19:21:43 INFO - Chipset Model: Intel Iris 19:21:43 INFO - Type: GPU 19:21:43 INFO - Bus: Built-In 19:21:43 INFO - VRAM (Dynamic, Max): 1536 MB 19:21:43 INFO - Vendor: Intel (0x8086) 19:21:43 INFO - Device ID: 0x0a2e 19:21:43 INFO - Revision ID: 0x0009 19:21:43 INFO - Displays: 19:21:43 INFO - Display: 19:21:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:21:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:21:43 INFO - Display Serial Number: 1600x1200 60 19:21:43 INFO - Main Display: Yes 19:21:43 INFO - Mirror: Off 19:21:43 INFO - Online: Yes 19:21:43 INFO - Rotation: Supported 19:21:43 INFO - Return code: 0 19:21:43 INFO - Running post-action listener: _resource_record_post_action 19:21:43 INFO - [mozharness: 2016-08-23 02:21:43.243570Z] Finished run-tests step (success) 19:21:43 INFO - Running post-run listener: _resource_record_post_run 19:21:43 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 745472; Write bytes: 704326144; Read time: 9; Write time: 2061 19:21:43 INFO - TinderboxPrint: CPU usage
53.5% 19:21:43 INFO - TinderboxPrint: I/O read bytes / time
745,472 / 9 19:21:43 INFO - TinderboxPrint: I/O write bytes / time
704,326,144 / 2,061 19:21:43 INFO - TinderboxPrint: CPU idle
2,253.2 (46.5%) 19:21:43 INFO - TinderboxPrint: CPU system
970.4 (20.0%) 19:21:43 INFO - TinderboxPrint: CPU user
1,626.2 (33.5%) 19:21:43 INFO - TinderboxPrint: Swap in / out
407,257,088 / 0 19:21:43 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 405924352; Write bytes: 400930304; Read time: 30083; Write time: 726 19:21:43 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 745472; Write bytes: 293381120; Read time: 9; Write time: 1298 19:21:43 INFO - Running post-run listener: _upload_blobber_files 19:21:43 INFO - Blob upload gear active. 19:21:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:21:43 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 19:21:43 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:21:43 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:21:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:21:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:21:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:21:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:21:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:21:45 INFO - (blobuploader) - INFO - Done attempting. 19:21:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:21:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:21:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:21:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:21:46 INFO - (blobuploader) - INFO - Done attempting. 19:21:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:21:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:21:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:21:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:21:48 INFO - (blobuploader) - INFO - Done attempting. 19:21:48 INFO - (blobuploader) - INFO - Iteration through files over. 19:21:48 INFO - Return code: 0 19:21:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:21:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:21:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b50522e99c8d611d8d45cdc8a42c8aab0ae53c92bb6aee2d793559f7b6c86d97e2e287f7e21d35cc084727cc96cac3c550b38c5602b58800900c8b937e48361e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a1d2035c18c0d6dddc4b3c12a967c609cbcf12fe8a38db859cdb693b91542d5066fbe9f089a9708a531b6b0c92defe64c8057d56d781de54b6c93d6c2ea198f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f888276dfb43b2c368a87046bc40a1cfbbb6b5bbff947402f21d1cfd39ac2bd6dec8f09117634b55cda858765916a4da7cfecfa4e288f29be914a79047295ab3"} 19:21:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:21:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:21:48 INFO - Contents: 19:21:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b50522e99c8d611d8d45cdc8a42c8aab0ae53c92bb6aee2d793559f7b6c86d97e2e287f7e21d35cc084727cc96cac3c550b38c5602b58800900c8b937e48361e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a1d2035c18c0d6dddc4b3c12a967c609cbcf12fe8a38db859cdb693b91542d5066fbe9f089a9708a531b6b0c92defe64c8057d56d781de54b6c93d6c2ea198f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f888276dfb43b2c368a87046bc40a1cfbbb6b5bbff947402f21d1cfd39ac2bd6dec8f09117634b55cda858765916a4da7cfecfa4e288f29be914a79047295ab3"} 19:21:48 INFO - Running post-run listener: copy_logs_to_upload_dir 19:21:48 INFO - Copying logs to upload dir... 19:21:48 INFO - mkdir: /builds/slave/test/build/upload/logs 19:21:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.016808 ========= master_lag: 0.91 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-22 19:21:49.425034) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 19:21:49.428749) ========= 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.NLWwB20CZD/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.boPrnoroIq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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/Larch/sha512/b50522e99c8d611d8d45cdc8a42c8aab0ae53c92bb6aee2d793559f7b6c86d97e2e287f7e21d35cc084727cc96cac3c550b38c5602b58800900c8b937e48361e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a1d2035c18c0d6dddc4b3c12a967c609cbcf12fe8a38db859cdb693b91542d5066fbe9f089a9708a531b6b0c92defe64c8057d56d781de54b6c93d6c2ea198f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f888276dfb43b2c368a87046bc40a1cfbbb6b5bbff947402f21d1cfd39ac2bd6dec8f09117634b55cda858765916a4da7cfecfa4e288f29be914a79047295ab3"} build_url:https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011129 build_url: 'https://queue.taskcluster.net/v1/task/OAuwGBnHQgGiIaIkl3CKwQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b50522e99c8d611d8d45cdc8a42c8aab0ae53c92bb6aee2d793559f7b6c86d97e2e287f7e21d35cc084727cc96cac3c550b38c5602b58800900c8b937e48361e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a1d2035c18c0d6dddc4b3c12a967c609cbcf12fe8a38db859cdb693b91542d5066fbe9f089a9708a531b6b0c92defe64c8057d56d781de54b6c93d6c2ea198f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f888276dfb43b2c368a87046bc40a1cfbbb6b5bbff947402f21d1cfd39ac2bd6dec8f09117634b55cda858765916a4da7cfecfa4e288f29be914a79047295ab3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 19:21:49.473649) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:21:49.473946) ========= 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.NLWwB20CZD/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.boPrnoroIq/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.005187 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 19:21:49.522592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 19:21:49.522871) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 19:21:49.995390) ========= ========= Total master_lag: 3.20 =========