builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0341 starttime: 1471992224.42 results: success (0) buildid: 20160823145014 builduid: 768790183eed48899efdec01509f26ce revision: 3f7afc56fae2a3bdc25fb25a3f69d62b32a54c94 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:44.421255) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:44.421867) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:44.422311) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:44.448247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:44.448632) ========= 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.Bw6ZfFvJor/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.objGopAqpl/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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-23 15:43:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 150M=0s 2016-08-23 15:43:44 (150 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109022 ========= master_lag: 0.38 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:44.940767) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:44.941207) ========= 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.Bw6ZfFvJor/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.objGopAqpl/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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.020635 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:45.031515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 15:43:45.031828) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3f7afc56fae2a3bdc25fb25a3f69d62b32a54c94 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 3f7afc56fae2a3bdc25fb25a3f69d62b32a54c94 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Bw6ZfFvJor/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.objGopAqpl/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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-23 15:43:45,110 truncating revision to first 12 chars 2016-08-23 15:43:45,110 Setting DEBUG logging. 2016-08-23 15:43:45,110 attempt 1/10 2016-08-23 15:43:45,111 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3f7afc56fae2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 15:43:46,554 unpacking tar archive at: fx-team-3f7afc56fae2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.700613 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 15:43:46.758982) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:46.759359) ========= script_repo_revision: 3f7afc56fae2a3bdc25fb25a3f69d62b32a54c94 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:46.759754) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:46.760048) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 15:43:46.779536) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-23 15:43:46.779981) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Bw6ZfFvJor/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.objGopAqpl/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 15:43:46 INFO - MultiFileLogger online at 20160823 15:43:46 in /builds/slave/test 15:43:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:43:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:43:46 INFO - {'append_to_log': False, 15:43:46 INFO - 'base_work_dir': '/builds/slave/test', 15:43:46 INFO - 'blob_upload_branch': 'Fx-Team', 15:43:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:43:46 INFO - 'branch': 'Fx-Team', 15:43:46 INFO - 'buildbot_json_path': 'buildprops.json', 15:43:46 INFO - 'config_files': ('talos/mac_config.py',), 15:43:46 INFO - 'default_actions': ('clobber', 15:43:46 INFO - 'read-buildbot-config', 15:43:46 INFO - 'download-and-extract', 15:43:46 INFO - 'populate-webroot', 15:43:46 INFO - 'create-virtualenv', 15:43:46 INFO - 'install', 15:43:46 INFO - 'run-tests'), 15:43:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:43:46 INFO - 'download_minidump_stackwalk': True, 15:43:46 INFO - 'download_symbols': 'ondemand', 15:43:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:43:46 INFO - 'tooltool.py': '/tools/tooltool.py', 15:43:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:43:46 INFO - '/tools/misc-python/virtualenv.py')}, 15:43:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:43:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:43:46 INFO - 'installer_path': 'installer.exe', 15:43:46 INFO - 'log_level': 'info', 15:43:46 INFO - 'log_name': 'talos', 15:43:46 INFO - 'log_to_console': True, 15:43:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:43:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:43:46 INFO - 'opt_config_files': (), 15:43:46 INFO - 'pip_index': False, 15:43:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:43:46 INFO - 'cmd': ('bash', 15:43:46 INFO - '-c', 15:43:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:43:46 INFO - 'enabled': True, 15:43:46 INFO - 'halt_on_failure': False, 15:43:46 INFO - 'name': 'check_screen_resolution'},), 15:43:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:43:46 INFO - 'cmd': ('bash', 15:43:46 INFO - '-c', 15:43:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:43:46 INFO - 'enabled': True, 15:43:46 INFO - 'halt_on_failure': False, 15:43:46 INFO - 'name': 'check_screen_resolution'},), 15:43:46 INFO - 'run_cmd_checks_enabled': True, 15:43:46 INFO - 'sps_profile': False, 15:43:46 INFO - 'sps_profile_interval': 0, 15:43:46 INFO - 'suite': 'dromaeojs', 15:43:46 INFO - 'system_bits': '32', 15:43:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:43:46 INFO - 'title': 't-yosemite-r7-0341', 15:43:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:43:46 INFO - 'use_talos_json': True, 15:43:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:43:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:43:46 INFO - 'work_dir': 'build'} 15:43:46 INFO - [mozharness: 2016-08-23 22:43:46.970398Z] Running clobber step. 15:43:46 INFO - Running pre-action listener: _resource_record_pre_action 15:43:46 INFO - Running main action method: clobber 15:43:46 INFO - rmtree: /builds/slave/test/build 15:43:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:43:48 INFO - Running post-action listener: _resource_record_post_action 15:43:48 INFO - [mozharness: 2016-08-23 22:43:48.595521Z] Finished clobber step (success) 15:43:48 INFO - [mozharness: 2016-08-23 22:43:48.595712Z] Running read-buildbot-config step. 15:43:48 INFO - Running pre-action listener: _resource_record_pre_action 15:43:48 INFO - Running main action method: read_buildbot_config 15:43:48 INFO - Using buildbot properties: 15:43:48 INFO - { 15:43:48 INFO - "project": "", 15:43:48 INFO - "product": "firefox", 15:43:48 INFO - "script_repo_revision": "production", 15:43:48 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:43:48 INFO - "repository": "", 15:43:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:43:48 INFO - "buildid": "20160823145014", 15:43:48 INFO - "pgo_build": "False", 15:43:48 INFO - "basedir": "/builds/slave/test", 15:43:48 INFO - "buildnumber": 7, 15:43:48 INFO - "slavename": "t-yosemite-r7-0341", 15:43:48 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:43:48 INFO - "platform": "yosemite_r7", 15:43:48 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:43:48 INFO - "branch": "fx-team", 15:43:48 INFO - "repo_path": "integration/fx-team", 15:43:48 INFO - "revision": "3f7afc56fae2a3bdc25fb25a3f69d62b32a54c94", 15:43:48 INFO - "stage_platform": "macosx64", 15:43:48 INFO - "builduid": "768790183eed48899efdec01509f26ce", 15:43:48 INFO - "slavebuilddir": "test" 15:43:48 INFO - } 15:43:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:43:48 INFO - Running post-action listener: _resource_record_post_action 15:43:48 INFO - [mozharness: 2016-08-23 22:43:48.601521Z] Finished read-buildbot-config step (success) 15:43:48 INFO - [mozharness: 2016-08-23 22:43:48.601672Z] Running download-and-extract step. 15:43:48 INFO - Running pre-action listener: _resource_record_pre_action 15:43:48 INFO - Running main action method: download_and_extract 15:43:48 INFO - mkdir: /builds/slave/test/build/tests 15:43:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:48 INFO - trying https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:43:48 INFO - Downloading https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/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 15:43:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/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 15:43:49 INFO - Downloaded 1199 bytes. 15:43:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:43:49 INFO - Using the following test package requirements: 15:43:49 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:43:49 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:43:49 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:43:49 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:43:49 INFO - u'jsshell-mac.zip'], 15:43:49 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:43:49 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:43:49 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:43:49 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:43:49 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:43:49 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:43:49 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:43:49 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:49 INFO - trying https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:43:49 INFO - Downloading https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/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 15:43:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/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 15:43:50 INFO - Downloaded 16346134 bytes. 15:43:50 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:43:57 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 15:43:57 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')]} 15:43:57 INFO - trying https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:43:57 INFO - Downloading https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/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 15:43:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/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 15:43:58 INFO - Downloaded 16346134 bytes. 15:43:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:44: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')]} 15:44:04 INFO - trying https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:44:04 INFO - Downloading https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/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 15:44:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/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 15:44:07 INFO - Downloaded 14413981 bytes. 15:44:07 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 15:44: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')]} 15:44:07 INFO - trying https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:44:07 INFO - Downloading https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:44:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/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 15:44:10 INFO - Downloaded 111484248 bytes. 15:44:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:44:10 INFO - mkdir: /builds/slave/test/properties 15:44:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:44:10 INFO - Writing to file /builds/slave/test/properties/build_url 15:44:10 INFO - Contents: 15:44:10 INFO - build_url:https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:44:11 INFO - Running post-action listener: _resource_record_post_action 15:44:11 INFO - Running post-action listener: set_extra_try_arguments 15:44:11 INFO - [mozharness: 2016-08-23 22:44:11.152262Z] Finished download-and-extract step (success) 15:44:11 INFO - [mozharness: 2016-08-23 22:44:11.152376Z] Running populate-webroot step. 15:44:11 INFO - Running pre-action listener: _resource_record_pre_action 15:44:11 INFO - Running main action method: populate_webroot 15:44:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:44:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:44:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:44:11 INFO - '--tppagecycles', 15:44:11 INFO - '7'], 15:44:11 INFO - 'tests': ['tsvgm']}}, 15:44:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:44:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:44:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:44:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:44:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:44:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:44:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:11 INFO - 'talos_options': ['--disable-e10s'], 15:44:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:11 INFO - 'talos_options': ['--disable-e10s'], 15:44:11 INFO - 'tests': ['damp', 'tps']}, 15:44:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:11 INFO - 'tests': ['damp', 'tps']}, 15:44:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:44:11 INFO - 'tests': ['dromaeo_dom']}, 15:44:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:44:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:44:11 INFO - 'tests': ['basic_compositor_video']}, 15:44:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:44:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:44:11 INFO - 'tests': ['a11yr', 15:44:11 INFO - 'ts_paint', 15:44:11 INFO - 'tpaint', 15:44:11 INFO - 'sessionrestore', 15:44:11 INFO - 'sessionrestore_no_auto_restore', 15:44:11 INFO - 'tabpaint']}, 15:44:11 INFO - 'other-e10s': {'tests': ['a11yr', 15:44:11 INFO - 'ts_paint', 15:44:11 INFO - 'tpaint', 15:44:11 INFO - 'sessionrestore', 15:44:11 INFO - 'sessionrestore_no_auto_restore', 15:44:11 INFO - 'tabpaint']}, 15:44:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:44:11 INFO - 'ts_paint', 15:44:11 INFO - 'tpaint', 15:44:11 INFO - 'sessionrestore', 15:44:11 INFO - 'sessionrestore_no_auto_restore', 15:44:11 INFO - 'tabpaint']}, 15:44:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:44:11 INFO - 'ts_paint', 15:44:11 INFO - 'tpaint', 15:44:11 INFO - 'sessionrestore', 15:44:11 INFO - 'sessionrestore_no_auto_restore', 15:44:11 INFO - 'tabpaint']}, 15:44:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:44:11 INFO - 'tests': ['a11yr', 15:44:11 INFO - 'ts_paint', 15:44:11 INFO - 'tpaint', 15:44:11 INFO - 'sessionrestore', 15:44:11 INFO - 'sessionrestore_no_auto_restore', 15:44:11 INFO - 'tabpaint']}, 15:44:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:44:11 INFO - 'tests': ['a11yr', 15:44:11 INFO - 'ts_paint', 15:44:11 INFO - 'tpaint', 15:44:11 INFO - 'sessionrestore', 15:44:11 INFO - 'sessionrestore_no_auto_restore', 15:44:11 INFO - 'tabpaint']}, 15:44:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:44:11 INFO - 'tests': ['tsvgx', 15:44:11 INFO - 'tsvgr_opacity', 15:44:11 INFO - 'tart', 15:44:11 INFO - 'tscrollx', 15:44:11 INFO - 'cart']}, 15:44:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:44:11 INFO - 'tsvgr_opacity', 15:44:11 INFO - 'tart', 15:44:11 INFO - 'tscrollx', 15:44:11 INFO - 'cart']}, 15:44:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:11 INFO - 'talos_options': ['--disable-e10s'], 15:44:11 INFO - 'tests': ['tp5o']}, 15:44:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:11 INFO - 'tests': ['tp5o']}, 15:44:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:11 INFO - 'talos_options': ['--disable-e10s', 15:44:11 INFO - '--xperf_path', 15:44:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:11 INFO - 'tests': ['tp5n']}, 15:44:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:11 INFO - 'talos_options': ['--xperf_path', 15:44:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:11 INFO - 'tests': ['tp5n']}}, 15:44:11 INFO - 'talos.zip': {'path': '', 15:44:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:44:11 INFO - Running post-action listener: _resource_record_post_action 15:44:11 INFO - [mozharness: 2016-08-23 22:44:11.171098Z] Finished populate-webroot step (success) 15:44:11 INFO - [mozharness: 2016-08-23 22:44:11.171222Z] Running create-virtualenv step. 15:44:11 INFO - Running pre-action listener: _resource_record_pre_action 15:44:11 INFO - Running main action method: create_virtualenv 15:44:11 INFO - Creating virtualenv /builds/slave/test/build/venv 15:44:11 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 15:44:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:44:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:44:11 INFO - Using real prefix '/tools/python27' 15:44:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:44:12 INFO - Installing distribute.............................................................................................................................................................................................done. 15:44:15 INFO - Installing pip.................done. 15:44:15 INFO - Return code: 0 15:44:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:44:15 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')]} 15:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:15 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')]} 15:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:15 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 0x10ae5fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3cadd85c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc3d30>, '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 0x10ab28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adbbb20>, '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.Bw6ZfFvJor/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.objGopAqpl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 15:44:15 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 15:44:15 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 15:44:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bw6ZfFvJor/Render', 15:44:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:15 INFO - 'HOME': '/Users/cltbld', 15:44:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:15 INFO - 'LOGNAME': 'cltbld', 15:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:15 INFO - 'MOZ_NO_REMOTE': '1', 15:44:15 INFO - 'NO_EM_RESTART': '1', 15:44:15 INFO - 'PAGER': '/bin/cat', 15:44:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:15 INFO - 'PWD': '/builds/slave/test', 15:44:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:15 INFO - 'SHELL': '/bin/bash', 15:44:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.objGopAqpl/Listeners', 15:44:15 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 15:44:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:15 INFO - 'USER': 'cltbld', 15:44:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:15 INFO - 'XPC_FLAGS': '0x0', 15:44:15 INFO - 'XPC_SERVICE_NAME': '0', 15:44:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:15 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 15:44:15 INFO - Cleaning up... 15:44:15 INFO - Return code: 0 15:44:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:44:15 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')]} 15:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:15 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')]} 15:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:15 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 0x10ae5fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3cadd85c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc3d30>, '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 0x10ab28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adbbb20>, '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.Bw6ZfFvJor/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.objGopAqpl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 15:44:15 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 15:44:15 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 15:44:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bw6ZfFvJor/Render', 15:44:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:15 INFO - 'HOME': '/Users/cltbld', 15:44:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:15 INFO - 'LOGNAME': 'cltbld', 15:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:15 INFO - 'MOZ_NO_REMOTE': '1', 15:44:15 INFO - 'NO_EM_RESTART': '1', 15:44:15 INFO - 'PAGER': '/bin/cat', 15:44:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:15 INFO - 'PWD': '/builds/slave/test', 15:44:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:15 INFO - 'SHELL': '/bin/bash', 15:44:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.objGopAqpl/Listeners', 15:44:15 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 15:44:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:15 INFO - 'USER': 'cltbld', 15:44:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:15 INFO - 'XPC_FLAGS': '0x0', 15:44:15 INFO - 'XPC_SERVICE_NAME': '0', 15:44:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:15 INFO - Downloading/unpacking psutil>=3.1.1 15:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:44:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:18 INFO - Installing collected packages: psutil 15:44:18 INFO - Running setup.py install for psutil 15:44:19 INFO - building 'psutil._psutil_osx' extension 15:44:19 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 15:44:19 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 15:44:19 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 15:44:19 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 15:44:19 INFO - building 'psutil._psutil_posix' extension 15:44:19 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 15:44:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:44:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:44:19 INFO - ^ 15:44:19 INFO - 1 warning generated. 15:44:19 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 15:44:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:19 INFO - Successfully installed psutil 15:44:19 INFO - Cleaning up... 15:44:19 INFO - Return code: 0 15:44:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:44: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')]} 15:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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')]} 15:44:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:19 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 0x10ae5fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3cadd85c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc3d30>, '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 0x10ab28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adbbb20>, '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.Bw6ZfFvJor/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.objGopAqpl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 15:44:19 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 15:44:19 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 15:44:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bw6ZfFvJor/Render', 15:44:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:19 INFO - 'HOME': '/Users/cltbld', 15:44:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:19 INFO - 'LOGNAME': 'cltbld', 15:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:19 INFO - 'MOZ_NO_REMOTE': '1', 15:44:19 INFO - 'NO_EM_RESTART': '1', 15:44:19 INFO - 'PAGER': '/bin/cat', 15:44:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:19 INFO - 'PWD': '/builds/slave/test', 15:44:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:19 INFO - 'SHELL': '/bin/bash', 15:44:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.objGopAqpl/Listeners', 15:44:19 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 15:44:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:19 INFO - 'USER': 'cltbld', 15:44:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:19 INFO - 'XPC_FLAGS': '0x0', 15:44:19 INFO - 'XPC_SERVICE_NAME': '0', 15:44:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:44:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:44:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:44:22 INFO - Installing collected packages: mozsystemmonitor 15:44:22 INFO - Running setup.py install for mozsystemmonitor 15:44:22 INFO - Successfully installed mozsystemmonitor 15:44:22 INFO - Cleaning up... 15:44:22 INFO - Return code: 0 15:44:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:44:22 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')]} 15:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:22 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')]} 15:44:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:22 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 0x10ae5fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3cadd85c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc3d30>, '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 0x10ab28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adbbb20>, '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.Bw6ZfFvJor/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.objGopAqpl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 15:44:22 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 15:44:22 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 15:44:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bw6ZfFvJor/Render', 15:44:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:22 INFO - 'HOME': '/Users/cltbld', 15:44:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:22 INFO - 'LOGNAME': 'cltbld', 15:44:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:22 INFO - 'MOZ_NO_REMOTE': '1', 15:44:22 INFO - 'NO_EM_RESTART': '1', 15:44:22 INFO - 'PAGER': '/bin/cat', 15:44:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:22 INFO - 'PWD': '/builds/slave/test', 15:44:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:22 INFO - 'SHELL': '/bin/bash', 15:44:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.objGopAqpl/Listeners', 15:44:22 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 15:44:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:22 INFO - 'USER': 'cltbld', 15:44:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:22 INFO - 'XPC_FLAGS': '0x0', 15:44:22 INFO - 'XPC_SERVICE_NAME': '0', 15:44:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:23 INFO - Downloading/unpacking jsonschema==2.5.1 15:44:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:44:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:44:25 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 15:44:25 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 15:44:25 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 15:44:25 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 15:44:25 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 15:44:25 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 15:44:25 INFO - Downloading functools32-3.2.3-2.tar.gz 15:44:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:44:26 INFO - warning: no files found matching '*.txt' 15:44:26 INFO - no previously-included directories found matching 'build' 15:44:26 INFO - no previously-included directories found matching 'dist' 15:44:26 INFO - no previously-included directories found matching '.git*' 15:44:26 INFO - Installing collected packages: jsonschema, functools32 15:44:26 INFO - Running setup.py install for functools32 15:44:26 INFO - warning: no files found matching '*.txt' 15:44:26 INFO - no previously-included directories found matching 'build' 15:44:26 INFO - no previously-included directories found matching 'dist' 15:44:26 INFO - no previously-included directories found matching '.git*' 15:44:26 INFO - Successfully installed jsonschema functools32 15:44:26 INFO - Cleaning up... 15:44:26 INFO - Return code: 0 15:44:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:44:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ae5fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3cadd85c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc3d30>, '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 0x10ab28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adbbb20>, '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.Bw6ZfFvJor/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.objGopAqpl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 15:44:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:44:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:44:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bw6ZfFvJor/Render', 15:44:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:26 INFO - 'HOME': '/Users/cltbld', 15:44:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:26 INFO - 'LOGNAME': 'cltbld', 15:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:26 INFO - 'MOZ_NO_REMOTE': '1', 15:44:26 INFO - 'NO_EM_RESTART': '1', 15:44:26 INFO - 'PAGER': '/bin/cat', 15:44:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:26 INFO - 'PWD': '/builds/slave/test', 15:44:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:26 INFO - 'SHELL': '/bin/bash', 15:44:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.objGopAqpl/Listeners', 15:44:26 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 15:44:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:26 INFO - 'USER': 'cltbld', 15:44:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:26 INFO - 'XPC_FLAGS': '0x0', 15:44:26 INFO - 'XPC_SERVICE_NAME': '0', 15:44:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:44:26 INFO - Cleaning up... 15:44:26 INFO - Return code: 0 15:44:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:44:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ae5fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3cadd85c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc3d30>, '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 0x10ab28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adbbb20>, '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.Bw6ZfFvJor/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.objGopAqpl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 15:44:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:44:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:44:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bw6ZfFvJor/Render', 15:44:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:26 INFO - 'HOME': '/Users/cltbld', 15:44:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:26 INFO - 'LOGNAME': 'cltbld', 15:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:26 INFO - 'MOZ_NO_REMOTE': '1', 15:44:26 INFO - 'NO_EM_RESTART': '1', 15:44:26 INFO - 'PAGER': '/bin/cat', 15:44:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:26 INFO - 'PWD': '/builds/slave/test', 15:44:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:26 INFO - 'SHELL': '/bin/bash', 15:44:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.objGopAqpl/Listeners', 15:44:26 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 15:44:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:26 INFO - 'USER': 'cltbld', 15:44:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:26 INFO - 'XPC_FLAGS': '0x0', 15:44:26 INFO - 'XPC_SERVICE_NAME': '0', 15:44:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:26 INFO - Downloading/unpacking blobuploader==1.2.4 15:44: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 15:44: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 15:44: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 15:44: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 15:44: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 15:44: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 15:44:29 INFO - Downloading blobuploader-1.2.4.tar.gz 15:44:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:44:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:44:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:44:30 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 15:44:30 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 15:44:30 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 15:44:30 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 15:44:30 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 15:44:30 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 15:44:30 INFO - Downloading docopt-0.6.1.tar.gz 15:44:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:44:30 INFO - Installing collected packages: blobuploader, requests, docopt 15:44:30 INFO - Running setup.py install for blobuploader 15:44:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:44:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:44:30 INFO - Running setup.py install for requests 15:44:31 INFO - Running setup.py install for docopt 15:44:31 INFO - Successfully installed blobuploader requests docopt 15:44:31 INFO - Cleaning up... 15:44:31 INFO - Return code: 0 15:44:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:31 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 0x10ae5fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3cadd85c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc3d30>, '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 0x10ab28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adbbb20>, '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.Bw6ZfFvJor/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.objGopAqpl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 15:44:31 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 15:44:31 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 15:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bw6ZfFvJor/Render', 15:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:31 INFO - 'HOME': '/Users/cltbld', 15:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:31 INFO - 'LOGNAME': 'cltbld', 15:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:31 INFO - 'MOZ_NO_REMOTE': '1', 15:44:31 INFO - 'NO_EM_RESTART': '1', 15:44:31 INFO - 'PAGER': '/bin/cat', 15:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:31 INFO - 'PWD': '/builds/slave/test', 15:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:31 INFO - 'SHELL': '/bin/bash', 15:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.objGopAqpl/Listeners', 15:44:31 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 15:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:31 INFO - 'USER': 'cltbld', 15:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:31 INFO - 'XPC_FLAGS': '0x0', 15:44:31 INFO - 'XPC_SERVICE_NAME': '0', 15:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:44:31 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-b6ntyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:44:31 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-vcwF0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:44:31 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-6BMUDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:44:32 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-B0khs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:44:32 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-7a_COD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:44:32 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-uUiaBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:44:32 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-uT2DPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:44:32 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-oNSwTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:44:32 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-G74Bdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:44:32 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-qXhlul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:44:32 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-YBiBNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:44:32 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-H2avQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:44:33 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-RlvL6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:44:33 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-WOPMtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:33 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-cc7fqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:44:33 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-8bt3rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:44:33 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-Lm1jiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:44:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:44:33 INFO - Running setup.py install for manifestparser 15:44:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:44:33 INFO - Running setup.py install for mozcrash 15:44:33 INFO - Running setup.py install for mozdebug 15:44:33 INFO - Running setup.py install for mozdevice 15:44:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:44:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:44:34 INFO - Running setup.py install for mozfile 15:44:34 INFO - Running setup.py install for mozhttpd 15:44:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:44:34 INFO - Running setup.py install for mozinfo 15:44:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:44:34 INFO - Running setup.py install for mozInstall 15:44:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:44:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:44:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:44:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:44:34 INFO - Running setup.py install for mozleak 15:44:34 INFO - Running setup.py install for mozlog 15:44:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:44:35 INFO - Running setup.py install for moznetwork 15:44:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:44:35 INFO - Running setup.py install for mozprocess 15:44:35 INFO - Running setup.py install for mozprofile 15:44:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:44:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:44:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:44:35 INFO - Running setup.py install for mozrunner 15:44:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:44:35 INFO - Running setup.py install for mozscreenshot 15:44:35 INFO - Running setup.py install for moztest 15:44:35 INFO - Running setup.py install for mozversion 15:44:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:44:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:44:36 INFO - Cleaning up... 15:44:36 INFO - Return code: 0 15:44:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:36 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')]} 15:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:36 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')]} 15:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:36 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 0x10ae5fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3cadd85c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc3d30>, '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 0x10ab28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adbbb20>, '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.Bw6ZfFvJor/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.objGopAqpl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 15:44:36 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 15:44:36 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 15:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bw6ZfFvJor/Render', 15:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:36 INFO - 'HOME': '/Users/cltbld', 15:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:36 INFO - 'LOGNAME': 'cltbld', 15:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:36 INFO - 'MOZ_NO_REMOTE': '1', 15:44:36 INFO - 'NO_EM_RESTART': '1', 15:44:36 INFO - 'PAGER': '/bin/cat', 15:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:36 INFO - 'PWD': '/builds/slave/test', 15:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:36 INFO - 'SHELL': '/bin/bash', 15:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.objGopAqpl/Listeners', 15:44:36 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 15:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:36 INFO - 'USER': 'cltbld', 15:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:36 INFO - 'XPC_FLAGS': '0x0', 15:44:36 INFO - 'XPC_SERVICE_NAME': '0', 15:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:44:36 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-OJmUph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:44:36 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)) 15:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:44:36 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-DsBO9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:44:36 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)) 15:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:44:36 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-MVyy8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:44:37 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)) 15:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:44:37 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-rQvRIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:44:37 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)) 15:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:44:37 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-RQFYr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:44:37 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)) 15:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:44:37 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-_2Nyns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:44:37 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)) 15:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:44:37 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-ubn1gM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:44:37 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)) 15:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:44:37 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-qFiQ3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:44:37 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)) 15:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:44:37 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-ai3gHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:44:37 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)) 15:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:44:37 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-l7OhOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:44:37 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)) 15:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:44:37 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-Bmn0yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:44:37 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)) 15:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:44:37 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-F3LhTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:44:38 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)) 15:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:44:38 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-wY5gFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:44:38 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)) 15:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:44:38 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-kxK8ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:44:38 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)) 15:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:38 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-1JjIWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:38 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)) 15:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:44:38 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-c4LxS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:44:38 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)) 15:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:44:38 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-RprG5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:44:38 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)) 15:44:38 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)) 15:44:38 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)) 15:44:38 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)) 15:44:38 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)) 15:44:38 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)) 15:44:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:44:38 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 15:44:38 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 15:44:38 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 15:44:38 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 15:44:38 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 15:44:38 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 15:44:41 INFO - Downloading blessings-1.6.tar.gz 15:44:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:44:41 INFO - Installing collected packages: blessings 15:44:41 INFO - Running setup.py install for blessings 15:44:41 INFO - Successfully installed blessings 15:44:41 INFO - Cleaning up... 15:44:41 INFO - Return code: 0 15:44:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:44:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:44:42 INFO - Reading from file tmpfile_stdout 15:44:42 INFO - Current package versions: 15:44:42 INFO - blessings == 1.6 15:44:42 INFO - blobuploader == 1.2.4 15:44:42 INFO - docopt == 0.6.1 15:44:42 INFO - functools32 == 3.2.3-2 15:44:42 INFO - jsonschema == 2.5.1 15:44:42 INFO - manifestparser == 1.1 15:44:42 INFO - mozInstall == 1.12 15:44:42 INFO - mozcrash == 0.17 15:44:42 INFO - mozdebug == 0.1 15:44:42 INFO - mozdevice == 0.48 15:44:42 INFO - mozfile == 1.2 15:44:42 INFO - mozhttpd == 0.7 15:44:42 INFO - mozinfo == 0.9 15:44:42 INFO - mozleak == 0.1 15:44:42 INFO - mozlog == 3.3 15:44:42 INFO - moznetwork == 0.27 15:44:42 INFO - mozprocess == 0.23 15:44:42 INFO - mozprofile == 0.28 15:44:42 INFO - mozrunner == 6.12 15:44:42 INFO - mozscreenshot == 0.1 15:44:42 INFO - mozsystemmonitor == 0.3 15:44:42 INFO - moztest == 0.7 15:44:42 INFO - mozversion == 1.4 15:44:42 INFO - psutil == 3.1.1 15:44:42 INFO - requests == 1.2.3 15:44:42 INFO - wsgiref == 0.1.2 15:44:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:42 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')]} 15:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:42 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')]} 15:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:42 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 0x10ae5fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3cadd85c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc3d30>, '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 0x10ab28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adbbb20>, '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.Bw6ZfFvJor/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.objGopAqpl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 15:44:42 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 15:44:42 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 15:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bw6ZfFvJor/Render', 15:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:42 INFO - 'HOME': '/Users/cltbld', 15:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:42 INFO - 'LOGNAME': 'cltbld', 15:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:42 INFO - 'MOZ_NO_REMOTE': '1', 15:44:42 INFO - 'NO_EM_RESTART': '1', 15:44:42 INFO - 'PAGER': '/bin/cat', 15:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:42 INFO - 'PWD': '/builds/slave/test', 15:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:42 INFO - 'SHELL': '/bin/bash', 15:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.objGopAqpl/Listeners', 15:44:42 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 15:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:42 INFO - 'USER': 'cltbld', 15:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:42 INFO - 'XPC_FLAGS': '0x0', 15:44:42 INFO - 'XPC_SERVICE_NAME': '0', 15:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:42 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)) 15:44:42 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)) 15:44:42 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)) 15:44:42 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)) 15:44:42 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)) 15:44:42 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)) 15:44:42 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)) 15:44:42 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)) 15:44:42 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)) 15:44:42 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)) 15:44:42 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)) 15:44:42 INFO - Cleaning up... 15:44:42 INFO - Return code: 0 15:44:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:44:42 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')]} 15:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:42 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')]} 15:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:42 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 0x10ae5fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3cadd85c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adc3d30>, '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 0x10ab28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adbbb20>, '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.Bw6ZfFvJor/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.objGopAqpl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 15:44:42 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 15:44:42 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 15:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bw6ZfFvJor/Render', 15:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:42 INFO - 'HOME': '/Users/cltbld', 15:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:42 INFO - 'LOGNAME': 'cltbld', 15:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:42 INFO - 'MOZ_NO_REMOTE': '1', 15:44:42 INFO - 'NO_EM_RESTART': '1', 15:44:42 INFO - 'PAGER': '/bin/cat', 15:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:42 INFO - 'PWD': '/builds/slave/test', 15:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:42 INFO - 'SHELL': '/bin/bash', 15:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.objGopAqpl/Listeners', 15:44:42 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 15:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:42 INFO - 'USER': 'cltbld', 15:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:42 INFO - 'XPC_FLAGS': '0x0', 15:44:42 INFO - 'XPC_SERVICE_NAME': '0', 15:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:44:43 INFO - Cleaning up... 15:44:43 INFO - Return code: 0 15:44:43 INFO - Running post-action listener: _resource_record_post_action 15:44:43 INFO - Running post-action listener: _start_resource_monitoring 15:44:43 INFO - Starting resource monitoring. 15:44:43 INFO - [mozharness: 2016-08-23 22:44:43.047227Z] Finished create-virtualenv step (success) 15:44:43 INFO - [mozharness: 2016-08-23 22:44:43.048250Z] Running install step. 15:44:43 INFO - Running pre-action listener: _resource_record_pre_action 15:44:43 INFO - Running main action method: install 15:44:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:44:43 INFO - Reading from file tmpfile_stdout 15:44:43 INFO - Detecting whether we're running mozinstall >=1.0... 15:44:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:44:43 INFO - Reading from file tmpfile_stdout 15:44:43 INFO - Output received: 15:44:43 INFO - Usage: mozinstall [options] installer 15:44:43 INFO - Options: 15:44:43 INFO - -h, --help show this help message and exit 15:44:43 INFO - -d DEST, --destination=DEST 15:44:43 INFO - Directory to install application into. [default: 15:44:43 INFO - "/builds/slave/test"] 15:44:43 INFO - --app=APP Application being installed. [default: firefox] 15:44:43 INFO - mkdir: /builds/slave/test/build/application 15:44:43 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'] 15:44:43 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 15:45:17 INFO - Reading from file tmpfile_stdout 15:45:17 INFO - Output received: 15:45:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:45:17 INFO - Running post-action listener: _resource_record_post_action 15:45:17 INFO - [mozharness: 2016-08-23 22:45:17.831705Z] Finished install step (success) 15:45:17 INFO - [mozharness: 2016-08-23 22:45:17.831843Z] Running run-tests step. 15:45:17 INFO - Running pre-action listener: _resource_record_pre_action 15:45:17 INFO - Running main action method: run_tests 15:45:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:45:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:45:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:45:17 INFO - 2016-08-23 15:45:17.846 screenresolution[1925:11087] starting screenresolution argv=screenresolution get 15:45:17 INFO - 2016-08-23 15:45:17.849 screenresolution[1925:11087] Display 0: 1600x1200x32@60 15:45:17 INFO - 2016-08-23 15:45:17.854 screenresolution[1926:11089] starting screenresolution argv=screenresolution list 15:45:17 INFO - Available Modes on Display 0 15:45:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:45:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:45:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:45:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:45:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:45:18 INFO - Intel Iris: 15:45:18 INFO - Chipset Model: Intel Iris 15:45:18 INFO - Type: GPU 15:45:18 INFO - Bus: Built-In 15:45:18 INFO - VRAM (Dynamic, Max): 1536 MB 15:45:18 INFO - Vendor: Intel (0x8086) 15:45:18 INFO - Device ID: 0x0a2e 15:45:18 INFO - Revision ID: 0x0009 15:45:18 INFO - Displays: 15:45:18 INFO - Display: 15:45:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:45:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:45:18 INFO - Display Serial Number: 1600x1200 60 15:45:18 INFO - Main Display: Yes 15:45:18 INFO - Mirror: Off 15:45:18 INFO - Online: Yes 15:45:18 INFO - Rotation: Supported 15:45:18 INFO - Return code: 0 15:45:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:45:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:45:18 INFO - Python 2.7.3 15:45:18 INFO - Return code: 0 15:45:18 INFO - grabbing minidump binary from tooltool 15:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:18 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 0x10adc3d30>, '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 0x10ab28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10adbbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:45:18 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 15:45:18 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 15:45:18 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 15:45:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:45:18 INFO - Return code: 0 15:45:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:45:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:45:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:45:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:45:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:45:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0341', '--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 15:45:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0341 --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 15:45:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bw6ZfFvJor/Render', 15:45:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:18 INFO - 'HOME': '/Users/cltbld', 15:45:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:18 INFO - 'LOGNAME': 'cltbld', 15:45:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:45:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:18 INFO - 'MOZ_NO_REMOTE': '1', 15:45:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:45:18 INFO - 'NO_EM_RESTART': '1', 15:45:18 INFO - 'PAGER': '/bin/cat', 15:45:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:18 INFO - 'PWD': '/builds/slave/test', 15:45:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:45:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:18 INFO - 'SHELL': '/bin/bash', 15:45:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.objGopAqpl/Listeners', 15:45:18 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 15:45:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:18 INFO - 'USER': 'cltbld', 15:45:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:18 INFO - 'XPC_FLAGS': '0x0', 15:45:18 INFO - 'XPC_SERVICE_NAME': '0', 15:45:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0341', '--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 15:45:18 INFO - mozversion application_buildid: 20160823145014 15:45:18 INFO - mozversion application_changeset: 3f7afc56fae2a3bdc25fb25a3f69d62b32a54c94 15:45:18 INFO - mozversion application_display_name: Nightly 15:45:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:45:18 INFO - mozversion application_name: Firefox 15:45:18 INFO - mozversion application_remotingname: firefox 15:45:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:45:18 INFO - mozversion application_vendor: Mozilla 15:45:18 INFO - mozversion application_version: 51.0a1 15:45:18 INFO - mozversion platform_buildid: 20160823145014 15:45:18 INFO - mozversion platform_changeset: 3f7afc56fae2a3bdc25fb25a3f69d62b32a54c94 15:45:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:45:18 INFO - mozversion platform_version: 51.0a1 15:45:18 INFO - using testdate: 1471992318 15:45:18 INFO - actual date: 1471992318 15:45:18 INFO - starting webserver on 'localhost:50513' 15:45:18 INFO - SUITE-START | Running 2 tests 15:45:18 INFO - TEST-START | dromaeo_css 15:45:18 INFO - operating with platform_type : mac_ 15:45:18 INFO - Initialising browser for dromaeo_css test... 15:45:18 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpcW4UDE/profile http://localhost:50513/getInfo.html) 15:45:20 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 15:45:20 INFO - PROCESS | 1936 | colorDepth:24 15:45:20 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 15:45:20 INFO - PROCESS | 1936 | __metrics 15:45:20 INFO - PROCESS | 1936 | 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" 15:45:20 INFO - PROCESS | 1936 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:45:21 INFO - TEST-INFO | 1936: exit 0 15:45:21 INFO - Browser initialized. 15:45:21 INFO - Running cycle 1/1 for dromaeo_css test... 15:45:21 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpcW4UDE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:33 INFO - PROCESS | 1938 | RSS: Main: 234967040 15:45:33 INFO - PROCESS | 1938 | 15:46:25 INFO - PROCESS | 1938 | 1471992385049 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] 15:48:37 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50513/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50513/tests/dromaeo/cssquery-ext.html) 15:48:38 INFO - PROCESS | 1938 | RSS: Main: 359460864 15:48:38 INFO - PROCESS | 1938 | 15:51:41 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50513/tests/dromaeo/cssquery-ext.html (next: http://localhost:50513/tests/dromaeo/cssquery-jquery.html) 15:51:42 INFO - PROCESS | 1938 | RSS: Main: 371019776 15:51:42 INFO - PROCESS | 1938 | 15:54:44 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50513/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50513/tests/dromaeo/cssquery-mootools.html) 15:54:45 INFO - PROCESS | 1938 | RSS: Main: 340606976 15:54:45 INFO - PROCESS | 1938 | 15:57:47 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50513/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50513/tests/dromaeo/cssquery-prototype.html) 15:57:48 INFO - PROCESS | 1938 | RSS: Main: 352321536 15:57:48 INFO - PROCESS | 1938 | 16:00:51 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50513/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50513/tests/dromaeo/cssquery-yui.html) 16:00:52 INFO - PROCESS | 1938 | RSS: Main: 330522624 16:00:52 INFO - PROCESS | 1938 | 16:03:55 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50513/tests/dromaeo/cssquery-yui.html (next: http://localhost:50513/tests/dromaeo/cssquery-dojo.html) 16:03:56 INFO - PROCESS | 1938 | RSS: Main: 494399488 16:03:56 INFO - PROCESS | 1938 | 16:03:56 INFO - PROCESS | 1938 | __start_tp_report 16:03:56 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 16:03:56 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 16:03:56 INFO - PROCESS | 1938 | |i|pagename|runs| 16:03:56 INFO - PROCESS | 1938 | |0;dojo.html;3345.933081338373;3346.916327091823;3382.2051817822794;3400.812955287461;3442.5180474733543;5677.57418193635;5702.515286200673;5759.193712880189;5801.9129713054335;5840.182374467573;5763.654180749158;5790.855228619286;5844.1818145459665;5861.7948371807;5948.226730525026;5213.556847667953;5261.947380526203;5285.762140703678;5311.681299122065;5394.352677678684;5385.892282154355;5393;5398.433164517728;5408.567314614821;5438.456154384569;5223.190405487156;5276.736163191864;5280.603954703393;5286.101362768339;5323.307969963918;5298.337707786527;5321.787128514855;5322.627416080876;5329.946700532983;5338.599605029638;5228.163493840967;5275.683458992473;5277.419483856773;5291.100512912775;5298.15229563268;5710.229119068914;5755.597108202427;5767.480926716616;5773.364929857712;5773.855653608652;6183.84540386494;6213.254409470893;6237.87524249517;6263.6868156592445;6299.118123462719;6238.344973777779;6283.8114856554375;6294.590851594678;6307.905381419282;6312.715927783261;5694.316681998104;5747.885042299215;5850.502707269845;5850.765969361263;5853.414658534112;5620.4660557247;6206.658633775095;6220.191375121205;6308;6313.55805093639;6177.8455538611;6200.81397558065;6214.5960512566535;6218.19163508741;6243.875122497616;6142.600730952474;6222.782202622887;6258.468030217391;6288.842778930563;6303.6532990685955;6217.6891155442045;6272.749090036347;6295.716692748837;6308.116863639094;6316.463100636497;6306.527010474233;6309;6314.494840412756;6319.46284565817;6335.334789847092;4846.7818948147415;4902.7058376497525;4911.877203069952;4977;5042.899142017212;4422.624076953431;4489.416375871181;4507.391502147234;4512.684112112121;4565.315202719618;5780.5953583248765;5792;5793.565482588822;5810.273715785527;5817.0983497557945;6285.182926219562;6315.5894866833505;6318.905216421757;6323.146375239376;6345.07996340528;5616.410276920946;5725.2843394575675;5773.39379365169;5776.826695199151;5869.413058694096;5455.890882182334;5458.263134476874;5466.508014278734;5471.808486703026;5476.288082549243;6253.468455181361;6285.465735412449;6300.0234963580715;6323.810285691437;6344.143540621957;5149.613778966517;6184.474319682878;6251.0623406487575;6253.062040693932;6303.243610766737;6323.272823625158;6327.430531252209;6332.968335158479;6336.3030066693545;6340.968295158495;6147.754089836357;6189.659568723763;6218.284897236876;6221.844453888689;6225.439710426083;6122.916604401471;6144.494598823317;6184.567080304336;6264.060390941395;6319.494440444845;6153.538484613761;6186.566940314135;6197.504199663926;6197.566170368031;6214.9689251553455;6159.84600384994;6190.102435146789;6207.286162091417;6211.223597050369;6228.906566401598;5800.24596802413;5977.970110149421;6003.49040139616;6026.849328766816;6123.663198524124;6117.406068681613;6178.165947597017;6181.381861813783;6198.752049917953;6199.1011303359855;6001.459868611846;6004.909926351195;6016.8796624068145;6032.788852390146;6081.878362432638 16:03:56 INFO - PROCESS | 1938 | |1;ext.html;517.0254071076022;524.1247117314085;524.3733738182873;530.3291336459378;547.3377213571579;24972.50054998901;32467.188320292003;37790.055248618795;39040.99999999996;39078.60921390793;40426.989325266884;40736;40739;40770;40813.38779918304;8160.387970902191;8650;9688.75778105546;9711.514424278757;9715.70852874415;8648.394612377137;11500.654980350602;11775.411229438536;11800.704982375424;11996.280223186594;11793.823092653616;11823.704407389798;11869.52521899123;11879.990200832939;11906.642800715948;11713;11795.764084718257;11812.7637447251;11823.527058917633;11832.467538960767;11526.366049867254;11611.6516504505;11620.941895290469;11629.418529073513;11630.709232269177;38108.42837357442;38463.230735385136;38498.230035399414;38519.229615407814;38525.37695476533;3274.361499507597;3322.7507936904585;3440.82795860206;3510.9297814043575;3583.229605634801;27968.902332324953;30010.549841752392;31179.84410077935;31354;31591.21021974469;2938.7061293870656;3210.951835722466;3319.96680033199;3360.663933606644;3394.8811791587177;512452;512706;515285;517293;517881.8211817871;55028.449715502335;55340.72329638326;55371.723141384035;55391;55468.445315547135;58478.70760646169;58629.12056319159;58675;58687.70656146692;58692;15538.300776465087;15683.451079212173;15710.9214453927;15744.763828542582;15788.447404341023;9790.657326993522;9904;11291.43542822872;12241.816372754416;12306.507739690313;13137.94896408285;13262.668433289246;13289;14484.493042743454;14856.554303370916;6715.597064176165;6814.512702863518;6893.620850853251;6921.6193109379465;6930.79207623762;837.8408102460513;991.4794732765356;1004.4927311707687;1049.4175732468552;1125.1111621818836;27274.45451091007;28634.997775077773;28976.27559310992;29938.101856944326;29949.85025074861;887.4409122253069;1038.5482315192914;1058.5501162006271;1070.3043022035583;1108.1578000719512;14138.929305353407;15010.69978600422;15011.624709382135;15201.543953681406;15427.84572154264;185065;185465;185493;185895;953695;53972.46027539753;54820.90358192814;55126.17310740342;57931.420685792604;58310.4168958305;1330.8935285177163;1366.644672385187;1377.8553251908606;1386.6048176269715;1386.7503849307222;28763;29774.851125744233;29996.550051749677;30257;30638.693613064475;52411;53462.73268633632;53676.73161634167;56692.14961775494;56964.71517642385;618285;622720;622722;624136;626854.8657256685;25081.49837003213;25340;25465;25494.362640933385;25497.49005019852;45189.54810451985;45440;46183;46703.29945050759;46762.532374675815;32902.506462402605;36063;38646.2270754589;38719.225615488096;39062.63280785159;35282.82358588191;37325.06687332838;37618;37784.433233502066;38533.614663854125;34831.65168348284;34934;35048.12379690426;37730;37934;25456;31153.532697010018;33323.50044248063;33419.16452088717;33657.326853462306;24514.264572062868;24744.50510989734;24887.875560622804;24991.41983138089;25027.87486062558 16:03:56 INFO - PROCESS | 1938 | |2;jquery.html;352.90295168828584;358.4855732024545;370.5312779334142;371.4687996165484;393.03509883236654;14860.702785944288;15532.611684707888;20855.895720521414;20946.371608851758;21782.346529604136;12218.38908054596;12336.321502317369;12380.442880070395;14282.714345713079;20538.69191962122;4039.9798001009954;5224.973875130619;5479.6164268501225;5487.149491828753;6091.847703807396;5986.910196347058;6164.229471316085;6170.197874276338;6178.907316390235;6181.1037399577035;6174.259088909337;6218.906716399235;6664.4002039816405;6728;6752.426043786284;6047.425494578008;6381.202349706286;6706.664666766666;6719.529632925697;6723.09238252837;5968.612040217372;5969.492593129589;5970.014947533652;5972.581919265654;5984.162217289583;13715.657108572266;16712.830101892872;19116.904415477973;26681;27003.594946075824;2098.916043358264;2137.5724855029057;2148.8611036150864;2195.9451013724624;2310.4223944013997;9699.369540979813;10199;10348.741281467948;10615.363078215254;10621.5220315086;1881.7365568820376;1885.1422602715732;1891.6500447417272;1963.813437723414;1963.9214431422727;133501.3324933379;134855;135799.32100339534;136586;139475;11301.321920684784;11403.258788178742;11462.332053742723;11479.25384849982;11667.183297169286;12694.49222031127;13484.325783710776;13703.657408564666;14370.712585748226;14414.639634009023;6851;8089.838203235902;8178.5092894426525;8267.62795674196;8472.74581762548;7954.960225198953;8216;8234.629441675139;8362.790930226793;8410.201030902177;3937.704672149543;4334.111507141029;4523.638108951276;4578.664880910759;4651.976565155728;1881.2757088521018;1912.7130930360279;1917.3960202536157;1920.2894928876406;1936.4190742777391;821.7000794709888;870.060334838373;875.282268539804;939.8543225800012;990.3166814897697;19749.012549372474;20004.49988750293;21408.78591214099;22180.88909555442;22360.105595776313;874.2044739287345;884.6417201033591;900.7477906186278;917.9935710548009;937.6905621145006;8243.793905152419;10585.682429527127;11558.711032224093;11588.76822463546;11599.710007249718;593229;612207;615102;615190;633803;105018.47490762497;131762.07070681843;132370.67629323583;134462;134781;953.080277532171;959.2421986630484;992.5930368549004;993.6671215142864;994.7562847102506;18969.90515047416;19224.231030759172;19261;19294.90352548228;19314.227430903167;54145.646358841346;55533.889322214134;55623;55940.44059559352;56257.71871140618;70437;77164;78543.21456785359;79690;81123.59438202772;7318.012068370915;7931.960340198262;8007.559584222924;8177.223163799429;8416.158384161536;8711.39020268575;12872.871271287167;13279.136856104322;13323.067385282937;13361.933190333986;10773.362510568397;10886.945565272123;11101.888981110085;11283.492242848928;11363.602273920373;11238.831417528907;11440.42797860087;11545.018673412855;11773;11777.175597708077;10466.267361284637;11286.322820630789;11638.88361116378;11869.465874035517;11892.227005244631;7845.960770196112;8012;8037.196280371916;8458.873116903374;8460.365472589703;5379.031774280667;5424.5660347172925;7056.329648683367;8528.147185281423;11132.276402033842 16:03:56 INFO - PROCESS | 1938 | |3;mootools.html;1352.465776018472;1392.422144809904;1491.1127878912048;1642.4087328561716;1844.3821319857855;7747.496412733169;8220.136885626998;8220.177982201776;8229.259366657;8235.135310792362;8381.95809020954;8673.658112309688;8673.826523469526;8734.91265087349;8737.86893196601;2367.95264094718;2399.4841109161525;2402.939926501843;2403.375122468153;2405.0860672944254;2329.77867102625;2363.6927199464044;2371.3834403055157;2378.357843382286;2379.7977171940406;2347.424880904181;2365.0894405653776;2377.9762202378015;2386.642003699442;2388.7491813359607;2322.059565875823;2350.330155905567;2354.2701762453607;2358.893849776764;2359.374765687094;2278.977210227893;2302.6200676888293;2304.8271379646594;2307.607706689867;2310.849794763352;8676.305895528343;8844.955775221148;8859.424137431046;8872.778180545474;8883.911160888438;5181.948180518222;5188.870278243036;5353.357597088374;5354.946450535484;5384.300040994645;3586.39031364666;3671.889843304705;3673.210259794157;3675.871344502956;3698.5007024051947;1106.5739690219323;1116.3302018788743;1138.7836311100948;1141.4635121492886;1141.8401423800674;11233.32600044;11529.481173347136;11573.189876708633;11614.651560453116;11674.70813229676;4396.911467053124;4459.643228541709;4633.907321853611;4643.837465688753;4669.719816811003;8000.199980002069;9125.406848554823;9133.680321188727;9144.222741067084;9429.057094290652;5249.186376111708;5251.238570407269;5252.816151434758;5255.395629502656;5275.4724527547705;4390.736555806598;4543.068670922453;4788.353572267699;4940.92588611164;4946.653734238641;3325.6341802401716;3712.5730540987593;3785.7539259948016;3820.598837122118;3859.6526312632;1159.3333833046033;1163.3892206591606;1164.6680696001768;1175.5944199251244;1176.1531697178018;915.6154415145655;921.2077613252598;927.3415874728884;938;985.827480190978;5280.973595132076;5710.486056254957;5924.229850119457;6181.81454556364;6208.099825525273;385.25837762308004;419.89712520432687;429.61549413275566;438.0844035964849;452.15672770283186;3804.31522325984;4030.6528662420264;4091.161311931047;4120.299549076664;4178.310578754532;9055.95472022649;10337.431441270803;10775.191860610328;10788.676339709822;10793.83809242862;10727.946360268148;10740.570377184982;10760.246782725291;10761.30051546647;10805.513751881184;7754.5734984575265;8027.518348899085;8220.506769593843;8237.423380363318;8430.807989922541;5337.786488540415;5777.544543569051;6016.8796624068145;6100.694965251809;6165.815025549241;10074.244431667801;10122.139618132247;10180.643677471253;10182.287239893136;10203.591856325664;9686;10148.137408320375;10158.796824063626;10182.694519164437;10285.640002599874;7065.540739851893;7280.344768970818;7375.299346562068;7521.172671006294;7716.035495563055;8293.83412331762;8557.44376615518;8561.22948934599;8566.35752318591;8580.356473264404;7514.549127052394;7578.810529736801;7606.391488681003;7622.466427350032;7771.4559980800805;7270.382017528358;7295.562266263829;7316.926830731624;7410.814729631802;7498.512596681198;6924.169099708067;7047.718091276497;7073.257307982588;7082.18554866197;7082.716691332289;4921.827736029221;5052.69683818972;5087.643864929568;5129.410117836496;5147.121300725284;4994.825181118788;5101.438841727481;5189.11784996544;5214.739263036909;5283.418823929288 16:03:56 INFO - PROCESS | 1938 | |4;prototype.html;296.2445763303575;297.10126866229655;306.9892553760618;309.7336290789919;310.4923450158991;4951.306817045616;5364.302640656714;5373.704446255454;5399.379071406789;5401.513863752261;4923.704577725339;5018.247262910565;5092.872678183038;5131.7690703918415;5368.570514358852;3515.8945231643092;3674.7427680062406;3895.590962948892;4115.650169735576;4147.605977432139;3519.87680431185;3899.590542992973;4156.521999969997;4169.437125987998;4176.3085950190225;3335.4323467970135;3464.4110501214855;4060.4315395844606;4139.81370838313;4141.358089496133;3397.706548080036;3564.7861128332383;4031.6169963853386;4057.4928133983253;4095.1195492969005;3208.6470488246273;3270.8528116234825;3512.648735126467;3520.383932811748;3536.982315088408;4484.775761211959;4763.951930575303;5219.582433405319;5301.549368303698;5323.600729945231;4163.5836416358425;4247.851325203603;4644.373009643688;4648.465426475965;4688.171613070905;5613.494785469286;5675.581104723819;5677.40387259336;5678.716064196774;5684.658920464744;3846.2499812536566;3888.1640447303685;3890.5914878937588;3891.754638515676;3891.905934781855;4775.952240477585;5385.78456861725;5702.54379649627;5964.493018093423;6057.546693589059;3416.350893330259;3672.155404257036;3875.5466699987505;3881.5342158940543;3889.8216998480375;3442.638522955104;3944.467496887941;3958.366661334173;3980.6315242430705;3991.401289806552;4391.912161756747;4471.5081341052455;4571.954280457219;4669.229577119805;4689.718616882998;4225.133847561243;5050.72221027847;5237.240600112962;5436.646617969898;5631.887362252732;1089.836524521328;1177.43483128099;1346.9897576817386;1349.062401630857;1357.8777909988148;750.9111787907556;848.7523340689177;848.9176300217149;882.3955590420583;883.2271762208068;4327.891802704958;5226.92159617606;5229.901720638709;5276.5251127398715;5327.188755823039;5743.569232307594;5748.367679555246;5752.309722833287;5752.511036561939;5768.278965129359;4613;4752.358431611758;5036.697798132123;5208.453112423217;5232.110541208004;2334.4514039200776;2962.674105848355;2968.9406211875644;2972;2975.2413134650524;5014.448410674823;5429.1856221566295;5436.836894893159;5458.052651568188;5579.972100139474;5841.474267315962;5849.590528662953;5855.853603659857;5879.647221166743;5898.675572843449;4818.277258411266;5284.233786100994;5657.886842263131;5721.828345149569;5958.821235362946;4970.931249781314;5037.168867136881;5131.3836141615275;5304.496072873071;5928.3775203603;5201.687898726089;5257.921131182959;5605.3184044785585;5640.982074590074;5937.5249980000635;4677.32178834074;5588.972055139699;6092.421219984094;6215.751369945152;6302.684865756785;3453.912017714412;3478.426059700121;3894.415837624379;3905.4614500573716;3941;5580.832575022756;5892.057270836645;5967.880642387215;5979.760809567569;5981.3420523743225;5460.890782184413;5546.694931778629;5738.9139162913425;5744.310682718101;5769.394213607511;4996.900681849987;5741.770329186953;5761.279840019997;5767.68277744728;5811.622244554091;4241.53343132249;4955.6531042826655;5098.617603679813;5362.758675859518;5661.952538780339;4862.610991120631;5556.943680975366;5745.827625171251;5778.739956702043;5798.955548337157;5008.549230569266;5405.86476839868;5589.245451864109;5600.551955843604;5608.831735047955 16:03:56 INFO - PROCESS | 1938 | |5;yui.html;627.6328347916467;709.3615745828754;717.4762423430899;724.4132252875172;752.8005078654154;15783.684326313467;16784.664306713858;16810.159492025396;16818.579535511588;16826.990380577157;14953.401863925457;15186.088834669927;15200.011999220043;15222.467213647524;15225.84774152258;686.327399148832;703.5884007855411;820.3765138494742;837.4431003382743;859.415597393772;828.9544075075869;838.584900474263;841.4824882697159;842.0190478093045;843.5529169540154;880.1770344727668;880.6169316347377;880.9207171354577;882.5984177199359;883.0727735877335;712.3659942651045;734.1923883727857;734.4051318432033;872.2672954718067;876.89915659699;541.6506353402034;542.3031404645052;590.6220019187723;658.2331583704978;681.151965802579;15206.695866082617;15250.923745381313;15263.38946442141;15274.38902443901;15287;14203;14418.49535266271;14468.782968255484;14553;14726.263686815617;10961.780764384748;13149.408276627575;13195.2742599157;13320.467181312735;13560.11859229147;4182.769947652878;4579.67942244043;4640.69835460694;4659.860204193879;4870.293807397943;122736;124921.37539312337;125297;126389;129723.35138324343;11532.25040372373;11546.94226528862;11620.593279235187;11632.360220187971;11885.465154068088;23048;23060.962256698487;23069.077236910336;23091.07635694587;23098.65352019721;7988.84022319562;8081.434299599089;8250.422470427133;8318.750437486886;8322.250997410141;4333;4457.487388950248;4550.34020067095;4563.178627847019;4661.743604101739;3565.9286814264087;3617.3488772021287;3693.6121707220354;3694.9815250923575;3695.334839728821;623.865868838202;664.4086762781129;675.8884784010681;682.6313790553145;691.1740470138137;523.5472553356747;543.570579242394;560.341598621618;578.3840210176149;584.0800738836265;7381.778546643609;8795.120487951153;9182.17360437564;9306.115918987685;9349.298802589832;520.841143451251;533.3732863884919;547.7671989404487;614.8155553333982;648.2156590525517;4419.270820314677;4487.865364039084;4531.750753708512;4571.525640513234;4653.302004699322;100272.49863750635;105518.47240763901;109681;141595.17667526554;147467;120554.3972280133;124335;125190;132353;154273;95060;122128;125447;126543;132924;9624;9777.41335519871;9923.950380248341;10433.9478302608;10435.217358698279;57906.13140802807;58286.12570811356;65640.67179664072;65757.04093315717;69524.26189345306;75059;79191.60404197942;86524.5673771627;93208;95258;13166.802497962346;13175.802362964372;13239.073264871367;13517.459301627825;13797.655058623213;15324.233788310317;15391.230438478255;15477.922610386877;15952.840471595135;15953.202339883192;8668.388926157117;13684.698100896752;14168.645783855485;14458.06022608527;15028.323725432163;14287.642808929444;14293.928530357281;14305.141691498544;14502.492412765503;14648.487302944346;13907;13978.091424057404;13994;14290.785638215642;14711.55865324042;11063.280886742335;11182.273152245078;11209.07614283797;11432.256903301259;11598.710032248924;10278.537465814206;11595.710107247387;11617.186796924134;11713.590024349107;11907.761844762883 16:03:56 INFO - PROCESS | 1938 | __end_tp_report 16:03:56 INFO - PROCESS | 1938 | __start_cc_report 16:03:56 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,1255 16:03:56 INFO - PROCESS | 1938 | __end_cc_report 16:03:56 INFO - PROCESS | 1938 | __startTimestamp1471993436202__endTimestamp 16:03:56 INFO - PROCESS | 1938 | 16:03:56 INFO - PROCESS | 1938 | ------- Summary: start ------- 16:03:56 INFO - PROCESS | 1938 | Number of tests: 6 16:03:56 INFO - PROCESS | 1938 | 16:03:56 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5795.22 Median:6016.88 stddev:630.44 (10.5%) stddev-sans-first:602.38 16:03:56 INFO - PROCESS | 1938 | Values: 3345.9 3346.9 3382.2 3400.8 3442.5 5677.6 5702.5 5759.2 5801.9 5840.2 5763.7 5790.9 5844.2 5861.8 5948.2 5213.6 5261.9 5285.8 5311.7 5394.4 5385.9 5393.0 5398.4 5408.6 5438.5 5223.2 5276.7 5280.6 5286.1 5323.3 5298.3 5321.8 5322.6 5329.9 5338.6 5228.2 5275.7 5277.4 5291.1 5298.2 5710.2 5755.6 5767.5 5773.4 5773.9 6183.8 6213.3 6237.9 6263.7 6299.1 6238.3 6283.8 6294.6 6307.9 6312.7 5694.3 5747.9 5850.5 5850.8 5853.4 5620.5 6206.7 6220.2 6308.0 6313.6 6177.8 6200.8 6214.6 6218.2 6243.9 6142.6 6222.8 6258.5 6288.8 6303.7 6217.7 6272.7 6295.7 6308.1 6316.5 6306.5 6309.0 6314.5 6319.5 6335.3 4846.8 4902.7 4911.9 4977.0 5042.9 4422.6 4489.4 4507.4 4512.7 4565.3 5780.6 5792.0 5793.6 5810.3 5817.1 6285.2 6315.6 6318.9 6323.1 6345.1 5616.4 5725.3 5773.4 5776.8 5869.4 5455.9 5458.3 5466.5 5471.8 5476.3 6253.5 6285.5 6300.0 6323.8 6344.1 5149.6 6184.5 6251.1 6253.1 6303.2 6323.3 6327.4 6333.0 6336.3 6341.0 6147.8 6189.7 6218.3 6221.8 6225.4 6122.9 6144.5 6184.6 6264.1 6319.5 6153.5 6186.6 6197.5 6197.6 6215.0 6159.8 6190.1 6207.3 6211.2 6228.9 5800.2 5978.0 6003.5 6026.8 6123.7 6117.4 6178.2 6181.4 6198.8 6199.1 6001.5 6004.9 6016.9 6032.8 6081.9 16:03:56 INFO - PROCESS | 1938 | 16:03:56 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:63100.68 Median:18509.00 stddev:144653.94 (781.5%) stddev-sans-first:144983.41 16:03:56 INFO - PROCESS | 1938 | Values: 517.0 524.1 524.4 530.3 547.3 24972.5 32467.2 37790.1 39041.0 39078.6 40427.0 40736.0 40739.0 40770.0 40813.4 8160.4 8650.0 9688.8 9711.5 9715.7 8648.4 11500.7 11775.4 11800.7 11996.3 11793.8 11823.7 11869.5 11880.0 11906.6 11713.0 11795.8 11812.8 11823.5 11832.5 11526.4 11611.7 11620.9 11629.4 11630.7 38108.4 38463.2 38498.2 38519.2 38525.4 3274.4 3322.8 3440.8 3510.9 3583.2 27968.9 30010.5 31179.8 31354.0 31591.2 2938.7 3211.0 3320.0 3360.7 3394.9 512452.0 512706.0 515285.0 517293.0 517881.8 55028.4 55340.7 55371.7 55391.0 55468.4 58478.7 58629.1 58675.0 58687.7 58692.0 15538.3 15683.5 15710.9 15744.8 15788.4 9790.7 9904.0 11291.4 12241.8 12306.5 13137.9 13262.7 13289.0 14484.5 14856.6 6715.6 6814.5 6893.6 6921.6 6930.8 837.8 991.5 1004.5 1049.4 1125.1 27274.5 28635.0 28976.3 29938.1 29949.9 887.4 1038.5 1058.6 1070.3 1108.2 14138.9 15010.7 15011.6 15201.5 15427.8 185065.0 185465.0 185493.0 185895.0 953695.0 53972.5 54820.9 55126.2 57931.4 58310.4 1330.9 1366.6 1377.9 1386.6 1386.8 28763.0 29774.9 29996.6 30257.0 30638.7 52411.0 53462.7 53676.7 56692.1 56964.7 618285.0 622720.0 622722.0 624136.0 626854.9 25081.5 25340.0 25465.0 25494.4 25497.5 45189.5 45440.0 46183.0 46703.3 46762.5 32902.5 36063.0 38646.2 38719.2 39062.6 35282.8 37325.1 37618.0 37784.4 38533.6 34831.7 34934.0 35048.1 37730.0 37934.0 25456.0 31153.5 33323.5 33419.2 33657.3 24514.3 24744.5 24887.9 24991.4 25027.9 16:03:56 INFO - PROCESS | 1938 | 16:03:56 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:35584.96 Median:371.00 stddev:102886.68 (27732.3%) stddev-sans-first:103141.29 16:03:56 INFO - PROCESS | 1938 | Values: 352.9 358.5 370.5 371.5 393.0 14860.7 15532.6 20855.9 20946.4 21782.3 12218.4 12336.3 12380.4 14282.7 20538.7 4040.0 5225.0 5479.6 5487.1 6091.8 5986.9 6164.2 6170.2 6178.9 6181.1 6174.3 6218.9 6664.4 6728.0 6752.4 6047.4 6381.2 6706.7 6719.5 6723.1 5968.6 5969.5 5970.0 5972.6 5984.2 13715.7 16712.8 19116.9 26681.0 27003.6 2098.9 2137.6 2148.9 2195.9 2310.4 9699.4 10199.0 10348.7 10615.4 10621.5 1881.7 1885.1 1891.7 1963.8 1963.9 133501.3 134855.0 135799.3 136586.0 139475.0 11301.3 11403.3 11462.3 11479.3 11667.2 12694.5 13484.3 13703.7 14370.7 14414.6 6851.0 8089.8 8178.5 8267.6 8472.7 7955.0 8216.0 8234.6 8362.8 8410.2 3937.7 4334.1 4523.6 4578.7 4652.0 1881.3 1912.7 1917.4 1920.3 1936.4 821.7 870.1 875.3 939.9 990.3 19749.0 20004.5 21408.8 22180.9 22360.1 874.2 884.6 900.7 918.0 937.7 8243.8 10585.7 11558.7 11588.8 11599.7 593229.0 612207.0 615102.0 615190.0 633803.0 105018.5 131762.1 132370.7 134462.0 134781.0 953.1 959.2 992.6 993.7 994.8 18969.9 19224.2 19261.0 19294.9 19314.2 54145.6 55533.9 55623.0 55940.4 56257.7 70437.0 77164.0 78543.2 79690.0 81123.6 7318.0 7932.0 8007.6 8177.2 8416.2 8711.4 12872.9 13279.1 13323.1 13361.9 10773.4 10886.9 11101.9 11283.5 11363.6 11238.8 11440.4 11545.0 11773.0 11777.2 10466.3 11286.3 11638.9 11869.5 11892.2 7846.0 8012.0 8037.2 8458.9 8460.4 5379.0 5424.6 7056.3 8528.1 11132.3 16:03:56 INFO - PROCESS | 1938 | 16:03:56 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:5658.14 Median:4855.09 stddev:3174.80 (65.4%) stddev-sans-first:3167.21 16:03:56 INFO - PROCESS | 1938 | Values: 1352.5 1392.4 1491.1 1642.4 1844.4 7747.5 8220.1 8220.2 8229.3 8235.1 8382.0 8673.7 8673.8 8734.9 8737.9 2368.0 2399.5 2402.9 2403.4 2405.1 2329.8 2363.7 2371.4 2378.4 2379.8 2347.4 2365.1 2378.0 2386.6 2388.7 2322.1 2350.3 2354.3 2358.9 2359.4 2279.0 2302.6 2304.8 2307.6 2310.8 8676.3 8845.0 8859.4 8872.8 8883.9 5181.9 5188.9 5353.4 5354.9 5384.3 3586.4 3671.9 3673.2 3675.9 3698.5 1106.6 1116.3 1138.8 1141.5 1141.8 11233.3 11529.5 11573.2 11614.7 11674.7 4396.9 4459.6 4633.9 4643.8 4669.7 8000.2 9125.4 9133.7 9144.2 9429.1 5249.2 5251.2 5252.8 5255.4 5275.5 4390.7 4543.1 4788.4 4940.9 4946.7 3325.6 3712.6 3785.8 3820.6 3859.7 1159.3 1163.4 1164.7 1175.6 1176.2 915.6 921.2 927.3 938.0 985.8 5281.0 5710.5 5924.2 6181.8 6208.1 385.3 419.9 429.6 438.1 452.2 3804.3 4030.7 4091.2 4120.3 4178.3 9056.0 10337.4 10775.2 10788.7 10793.8 10727.9 10740.6 10760.2 10761.3 10805.5 7754.6 8027.5 8220.5 8237.4 8430.8 5337.8 5777.5 6016.9 6100.7 6165.8 10074.2 10122.1 10180.6 10182.3 10203.6 9686.0 10148.1 10158.8 10182.7 10285.6 7065.5 7280.3 7375.3 7521.2 7716.0 8293.8 8557.4 8561.2 8566.4 8580.4 7514.5 7578.8 7606.4 7622.5 7771.5 7270.4 7295.6 7316.9 7410.8 7498.5 6924.2 7047.7 7073.3 7082.2 7082.7 4921.8 5052.7 5087.6 5129.4 5147.1 4994.8 5101.4 5189.1 5214.7 5283.4 16:03:56 INFO - PROCESS | 1938 | 16:03:56 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:4515.05 Median:5043.95 stddev:1414.29 (28.0%) stddev-sans-first:1382.36 16:03:56 INFO - PROCESS | 1938 | Values: 296.2 297.1 307.0 309.7 310.5 4951.3 5364.3 5373.7 5399.4 5401.5 4923.7 5018.2 5092.9 5131.8 5368.6 3515.9 3674.7 3895.6 4115.7 4147.6 3519.9 3899.6 4156.5 4169.4 4176.3 3335.4 3464.4 4060.4 4139.8 4141.4 3397.7 3564.8 4031.6 4057.5 4095.1 3208.6 3270.9 3512.6 3520.4 3537.0 4484.8 4764.0 5219.6 5301.5 5323.6 4163.6 4247.9 4644.4 4648.5 4688.2 5613.5 5675.6 5677.4 5678.7 5684.7 3846.2 3888.2 3890.6 3891.8 3891.9 4776.0 5385.8 5702.5 5964.5 6057.5 3416.4 3672.2 3875.5 3881.5 3889.8 3442.6 3944.5 3958.4 3980.6 3991.4 4391.9 4471.5 4572.0 4669.2 4689.7 4225.1 5050.7 5237.2 5436.6 5631.9 1089.8 1177.4 1347.0 1349.1 1357.9 750.9 848.8 848.9 882.4 883.2 4327.9 5226.9 5229.9 5276.5 5327.2 5743.6 5748.4 5752.3 5752.5 5768.3 4613.0 4752.4 5036.7 5208.5 5232.1 2334.5 2962.7 2968.9 2972.0 2975.2 5014.4 5429.2 5436.8 5458.1 5580.0 5841.5 5849.6 5855.9 5879.6 5898.7 4818.3 5284.2 5657.9 5721.8 5958.8 4970.9 5037.2 5131.4 5304.5 5928.4 5201.7 5257.9 5605.3 5641.0 5937.5 4677.3 5589.0 6092.4 6215.8 6302.7 3453.9 3478.4 3894.4 3905.5 3941.0 5580.8 5892.1 5967.9 5979.8 5981.3 5460.9 5546.7 5738.9 5744.3 5769.4 4996.9 5741.8 5761.3 5767.7 5811.6 4241.5 4955.7 5098.6 5362.8 5662.0 4862.6 5556.9 5745.8 5778.7 5799.0 5008.5 5405.9 5589.2 5600.6 5608.8 16:03:56 INFO - PROCESS | 1938 | 16:03:56 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:25028.33 Median:3591.64 stddev:39269.98 (1093.4%) stddev-sans-first:39337.41 16:03:56 INFO - PROCESS | 1938 | Values: 627.6 709.4 717.5 724.4 752.8 15783.7 16784.7 16810.2 16818.6 16827.0 14953.4 15186.1 15200.0 15222.5 15225.8 686.3 703.6 820.4 837.4 859.4 829.0 838.6 841.5 842.0 843.6 880.2 880.6 880.9 882.6 883.1 712.4 734.2 734.4 872.3 876.9 541.7 542.3 590.6 658.2 681.2 15206.7 15250.9 15263.4 15274.4 15287.0 14203.0 14418.5 14468.8 14553.0 14726.3 10961.8 13149.4 13195.3 13320.5 13560.1 4182.8 4579.7 4640.7 4659.9 4870.3 122736.0 124921.4 125297.0 126389.0 129723.4 11532.3 11546.9 11620.6 11632.4 11885.5 23048.0 23061.0 23069.1 23091.1 23098.7 7988.8 8081.4 8250.4 8318.8 8322.3 4333.0 4457.5 4550.3 4563.2 4661.7 3565.9 3617.3 3693.6 3695.0 3695.3 623.9 664.4 675.9 682.6 691.2 523.5 543.6 560.3 578.4 584.1 7381.8 8795.1 9182.2 9306.1 9349.3 520.8 533.4 547.8 614.8 648.2 4419.3 4487.9 4531.8 4571.5 4653.3 100272.5 105518.5 109681.0 141595.2 147467.0 120554.4 124335.0 125190.0 132353.0 154273.0 95060.0 122128.0 125447.0 126543.0 132924.0 9624.0 9777.4 9924.0 10433.9 10435.2 57906.1 58286.1 65640.7 65757.0 69524.3 75059.0 79191.6 86524.6 93208.0 95258.0 13166.8 13175.8 13239.1 13517.5 13797.7 15324.2 15391.2 15477.9 15952.8 15953.2 8668.4 13684.7 14168.6 14458.1 15028.3 14287.6 14293.9 14305.1 14502.5 14648.5 13907.0 13978.1 13994.0 14290.8 14711.6 11063.3 11182.3 11209.1 11432.3 11598.7 10278.5 11595.7 11617.2 11713.6 11907.8 16:03:56 INFO - PROCESS | 1938 | -------- Summary: end -------- 16:03:56 INFO - PROCESS | 1938 | 16:03:56 INFO - TEST-INFO | 1938: exit 0 16:03:56 INFO - TEST-OK | dromaeo_css | took 1118372ms 16:03:56 INFO - TEST-START | kraken 16:03:56 INFO - operating with platform_type : mac_ 16:03:56 INFO - Initialising browser for kraken test... 16:03:56 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpfK6_Po/profile http://localhost:50513/getInfo.html) 16:03:59 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 16:03:59 INFO - PROCESS | 1942 | colorDepth:24 16:03:59 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 16:03:59 INFO - PROCESS | 1942 | __metrics 16:03:59 INFO - PROCESS | 1942 | 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" 16:03:59 INFO - PROCESS | 1942 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:03:59 INFO - TEST-INFO | 1942: exit 0 16:03:59 INFO - Browser initialized. 16:03:59 INFO - Running cycle 1/1 for kraken test... 16:03:59 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpfK6_Po/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:04:12 INFO - PROCESS | 1943 | RSS: Main: 234958848 16:04:12 INFO - PROCESS | 1943 | 16:04:14 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50513/tests/kraken/driver.html?testName=audio-beat-detection) 16:04:15 INFO - PROCESS | 1943 | RSS: Main: 294522880 16:04:15 INFO - PROCESS | 1943 | 16:04:18 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50513/tests/kraken/driver.html?testName=audio-dft) 16:04:18 INFO - PROCESS | 1943 | RSS: Main: 291733504 16:04:18 INFO - PROCESS | 1943 | 16:04:22 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50513/tests/kraken/driver.html?testName=audio-fft) 16:04:23 INFO - PROCESS | 1943 | RSS: Main: 380878848 16:04:23 INFO - PROCESS | 1943 | 16:04:25 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50513/tests/kraken/driver.html?testName=audio-oscillator) 16:04:25 INFO - PROCESS | 1943 | RSS: Main: 282148864 16:04:25 INFO - PROCESS | 1943 | 16:04:27 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50513/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:04:28 INFO - PROCESS | 1943 | RSS: Main: 285126656 16:04:28 INFO - PROCESS | 1943 | 16:04:31 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50513/tests/kraken/driver.html?testName=imaging-darkroom) 16:04:31 INFO - PROCESS | 1943 | RSS: Main: 325074944 16:04:31 INFO - PROCESS | 1943 | 16:04:34 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50513/tests/kraken/driver.html?testName=imaging-desaturate) 16:04:35 INFO - PROCESS | 1943 | RSS: Main: 327245824 16:04:35 INFO - PROCESS | 1943 | 16:04:38 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50513/tests/kraken/driver.html?testName=json-parse-financial) 16:04:38 INFO - PROCESS | 1943 | RSS: Main: 326545408 16:04:38 INFO - PROCESS | 1943 | 16:04:40 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50513/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:04:41 INFO - PROCESS | 1943 | RSS: Main: 299888640 16:04:41 INFO - PROCESS | 1943 | 16:04:43 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50513/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:04:43 INFO - PROCESS | 1943 | RSS: Main: 305995776 16:04:43 INFO - PROCESS | 1943 | 16:04:45 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50513/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:04:46 INFO - PROCESS | 1943 | RSS: Main: 302989312 16:04:46 INFO - PROCESS | 1943 | 16:04:49 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50513/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:04:50 INFO - PROCESS | 1943 | RSS: Main: 311869440 16:04:50 INFO - PROCESS | 1943 | 16:04:52 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50513/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:04:53 INFO - PROCESS | 1943 | RSS: Main: 314527744 16:04:53 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50513/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50513/tests/kraken/driver.html?testName=ai-astar) 16:04:55 INFO - PROCESS | 1943 | RSS: Main: 358199296 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | __start_tp_report 16:04:55 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 16:04:55 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 16:04:55 INFO - PROCESS | 1943 | |i|pagename|runs| 16:04:55 INFO - PROCESS | 1943 | |0;ai-astar;113;116;154;112;113;112;113;113;112;121 16:04:55 INFO - PROCESS | 1943 | |1;audio-beat-detection;174;136;133;160;106;144;128;106;167;134 16:04:55 INFO - PROCESS | 1943 | |2;audio-dft;184;185;184;184;184;183;184;186;184;181 16:04:55 INFO - PROCESS | 1943 | |3;audio-fft;78;74;76;165;58;58;56;69;139;58 16:04:55 INFO - PROCESS | 1943 | |4;audio-oscillator;83;83;140;72;71;116;121;73;69;131 16:04:55 INFO - PROCESS | 1943 | |5;imaging-gaussian-blur;91;86;90;87;89;86;91;88;90;87 16:04:55 INFO - PROCESS | 1943 | |6;imaging-darkroom;95;93;94;94;94;93;95;96;95;92 16:04:55 INFO - PROCESS | 1943 | |7;imaging-desaturate;122;93;126;143;118;86;86;84;85;84 16:04:55 INFO - PROCESS | 1943 | |8;json-parse-financial;58;53;57;57;53;56;56;55;54;54 16:04:55 INFO - PROCESS | 1943 | |9;json-stringify-tinderbox;66;66;68;80;76;78;74;71;70;72 16:04:55 INFO - PROCESS | 1943 | |10;stanford-crypto-aes;86;86;85;86;87;84;89;87;97;107 16:04:55 INFO - PROCESS | 1943 | |11;stanford-crypto-ccm;136;139;135;138;136;137;140;145;212;137 16:04:55 INFO - PROCESS | 1943 | |12;stanford-crypto-pbkdf2;168;169;169;167;166;167;165;169;173;168 16:04:55 INFO - PROCESS | 1943 | |13;stanford-crypto-sha256-iterative;68;67;66;66;67;67;67;66;68;66 16:04:55 INFO - PROCESS | 1943 | __end_tp_report 16:04:55 INFO - PROCESS | 1943 | __start_cc_report 16:04:55 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,2058 16:04:55 INFO - PROCESS | 1943 | __end_cc_report 16:04:55 INFO - PROCESS | 1943 | __startTimestamp1471993495552__endTimestamp 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | ------- Summary: start ------- 16:04:55 INFO - PROCESS | 1943 | Number of tests: 14 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#0] ai-astar Cycles:10 Average:117.90 Median:113.00 stddev:12.98 (11.5%) stddev-sans-first:13.65 16:04:55 INFO - PROCESS | 1943 | Values: 113.0 116.0 154.0 112.0 113.0 112.0 113.0 113.0 112.0 121.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:140.00 stddev:23.20 (16.6%) stddev-sans-first:20.82 16:04:55 INFO - PROCESS | 1943 | Values: 174.0 136.0 133.0 160.0 106.0 144.0 128.0 106.0 167.0 134.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.29 (0.7%) stddev-sans-first:1.36 16:04:55 INFO - PROCESS | 1943 | Values: 184.0 185.0 184.0 184.0 184.0 183.0 184.0 186.0 184.0 181.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#3] audio-fft Cycles:10 Average:83.10 Median:63.50 stddev:37.73 (59.4%) stddev-sans-first:39.98 16:04:55 INFO - PROCESS | 1943 | Values: 78.0 74.0 76.0 165.0 58.0 58.0 56.0 69.0 139.0 58.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:71.50 stddev:27.85 (39.0%) stddev-sans-first:29.15 16:04:55 INFO - PROCESS | 1943 | Values: 83.0 83.0 140.0 72.0 71.0 116.0 121.0 73.0 69.0 131.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:89.50 stddev:1.96 (2.2%) stddev-sans-first:1.86 16:04:55 INFO - PROCESS | 1943 | Values: 91.0 86.0 90.0 87.0 89.0 86.0 91.0 88.0 90.0 87.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.50 stddev:1.20 (1.3%) stddev-sans-first:1.22 16:04:55 INFO - PROCESS | 1943 | Values: 95.0 93.0 94.0 94.0 94.0 93.0 95.0 96.0 95.0 92.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#7] imaging-desaturate Cycles:10 Average:102.70 Median:84.50 stddev:22.21 (26.3%) stddev-sans-first:22.43 16:04:55 INFO - PROCESS | 1943 | Values: 122.0 93.0 126.0 143.0 118.0 86.0 86.0 84.0 85.0 84.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:1.77 (3.2%) stddev-sans-first:1.58 16:04:55 INFO - PROCESS | 1943 | Values: 58.0 53.0 57.0 57.0 53.0 56.0 56.0 55.0 54.0 54.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:73.00 stddev:4.86 (6.7%) stddev-sans-first:4.63 16:04:55 INFO - PROCESS | 1943 | Values: 66.0 66.0 68.0 80.0 76.0 78.0 74.0 71.0 70.0 72.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:86.50 stddev:7.17 (8.3%) stddev-sans-first:7.50 16:04:55 INFO - PROCESS | 1943 | Values: 86.0 86.0 85.0 86.0 87.0 84.0 89.0 87.0 97.0 107.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:138.50 stddev:23.54 (17.0%) stddev-sans-first:24.71 16:04:55 INFO - PROCESS | 1943 | Values: 136.0 139.0 135.0 138.0 136.0 137.0 140.0 145.0 212.0 137.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.10 Median:168.50 stddev:2.18 (1.3%) stddev-sans-first:2.32 16:04:55 INFO - PROCESS | 1943 | Values: 168.0 169.0 169.0 167.0 166.0 167.0 165.0 169.0 173.0 168.0 16:04:55 INFO - PROCESS | 1943 | 16:04:55 INFO - PROCESS | 1943 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.79 (1.2%) stddev-sans-first:0.71 16:04:55 INFO - PROCESS | 1943 | Values: 68.0 67.0 66.0 66.0 67.0 67.0 67.0 66.0 68.0 66.0 16:04:55 INFO - PROCESS | 1943 | -------- Summary: end -------- 16:04:55 INFO - PROCESS | 1943 | 16:04:56 INFO - TEST-INFO | 1943: exit 0 16:04:56 INFO - TEST-OK | kraken | took 59605ms 16:04:56 INFO - SUITE-END | took 1177s 16:04:56 INFO - Completed test suite (00:19:38) 16:04:56 INFO - javascript benchmark 16:04:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.933081338373, 3346.916327091823, 3382.2051817822794, 3400.812955287461, 3442.5180474733543, 5677.57418193635, 5702.515286200673, 5759.193712880189, 5801.9129713054335, 5840.182374467573, 5763.654180749158, 5790.855228619286, 5844.1818145459665, 5861.7948371807, 5948.226730525026, 5213.556847667953, 5261.947380526203, 5285.762140703678, 5311.681299122065, 5394.352677678684, 5385.892282154355, 5393.0, 5398.433164517728, 5408.567314614821, 5438.456154384569, 5223.190405487156, 5276.736163191864, 5280.603954703393, 5286.101362768339, 5323.307969963918, 5298.337707786527, 5321.787128514855, 5322.627416080876, 5329.946700532983, 5338.599605029638, 5228.163493840967, 5275.683458992473, 5277.419483856773, 5291.100512912775, 5298.15229563268, 5710.229119068914, 5755.597108202427, 5767.480926716616, 5773.364929857712, 5773.855653608652, 6183.84540386494, 6213.254409470893, 6237.87524249517, 6263.6868156592445, 6299.118123462719, 6238.344973777779, 6283.8114856554375, 6294.590851594678, 6307.905381419282, 6312.715927783261, 5694.316681998104, 5747.885042299215, 5850.502707269845, 5850.765969361263, 5853.414658534112, 5620.4660557247, 6206.658633775095, 6220.191375121205, 6308.0, 6313.55805093639, 6177.8455538611, 6200.81397558065, 6214.5960512566535, 6218.19163508741, 6243.875122497616, 6142.600730952474, 6222.782202622887, 6258.468030217391, 6288.842778930563, 6303.6532990685955, 6217.6891155442045, 6272.749090036347, 6295.716692748837, 6308.116863639094, 6316.463100636497, 6306.527010474233, 6309.0, 6314.494840412756, 6319.46284565817, 6335.334789847092, 4846.7818948147415, 4902.7058376497525, 4911.877203069952, 4977.0, 5042.899142017212, 4422.624076953431, 4489.416375871181, 4507.391502147234, 4512.684112112121, 4565.315202719618, 5780.5953583248765, 5792.0, 5793.565482588822, 5810.273715785527, 5817.0983497557945, 6285.182926219562, 6315.5894866833505, 6318.905216421757, 6323.146375239376, 6345.07996340528, 5616.410276920946, 5725.2843394575675, 5773.39379365169, 5776.826695199151, 5869.413058694096, 5455.890882182334, 5458.263134476874, 5466.508014278734, 5471.808486703026, 5476.288082549243, 6253.468455181361, 6285.465735412449, 6300.0234963580715, 6323.810285691437, 6344.143540621957, 5149.613778966517, 6184.474319682878, 6251.0623406487575, 6253.062040693932, 6303.243610766737, 6323.272823625158, 6327.430531252209, 6332.968335158479, 6336.3030066693545, 6340.968295158495, 6147.754089836357, 6189.659568723763, 6218.284897236876, 6221.844453888689, 6225.439710426083, 6122.916604401471, 6144.494598823317, 6184.567080304336, 6264.060390941395, 6319.494440444845, 6153.538484613761, 6186.566940314135, 6197.504199663926, 6197.566170368031, 6214.9689251553455, 6159.84600384994, 6190.102435146789, 6207.286162091417, 6211.223597050369, 6228.906566401598, 5800.24596802413, 5977.970110149421, 6003.49040139616, 6026.849328766816, 6123.663198524124, 6117.406068681613, 6178.165947597017, 6181.381861813783, 6198.752049917953, 6199.1011303359855, 6001.459868611846, 6004.909926351195, 6016.8796624068145, 6032.788852390146, 6081.878362432638], "value": 5755.132708784839, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.0254071076022, 524.1247117314085, 524.3733738182873, 530.3291336459378, 547.3377213571579, 24972.50054998901, 32467.188320292003, 37790.055248618795, 39040.99999999996, 39078.60921390793, 40426.989325266884, 40736.0, 40739.0, 40770.0, 40813.38779918304, 8160.387970902191, 8650.0, 9688.75778105546, 9711.514424278757, 9715.70852874415, 8648.394612377137, 11500.654980350602, 11775.411229438536, 11800.704982375424, 11996.280223186594, 11793.823092653616, 11823.704407389798, 11869.52521899123, 11879.990200832939, 11906.642800715948, 11713.0, 11795.764084718257, 11812.7637447251, 11823.527058917633, 11832.467538960767, 11526.366049867254, 11611.6516504505, 11620.941895290469, 11629.418529073513, 11630.709232269177, 38108.42837357442, 38463.230735385136, 38498.230035399414, 38519.229615407814, 38525.37695476533, 3274.361499507597, 3322.7507936904585, 3440.82795860206, 3510.9297814043575, 3583.229605634801, 27968.902332324953, 30010.549841752392, 31179.84410077935, 31354.0, 31591.21021974469, 2938.7061293870656, 3210.951835722466, 3319.96680033199, 3360.663933606644, 3394.8811791587177, 512452.0, 512706.0, 515285.0, 517293.0, 517881.8211817871, 55028.449715502335, 55340.72329638326, 55371.723141384035, 55391.0, 55468.445315547135, 58478.70760646169, 58629.12056319159, 58675.0, 58687.70656146692, 58692.0, 15538.300776465087, 15683.451079212173, 15710.9214453927, 15744.763828542582, 15788.447404341023, 9790.657326993522, 9904.0, 11291.43542822872, 12241.816372754416, 12306.507739690313, 13137.94896408285, 13262.668433289246, 13289.0, 14484.493042743454, 14856.554303370916, 6715.597064176165, 6814.512702863518, 6893.620850853251, 6921.6193109379465, 6930.79207623762, 837.8408102460513, 991.4794732765356, 1004.4927311707687, 1049.4175732468552, 1125.1111621818836, 27274.45451091007, 28634.997775077773, 28976.27559310992, 29938.101856944326, 29949.85025074861, 887.4409122253069, 1038.5482315192914, 1058.5501162006271, 1070.3043022035583, 1108.1578000719512, 14138.929305353407, 15010.69978600422, 15011.624709382135, 15201.543953681406, 15427.84572154264, 185065.0, 185465.0, 185493.0, 185895.0, 953695.0, 53972.46027539753, 54820.90358192814, 55126.17310740342, 57931.420685792604, 58310.4168958305, 1330.8935285177163, 1366.644672385187, 1377.8553251908606, 1386.6048176269715, 1386.7503849307222, 28763.0, 29774.851125744233, 29996.550051749677, 30257.0, 30638.693613064475, 52411.0, 53462.73268633632, 53676.73161634167, 56692.14961775494, 56964.71517642385, 618285.0, 622720.0, 622722.0, 624136.0, 626854.8657256685, 25081.49837003213, 25340.0, 25465.0, 25494.362640933385, 25497.49005019852, 45189.54810451985, 45440.0, 46183.0, 46703.29945050759, 46762.532374675815, 32902.506462402605, 36063.0, 38646.2270754589, 38719.225615488096, 39062.63280785159, 35282.82358588191, 37325.06687332838, 37618.0, 37784.433233502066, 38533.614663854125, 34831.65168348284, 34934.0, 35048.12379690426, 37730.0, 37934.0, 25456.0, 31153.532697010018, 33323.50044248063, 33419.16452088717, 33657.326853462306, 24514.264572062868, 24744.50510989734, 24887.875560622804, 24991.41983138089, 25027.87486062558], "value": 19425.53390964645, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.90295168828584, 358.4855732024545, 370.5312779334142, 371.4687996165484, 393.03509883236654, 14860.702785944288, 15532.611684707888, 20855.895720521414, 20946.371608851758, 21782.346529604136, 12218.38908054596, 12336.321502317369, 12380.442880070395, 14282.714345713079, 20538.69191962122, 4039.9798001009954, 5224.973875130619, 5479.6164268501225, 5487.149491828753, 6091.847703807396, 5986.910196347058, 6164.229471316085, 6170.197874276338, 6178.907316390235, 6181.1037399577035, 6174.259088909337, 6218.906716399235, 6664.4002039816405, 6728.0, 6752.426043786284, 6047.425494578008, 6381.202349706286, 6706.664666766666, 6719.529632925697, 6723.09238252837, 5968.612040217372, 5969.492593129589, 5970.014947533652, 5972.581919265654, 5984.162217289583, 13715.657108572266, 16712.830101892872, 19116.904415477973, 26681.0, 27003.594946075824, 2098.916043358264, 2137.5724855029057, 2148.8611036150864, 2195.9451013724624, 2310.4223944013997, 9699.369540979813, 10199.0, 10348.741281467948, 10615.363078215254, 10621.5220315086, 1881.7365568820376, 1885.1422602715732, 1891.6500447417272, 1963.813437723414, 1963.9214431422727, 133501.3324933379, 134855.0, 135799.32100339534, 136586.0, 139475.0, 11301.321920684784, 11403.258788178742, 11462.332053742723, 11479.25384849982, 11667.183297169286, 12694.49222031127, 13484.325783710776, 13703.657408564666, 14370.712585748226, 14414.639634009023, 6851.0, 8089.838203235902, 8178.5092894426525, 8267.62795674196, 8472.74581762548, 7954.960225198953, 8216.0, 8234.629441675139, 8362.790930226793, 8410.201030902177, 3937.704672149543, 4334.111507141029, 4523.638108951276, 4578.664880910759, 4651.976565155728, 1881.2757088521018, 1912.7130930360279, 1917.3960202536157, 1920.2894928876406, 1936.4190742777391, 821.7000794709888, 870.060334838373, 875.282268539804, 939.8543225800012, 990.3166814897697, 19749.012549372474, 20004.49988750293, 21408.78591214099, 22180.88909555442, 22360.105595776313, 874.2044739287345, 884.6417201033591, 900.7477906186278, 917.9935710548009, 937.6905621145006, 8243.793905152419, 10585.682429527127, 11558.711032224093, 11588.76822463546, 11599.710007249718, 593229.0, 612207.0, 615102.0, 615190.0, 633803.0, 105018.47490762497, 131762.07070681843, 132370.67629323583, 134462.0, 134781.0, 953.080277532171, 959.2421986630484, 992.5930368549004, 993.6671215142864, 994.7562847102506, 18969.90515047416, 19224.231030759172, 19261.0, 19294.90352548228, 19314.227430903167, 54145.646358841346, 55533.889322214134, 55623.0, 55940.44059559352, 56257.71871140618, 70437.0, 77164.0, 78543.21456785359, 79690.0, 81123.59438202772, 7318.012068370915, 7931.960340198262, 8007.559584222924, 8177.223163799429, 8416.158384161536, 8711.39020268575, 12872.871271287167, 13279.136856104322, 13323.067385282937, 13361.933190333986, 10773.362510568397, 10886.945565272123, 11101.888981110085, 11283.492242848928, 11363.602273920373, 11238.831417528907, 11440.42797860087, 11545.018673412855, 11773.0, 11777.175597708077, 10466.267361284637, 11286.322820630789, 11638.88361116378, 11869.465874035517, 11892.227005244631, 7845.960770196112, 8012.0, 8037.196280371916, 8458.873116903374, 8460.365472589703, 5379.031774280667, 5424.5660347172925, 7056.329648683367, 8528.147185281423, 11132.276402033842], "value": 9348.444109130292, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1352.465776018472, 1392.422144809904, 1491.1127878912048, 1642.4087328561716, 1844.3821319857855, 7747.496412733169, 8220.136885626998, 8220.177982201776, 8229.259366657, 8235.135310792362, 8381.95809020954, 8673.658112309688, 8673.826523469526, 8734.91265087349, 8737.86893196601, 2367.95264094718, 2399.4841109161525, 2402.939926501843, 2403.375122468153, 2405.0860672944254, 2329.77867102625, 2363.6927199464044, 2371.3834403055157, 2378.357843382286, 2379.7977171940406, 2347.424880904181, 2365.0894405653776, 2377.9762202378015, 2386.642003699442, 2388.7491813359607, 2322.059565875823, 2350.330155905567, 2354.2701762453607, 2358.893849776764, 2359.374765687094, 2278.977210227893, 2302.6200676888293, 2304.8271379646594, 2307.607706689867, 2310.849794763352, 8676.305895528343, 8844.955775221148, 8859.424137431046, 8872.778180545474, 8883.911160888438, 5181.948180518222, 5188.870278243036, 5353.357597088374, 5354.946450535484, 5384.300040994645, 3586.39031364666, 3671.889843304705, 3673.210259794157, 3675.871344502956, 3698.5007024051947, 1106.5739690219323, 1116.3302018788743, 1138.7836311100948, 1141.4635121492886, 1141.8401423800674, 11233.32600044, 11529.481173347136, 11573.189876708633, 11614.651560453116, 11674.70813229676, 4396.911467053124, 4459.643228541709, 4633.907321853611, 4643.837465688753, 4669.719816811003, 8000.199980002069, 9125.406848554823, 9133.680321188727, 9144.222741067084, 9429.057094290652, 5249.186376111708, 5251.238570407269, 5252.816151434758, 5255.395629502656, 5275.4724527547705, 4390.736555806598, 4543.068670922453, 4788.353572267699, 4940.92588611164, 4946.653734238641, 3325.6341802401716, 3712.5730540987593, 3785.7539259948016, 3820.598837122118, 3859.6526312632, 1159.3333833046033, 1163.3892206591606, 1164.6680696001768, 1175.5944199251244, 1176.1531697178018, 915.6154415145655, 921.2077613252598, 927.3415874728884, 938.0, 985.827480190978, 5280.973595132076, 5710.486056254957, 5924.229850119457, 6181.81454556364, 6208.099825525273, 385.25837762308004, 419.89712520432687, 429.61549413275566, 438.0844035964849, 452.15672770283186, 3804.31522325984, 4030.6528662420264, 4091.161311931047, 4120.299549076664, 4178.310578754532, 9055.95472022649, 10337.431441270803, 10775.191860610328, 10788.676339709822, 10793.83809242862, 10727.946360268148, 10740.570377184982, 10760.246782725291, 10761.30051546647, 10805.513751881184, 7754.5734984575265, 8027.518348899085, 8220.506769593843, 8237.423380363318, 8430.807989922541, 5337.786488540415, 5777.544543569051, 6016.8796624068145, 6100.694965251809, 6165.815025549241, 10074.244431667801, 10122.139618132247, 10180.643677471253, 10182.287239893136, 10203.591856325664, 9686.0, 10148.137408320375, 10158.796824063626, 10182.694519164437, 10285.640002599874, 7065.540739851893, 7280.344768970818, 7375.299346562068, 7521.172671006294, 7716.035495563055, 8293.83412331762, 8557.44376615518, 8561.22948934599, 8566.35752318591, 8580.356473264404, 7514.549127052394, 7578.810529736801, 7606.391488681003, 7622.466427350032, 7771.4559980800805, 7270.382017528358, 7295.562266263829, 7316.926830731624, 7410.814729631802, 7498.512596681198, 6924.169099708067, 7047.718091276497, 7073.257307982588, 7082.18554866197, 7082.716691332289, 4921.827736029221, 5052.69683818972, 5087.643864929568, 5129.410117836496, 5147.121300725284, 4994.825181118788, 5101.438841727481, 5189.11784996544, 5214.739263036909, 5283.418823929288], "value": 4470.473644220724, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.2445763303575, 297.10126866229655, 306.9892553760618, 309.7336290789919, 310.4923450158991, 4951.306817045616, 5364.302640656714, 5373.704446255454, 5399.379071406789, 5401.513863752261, 4923.704577725339, 5018.247262910565, 5092.872678183038, 5131.7690703918415, 5368.570514358852, 3515.8945231643092, 3674.7427680062406, 3895.590962948892, 4115.650169735576, 4147.605977432139, 3519.87680431185, 3899.590542992973, 4156.521999969997, 4169.437125987998, 4176.3085950190225, 3335.4323467970135, 3464.4110501214855, 4060.4315395844606, 4139.81370838313, 4141.358089496133, 3397.706548080036, 3564.7861128332383, 4031.6169963853386, 4057.4928133983253, 4095.1195492969005, 3208.6470488246273, 3270.8528116234825, 3512.648735126467, 3520.383932811748, 3536.982315088408, 4484.775761211959, 4763.951930575303, 5219.582433405319, 5301.549368303698, 5323.600729945231, 4163.5836416358425, 4247.851325203603, 4644.373009643688, 4648.465426475965, 4688.171613070905, 5613.494785469286, 5675.581104723819, 5677.40387259336, 5678.716064196774, 5684.658920464744, 3846.2499812536566, 3888.1640447303685, 3890.5914878937588, 3891.754638515676, 3891.905934781855, 4775.952240477585, 5385.78456861725, 5702.54379649627, 5964.493018093423, 6057.546693589059, 3416.350893330259, 3672.155404257036, 3875.5466699987505, 3881.5342158940543, 3889.8216998480375, 3442.638522955104, 3944.467496887941, 3958.366661334173, 3980.6315242430705, 3991.401289806552, 4391.912161756747, 4471.5081341052455, 4571.954280457219, 4669.229577119805, 4689.718616882998, 4225.133847561243, 5050.72221027847, 5237.240600112962, 5436.646617969898, 5631.887362252732, 1089.836524521328, 1177.43483128099, 1346.9897576817386, 1349.062401630857, 1357.8777909988148, 750.9111787907556, 848.7523340689177, 848.9176300217149, 882.3955590420583, 883.2271762208068, 4327.891802704958, 5226.92159617606, 5229.901720638709, 5276.5251127398715, 5327.188755823039, 5743.569232307594, 5748.367679555246, 5752.309722833287, 5752.511036561939, 5768.278965129359, 4613.0, 4752.358431611758, 5036.697798132123, 5208.453112423217, 5232.110541208004, 2334.4514039200776, 2962.674105848355, 2968.9406211875644, 2972.0, 2975.2413134650524, 5014.448410674823, 5429.1856221566295, 5436.836894893159, 5458.052651568188, 5579.972100139474, 5841.474267315962, 5849.590528662953, 5855.853603659857, 5879.647221166743, 5898.675572843449, 4818.277258411266, 5284.233786100994, 5657.886842263131, 5721.828345149569, 5958.821235362946, 4970.931249781314, 5037.168867136881, 5131.3836141615275, 5304.496072873071, 5928.3775203603, 5201.687898726089, 5257.921131182959, 5605.3184044785585, 5640.982074590074, 5937.5249980000635, 4677.32178834074, 5588.972055139699, 6092.421219984094, 6215.751369945152, 6302.684865756785, 3453.912017714412, 3478.426059700121, 3894.415837624379, 3905.4614500573716, 3941.0, 5580.832575022756, 5892.057270836645, 5967.880642387215, 5979.760809567569, 5981.3420523743225, 5460.890782184413, 5546.694931778629, 5738.9139162913425, 5744.310682718101, 5769.394213607511, 4996.900681849987, 5741.770329186953, 5761.279840019997, 5767.68277744728, 5811.622244554091, 4241.53343132249, 4955.6531042826655, 5098.617603679813, 5362.758675859518, 5661.952538780339, 4862.610991120631, 5556.943680975366, 5745.827625171251, 5778.739956702043, 5798.955548337157, 5008.549230569266, 5405.86476839868, 5589.245451864109, 5600.551955843604, 5608.831735047955], "value": 4060.457253456783, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.6328347916467, 709.3615745828754, 717.4762423430899, 724.4132252875172, 752.8005078654154, 15783.684326313467, 16784.664306713858, 16810.159492025396, 16818.579535511588, 16826.990380577157, 14953.401863925457, 15186.088834669927, 15200.011999220043, 15222.467213647524, 15225.84774152258, 686.327399148832, 703.5884007855411, 820.3765138494742, 837.4431003382743, 859.415597393772, 828.9544075075869, 838.584900474263, 841.4824882697159, 842.0190478093045, 843.5529169540154, 880.1770344727668, 880.6169316347377, 880.9207171354577, 882.5984177199359, 883.0727735877335, 712.3659942651045, 734.1923883727857, 734.4051318432033, 872.2672954718067, 876.89915659699, 541.6506353402034, 542.3031404645052, 590.6220019187723, 658.2331583704978, 681.151965802579, 15206.695866082617, 15250.923745381313, 15263.38946442141, 15274.38902443901, 15287.0, 14203.0, 14418.49535266271, 14468.782968255484, 14553.0, 14726.263686815617, 10961.780764384748, 13149.408276627575, 13195.2742599157, 13320.467181312735, 13560.11859229147, 4182.769947652878, 4579.67942244043, 4640.69835460694, 4659.860204193879, 4870.293807397943, 122736.0, 124921.37539312337, 125297.0, 126389.0, 129723.35138324343, 11532.25040372373, 11546.94226528862, 11620.593279235187, 11632.360220187971, 11885.465154068088, 23048.0, 23060.962256698487, 23069.077236910336, 23091.07635694587, 23098.65352019721, 7988.84022319562, 8081.434299599089, 8250.422470427133, 8318.750437486886, 8322.250997410141, 4333.0, 4457.487388950248, 4550.34020067095, 4563.178627847019, 4661.743604101739, 3565.9286814264087, 3617.3488772021287, 3693.6121707220354, 3694.9815250923575, 3695.334839728821, 623.865868838202, 664.4086762781129, 675.8884784010681, 682.6313790553145, 691.1740470138137, 523.5472553356747, 543.570579242394, 560.341598621618, 578.3840210176149, 584.0800738836265, 7381.778546643609, 8795.120487951153, 9182.17360437564, 9306.115918987685, 9349.298802589832, 520.841143451251, 533.3732863884919, 547.7671989404487, 614.8155553333982, 648.2156590525517, 4419.270820314677, 4487.865364039084, 4531.750753708512, 4571.525640513234, 4653.302004699322, 100272.49863750635, 105518.47240763901, 109681.0, 141595.17667526554, 147467.0, 120554.3972280133, 124335.0, 125190.0, 132353.0, 154273.0, 95060.0, 122128.0, 125447.0, 126543.0, 132924.0, 9624.0, 9777.41335519871, 9923.950380248341, 10433.9478302608, 10435.217358698279, 57906.13140802807, 58286.12570811356, 65640.67179664072, 65757.04093315717, 69524.26189345306, 75059.0, 79191.60404197942, 86524.5673771627, 93208.0, 95258.0, 13166.802497962346, 13175.802362964372, 13239.073264871367, 13517.459301627825, 13797.655058623213, 15324.233788310317, 15391.230438478255, 15477.922610386877, 15952.840471595135, 15953.202339883192, 8668.388926157117, 13684.698100896752, 14168.645783855485, 14458.06022608527, 15028.323725432163, 14287.642808929444, 14293.928530357281, 14305.141691498544, 14502.492412765503, 14648.487302944346, 13907.0, 13978.091424057404, 13994.0, 14290.785638215642, 14711.55865324042, 11063.280886742335, 11182.273152245078, 11209.07614283797, 11432.256903301259, 11598.710032248924, 10278.537465814206, 11595.710107247387, 11617.186796924134, 11713.590024349107, 11907.761844762883], "value": 7880.840158025403, "unit": "score"}], "name": "dromaeo_css", "value": 7285.383334161299, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 154.0, 112.0, 113.0, 112.0, 113.0, 113.0, 112.0, 121.0], "value": 117.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 136.0, 133.0, 160.0, 106.0, 144.0, 128.0, 106.0, 167.0, 134.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 184.0, 184.0, 184.0, 183.0, 184.0, 186.0, 184.0, 181.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 76.0, 165.0, 58.0, 58.0, 56.0, 69.0, 139.0, 58.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 140.0, 72.0, 71.0, 116.0, 121.0, 73.0, 69.0, 131.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 90.0, 87.0, 89.0, 86.0, 91.0, 88.0, 90.0, 87.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 94.0, 94.0, 94.0, 93.0, 95.0, 96.0, 95.0, 92.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 93.0, 126.0, 143.0, 118.0, 86.0, 86.0, 84.0, 85.0, 84.0], "value": 102.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 53.0, 57.0, 57.0, 53.0, 56.0, 56.0, 55.0, 54.0, 54.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 80.0, 76.0, 78.0, 74.0, 71.0, 70.0, 72.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 86.0, 87.0, 84.0, 89.0, 87.0, 97.0, 107.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 139.0, 135.0, 138.0, 136.0, 137.0, 140.0, 145.0, 212.0, 137.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 169.0, 167.0, 166.0, 167.0, 165.0, 169.0, 173.0, 168.0], "value": 168.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 66.0, 67.0, 67.0, 67.0, 66.0, 68.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1502.1, "alertThreshold": 2.0}]} 16:04:56 INFO - Return code: 0 16:04:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:04:56 INFO - # TBPL SUCCESS # 16:04:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:04:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:04:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:04:56 INFO - 2016-08-23 16:04:56.902 screenresolution[1947:13076] starting screenresolution argv=screenresolution get 16:04:56 INFO - 2016-08-23 16:04:56.905 screenresolution[1947:13076] Display 0: 1600x1200x32@60 16:04:56 INFO - 2016-08-23 16:04:56.911 screenresolution[1948:13079] starting screenresolution argv=screenresolution list 16:04:56 INFO - Available Modes on Display 0 16:04:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:04:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:04:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:04:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:04:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:04:57 INFO - Intel Iris: 16:04:57 INFO - Chipset Model: Intel Iris 16:04:57 INFO - Type: GPU 16:04:57 INFO - Bus: Built-In 16:04:57 INFO - VRAM (Dynamic, Max): 1536 MB 16:04:57 INFO - Vendor: Intel (0x8086) 16:04:57 INFO - Device ID: 0x0a2e 16:04:57 INFO - Revision ID: 0x0009 16:04:57 INFO - Displays: 16:04:57 INFO - Display: 16:04:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:04:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:04:57 INFO - Display Serial Number: 1600x1200 60 16:04:57 INFO - Main Display: Yes 16:04:57 INFO - Mirror: Off 16:04:57 INFO - Online: Yes 16:04:57 INFO - Rotation: Supported 16:04:57 INFO - Return code: 0 16:04:57 INFO - Running post-action listener: _resource_record_post_action 16:04:57 INFO - [mozharness: 2016-08-23 23:04:57.145878Z] Finished run-tests step (success) 16:04:57 INFO - Running post-run listener: _resource_record_post_run 16:04:57 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 843776; Write bytes: 705151488; Read time: 10; Write time: 1931 16:04:57 INFO - TinderboxPrint: CPU usage
53.7% 16:04:57 INFO - TinderboxPrint: I/O read bytes / time
843,776 / 10 16:04:57 INFO - TinderboxPrint: I/O write bytes / time
705,151,488 / 1,931 16:04:57 INFO - TinderboxPrint: CPU idle
2,245.7 (46.3%) 16:04:57 INFO - TinderboxPrint: CPU system
979.2 (20.2%) 16:04:57 INFO - TinderboxPrint: CPU user
1,628.8 (33.6%) 16:04:57 INFO - TinderboxPrint: Swap in / out
406,732,800 / 0 16:04:57 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390535680; Write bytes: 384591360; Read time: 29483; Write time: 761 16:04:57 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 843776; Write bytes: 297155584; Read time: 10; Write time: 1115 16:04:57 INFO - Running post-run listener: _upload_blobber_files 16:04:57 INFO - Blob upload gear active. 16:04:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:04:57 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 16:04:57 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:04:57 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:04:58 INFO - (blobuploader) - INFO - Open directory for files ... 16:04:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:04:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:04:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:59 INFO - (blobuploader) - INFO - Done attempting. 16:04:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:04:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:00 INFO - (blobuploader) - INFO - Done attempting. 16:05:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:05:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:05:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:02 INFO - (blobuploader) - INFO - Done attempting. 16:05:02 INFO - (blobuploader) - INFO - Iteration through files over. 16:05:02 INFO - Return code: 0 16:05:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:05:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:05:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/99b59f836df577a299b50a4dd38a53f9eb1250b4d4ac0dbdf71d8cab3ac51c26444bce9a323e2a7e912b39b434cdafdb31e0cb385baf5ba8353ecfbd231f59b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4347a523a693e2b37924f36cdf4d39e9821a2f9eb1b889ccba4b2af775cd217195b435b6e2d9788f7c10ae7e27bdeefa197a01b41499a95ee277953534d7a71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cf358e8b225fa655e49690153930cdbd24c20d5733a0dbfe0127b98944fc6ca035d70308401ebdc3e58e4e887976efdd9e9cddc19d053cdcf4c86132bcd765f"} 16:05:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:05:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:05:02 INFO - Contents: 16:05:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/99b59f836df577a299b50a4dd38a53f9eb1250b4d4ac0dbdf71d8cab3ac51c26444bce9a323e2a7e912b39b434cdafdb31e0cb385baf5ba8353ecfbd231f59b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4347a523a693e2b37924f36cdf4d39e9821a2f9eb1b889ccba4b2af775cd217195b435b6e2d9788f7c10ae7e27bdeefa197a01b41499a95ee277953534d7a71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cf358e8b225fa655e49690153930cdbd24c20d5733a0dbfe0127b98944fc6ca035d70308401ebdc3e58e4e887976efdd9e9cddc19d053cdcf4c86132bcd765f"} 16:05:02 INFO - Running post-run listener: copy_logs_to_upload_dir 16:05:02 INFO - Copying logs to upload dir... 16:05:02 INFO - mkdir: /builds/slave/test/build/upload/logs 16:05:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.759412 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-23 16:05:02.577193) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 16:05:02.580362) ========= 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.Bw6ZfFvJor/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.objGopAqpl/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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/Fx-Team/sha512/99b59f836df577a299b50a4dd38a53f9eb1250b4d4ac0dbdf71d8cab3ac51c26444bce9a323e2a7e912b39b434cdafdb31e0cb385baf5ba8353ecfbd231f59b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4347a523a693e2b37924f36cdf4d39e9821a2f9eb1b889ccba4b2af775cd217195b435b6e2d9788f7c10ae7e27bdeefa197a01b41499a95ee277953534d7a71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cf358e8b225fa655e49690153930cdbd24c20d5733a0dbfe0127b98944fc6ca035d70308401ebdc3e58e4e887976efdd9e9cddc19d053cdcf4c86132bcd765f"} build_url:https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011232 build_url: 'https://queue.taskcluster.net/v1/task/b_gtrB5-RKiNSAeI7wLiqg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/99b59f836df577a299b50a4dd38a53f9eb1250b4d4ac0dbdf71d8cab3ac51c26444bce9a323e2a7e912b39b434cdafdb31e0cb385baf5ba8353ecfbd231f59b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4347a523a693e2b37924f36cdf4d39e9821a2f9eb1b889ccba4b2af775cd217195b435b6e2d9788f7c10ae7e27bdeefa197a01b41499a95ee277953534d7a71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cf358e8b225fa655e49690153930cdbd24c20d5733a0dbfe0127b98944fc6ca035d70308401ebdc3e58e4e887976efdd9e9cddc19d053cdcf4c86132bcd765f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 16:05:02.614133) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 16:05:02.614460) ========= 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.Bw6ZfFvJor/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.objGopAqpl/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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.005144 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 16:05:02.662943) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 16:05:02.663282) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 16:05:02.663618) ========= ========= Total master_lag: 0.58 =========