builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0038 starttime: 1472763598.9 results: success (0) buildid: 20160901111321 builduid: f0df6025abeb4b85b733ba7b66f4dc2d revision: d667c9cd84f16752ae92c2b683afcc35e0483ec3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 13:59:58.896329) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 13:59:58.896817) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 13:59:58.897095) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 13:59:58.923059) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 13:59:58.923460) ========= 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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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-09-01 13:59:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.44M=0.001s 2016-09-01 13:59:59 (9.44 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092070 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 13:59:59.038409) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 13:59:59.038756) ========= 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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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.027960 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 13:59:59.087782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 13:59:59.088133) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d667c9cd84f16752ae92c2b683afcc35e0483ec3 --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 d667c9cd84f16752ae92c2b683afcc35e0483ec3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4jVDMUuc0n/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.Ua86KsXNyT/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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-09-01 13:59:59,160 truncating revision to first 12 chars 2016-09-01 13:59:59,161 Setting DEBUG logging. 2016-09-01 13:59:59,161 attempt 1/10 2016-09-01 13:59:59,161 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d667c9cd84f1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 14:00:00,442 unpacking tar archive at: fx-team-d667c9cd84f1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.560528 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 14:00:00.673025) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 14:00:00.673542) ========= script_repo_revision: d667c9cd84f16752ae92c2b683afcc35e0483ec3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 14:00:00.674184) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 14:00:00.674634) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 14:00:00.693823) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-09-01 14:00:00.694173) ========= /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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 14:00:00 INFO - MultiFileLogger online at 20160901 14:00:00 in /builds/slave/test 14:00:00 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 14:00:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:00:00 INFO - {'append_to_log': False, 14:00:00 INFO - 'base_work_dir': '/builds/slave/test', 14:00:00 INFO - 'blob_upload_branch': 'Fx-Team', 14:00:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:00:00 INFO - 'branch': 'Fx-Team', 14:00:00 INFO - 'buildbot_json_path': 'buildprops.json', 14:00:00 INFO - 'config_files': ('talos/mac_config.py',), 14:00:00 INFO - 'default_actions': ('clobber', 14:00:00 INFO - 'read-buildbot-config', 14:00:00 INFO - 'download-and-extract', 14:00:00 INFO - 'populate-webroot', 14:00:00 INFO - 'create-virtualenv', 14:00:00 INFO - 'install', 14:00:00 INFO - 'run-tests'), 14:00:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:00:00 INFO - 'download_minidump_stackwalk': True, 14:00:00 INFO - 'download_symbols': 'ondemand', 14:00:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:00:00 INFO - 'tooltool.py': '/tools/tooltool.py', 14:00:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:00:00 INFO - '/tools/misc-python/virtualenv.py')}, 14:00:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:00:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:00:00 INFO - 'installer_path': 'installer.exe', 14:00:00 INFO - 'log_level': 'info', 14:00:00 INFO - 'log_name': 'talos', 14:00:00 INFO - 'log_to_console': True, 14:00:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:00:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:00:00 INFO - 'opt_config_files': (), 14:00:00 INFO - 'pip_index': False, 14:00:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:00:00 INFO - 'cmd': ('bash', 14:00:00 INFO - '-c', 14:00:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:00:00 INFO - 'enabled': True, 14:00:00 INFO - 'halt_on_failure': False, 14:00:00 INFO - 'name': 'check_screen_resolution'},), 14:00:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:00:00 INFO - 'cmd': ('bash', 14:00:00 INFO - '-c', 14:00:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:00:00 INFO - 'enabled': True, 14:00:00 INFO - 'halt_on_failure': False, 14:00:00 INFO - 'name': 'check_screen_resolution'},), 14:00:00 INFO - 'run_cmd_checks_enabled': True, 14:00:00 INFO - 'sps_profile': False, 14:00:00 INFO - 'sps_profile_interval': 0, 14:00:00 INFO - 'suite': 'dromaeojs', 14:00:00 INFO - 'system_bits': '32', 14:00:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:00:00 INFO - 'title': 't-yosemite-r7-0038', 14:00:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:00:00 INFO - 'use_talos_json': True, 14:00:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:00:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:00:00 INFO - 'work_dir': 'build'} 14:00:00 INFO - [mozharness: 2016-09-01 21:00:00.885723Z] Running clobber step. 14:00:00 INFO - Running pre-action listener: _resource_record_pre_action 14:00:00 INFO - Running main action method: clobber 14:00:00 INFO - rmtree: /builds/slave/test/build 14:00:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:00:02 INFO - Running post-action listener: _resource_record_post_action 14:00:02 INFO - [mozharness: 2016-09-01 21:00:02.758139Z] Finished clobber step (success) 14:00:02 INFO - [mozharness: 2016-09-01 21:00:02.758465Z] Running read-buildbot-config step. 14:00:02 INFO - Running pre-action listener: _resource_record_pre_action 14:00:02 INFO - Running main action method: read_buildbot_config 14:00:02 INFO - Using buildbot properties: 14:00:02 INFO - { 14:00:02 INFO - "project": "", 14:00:02 INFO - "product": "firefox", 14:00:02 INFO - "script_repo_revision": "production", 14:00:02 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 14:00:02 INFO - "repository": "", 14:00:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 14:00:02 INFO - "buildid": "20160901111321", 14:00:02 INFO - "pgo_build": "False", 14:00:02 INFO - "basedir": "/builds/slave/test", 14:00:02 INFO - "buildnumber": 152, 14:00:02 INFO - "slavename": "t-yosemite-r7-0038", 14:00:02 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 14:00:02 INFO - "platform": "yosemite_r7", 14:00:02 INFO - "scheduler": "tests-fx-team-macosx64-talos", 14:00:02 INFO - "branch": "fx-team", 14:00:02 INFO - "repo_path": "integration/fx-team", 14:00:02 INFO - "revision": "d667c9cd84f16752ae92c2b683afcc35e0483ec3", 14:00:02 INFO - "stage_platform": "macosx64", 14:00:02 INFO - "builduid": "f0df6025abeb4b85b733ba7b66f4dc2d", 14:00:02 INFO - "slavebuilddir": "test" 14:00:02 INFO - } 14:00:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 14:00:02 INFO - Running post-action listener: _resource_record_post_action 14:00:02 INFO - [mozharness: 2016-09-01 21:00:02.764170Z] Finished read-buildbot-config step (success) 14:00:02 INFO - [mozharness: 2016-09-01 21:00:02.764347Z] Running download-and-extract step. 14:00:02 INFO - Running pre-action listener: _resource_record_pre_action 14:00:02 INFO - Running main action method: download_and_extract 14:00:02 INFO - mkdir: /builds/slave/test/build/tests 14:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:02 INFO - trying https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 14:00:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/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 14:00:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/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 14:00:05 INFO - Downloaded 1199 bytes. 14:00:05 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 14:00:05 INFO - Using the following test package requirements: 14:00:05 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 14:00:05 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:05 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 14:00:05 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:05 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 14:00:05 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:05 INFO - u'jsshell-mac.zip'], 14:00:05 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:05 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 14:00:05 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 14:00:05 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:05 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 14:00:05 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:05 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 14:00:05 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:05 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 14:00:05 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:05 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 14:00:05 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:05 INFO - trying https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:00:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/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 14:00:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/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 14:00:07 INFO - Downloaded 16399299 bytes. 14:00:07 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 14:00:14 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 14:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:14 INFO - trying https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:00:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/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 14:00:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/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 14:00:15 INFO - Downloaded 16399299 bytes. 14:00:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 14:00: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')]} 14:00:22 INFO - trying https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 14:00:22 INFO - Downloading https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/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 14:00:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/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 14:00:23 INFO - Downloaded 14413998 bytes. 14:00:23 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 14:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:23 INFO - trying https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:00:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 14:00:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/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 14:00:27 INFO - Downloaded 111577081 bytes. 14:00:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:00:27 INFO - mkdir: /builds/slave/test/properties 14:00:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:00:27 INFO - Writing to file /builds/slave/test/properties/build_url 14:00:27 INFO - Contents: 14:00:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:00:28 INFO - Running post-action listener: _resource_record_post_action 14:00:28 INFO - Running post-action listener: set_extra_try_arguments 14:00:28 INFO - [mozharness: 2016-09-01 21:00:28.193872Z] Finished download-and-extract step (success) 14:00:28 INFO - [mozharness: 2016-09-01 21:00:28.194031Z] Running populate-webroot step. 14:00:28 INFO - Running pre-action listener: _resource_record_pre_action 14:00:28 INFO - Running main action method: populate_webroot 14:00:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:00:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:00:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:00:28 INFO - '--tppagecycles', 14:00:28 INFO - '7'], 14:00:28 INFO - 'tests': ['tsvgm']}}, 14:00:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:00:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:00:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:00:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:00:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:00:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:00:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:28 INFO - 'talos_options': ['--disable-e10s'], 14:00:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:00:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:00:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:28 INFO - 'talos_options': ['--disable-e10s'], 14:00:28 INFO - 'tests': ['damp', 'tps']}, 14:00:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:28 INFO - 'tests': ['damp', 'tps']}, 14:00:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:00:28 INFO - 'tests': ['dromaeo_dom']}, 14:00:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:00:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:00:28 INFO - 'tests': ['basic_compositor_video']}, 14:00:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:00:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:00:28 INFO - 'tests': ['a11yr', 14:00:28 INFO - 'ts_paint', 14:00:28 INFO - 'tpaint', 14:00:28 INFO - 'sessionrestore', 14:00:28 INFO - 'sessionrestore_no_auto_restore', 14:00:28 INFO - 'tabpaint']}, 14:00:28 INFO - 'other-e10s': {'tests': ['a11yr', 14:00:28 INFO - 'ts_paint', 14:00:28 INFO - 'tpaint', 14:00:28 INFO - 'sessionrestore', 14:00:28 INFO - 'sessionrestore_no_auto_restore', 14:00:28 INFO - 'tabpaint']}, 14:00:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:00:28 INFO - 'ts_paint', 14:00:28 INFO - 'tpaint', 14:00:28 INFO - 'sessionrestore', 14:00:28 INFO - 'sessionrestore_no_auto_restore', 14:00:28 INFO - 'tabpaint']}, 14:00:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:00:28 INFO - 'ts_paint', 14:00:28 INFO - 'tpaint', 14:00:28 INFO - 'sessionrestore', 14:00:28 INFO - 'sessionrestore_no_auto_restore', 14:00:28 INFO - 'tabpaint']}, 14:00:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:00:28 INFO - 'tests': ['a11yr', 14:00:28 INFO - 'ts_paint', 14:00:28 INFO - 'tpaint', 14:00:28 INFO - 'sessionrestore', 14:00:28 INFO - 'sessionrestore_no_auto_restore', 14:00:28 INFO - 'tabpaint']}, 14:00:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:00:28 INFO - 'tests': ['a11yr', 14:00:28 INFO - 'ts_paint', 14:00:28 INFO - 'tpaint', 14:00:28 INFO - 'sessionrestore', 14:00:28 INFO - 'sessionrestore_no_auto_restore', 14:00:28 INFO - 'tabpaint']}, 14:00:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:00:28 INFO - 'tests': ['tsvgx', 14:00:28 INFO - 'tsvgr_opacity', 14:00:28 INFO - 'tart', 14:00:28 INFO - 'tscrollx', 14:00:28 INFO - 'cart']}, 14:00:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:00:28 INFO - 'tsvgr_opacity', 14:00:28 INFO - 'tart', 14:00:28 INFO - 'tscrollx', 14:00:28 INFO - 'cart']}, 14:00:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:28 INFO - 'talos_options': ['--disable-e10s'], 14:00:28 INFO - 'tests': ['tp5o']}, 14:00:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:28 INFO - 'tests': ['tp5o']}, 14:00:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:28 INFO - 'talos_options': ['--disable-e10s', 14:00:28 INFO - '--xperf_path', 14:00:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:00:28 INFO - 'tests': ['tp5n']}, 14:00:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:28 INFO - 'talos_options': ['--xperf_path', 14:00:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:00:28 INFO - 'tests': ['tp5n']}}, 14:00:28 INFO - 'talos.zip': {'path': '', 14:00:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:00:28 INFO - Running post-action listener: _resource_record_post_action 14:00:28 INFO - [mozharness: 2016-09-01 21:00:28.213118Z] Finished populate-webroot step (success) 14:00:28 INFO - [mozharness: 2016-09-01 21:00:28.213227Z] Running create-virtualenv step. 14:00:28 INFO - Running pre-action listener: _resource_record_pre_action 14:00:28 INFO - Running main action method: create_virtualenv 14:00:28 INFO - Creating virtualenv /builds/slave/test/build/venv 14:00:28 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 14:00:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:00:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:00:28 INFO - Using real prefix '/tools/python27' 14:00:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:00:29 INFO - Installing distribute.............................................................................................................................................................................................done. 14:00:31 INFO - Installing pip.................done. 14:00:31 INFO - Return code: 0 14:00:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:00: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')]} 14:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00: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')]} 14:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:31 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 0x10ff61a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb909caa760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec5d30>, '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 0x10fc2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10febdb20>, '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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 14:00:31 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 14:00:31 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 14:00:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jVDMUuc0n/Render', 14:00:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:31 INFO - 'HOME': '/Users/cltbld', 14:00:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:31 INFO - 'LOGNAME': 'cltbld', 14:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:31 INFO - 'MOZ_NO_REMOTE': '1', 14:00:31 INFO - 'NO_EM_RESTART': '1', 14:00:31 INFO - 'PAGER': '/bin/cat', 14:00:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:31 INFO - 'PWD': '/builds/slave/test', 14:00:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:31 INFO - 'SHELL': '/bin/bash', 14:00:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ua86KsXNyT/Listeners', 14:00:31 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 14:00:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:31 INFO - 'USER': 'cltbld', 14:00:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:31 INFO - 'XPC_FLAGS': '0x0', 14:00:31 INFO - 'XPC_SERVICE_NAME': '0', 14:00:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:32 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 14:00:32 INFO - Cleaning up... 14:00:32 INFO - Return code: 0 14:00:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:00:32 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')]} 14:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:32 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')]} 14:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:32 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 0x10ff61a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb909caa760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec5d30>, '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 0x10fc2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10febdb20>, '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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 14:00:32 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 14:00:32 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 14:00:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jVDMUuc0n/Render', 14:00:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:32 INFO - 'HOME': '/Users/cltbld', 14:00:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:32 INFO - 'LOGNAME': 'cltbld', 14:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:32 INFO - 'MOZ_NO_REMOTE': '1', 14:00:32 INFO - 'NO_EM_RESTART': '1', 14:00:32 INFO - 'PAGER': '/bin/cat', 14:00:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:32 INFO - 'PWD': '/builds/slave/test', 14:00:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:32 INFO - 'SHELL': '/bin/bash', 14:00:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ua86KsXNyT/Listeners', 14:00:32 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 14:00:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:32 INFO - 'USER': 'cltbld', 14:00:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:32 INFO - 'XPC_FLAGS': '0x0', 14:00:32 INFO - 'XPC_SERVICE_NAME': '0', 14:00:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:32 INFO - Downloading/unpacking psutil>=3.1.1 14:00:32 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 14:00:32 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 14:00:32 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 14:00:32 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 14:00:32 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 14:00:32 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 14:00:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:00:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:00:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:00:35 INFO - Installing collected packages: psutil 14:00:35 INFO - Running setup.py install for psutil 14:00:35 INFO - building 'psutil._psutil_osx' extension 14:00:35 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 14:00:36 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 14:00:36 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 14:00:36 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 14:00:36 INFO - building 'psutil._psutil_posix' extension 14:00:36 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 14:00:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:00:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:00:36 INFO - ^ 14:00:36 INFO - 1 warning generated. 14:00:36 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 14:00:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:00:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:00:37 INFO - Successfully installed psutil 14:00:37 INFO - Cleaning up... 14:00:37 INFO - Return code: 0 14:00:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:37 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 0x10ff61a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb909caa760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec5d30>, '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 0x10fc2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10febdb20>, '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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 14:00:37 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 14:00:37 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 14:00:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jVDMUuc0n/Render', 14:00:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:37 INFO - 'HOME': '/Users/cltbld', 14:00:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:37 INFO - 'LOGNAME': 'cltbld', 14:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:37 INFO - 'MOZ_NO_REMOTE': '1', 14:00:37 INFO - 'NO_EM_RESTART': '1', 14:00:37 INFO - 'PAGER': '/bin/cat', 14:00:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:37 INFO - 'PWD': '/builds/slave/test', 14:00:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:37 INFO - 'SHELL': '/bin/bash', 14:00:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ua86KsXNyT/Listeners', 14:00:37 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 14:00:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:37 INFO - 'USER': 'cltbld', 14:00:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:37 INFO - 'XPC_FLAGS': '0x0', 14:00:37 INFO - 'XPC_SERVICE_NAME': '0', 14:00:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:00:37 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 14:00:37 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 14:00:37 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 14:00:37 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 14:00:37 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 14:00:37 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 14:00:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:00:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:00:39 INFO - Installing collected packages: mozsystemmonitor 14:00:39 INFO - Running setup.py install for mozsystemmonitor 14:00:39 INFO - Successfully installed mozsystemmonitor 14:00:39 INFO - Cleaning up... 14:00:40 INFO - Return code: 0 14:00:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:00:40 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')]} 14:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:40 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')]} 14:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:40 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 0x10ff61a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb909caa760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec5d30>, '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 0x10fc2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10febdb20>, '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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 14:00:40 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 14:00:40 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 14:00:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jVDMUuc0n/Render', 14:00:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:40 INFO - 'HOME': '/Users/cltbld', 14:00:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:40 INFO - 'LOGNAME': 'cltbld', 14:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:40 INFO - 'MOZ_NO_REMOTE': '1', 14:00:40 INFO - 'NO_EM_RESTART': '1', 14:00:40 INFO - 'PAGER': '/bin/cat', 14:00:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:40 INFO - 'PWD': '/builds/slave/test', 14:00:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:40 INFO - 'SHELL': '/bin/bash', 14:00:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ua86KsXNyT/Listeners', 14:00:40 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 14:00:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:40 INFO - 'USER': 'cltbld', 14:00:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:40 INFO - 'XPC_FLAGS': '0x0', 14:00:40 INFO - 'XPC_SERVICE_NAME': '0', 14:00:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:40 INFO - Downloading/unpacking jsonschema==2.5.1 14:00:40 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 14:00:40 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 14:00:40 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 14:00:40 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 14:00:40 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 14:00:40 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 14:00:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:00:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:00:42 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 14:00:42 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 14:00:42 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 14:00:42 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 14:00:42 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 14:00:42 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 14:00:43 INFO - Downloading functools32-3.2.3-2.tar.gz 14:00:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:00:43 INFO - warning: no files found matching '*.txt' 14:00:43 INFO - no previously-included directories found matching 'build' 14:00:43 INFO - no previously-included directories found matching 'dist' 14:00:43 INFO - no previously-included directories found matching '.git*' 14:00:43 INFO - Installing collected packages: jsonschema, functools32 14:00:43 INFO - Running setup.py install for functools32 14:00:43 INFO - warning: no files found matching '*.txt' 14:00:43 INFO - no previously-included directories found matching 'build' 14:00:43 INFO - no previously-included directories found matching 'dist' 14:00:43 INFO - no previously-included directories found matching '.git*' 14:00:43 INFO - Successfully installed jsonschema functools32 14:00:43 INFO - Cleaning up... 14:00:43 INFO - Return code: 0 14:00:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:00:43 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')]} 14:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:43 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')]} 14:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:43 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 0x10ff61a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb909caa760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec5d30>, '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 0x10fc2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10febdb20>, '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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 14:00:43 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 14:00:43 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 14:00:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jVDMUuc0n/Render', 14:00:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:43 INFO - 'HOME': '/Users/cltbld', 14:00:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:43 INFO - 'LOGNAME': 'cltbld', 14:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:43 INFO - 'MOZ_NO_REMOTE': '1', 14:00:43 INFO - 'NO_EM_RESTART': '1', 14:00:43 INFO - 'PAGER': '/bin/cat', 14:00:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:43 INFO - 'PWD': '/builds/slave/test', 14:00:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:43 INFO - 'SHELL': '/bin/bash', 14:00:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ua86KsXNyT/Listeners', 14:00:43 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 14:00:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:43 INFO - 'USER': 'cltbld', 14:00:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:43 INFO - 'XPC_FLAGS': '0x0', 14:00:43 INFO - 'XPC_SERVICE_NAME': '0', 14:00:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:00:43 INFO - Cleaning up... 14:00:43 INFO - Return code: 0 14:00:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:00:43 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')]} 14:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:43 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')]} 14:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:43 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 0x10ff61a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb909caa760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec5d30>, '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 0x10fc2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10febdb20>, '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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 14:00:43 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 14:00:43 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 14:00:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jVDMUuc0n/Render', 14:00:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:43 INFO - 'HOME': '/Users/cltbld', 14:00:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:43 INFO - 'LOGNAME': 'cltbld', 14:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:43 INFO - 'MOZ_NO_REMOTE': '1', 14:00:43 INFO - 'NO_EM_RESTART': '1', 14:00:43 INFO - 'PAGER': '/bin/cat', 14:00:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:43 INFO - 'PWD': '/builds/slave/test', 14:00:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:43 INFO - 'SHELL': '/bin/bash', 14:00:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ua86KsXNyT/Listeners', 14:00:43 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 14:00:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:43 INFO - 'USER': 'cltbld', 14:00:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:43 INFO - 'XPC_FLAGS': '0x0', 14:00:43 INFO - 'XPC_SERVICE_NAME': '0', 14:00:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:44 INFO - Downloading/unpacking blobuploader==1.2.4 14:00:44 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 14:00:44 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 14:00:44 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 14:00:44 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 14:00:44 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 14:00:44 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 14:00:46 INFO - Downloading blobuploader-1.2.4.tar.gz 14:00:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:00:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:00:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:00:47 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 14:00:47 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 14:00:47 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 14:00:47 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 14:00:47 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 14:00:47 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 14:00:47 INFO - Downloading docopt-0.6.1.tar.gz 14:00:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:00:47 INFO - Installing collected packages: blobuploader, requests, docopt 14:00:47 INFO - Running setup.py install for blobuploader 14:00:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:00:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:00:48 INFO - Running setup.py install for requests 14:00:48 INFO - Running setup.py install for docopt 14:00:48 INFO - Successfully installed blobuploader requests docopt 14:00:48 INFO - Cleaning up... 14:00:48 INFO - Return code: 0 14:00:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00: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')]} 14:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00: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')]} 14:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:48 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 0x10ff61a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb909caa760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec5d30>, '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 0x10fc2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10febdb20>, '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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 14:00:48 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 14:00:48 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 14:00:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jVDMUuc0n/Render', 14:00:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:48 INFO - 'HOME': '/Users/cltbld', 14:00:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:48 INFO - 'LOGNAME': 'cltbld', 14:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:48 INFO - 'MOZ_NO_REMOTE': '1', 14:00:48 INFO - 'NO_EM_RESTART': '1', 14:00:48 INFO - 'PAGER': '/bin/cat', 14:00:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:48 INFO - 'PWD': '/builds/slave/test', 14:00:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:48 INFO - 'SHELL': '/bin/bash', 14:00:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ua86KsXNyT/Listeners', 14:00:48 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 14:00:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:48 INFO - 'USER': 'cltbld', 14:00:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:48 INFO - 'XPC_FLAGS': '0x0', 14:00:48 INFO - 'XPC_SERVICE_NAME': '0', 14:00:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:00:49 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-sTjkKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:00:49 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-jGzpHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:00:49 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-_6VzUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:00:49 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-VT3SWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:00:49 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-s0jwXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:00:49 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-uYkyON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:00:49 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-nWsRrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:00:49 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-7rHBLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:00:49 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-0eTJsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:00:50 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-rMCSEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:00:50 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-jLGlRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:00:50 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-Gn6Ejq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:00:50 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-Td2o8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:00:50 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-uug3r6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:50 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-6YaCqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:00:50 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-LiKhja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:00:50 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-evQGb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:00:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:00:50 INFO - Running setup.py install for manifestparser 14:00:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:00:51 INFO - Running setup.py install for mozcrash 14:00:51 INFO - Running setup.py install for mozdebug 14:00:51 INFO - Running setup.py install for mozdevice 14:00:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:00:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:00:51 INFO - Running setup.py install for mozfile 14:00:51 INFO - Running setup.py install for mozhttpd 14:00:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:00:51 INFO - Running setup.py install for mozinfo 14:00:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:00:51 INFO - Running setup.py install for mozInstall 14:00:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:00:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:00:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:00:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:00:52 INFO - Running setup.py install for mozleak 14:00:52 INFO - Running setup.py install for mozlog 14:00:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:00:52 INFO - Running setup.py install for moznetwork 14:00:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:00:52 INFO - Running setup.py install for mozprocess 14:00:52 INFO - Running setup.py install for mozprofile 14:00:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:00:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:00:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:00:52 INFO - Running setup.py install for mozrunner 14:00:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:00:52 INFO - Running setup.py install for mozscreenshot 14:00:53 INFO - Running setup.py install for moztest 14:00:53 INFO - Running setup.py install for mozversion 14:00:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:00:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:00:53 INFO - Cleaning up... 14:00:53 INFO - Return code: 0 14:00:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:53 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')]} 14:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:53 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')]} 14:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:53 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 0x10ff61a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb909caa760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec5d30>, '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 0x10fc2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10febdb20>, '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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 14:00:53 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 14:00:53 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 14:00:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jVDMUuc0n/Render', 14:00:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:53 INFO - 'HOME': '/Users/cltbld', 14:00:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:53 INFO - 'LOGNAME': 'cltbld', 14:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:53 INFO - 'MOZ_NO_REMOTE': '1', 14:00:53 INFO - 'NO_EM_RESTART': '1', 14:00:53 INFO - 'PAGER': '/bin/cat', 14:00:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:53 INFO - 'PWD': '/builds/slave/test', 14:00:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:53 INFO - 'SHELL': '/bin/bash', 14:00:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ua86KsXNyT/Listeners', 14:00:53 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 14:00:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:53 INFO - 'USER': 'cltbld', 14:00:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:53 INFO - 'XPC_FLAGS': '0x0', 14:00:53 INFO - 'XPC_SERVICE_NAME': '0', 14:00:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:00:53 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-o_w5ML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:00:53 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)) 14:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:00:53 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-jpB1fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:00:53 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)) 14:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:00:53 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-X6N4Ys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:00:54 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)) 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:00:54 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-iMqOUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:00:54 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)) 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:00:54 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-8SDXEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:00:54 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)) 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:00:54 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-ydpjwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:00:54 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)) 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:00:54 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-RMiM3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:00:54 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)) 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:00:54 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-Pvidi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:00:54 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)) 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:00:54 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-YiKkG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:00:54 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)) 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:00:54 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-x68grp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:00:54 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)) 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:00:54 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-OkhNdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:00:54 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)) 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:00:54 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-FsH5Ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:00:54 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)) 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:00:54 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-JWerJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:00:55 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)) 14:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:00:55 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-MMNeNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:00:55 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)) 14:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:55 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-6EBxwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:55 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)) 14:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:00:55 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-i7T7Ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:00:55 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)) 14:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:00:55 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-rG5uWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:00:55 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)) 14:00:55 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)) 14:00:55 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)) 14:00:55 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)) 14:00:55 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)) 14:00:55 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)) 14:00:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:58 INFO - Downloading blessings-1.6.tar.gz 14:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:00:58 INFO - Installing collected packages: blessings 14:00:58 INFO - Running setup.py install for blessings 14:00:58 INFO - Successfully installed blessings 14:00:58 INFO - Cleaning up... 14:00:58 INFO - Return code: 0 14:00:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:00:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:00:58 INFO - Reading from file tmpfile_stdout 14:00:58 INFO - Current package versions: 14:00:58 INFO - blessings == 1.6 14:00:58 INFO - blobuploader == 1.2.4 14:00:58 INFO - docopt == 0.6.1 14:00:58 INFO - functools32 == 3.2.3-2 14:00:58 INFO - jsonschema == 2.5.1 14:00:58 INFO - manifestparser == 1.1 14:00:58 INFO - mozInstall == 1.12 14:00:58 INFO - mozcrash == 0.17 14:00:58 INFO - mozdebug == 0.1 14:00:58 INFO - mozdevice == 0.48 14:00:58 INFO - mozfile == 1.2 14:00:58 INFO - mozhttpd == 0.7 14:00:58 INFO - mozinfo == 0.9 14:00:58 INFO - mozleak == 0.1 14:00:58 INFO - mozlog == 3.3 14:00:58 INFO - moznetwork == 0.27 14:00:58 INFO - mozprocess == 0.23 14:00:58 INFO - mozprofile == 0.28 14:00:58 INFO - mozrunner == 6.12 14:00:58 INFO - mozscreenshot == 0.1 14:00:58 INFO - mozsystemmonitor == 0.3 14:00:58 INFO - moztest == 0.7 14:00:58 INFO - mozversion == 1.4 14:00:58 INFO - psutil == 3.1.1 14:00:58 INFO - requests == 1.2.3 14:00:58 INFO - wsgiref == 0.1.2 14:00:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:58 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 0x10ff61a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb909caa760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec5d30>, '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 0x10fc2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10febdb20>, '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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 14:00:58 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 14:00:58 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 14:00:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jVDMUuc0n/Render', 14:00:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:58 INFO - 'HOME': '/Users/cltbld', 14:00:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:58 INFO - 'LOGNAME': 'cltbld', 14:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:58 INFO - 'MOZ_NO_REMOTE': '1', 14:00:58 INFO - 'NO_EM_RESTART': '1', 14:00:58 INFO - 'PAGER': '/bin/cat', 14:00:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:58 INFO - 'PWD': '/builds/slave/test', 14:00:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:58 INFO - 'SHELL': '/bin/bash', 14:00:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ua86KsXNyT/Listeners', 14:00:58 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 14:00:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:58 INFO - 'USER': 'cltbld', 14:00:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:58 INFO - 'XPC_FLAGS': '0x0', 14:00:58 INFO - 'XPC_SERVICE_NAME': '0', 14:00:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 INFO - Cleaning up... 14:00:59 INFO - Return code: 0 14:00:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:59 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 0x10ff61a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb909caa760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec5d30>, '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 0x10fc2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10febdb20>, '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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 14:00:59 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 14:00:59 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 14:00:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jVDMUuc0n/Render', 14:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:59 INFO - 'HOME': '/Users/cltbld', 14:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:59 INFO - 'LOGNAME': 'cltbld', 14:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:59 INFO - 'MOZ_NO_REMOTE': '1', 14:00:59 INFO - 'NO_EM_RESTART': '1', 14:00:59 INFO - 'PAGER': '/bin/cat', 14:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:59 INFO - 'PWD': '/builds/slave/test', 14:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:59 INFO - 'SHELL': '/bin/bash', 14:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ua86KsXNyT/Listeners', 14:00:59 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 14:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:59 INFO - 'USER': 'cltbld', 14:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:59 INFO - 'XPC_FLAGS': '0x0', 14:00:59 INFO - 'XPC_SERVICE_NAME': '0', 14:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:00:59 INFO - Cleaning up... 14:00:59 INFO - Return code: 0 14:00:59 INFO - Running post-action listener: _resource_record_post_action 14:00:59 INFO - Running post-action listener: _start_resource_monitoring 14:00:59 INFO - Starting resource monitoring. 14:00:59 INFO - [mozharness: 2016-09-01 21:00:59.535650Z] Finished create-virtualenv step (success) 14:00:59 INFO - [mozharness: 2016-09-01 21:00:59.536638Z] Running install step. 14:00:59 INFO - Running pre-action listener: _resource_record_pre_action 14:00:59 INFO - Running main action method: install 14:00:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:00:59 INFO - Reading from file tmpfile_stdout 14:00:59 INFO - Detecting whether we're running mozinstall >=1.0... 14:00:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:00:59 INFO - Reading from file tmpfile_stdout 14:00:59 INFO - Output received: 14:00:59 INFO - Usage: mozinstall [options] installer 14:00:59 INFO - Options: 14:00:59 INFO - -h, --help show this help message and exit 14:00:59 INFO - -d DEST, --destination=DEST 14:00:59 INFO - Directory to install application into. [default: 14:00:59 INFO - "/builds/slave/test"] 14:00:59 INFO - --app=APP Application being installed. [default: firefox] 14:00:59 INFO - mkdir: /builds/slave/test/build/application 14:00:59 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'] 14:00:59 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 14:01:34 INFO - Reading from file tmpfile_stdout 14:01:34 INFO - Output received: 14:01:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:01:34 INFO - Running post-action listener: _resource_record_post_action 14:01:34 INFO - [mozharness: 2016-09-01 21:01:34.098003Z] Finished install step (success) 14:01:34 INFO - [mozharness: 2016-09-01 21:01:34.098148Z] Running run-tests step. 14:01:34 INFO - Running pre-action listener: _resource_record_pre_action 14:01:34 INFO - Running main action method: run_tests 14:01:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:01:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:01:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:01:34 INFO - 2016-09-01 14:01:34.112 screenresolution[1643:7593] starting screenresolution argv=screenresolution get 14:01:34 INFO - 2016-09-01 14:01:34.115 screenresolution[1643:7593] Display 0: 1600x1200x32@60 14:01:34 INFO - 2016-09-01 14:01:34.120 screenresolution[1644:7595] starting screenresolution argv=screenresolution list 14:01:34 INFO - Available Modes on Display 0 14:01:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:01:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:01:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:01:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:01:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:01:34 INFO - Intel Iris: 14:01:34 INFO - Chipset Model: Intel Iris 14:01:34 INFO - Type: GPU 14:01:34 INFO - Bus: Built-In 14:01:34 INFO - VRAM (Dynamic, Max): 1536 MB 14:01:34 INFO - Vendor: Intel (0x8086) 14:01:34 INFO - Device ID: 0x0a2e 14:01:34 INFO - Revision ID: 0x0009 14:01:34 INFO - Displays: 14:01:34 INFO - Display: 14:01:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:01:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:01:34 INFO - Display Serial Number: 1600x1200 60 14:01:34 INFO - Main Display: Yes 14:01:34 INFO - Mirror: Off 14:01:34 INFO - Online: Yes 14:01:34 INFO - Rotation: Supported 14:01:34 INFO - Return code: 0 14:01:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:01:34 INFO - Python 2.7.3 14:01:34 INFO - Return code: 0 14:01:34 INFO - grabbing minidump binary from tooltool 14:01:34 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')]} 14:01:34 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 0x10fec5d30>, '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 0x10fc2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10febdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:01:34 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 14:01:34 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 14:01:34 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 14:01:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:01:34 INFO - Return code: 0 14:01:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:01:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:01:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:01:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:01:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:01:34 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/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0038', '--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 14:01:34 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/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0038 --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 14:01:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jVDMUuc0n/Render', 14:01:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:34 INFO - 'HOME': '/Users/cltbld', 14:01:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:34 INFO - 'LOGNAME': 'cltbld', 14:01:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:01:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:01:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:34 INFO - 'MOZ_NO_REMOTE': '1', 14:01:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:01:34 INFO - 'NO_EM_RESTART': '1', 14:01:34 INFO - 'PAGER': '/bin/cat', 14:01:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:34 INFO - 'PWD': '/builds/slave/test', 14:01:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:01:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:34 INFO - 'SHELL': '/bin/bash', 14:01:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ua86KsXNyT/Listeners', 14:01:34 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 14:01:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:34 INFO - 'USER': 'cltbld', 14:01:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:34 INFO - 'XPC_FLAGS': '0x0', 14:01:34 INFO - 'XPC_SERVICE_NAME': '0', 14:01:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:34 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/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0038', '--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 14:01:34 INFO - mozversion application_buildid: 20160901111321 14:01:34 INFO - mozversion application_changeset: d667c9cd84f16752ae92c2b683afcc35e0483ec3 14:01:34 INFO - mozversion application_display_name: Nightly 14:01:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:01:34 INFO - mozversion application_name: Firefox 14:01:34 INFO - mozversion application_remotingname: firefox 14:01:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:01:34 INFO - mozversion application_vendor: Mozilla 14:01:34 INFO - mozversion application_version: 51.0a1 14:01:34 INFO - mozversion platform_buildid: 20160901111321 14:01:34 INFO - mozversion platform_changeset: d667c9cd84f16752ae92c2b683afcc35e0483ec3 14:01:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:01:34 INFO - mozversion platform_version: 51.0a1 14:01:34 INFO - using testdate: 1472763694 14:01:34 INFO - actual date: 1472763694 14:01:34 INFO - starting webserver on 'localhost:49280' 14:01:34 INFO - SUITE-START | Running 2 tests 14:01:34 INFO - TEST-START | dromaeo_css 14:01:34 INFO - operating with platform_type : mac_ 14:01:34 INFO - Initialising browser for dromaeo_css test... 14:01:34 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmpr7L3vn/profile http://localhost:49280/getInfo.html) 14:01:37 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 14:01:37 INFO - PROCESS | 1654 | colorDepth:24 14:01:37 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 14:01:37 INFO - PROCESS | 1654 | __metrics 14:01:37 INFO - PROCESS | 1654 | 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" 14:01:37 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:01:38 INFO - TEST-INFO | 1654: exit 0 14:01:38 INFO - Browser initialized. 14:01:38 INFO - Running cycle 1/1 for dromaeo_css test... 14:01:38 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmpr7L3vn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:01:49 INFO - PROCESS | 1656 | RSS: Main: 233799680 14:01:49 INFO - PROCESS | 1656 | 14:02:40 INFO - PROCESS | 1656 | 1472763760774 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] 14:04:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 14:04:54 INFO - PROCESS | 1656 | RSS: Main: 366247936 14:04:54 INFO - PROCESS | 1656 | 14:07:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 14:07:58 INFO - PROCESS | 1656 | RSS: Main: 370688000 14:07:58 INFO - PROCESS | 1656 | 14:11:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 14:11:01 INFO - PROCESS | 1656 | RSS: Main: 334987264 14:11:01 INFO - PROCESS | 1656 | 14:14:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 14:14:04 INFO - PROCESS | 1656 | RSS: Main: 331505664 14:14:04 INFO - PROCESS | 1656 | 14:17:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 14:17:08 INFO - PROCESS | 1656 | RSS: Main: 323207168 14:17:08 INFO - PROCESS | 1656 | 14:20:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 14:20:11 INFO - PROCESS | 1656 | RSS: Main: 374603776 14:20:11 INFO - PROCESS | 1656 | 14:20:11 INFO - PROCESS | 1656 | __start_tp_report 14:20:11 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 14:20:11 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 14:20:11 INFO - PROCESS | 1656 | |i|pagename|runs| 14:20:11 INFO - PROCESS | 1656 | |0;dojo.html;3299.999999999999;3307.355065762176;3343.297907439436;3379.2227787608836;3416.2484253464245;5524.640898341605;5570.997220500309;5640.830775076744;5703.771849126035;5727.541796656263;5628.831135065944;5655.886882262363;5706.828795136143;5725.169850371704;5778.653280803154;5106.183010718286;5120.92318615221;5189.325387699594;5202.973985130069;5289.418164001957;5287.154055351145;5294.338207724035;5296.523312901838;5319.361676598814;5343.946560534403;5117.590592752571;5159.2003239497935;5170.379554453452;5179.119549676564;5208.8437346879655;5205.739713014372;5208.739563021833;5214.139666954948;5217.660852044605;5221.843344699665;5123.38519377677;5164.845054648366;5166.948330516684;5168.767405466763;5184;5597;5604.963081829875;5611.2144299798065;5707.543396528309;5767.971160144172;5526.198701188305;6194.845128871769;6221.3156552779155;6254.530910181709;6262.71817768197;6118.680093383022;6176.227971503562;6255.155554000197;6267.5299352548745;6298.779542716029;5771.826845194651;5800.738966746507;5806.593538452268;5833.300003999505;5857.297124345106;5280.867978300496;6228.408301211378;6252.437280644764;6261;6275.090111933744;5956.463918247378;6040.516758659297;6061.3029501607;6079.483243924227;6166.814995550051;6234.9688251559355;6248.687565621701;6254.843628909222;6258.186435763321;6259.18630578031;6213.03697926822;6225.470834978987;6232.439080482778;6234.37656234382;6236.781712640036;6094.421030002142;6097.524678803529;6137.070562331796;6184.5052395808225;6222.533310001678;4865.9756701216265;4912.091263116335;4938.555530002245;4941.431735350408;4948.282499037619;4356.695031347838;4360.367746676714;4373.628241599499;4383.7588932609015;4399.912001759947;5677.602567820213;5688.203651488795;5690.2887139107615;5693.829185124453;5695.829125126253;6080.665563394055;6173.043178307369;6189.597676151036;6247.687615619201;6264.843378915472;5740.081586946152;5746.597738158289;5753.539716822645;5762.942370576325;5790.044642633919;5219.034478621467;5386.622936394415;5422.75597598109;5431.185322201702;5435.673859568438;6194.566380353513;6218.409251121137;6219.346968568235;6278.497720182466;6298.11826344303;6268;6273.090401891609;6277.497800176066;6281.026440901667;6290.716917738804;6254.155688981929;6283.434490895841;6293.685315734287;6294.3076261612105;6317.210348706411;6006.15913772081;6131.386861313833;6136.764169924236;6185.628862268279;6196.1635179250225;6249.8750024998335;6257.155284036597;6288.685565721604;6302.023186405931;6312.747490100344;5897.498712609477;6126.877462450815;6158.476529495043;6164.260288765377;6177.536684748752;5779.364269930388;5883.529317654663;5896.204012458435;6115.3578874217565;6138.785142519991;5857.707114644336;5967.791127310524;5972.044472884318;6020.54845886569;6064.787732429344;6106.166166662464;6107.180774535289;6168.876622467616;6170.691465426621;6171.722272497838;5789.478946894799;5803.216565763737;5974.522038237017;5997.970010149921;6017.939820601738 14:20:11 INFO - PROCESS | 1656 | |1;ext.html;504.4224363104246;524.7559884653635;535.3976776126857;539.962202645815;549.2173652545122;30270.54594181089;39672.20655586886;40917.97705057375;41006.79496602517;43296.567034329644;42229.577704222946;42607.360889586605;42671.78664106675;42696.57303426965;42736;8487.44831585948;8789.165029322236;9732.562034708437;9904.306698531105;9915.702528924145;8829.190016046727;8951.194392504673;8975.192232699088;8981.955090224572;12005.519779208866;11853.111016673784;12174.147809653328;12255.632331030083;12284.631461056182;12304.5693400731;11161.441927903572;11239.595050618673;12057.432533770598;12092.697682558006;12107.091968102428;11881.40592970348;11966.880331196751;11971.580994665232;11978.041756659446;11983;39588.60411395878;39916.40125398121;39923;39926;39947.40078898819;3559.4660800879815;3989.6608788252743;4009.8195581198916;4053.5338436079637;4139.627433530966;30069.94755183558;30775.38449231003;30841.537376939363;30919;31421.900233491946;2755.641766570333;3221.5167724841463;3282.1302354875975;3313.6686331366914;3416.5046068320203;436734;436887;437825;441478;441997.9999999968;55036.89926201534;55119.173212401845;55168.724156379765;55249;55249;33705.83147084249;33841.66158338385;34175.65824341775;35548;59944.700276499214;16171.919140404223;16360.018398896103;16373;16381.508554743377;16384.426545070866;12552.748945021049;12647.87352126467;12667.36663166838;12667.683307917192;12684.04869634781;16257.268422920997;16601.086940218163;18448.446546603624;18691.06544672761;18905.7164142538;7521.135069466972;7640;7655.2344765523;7707.884381734167;7720.02778930116;882.5895958379384;960.7309953213112;979.7305740921304;994.2195376629311;1055.5408397347178;29828.850855745583;29859;30095;30178.69821301759;31945.56244968982;865.5888452984908;1089.1559041742712;1103.7130346110068;1135.4890299365318;1204.114975493012;15444.613884652748;15489.993150445185;15491.225438728019;15680;15851.286692098653;594880;595373;601889;603464;603644;56066.439335606934;56476.152857707166;56623;56801;56855;1365.0649305225952;1381.012576008159;1392.749305125067;1397.3502321420183;1397.4200706706806;23794.286171414886;25670.101546445785;30478.238044049078;31078.844605776827;32358.036093152907;53820;54754.976952534395;54767.72616136894;54787;54855.177172341646;472161;540289;548445.6709761843;548477;558687;34764.65235347614;35694.64305356913;39932.80033599814;41789;42192.78903605462;43898.78050609727;45688.54311456843;46157.538424615326;46868.06263874772;47420.7628961853;38647.806760966014;38656.61343386643;38732.6126738729;39147.0944430104;40203.79388618346;37881;38110;38387.84836454911;38876.80561597287;39633.81098566932;37289.81355093207;38210.80894595509;38289.617103828605;38436.80781596074;38436.80781596074;30081;33672;33778.831105844314;33936.660633394335;34008.829955850066;21292.35752525551;24747.50504989926;25057.49885002326;25187.99248030058;25767.490689129703 14:20:11 INFO - PROCESS | 1656 | |2;jquery.html;345.8236299487261;366.432030352953;368.6497827064289;375.195206282524;398.1976317719795;14896.92551537242;16309.347626094943;19599.314024009163;20087.09608067637;20137.597248055048;19273.4217973461;19627.11677974491;20077.89961050193;20145.697814532792;20169.59660806783;5169.224616307546;5669.206311116437;5932.525397968152;5936.168936348915;5942.197803296543;5724.771009159628;5905.763769449217;5917.704114794265;5930.436608522184;5941.168236446901;5559.722013899309;5851.297844258695;5861.589688721791;5873.442023007808;5896.174535565003;5838;5881.3530511643685;5883.058710606325;5883.205767221434;5886.3230728465915;5452.509274165344;5635.267415235993;5664.518515926151;5682.232898558683;5688.089905615123;12640.367981600975;12687.238765674088;12727.74544509114;12894.742105157939;12896.871031289755;2106.610277098742;2258.3563684350047;2334.113037045912;2499.350168956066;2910.0542323744867;7247.383972362356;9548.283878709055;9684.70945871625;9939;10146.949265253701;1754.2106052276526;1834.6789311870368;1834.9633007339778;1861.3113148135276;1865.4683415226596;114158;122021;122127.99999999911;124833.37583312026;125907.11139332918;10962.342259464458;11106.167037472222;11180.552777888794;11185.552577896955;11373.772524549628;13951.02342836012;13963.232022238875;13974.650633734034;13988.510402135877;13993.440262389595;7978.6409611567615;8235.629396677165;8270.875936860952;8389.53857537829;8395.580220988926;6660.70026848793;8118.675252989932;8138.308243799225;8190;8334.458260213067;4325.480942286883;4349.565043495689;4377.3871657967275;4387.5393083726385;4396.384506169139;1525.908445493274;1848.316123034459;1850.9814901851078;1858.4889155482351;1866.1415748755453;878.6090189865577;913.1827014821697;923.3813345058827;946.3186505715873;962.2157941277836;17034.74447883283;19752;20215.393538193584;20215.79784202139;20435.897820510803;789.5894135049742;876.1807709791366;916.4959272400154;950.6292545907102;984.7242772023844;10073.143782778547;10485.68542943713;10581.84127238092;10581.84127238092;10600.0989915858;526310;537114;537548.869624341;551439;552380;92163;118397.40801295938;122783;123768.38115809544;125225.4954900915;872.2193636695122;977.5649835822991;984.3995162950595;1020.7805321855689;1021.7803172317987;18103.90948045304;18452.723209152144;18522.81477185211;18567;18670.906645466686;54145.646358841346;54608.180877286075;55022.899542008134;55060.72469637626;55498.72250638721;77032.84450733356;77196;77334.22665773495;79701;79951;6906.274841141608;7133.3936615388275;7647.705688293601;7913.248241417056;8171.142030086993;11567.942160289145;12552.686182845502;12861.871381286068;13084.411201496147;13183.340832958122;10777.73055673583;11351.318920864775;11408.657740267805;11531.308121512737;11564.306141631529;10984.890151098707;11047.558097676325;11165.832512512481;11330.206885517935;11611.709707257387;11200.21598488098;11308.943455282671;11565.653030409101;11719.472623731783;11887.28676279426;7124.893126603209;7210.71157153708;7261.237570055068;7280.781576552712;7458.179600244077;10366.688999330032;11107.777844442904;11124.443777810915;11400.144989125683;11540.884591153981 14:20:11 INFO - PROCESS | 1656 | |3;mootools.html;1539.592008117849;1556.8365321641227;1772.4682595221439;1863.8788478748872;1918.9904050479754;8409.453385529943;8924.46453212807;8926.41978271412;8930.732078037652;8933.330000249975;7818.374530037598;8029.478083924555;8479.533625650587;8696.260817830493;8719.302455803536;2345.6129738593154;2378.2508509819436;2382.0233704181287;2383.92848214553;2388.8088952883813;2327.767223277667;2327.8021368183727;2351.1065794997876;2368.0646144772804;2371.4071482129466;2276.8843266799313;2336.6611841283;2355.034435881289;2357.4460001899624;2368.7039120109985;2055.660815965364;2310.7342655594653;2336.789688927996;2337.9776493333143;2349.48311371498;2328.1036800831635;2329.4758679297192;2332.661764044221;2333.4749681321737;2334.661474086248;8757.868631970525;8786.297096232285;8848.070952549953;8849.292056635453;8852.822943541094;5125.410577783528;5278.419373868871;5279.70961597112;5280.709560974145;5290.60320475962;3452.654734526552;3495.6329585393423;3592.4252119660728;3649.069487280726;3663.3405986922344;932.4871320773548;980.6126580000931;1042.2131290875416;1051.6634676903393;1070.9518071686794;11554.19120661554;11688.649340519798;11690.590829320932;11705.648830535012;11709.414529273587;4457.242268814244;4524.638028957741;4683.437987441529;4713.552212539803;4718.551737584924;9863.408195508293;9869.901300987041;9872;9872.35829671069;9889.20886329092;5275.60432967529;5277.656952298088;5277.815276465305;5278.287431196816;5285.048691235615;4258.403823464718;4384.890377740582;4535.63714902807;4824.734639594856;4853.223484242504;3793.696504279651;3814.9809250953567;3932.623581746423;3938.5667576566557;4018;1083.5557421457322;1247.7504499100216;1263.9873601264053;1265.0954567484293;1280.2126692084487;779.2558107007794;824.6536454689045;846.4582667093065;893.3657103456488;905.9094090590888;5765.164051212634;5768.077107662838;5779.913301300484;5822.884756655169;6027.276726792726;417.19689597524643;419.3018623991021;422.32006469583945;425.73604365293716;439;3818.6181381861593;3872;3878.7866667333005;3882.475865758143;3928.3518219494035;10310.845337319946;10792;10796.244262901679;10811.675649730521;10851.457427128613;10486.580536778596;10588.417637029883;10639.734006649896;10700;10700.464976751287;7985.640646170937;8079.757607271791;8083.797905052421;8163.183681631789;8178.550179740052;5515.979543784252;5657.801976930952;5657.830265092054;5728.455796699307;5840.970795145997;9664.130228279488;9793.902060979299;9820.901790982;9926.851097233692;10068.093871551595;9790.412575245224;10098.495075246356;10166.8474972874;10181;10210.132138768287;5661.433856614306;6990.720371185095;7373.5575865448245;7422.665980030803;7453.776386708408;7582.279683430065;7820.804479888048;8035.758927232192;8216.958915205385;8408.369372297224;7271.890921636285;7333.120025596749;7364.77905662831;7435.107787065371;7504.137024242282;7229.204787473269;7299.5255308404785;7304.707811687473;7318.597477138808;7322.3775979042375;6662.933370666231;6830.59016459014;6927.168739751262;6994.2656021117045;6995.580265184105;4150.418941348274;4887.3890763654545;4947.3073769671555;4983.401991760867;5068.670536415121;4305.956940430556;4777.235642297216;4862.4651288357545;4982.078315511642;5153.639245252796 14:20:11 INFO - PROCESS | 1656 | |4;prototype.html;299.72874548533576;300.5821907548508;308.5017696420281;317.0820474725668;325.73615371549045;5200.739963001844;5413.512783849463;5471.234027236185;5474.42518535554;5502.147167189087;4185.702432246006;5355.705436201008;5382.300300960869;5396.757145928433;5433.8369848904495;4113.300738874398;4114.773687447189;4126.46355973723;4139.26151016573;4139.503259608851;3822.2928758179823;4068.2270368630016;4132.669386449077;4142.875713728593;4153.667706583481;3351.7821341612716;3768.8492460301554;4068.65416439603;4083.1629515949307;4091.3453847384426;3471.583409990792;3921.705872059593;4012.8394864205397;4059.219434687234;4081.3877918312196;2882.380288238018;2924.327404696932;3485.860565577374;3500.0549851540195;3527.1358517163203;3920.8039598019986;4885.487023862479;5170.62978310748;5221.477852214786;5297.814576489843;4174.060836311805;4279.743215407086;4614.953850461486;4629.722216666977;4647.88380290492;5695.572832037572;5703.403047146806;5710.772183980423;5719.998000699763;5720.542356611461;3671.559412870446;3810.3903375460072;3822.8279727412055;3840.2895464339185;3844.5770965193806;5206.609504287194;5513;6039.607425517372;6048.820480006357;6081.665508397036;3885.959871067712;3903.785291808921;3918.1967696622123;3929.5088113985753;3940.9014774630864;3655.5796083450746;3833.8083095845654;3935.858601005676;3950.6246906543834;3987.3620220764537;4532.161550113239;4569.771511424416;4627.629789616823;4627.907441851212;4638.257878739386;5789.942100579025;5796.565257605696;5806.229100124418;5817.941820581825;5876.4437469700815;1251.8748125187408;1299.928503932274;1309.5285697149018;1340.785474324123;1352.7216780142671;877.2455688108222;888.2760550151605;908.7822318093786;910.6084383715066;911.3095208388344;4642.46611639659;4974.040445934663;5318.813841515605;5333.839984800462;5352.6253162279045;5279.313689220377;5835.978703726831;5839.445252700986;5868.589198756046;5877.294724633071;4429.468463784302;4680.953190468052;4955.281484184773;5285.233641122016;5287.629865909349;2386.854309931208;2394.245812569035;2428.7291382557364;2447.6818013658467;2629.4609605030923;4576.58810707038;5145.76844042019;5167.586593072545;5183.481651834786;5247.895042099137;4254.595813397722;4389.209942210369;4468.798904049326;4858.6356023298395;5032.798688052437;4462.2780104136455;4620.953790462052;4938.950610493921;5107.233914912718;5124.641275110706;4396.934045989249;4618.891466048191;4704.823794051487;4917.655764096479;4983.925241121458;3849.672777813805;4235.178873084603;4616.6075883550275;4796.35249241348;4888.5779954031605;4518.6385089193445;4742.857714268577;4743.264793956942;5105.953279577603;5130.461301563282;2987.8057926234724;3365.242820365398;3405.2462981564017;3410.9317813643092;3419.2049174183753;4334.523202447791;4503.946448196518;4575.58819706228;4924.679895806761;5133.948660513347;4329.588689074533;5169.327987361619;5275.551578115903;5734.971325143348;5761.740721667619;5238.397584277857;5733.684647344269;5741.885162296814;5762.2221000164445;5786.07422812348;5327.520523152934;5624;5664.37691853904;5666.526703057317;5704.914426283692;5750.9360768258;5754.798582049608;5755.510781583613;5773.420479302859;5804.258722383183;5609.691466969356;5634.211210430625;5646.491815736604;5690.004249256281;5704.144378343116 14:20:11 INFO - PROCESS | 1656 | |5;yui.html;652.4356431686592;677.6510097299891;711.2602892991289;764.6329761714377;831.1563762780777;14944.701105977874;15056.473023444154;15653.84346156538;15676.608084797886;15715.764263536043;13993.440262389493;14047.22740249286;14185.716285674305;14190.361433735481;14216.78674819876;677.3159109299594;833.162671515128;856.648774002659;856.7258477287271;858.2404571953834;700.075899812248;830.7341650671757;850.268768858781;858.0218550852034;863.9870401943975;889.3552174673375;891.1044400377643;891.8974317953421;892.9910700892973;895.4806212396827;702.7470110760122;887.6671248281895;888.6978427334673;889.3774357949429;890.9287257019423;643.3148696638112;658.246307977368;658.3548122839596;663.3697986912453;664.8570557330197;13744.10663306892;13805.654858628615;13811.930940345335;13832.23922684252;13846.169229846242;12962.675933101653;13745.106568073043;13757.724845503133;13846;14084.436622535086;10848.78302433947;12076.516939322417;12274.631761047183;12334.629961101182;12347.320897350643;4382.342648602703;4426.313921342165;4495.34817451471;4519.706219095749;4643.001754622773;120396.99999999913;120503;121679;122631.77368226292;122855;10728.839067413996;10761.139108871272;10775.6228532001;10865.83701244482;11109.111271098292;21242.681359779617;21267.46831329198;21272;21305.25431609886;21341.893290533757;7842.803929901798;7957.801054973672;8047.51714897108;8075.717349892843;8087.312578430781;4326.3510473428605;4454.042380888124;4535.795889184995;4592.632589392841;4600.953990460052;3318.7510936679846;3433.776804507699;3435.7423193260606;3439.3465241604017;3441.380551500704;589.7817807411285;590.6886711500462;605.4460168945453;630.2846269484169;632.1118828046602;582.1645938078785;591.3052163707712;594.5090072595809;605.8394525450759;612.2133059019139;7086.681099350541;8169.142240064708;8600.784980375416;8879.733607991771;8965.462072275684;529.6758104738154;588.7350692188533;601.6570554783731;633.338161621107;637.5441559285041;4252.468441444819;4306.569343065668;4364.454443194601;4405.052913623586;4424.292113261927;130629.34685326513;149482;155086.22456887644;155426.44573554347;158090;135375.96936045968;136565.31717341547;137949.3102534481;138124.54688632864;142164.8675269872;114669;117517;118569;119216;122884;8054.923997974668;8278;8367;8493.362997775068;8568.314534837322;55956;57282.276652017485;64145.35854641394;65878.67060664858;66733;95858;98278;100693;102163.48918255362;102907.4854625752;11474.4262786862;12104.757904841677;12424.875751242373;13201.339933003503;13662.31688415595;14497.420103195755;14669.339879705652;15032.549023529311;15625.531234062997;15964.8403515968;13682.384292706653;13819.861801381858;14136.08115472491;14376.137431754129;14451;12153.69615759613;14255.429782808986;14348.248300590209;14348.426062957366;14519.78220326717;11592.710182245512;13443.731125377633;13758.93120534431;13822.792658110338;13863.930680346937;11229.71925701864;11297.830532541853;11313.604023859125;11937.940310298392;11996.76006479848;9165.083491650781;11003.504842282277;11172.832407514057;11362.261453005529;11391.316521008766 14:20:11 INFO - PROCESS | 1656 | __end_tp_report 14:20:11 INFO - PROCESS | 1656 | __start_cc_report 14:20:11 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1138 14:20:11 INFO - PROCESS | 1656 | __end_cc_report 14:20:11 INFO - PROCESS | 1656 | __startTimestamp1472764811959__endTimestamp 14:20:11 INFO - PROCESS | 1656 | 14:20:11 INFO - PROCESS | 1656 | ------- Summary: start ------- 14:20:11 INFO - PROCESS | 1656 | Number of tests: 6 14:20:11 INFO - PROCESS | 1656 | 14:20:11 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5723.94 Median:5896.20 stddev:637.37 (10.8%) stddev-sans-first:610.30 14:20:11 INFO - PROCESS | 1656 | Values: 3300.0 3307.4 3343.3 3379.2 3416.2 5524.6 5571.0 5640.8 5703.8 5727.5 5628.8 5655.9 5706.8 5725.2 5778.7 5106.2 5120.9 5189.3 5203.0 5289.4 5287.2 5294.3 5296.5 5319.4 5343.9 5117.6 5159.2 5170.4 5179.1 5208.8 5205.7 5208.7 5214.1 5217.7 5221.8 5123.4 5164.8 5166.9 5168.8 5184.0 5597.0 5605.0 5611.2 5707.5 5768.0 5526.2 6194.8 6221.3 6254.5 6262.7 6118.7 6176.2 6255.2 6267.5 6298.8 5771.8 5800.7 5806.6 5833.3 5857.3 5280.9 6228.4 6252.4 6261.0 6275.1 5956.5 6040.5 6061.3 6079.5 6166.8 6235.0 6248.7 6254.8 6258.2 6259.2 6213.0 6225.5 6232.4 6234.4 6236.8 6094.4 6097.5 6137.1 6184.5 6222.5 4866.0 4912.1 4938.6 4941.4 4948.3 4356.7 4360.4 4373.6 4383.8 4399.9 5677.6 5688.2 5690.3 5693.8 5695.8 6080.7 6173.0 6189.6 6247.7 6264.8 5740.1 5746.6 5753.5 5762.9 5790.0 5219.0 5386.6 5422.8 5431.2 5435.7 6194.6 6218.4 6219.3 6278.5 6298.1 6268.0 6273.1 6277.5 6281.0 6290.7 6254.2 6283.4 6293.7 6294.3 6317.2 6006.2 6131.4 6136.8 6185.6 6196.2 6249.9 6257.2 6288.7 6302.0 6312.7 5897.5 6126.9 6158.5 6164.3 6177.5 5779.4 5883.5 5896.2 6115.4 6138.8 5857.7 5967.8 5972.0 6020.5 6064.8 6106.2 6107.2 6168.9 6170.7 6171.7 5789.5 5803.2 5974.5 5998.0 6017.9 14:20:11 INFO - PROCESS | 1656 | 14:20:11 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:66211.98 Median:38436.81 stddev:140874.33 (366.5%) stddev-sans-first:141183.14 14:20:11 INFO - PROCESS | 1656 | Values: 504.4 524.8 535.4 540.0 549.2 30270.5 39672.2 40918.0 41006.8 43296.6 42229.6 42607.4 42671.8 42696.6 42736.0 8487.4 8789.2 9732.6 9904.3 9915.7 8829.2 8951.2 8975.2 8982.0 12005.5 11853.1 12174.1 12255.6 12284.6 12304.6 11161.4 11239.6 12057.4 12092.7 12107.1 11881.4 11966.9 11971.6 11978.0 11983.0 39588.6 39916.4 39923.0 39926.0 39947.4 3559.5 3989.7 4009.8 4053.5 4139.6 30069.9 30775.4 30841.5 30919.0 31421.9 2755.6 3221.5 3282.1 3313.7 3416.5 436734.0 436887.0 437825.0 441478.0 441998.0 55036.9 55119.2 55168.7 55249.0 55249.0 33705.8 33841.7 34175.7 35548.0 59944.7 16171.9 16360.0 16373.0 16381.5 16384.4 12552.7 12647.9 12667.4 12667.7 12684.0 16257.3 16601.1 18448.4 18691.1 18905.7 7521.1 7640.0 7655.2 7707.9 7720.0 882.6 960.7 979.7 994.2 1055.5 29828.9 29859.0 30095.0 30178.7 31945.6 865.6 1089.2 1103.7 1135.5 1204.1 15444.6 15490.0 15491.2 15680.0 15851.3 594880.0 595373.0 601889.0 603464.0 603644.0 56066.4 56476.2 56623.0 56801.0 56855.0 1365.1 1381.0 1392.7 1397.4 1397.4 23794.3 25670.1 30478.2 31078.8 32358.0 53820.0 54755.0 54767.7 54787.0 54855.2 472161.0 540289.0 548445.7 548477.0 558687.0 34764.7 35694.6 39932.8 41789.0 42192.8 43898.8 45688.5 46157.5 46868.1 47420.8 38647.8 38656.6 38732.6 39147.1 40203.8 37881.0 38110.0 38387.8 38876.8 39633.8 37289.8 38210.8 38289.6 38436.8 38436.8 30081.0 33672.0 33778.8 33936.7 34008.8 21292.4 24747.5 25057.5 25188.0 25767.5 14:20:11 INFO - PROCESS | 1656 | 14:20:11 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:32705.71 Median:2704.70 stddev:90803.35 (3357.2%) stddev-sans-first:91025.57 14:20:11 INFO - PROCESS | 1656 | Values: 345.8 366.4 368.6 375.2 398.2 14896.9 16309.3 19599.3 20087.1 20137.6 19273.4 19627.1 20077.9 20145.7 20169.6 5169.2 5669.2 5932.5 5936.2 5942.2 5724.8 5905.8 5917.7 5930.4 5941.2 5559.7 5851.3 5861.6 5873.4 5896.2 5838.0 5881.4 5883.1 5883.2 5886.3 5452.5 5635.3 5664.5 5682.2 5688.1 12640.4 12687.2 12727.7 12894.7 12896.9 2106.6 2258.4 2334.1 2499.4 2910.1 7247.4 9548.3 9684.7 9939.0 10146.9 1754.2 1834.7 1835.0 1861.3 1865.5 114158.0 122021.0 122128.0 124833.4 125907.1 10962.3 11106.2 11180.6 11185.6 11373.8 13951.0 13963.2 13974.7 13988.5 13993.4 7978.6 8235.6 8270.9 8389.5 8395.6 6660.7 8118.7 8138.3 8190.0 8334.5 4325.5 4349.6 4377.4 4387.5 4396.4 1525.9 1848.3 1851.0 1858.5 1866.1 878.6 913.2 923.4 946.3 962.2 17034.7 19752.0 20215.4 20215.8 20435.9 789.6 876.2 916.5 950.6 984.7 10073.1 10485.7 10581.8 10581.8 10600.1 526310.0 537114.0 537548.9 551439.0 552380.0 92163.0 118397.4 122783.0 123768.4 125225.5 872.2 977.6 984.4 1020.8 1021.8 18103.9 18452.7 18522.8 18567.0 18670.9 54145.6 54608.2 55022.9 55060.7 55498.7 77032.8 77196.0 77334.2 79701.0 79951.0 6906.3 7133.4 7647.7 7913.2 8171.1 11567.9 12552.7 12861.9 13084.4 13183.3 10777.7 11351.3 11408.7 11531.3 11564.3 10984.9 11047.6 11165.8 11330.2 11611.7 11200.2 11308.9 11565.7 11719.5 11887.3 7124.9 7210.7 7261.2 7280.8 7458.2 10366.7 11107.8 11124.4 11400.1 11540.9 14:20:11 INFO - PROCESS | 1656 | 14:20:11 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5627.53 Median:4857.84 stddev:3202.50 (65.9%) stddev-sans-first:3196.75 14:20:11 INFO - PROCESS | 1656 | Values: 1539.6 1556.8 1772.5 1863.9 1919.0 8409.5 8924.5 8926.4 8930.7 8933.3 7818.4 8029.5 8479.5 8696.3 8719.3 2345.6 2378.3 2382.0 2383.9 2388.8 2327.8 2327.8 2351.1 2368.1 2371.4 2276.9 2336.7 2355.0 2357.4 2368.7 2055.7 2310.7 2336.8 2338.0 2349.5 2328.1 2329.5 2332.7 2333.5 2334.7 8757.9 8786.3 8848.1 8849.3 8852.8 5125.4 5278.4 5279.7 5280.7 5290.6 3452.7 3495.6 3592.4 3649.1 3663.3 932.5 980.6 1042.2 1051.7 1071.0 11554.2 11688.6 11690.6 11705.6 11709.4 4457.2 4524.6 4683.4 4713.6 4718.6 9863.4 9869.9 9872.0 9872.4 9889.2 5275.6 5277.7 5277.8 5278.3 5285.0 4258.4 4384.9 4535.6 4824.7 4853.2 3793.7 3815.0 3932.6 3938.6 4018.0 1083.6 1247.8 1264.0 1265.1 1280.2 779.3 824.7 846.5 893.4 905.9 5765.2 5768.1 5779.9 5822.9 6027.3 417.2 419.3 422.3 425.7 439.0 3818.6 3872.0 3878.8 3882.5 3928.4 10310.8 10792.0 10796.2 10811.7 10851.5 10486.6 10588.4 10639.7 10700.0 10700.5 7985.6 8079.8 8083.8 8163.2 8178.6 5516.0 5657.8 5657.8 5728.5 5841.0 9664.1 9793.9 9820.9 9926.9 10068.1 9790.4 10098.5 10166.8 10181.0 10210.1 5661.4 6990.7 7373.6 7422.7 7453.8 7582.3 7820.8 8035.8 8217.0 8408.4 7271.9 7333.1 7364.8 7435.1 7504.1 7229.2 7299.5 7304.7 7318.6 7322.4 6662.9 6830.6 6927.2 6994.3 6995.6 4150.4 4887.4 4947.3 4983.4 5068.7 4306.0 4777.2 4862.5 4982.1 5153.6 14:20:11 INFO - PROCESS | 1656 | 14:20:11 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4387.12 Median:4692.89 stddev:1366.01 (29.1%) stddev-sans-first:1334.95 14:20:11 INFO - PROCESS | 1656 | Values: 299.7 300.6 308.5 317.1 325.7 5200.7 5413.5 5471.2 5474.4 5502.1 4185.7 5355.7 5382.3 5396.8 5433.8 4113.3 4114.8 4126.5 4139.3 4139.5 3822.3 4068.2 4132.7 4142.9 4153.7 3351.8 3768.8 4068.7 4083.2 4091.3 3471.6 3921.7 4012.8 4059.2 4081.4 2882.4 2924.3 3485.9 3500.1 3527.1 3920.8 4885.5 5170.6 5221.5 5297.8 4174.1 4279.7 4615.0 4629.7 4647.9 5695.6 5703.4 5710.8 5720.0 5720.5 3671.6 3810.4 3822.8 3840.3 3844.6 5206.6 5513.0 6039.6 6048.8 6081.7 3886.0 3903.8 3918.2 3929.5 3940.9 3655.6 3833.8 3935.9 3950.6 3987.4 4532.2 4569.8 4627.6 4627.9 4638.3 5789.9 5796.6 5806.2 5817.9 5876.4 1251.9 1299.9 1309.5 1340.8 1352.7 877.2 888.3 908.8 910.6 911.3 4642.5 4974.0 5318.8 5333.8 5352.6 5279.3 5836.0 5839.4 5868.6 5877.3 4429.5 4681.0 4955.3 5285.2 5287.6 2386.9 2394.2 2428.7 2447.7 2629.5 4576.6 5145.8 5167.6 5183.5 5247.9 4254.6 4389.2 4468.8 4858.6 5032.8 4462.3 4621.0 4939.0 5107.2 5124.6 4396.9 4618.9 4704.8 4917.7 4983.9 3849.7 4235.2 4616.6 4796.4 4888.6 4518.6 4742.9 4743.3 5106.0 5130.5 2987.8 3365.2 3405.2 3410.9 3419.2 4334.5 4503.9 4575.6 4924.7 5133.9 4329.6 5169.3 5275.6 5735.0 5761.7 5238.4 5733.7 5741.9 5762.2 5786.1 5327.5 5624.0 5664.4 5666.5 5704.9 5750.9 5754.8 5755.5 5773.4 5804.3 5609.7 5634.2 5646.5 5690.0 5704.1 14:20:11 INFO - PROCESS | 1656 | 14:20:11 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:25847.83 Median:21323.57 stddev:42040.71 (197.2%) stddev-sans-first:42116.08 14:20:11 INFO - PROCESS | 1656 | Values: 652.4 677.7 711.3 764.6 831.2 14944.7 15056.5 15653.8 15676.6 15715.8 13993.4 14047.2 14185.7 14190.4 14216.8 677.3 833.2 856.6 856.7 858.2 700.1 830.7 850.3 858.0 864.0 889.4 891.1 891.9 893.0 895.5 702.7 887.7 888.7 889.4 890.9 643.3 658.2 658.4 663.4 664.9 13744.1 13805.7 13811.9 13832.2 13846.2 12962.7 13745.1 13757.7 13846.0 14084.4 10848.8 12076.5 12274.6 12334.6 12347.3 4382.3 4426.3 4495.3 4519.7 4643.0 120397.0 120503.0 121679.0 122631.8 122855.0 10728.8 10761.1 10775.6 10865.8 11109.1 21242.7 21267.5 21272.0 21305.3 21341.9 7842.8 7957.8 8047.5 8075.7 8087.3 4326.4 4454.0 4535.8 4592.6 4601.0 3318.8 3433.8 3435.7 3439.3 3441.4 589.8 590.7 605.4 630.3 632.1 582.2 591.3 594.5 605.8 612.2 7086.7 8169.1 8600.8 8879.7 8965.5 529.7 588.7 601.7 633.3 637.5 4252.5 4306.6 4364.5 4405.1 4424.3 130629.3 149482.0 155086.2 155426.4 158090.0 135376.0 136565.3 137949.3 138124.5 142164.9 114669.0 117517.0 118569.0 119216.0 122884.0 8054.9 8278.0 8367.0 8493.4 8568.3 55956.0 57282.3 64145.4 65878.7 66733.0 95858.0 98278.0 100693.0 102163.5 102907.5 11474.4 12104.8 12424.9 13201.3 13662.3 14497.4 14669.3 15032.5 15625.5 15964.8 13682.4 13819.9 14136.1 14376.1 14451.0 12153.7 14255.4 14348.2 14348.4 14519.8 11592.7 13443.7 13758.9 13822.8 13863.9 11229.7 11297.8 11313.6 11937.9 11996.8 9165.1 11003.5 11172.8 11362.3 11391.3 14:20:11 INFO - PROCESS | 1656 | -------- Summary: end -------- 14:20:11 INFO - PROCESS | 1656 | 14:20:12 INFO - TEST-INFO | 1656: exit 0 14:20:12 INFO - TEST-OK | dromaeo_css | took 1117863ms 14:20:12 INFO - TEST-START | kraken 14:20:12 INFO - operating with platform_type : mac_ 14:20:12 INFO - Initialising browser for kraken test... 14:20:12 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmpoP0yFv/profile http://localhost:49280/getInfo.html) 14:20:14 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 14:20:14 INFO - PROCESS | 1663 | colorDepth:24 14:20:14 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 14:20:14 INFO - PROCESS | 1663 | __metrics 14:20:14 INFO - PROCESS | 1663 | 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" 14:20:14 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:20:15 INFO - TEST-INFO | 1663: exit 0 14:20:15 INFO - Browser initialized. 14:20:15 INFO - Running cycle 1/1 for kraken test... 14:20:15 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmpoP0yFv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:20:27 INFO - PROCESS | 1664 | RSS: Main: 233865216 14:20:27 INFO - PROCESS | 1664 | 14:20:30 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 14:20:31 INFO - PROCESS | 1664 | RSS: Main: 293785600 14:20:31 INFO - PROCESS | 1664 | 14:20:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 14:20:34 INFO - PROCESS | 1664 | RSS: Main: 288870400 14:20:34 INFO - PROCESS | 1664 | 14:20:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 14:20:38 INFO - PROCESS | 1664 | RSS: Main: 377028608 14:20:38 INFO - PROCESS | 1664 | 14:20:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 14:20:40 INFO - PROCESS | 1664 | RSS: Main: 281300992 14:20:40 INFO - PROCESS | 1664 | 14:20:43 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:20:43 INFO - PROCESS | 1664 | RSS: Main: 284143616 14:20:43 INFO - PROCESS | 1664 | 14:20:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 14:20:47 INFO - PROCESS | 1664 | RSS: Main: 325005312 14:20:47 INFO - PROCESS | 1664 | 14:20:49 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 14:20:50 INFO - PROCESS | 1664 | RSS: Main: 325341184 14:20:50 INFO - PROCESS | 1664 | 14:20:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 14:20:53 INFO - PROCESS | 1664 | RSS: Main: 323420160 14:20:53 INFO - PROCESS | 1664 | 14:20:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:20:56 INFO - PROCESS | 1664 | RSS: Main: 296914944 14:20:56 INFO - PROCESS | 1664 | 14:20:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:20:58 INFO - PROCESS | 1664 | RSS: Main: 301985792 14:20:58 INFO - PROCESS | 1664 | 14:21:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:21:01 INFO - PROCESS | 1664 | RSS: Main: 307011584 14:21:01 INFO - PROCESS | 1664 | 14:21:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:21:05 INFO - PROCESS | 1664 | RSS: Main: 321392640 14:21:05 INFO - PROCESS | 1664 | 14:21:07 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:21:08 INFO - PROCESS | 1664 | RSS: Main: 317227008 14:21:08 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 14:21:10 INFO - PROCESS | 1664 | RSS: Main: 360968192 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | __start_tp_report 14:21:10 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 14:21:10 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 14:21:10 INFO - PROCESS | 1664 | |i|pagename|runs| 14:21:10 INFO - PROCESS | 1664 | |0;ai-astar;114;113;115;113;112;114;113;111;114;123 14:21:10 INFO - PROCESS | 1664 | |1;audio-beat-detection;147;111;129;161;105;129;128;108;160;106 14:21:10 INFO - PROCESS | 1664 | |2;audio-dft;186;186;183;185;183;184;186;179;184;183 14:21:10 INFO - PROCESS | 1664 | |3;audio-fft;75;77;76;151;58;58;56;70;140;57 14:21:10 INFO - PROCESS | 1664 | |4;audio-oscillator;80;81;135;72;75;86;123;68;72;119 14:21:10 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;88;84;89;84;88;84;87;85;87;83 14:21:10 INFO - PROCESS | 1664 | |6;imaging-darkroom;93;112;149;94;93;93;95;94;93;94 14:21:10 INFO - PROCESS | 1664 | |7;imaging-desaturate;89;91;86;91;91;88;89;92;90;91 14:21:10 INFO - PROCESS | 1664 | |8;json-parse-financial;56;52;56;55;55;55;56;54;54;55 14:21:10 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;66;66;74;76;73;71;70;70;70;71 14:21:10 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;86;87;85;86;84;87;88;89;89;86 14:21:10 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;137;141;135;139;143;141;146;143;185;135 14:21:10 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;200;168;169;171;174;171;169;168;168;168 14:21:10 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;67;68;69;68;67;69;67;67;66;67 14:21:10 INFO - PROCESS | 1664 | __end_tp_report 14:21:10 INFO - PROCESS | 1664 | __start_cc_report 14:21:10 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1983 14:21:10 INFO - PROCESS | 1664 | __end_cc_report 14:21:10 INFO - PROCESS | 1664 | __startTimestamp1472764870526__endTimestamp 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | ------- Summary: start ------- 14:21:10 INFO - PROCESS | 1664 | Number of tests: 14 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.00 stddev:3.29 (2.9%) stddev-sans-first:3.49 14:21:10 INFO - PROCESS | 1664 | Values: 114.0 113.0 115.0 113.0 112.0 114.0 113.0 111.0 114.0 123.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:128.40 Median:129.00 stddev:21.54 (16.7%) stddev-sans-first:21.77 14:21:10 INFO - PROCESS | 1664 | Values: 147.0 111.0 129.0 161.0 105.0 129.0 128.0 108.0 160.0 106.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:2.13 (1.2%) stddev-sans-first:2.12 14:21:10 INFO - PROCESS | 1664 | Values: 186.0 186.0 183.0 185.0 183.0 184.0 186.0 179.0 184.0 183.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:81.80 Median:64.00 stddev:34.69 (54.2%) stddev-sans-first:36.71 14:21:10 INFO - PROCESS | 1664 | Values: 75.0 77.0 76.0 151.0 58.0 58.0 56.0 70.0 140.0 57.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:73.50 stddev:24.71 (33.6%) stddev-sans-first:25.88 14:21:10 INFO - PROCESS | 1664 | Values: 80.0 81.0 135.0 72.0 75.0 86.0 123.0 68.0 72.0 119.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:87.00 stddev:2.13 (2.5%) stddev-sans-first:2.12 14:21:10 INFO - PROCESS | 1664 | Values: 88.0 84.0 89.0 84.0 88.0 84.0 87.0 85.0 87.0 83.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:101.00 Median:93.50 stddev:17.84 (19.1%) stddev-sans-first:18.68 14:21:10 INFO - PROCESS | 1664 | Values: 93.0 112.0 149.0 94.0 93.0 93.0 95.0 94.0 93.0 94.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:89.80 Median:91.00 stddev:1.81 (2.0%) stddev-sans-first:1.90 14:21:10 INFO - PROCESS | 1664 | Values: 89.0 91.0 86.0 91.0 91.0 88.0 89.0 92.0 90.0 91.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:1.23 (2.2%) stddev-sans-first:1.22 14:21:10 INFO - PROCESS | 1664 | Values: 56.0 52.0 56.0 55.0 55.0 55.0 56.0 54.0 54.0 55.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.00 stddev:3.16 (4.5%) stddev-sans-first:2.86 14:21:10 INFO - PROCESS | 1664 | Values: 66.0 66.0 74.0 76.0 73.0 71.0 70.0 70.0 70.0 71.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:87.00 stddev:1.64 (1.9%) stddev-sans-first:1.72 14:21:10 INFO - PROCESS | 1664 | Values: 86.0 87.0 85.0 86.0 84.0 87.0 88.0 89.0 89.0 86.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:142.00 stddev:14.68 (10.3%) stddev-sans-first:15.31 14:21:10 INFO - PROCESS | 1664 | Values: 137.0 141.0 135.0 139.0 143.0 141.0 146.0 143.0 185.0 135.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:170.00 stddev:9.82 (5.8%) stddev-sans-first:2.07 14:21:10 INFO - PROCESS | 1664 | Values: 200.0 168.0 169.0 171.0 174.0 171.0 169.0 168.0 168.0 168.0 14:21:10 INFO - PROCESS | 1664 | 14:21:10 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.50 stddev:0.97 (1.4%) stddev-sans-first:1.01 14:21:10 INFO - PROCESS | 1664 | Values: 67.0 68.0 69.0 68.0 67.0 69.0 67.0 67.0 66.0 67.0 14:21:10 INFO - PROCESS | 1664 | -------- Summary: end -------- 14:21:10 INFO - PROCESS | 1664 | 14:21:11 INFO - TEST-INFO | 1664: exit 0 14:21:11 INFO - TEST-OK | kraken | took 58805ms 14:21:11 INFO - SUITE-END | took 1176s 14:21:11 INFO - Completed test suite (00:19:36) 14:21:11 INFO - javascript benchmark 14:21:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3299.999999999999, 3307.355065762176, 3343.297907439436, 3379.2227787608836, 3416.2484253464245, 5524.640898341605, 5570.997220500309, 5640.830775076744, 5703.771849126035, 5727.541796656263, 5628.831135065944, 5655.886882262363, 5706.828795136143, 5725.169850371704, 5778.653280803154, 5106.183010718286, 5120.92318615221, 5189.325387699594, 5202.973985130069, 5289.418164001957, 5287.154055351145, 5294.338207724035, 5296.523312901838, 5319.361676598814, 5343.946560534403, 5117.590592752571, 5159.2003239497935, 5170.379554453452, 5179.119549676564, 5208.8437346879655, 5205.739713014372, 5208.739563021833, 5214.139666954948, 5217.660852044605, 5221.843344699665, 5123.38519377677, 5164.845054648366, 5166.948330516684, 5168.767405466763, 5184.0, 5597.0, 5604.963081829875, 5611.2144299798065, 5707.543396528309, 5767.971160144172, 5526.198701188305, 6194.845128871769, 6221.3156552779155, 6254.530910181709, 6262.71817768197, 6118.680093383022, 6176.227971503562, 6255.155554000197, 6267.5299352548745, 6298.779542716029, 5771.826845194651, 5800.738966746507, 5806.593538452268, 5833.300003999505, 5857.297124345106, 5280.867978300496, 6228.408301211378, 6252.437280644764, 6261.0, 6275.090111933744, 5956.463918247378, 6040.516758659297, 6061.3029501607, 6079.483243924227, 6166.814995550051, 6234.9688251559355, 6248.687565621701, 6254.843628909222, 6258.186435763321, 6259.18630578031, 6213.03697926822, 6225.470834978987, 6232.439080482778, 6234.37656234382, 6236.781712640036, 6094.421030002142, 6097.524678803529, 6137.070562331796, 6184.5052395808225, 6222.533310001678, 4865.9756701216265, 4912.091263116335, 4938.555530002245, 4941.431735350408, 4948.282499037619, 4356.695031347838, 4360.367746676714, 4373.628241599499, 4383.7588932609015, 4399.912001759947, 5677.602567820213, 5688.203651488795, 5690.2887139107615, 5693.829185124453, 5695.829125126253, 6080.665563394055, 6173.043178307369, 6189.597676151036, 6247.687615619201, 6264.843378915472, 5740.081586946152, 5746.597738158289, 5753.539716822645, 5762.942370576325, 5790.044642633919, 5219.034478621467, 5386.622936394415, 5422.75597598109, 5431.185322201702, 5435.673859568438, 6194.566380353513, 6218.409251121137, 6219.346968568235, 6278.497720182466, 6298.11826344303, 6268.0, 6273.090401891609, 6277.497800176066, 6281.026440901667, 6290.716917738804, 6254.155688981929, 6283.434490895841, 6293.685315734287, 6294.3076261612105, 6317.210348706411, 6006.15913772081, 6131.386861313833, 6136.764169924236, 6185.628862268279, 6196.1635179250225, 6249.8750024998335, 6257.155284036597, 6288.685565721604, 6302.023186405931, 6312.747490100344, 5897.498712609477, 6126.877462450815, 6158.476529495043, 6164.260288765377, 6177.536684748752, 5779.364269930388, 5883.529317654663, 5896.204012458435, 6115.3578874217565, 6138.785142519991, 5857.707114644336, 5967.791127310524, 5972.044472884318, 6020.54845886569, 6064.787732429344, 6106.166166662464, 6107.180774535289, 6168.876622467616, 6170.691465426621, 6171.722272497838, 5789.478946894799, 5803.216565763737, 5974.522038237017, 5997.970010149921, 6017.939820601738], "value": 5682.745814771251, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.4224363104246, 524.7559884653635, 535.3976776126857, 539.962202645815, 549.2173652545122, 30270.54594181089, 39672.20655586886, 40917.97705057375, 41006.79496602517, 43296.567034329644, 42229.577704222946, 42607.360889586605, 42671.78664106675, 42696.57303426965, 42736.0, 8487.44831585948, 8789.165029322236, 9732.562034708437, 9904.306698531105, 9915.702528924145, 8829.190016046727, 8951.194392504673, 8975.192232699088, 8981.955090224572, 12005.519779208866, 11853.111016673784, 12174.147809653328, 12255.632331030083, 12284.631461056182, 12304.5693400731, 11161.441927903572, 11239.595050618673, 12057.432533770598, 12092.697682558006, 12107.091968102428, 11881.40592970348, 11966.880331196751, 11971.580994665232, 11978.041756659446, 11983.0, 39588.60411395878, 39916.40125398121, 39923.0, 39926.0, 39947.40078898819, 3559.4660800879815, 3989.6608788252743, 4009.8195581198916, 4053.5338436079637, 4139.627433530966, 30069.94755183558, 30775.38449231003, 30841.537376939363, 30919.0, 31421.900233491946, 2755.641766570333, 3221.5167724841463, 3282.1302354875975, 3313.6686331366914, 3416.5046068320203, 436734.0, 436887.0, 437825.0, 441478.0, 441997.9999999968, 55036.89926201534, 55119.173212401845, 55168.724156379765, 55249.0, 55249.0, 33705.83147084249, 33841.66158338385, 34175.65824341775, 35548.0, 59944.700276499214, 16171.919140404223, 16360.018398896103, 16373.0, 16381.508554743377, 16384.426545070866, 12552.748945021049, 12647.87352126467, 12667.36663166838, 12667.683307917192, 12684.04869634781, 16257.268422920997, 16601.086940218163, 18448.446546603624, 18691.06544672761, 18905.7164142538, 7521.135069466972, 7640.0, 7655.2344765523, 7707.884381734167, 7720.02778930116, 882.5895958379384, 960.7309953213112, 979.7305740921304, 994.2195376629311, 1055.5408397347178, 29828.850855745583, 29859.0, 30095.0, 30178.69821301759, 31945.56244968982, 865.5888452984908, 1089.1559041742712, 1103.7130346110068, 1135.4890299365318, 1204.114975493012, 15444.613884652748, 15489.993150445185, 15491.225438728019, 15680.0, 15851.286692098653, 594880.0, 595373.0, 601889.0, 603464.0, 603644.0, 56066.439335606934, 56476.152857707166, 56623.0, 56801.0, 56855.0, 1365.0649305225952, 1381.012576008159, 1392.749305125067, 1397.3502321420183, 1397.4200706706806, 23794.286171414886, 25670.101546445785, 30478.238044049078, 31078.844605776827, 32358.036093152907, 53820.0, 54754.976952534395, 54767.72616136894, 54787.0, 54855.177172341646, 472161.0, 540289.0, 548445.6709761843, 548477.0, 558687.0, 34764.65235347614, 35694.64305356913, 39932.80033599814, 41789.0, 42192.78903605462, 43898.78050609727, 45688.54311456843, 46157.538424615326, 46868.06263874772, 47420.7628961853, 38647.806760966014, 38656.61343386643, 38732.6126738729, 39147.0944430104, 40203.79388618346, 37881.0, 38110.0, 38387.84836454911, 38876.80561597287, 39633.81098566932, 37289.81355093207, 38210.80894595509, 38289.617103828605, 38436.80781596074, 38436.80781596074, 30081.0, 33672.0, 33778.831105844314, 33936.660633394335, 34008.829955850066, 21292.35752525551, 24747.50504989926, 25057.49885002326, 25187.99248030058, 25767.490689129703], "value": 20160.767905985354, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.8236299487261, 366.432030352953, 368.6497827064289, 375.195206282524, 398.1976317719795, 14896.92551537242, 16309.347626094943, 19599.314024009163, 20087.09608067637, 20137.597248055048, 19273.4217973461, 19627.11677974491, 20077.89961050193, 20145.697814532792, 20169.59660806783, 5169.224616307546, 5669.206311116437, 5932.525397968152, 5936.168936348915, 5942.197803296543, 5724.771009159628, 5905.763769449217, 5917.704114794265, 5930.436608522184, 5941.168236446901, 5559.722013899309, 5851.297844258695, 5861.589688721791, 5873.442023007808, 5896.174535565003, 5838.0, 5881.3530511643685, 5883.058710606325, 5883.205767221434, 5886.3230728465915, 5452.509274165344, 5635.267415235993, 5664.518515926151, 5682.232898558683, 5688.089905615123, 12640.367981600975, 12687.238765674088, 12727.74544509114, 12894.742105157939, 12896.871031289755, 2106.610277098742, 2258.3563684350047, 2334.113037045912, 2499.350168956066, 2910.0542323744867, 7247.383972362356, 9548.283878709055, 9684.70945871625, 9939.0, 10146.949265253701, 1754.2106052276526, 1834.6789311870368, 1834.9633007339778, 1861.3113148135276, 1865.4683415226596, 114158.0, 122021.0, 122127.99999999911, 124833.37583312026, 125907.11139332918, 10962.342259464458, 11106.167037472222, 11180.552777888794, 11185.552577896955, 11373.772524549628, 13951.02342836012, 13963.232022238875, 13974.650633734034, 13988.510402135877, 13993.440262389595, 7978.6409611567615, 8235.629396677165, 8270.875936860952, 8389.53857537829, 8395.580220988926, 6660.70026848793, 8118.675252989932, 8138.308243799225, 8190.0, 8334.458260213067, 4325.480942286883, 4349.565043495689, 4377.3871657967275, 4387.5393083726385, 4396.384506169139, 1525.908445493274, 1848.316123034459, 1850.9814901851078, 1858.4889155482351, 1866.1415748755453, 878.6090189865577, 913.1827014821697, 923.3813345058827, 946.3186505715873, 962.2157941277836, 17034.74447883283, 19752.0, 20215.393538193584, 20215.79784202139, 20435.897820510803, 789.5894135049742, 876.1807709791366, 916.4959272400154, 950.6292545907102, 984.7242772023844, 10073.143782778547, 10485.68542943713, 10581.84127238092, 10581.84127238092, 10600.0989915858, 526310.0, 537114.0, 537548.869624341, 551439.0, 552380.0, 92163.0, 118397.40801295938, 122783.0, 123768.38115809544, 125225.4954900915, 872.2193636695122, 977.5649835822991, 984.3995162950595, 1020.7805321855689, 1021.7803172317987, 18103.90948045304, 18452.723209152144, 18522.81477185211, 18567.0, 18670.906645466686, 54145.646358841346, 54608.180877286075, 55022.899542008134, 55060.72469637626, 55498.72250638721, 77032.84450733356, 77196.0, 77334.22665773495, 79701.0, 79951.0, 6906.274841141608, 7133.3936615388275, 7647.705688293601, 7913.248241417056, 8171.142030086993, 11567.942160289145, 12552.686182845502, 12861.871381286068, 13084.411201496147, 13183.340832958122, 10777.73055673583, 11351.318920864775, 11408.657740267805, 11531.308121512737, 11564.306141631529, 10984.890151098707, 11047.558097676325, 11165.832512512481, 11330.206885517935, 11611.709707257387, 11200.21598488098, 11308.943455282671, 11565.653030409101, 11719.472623731783, 11887.28676279426, 7124.893126603209, 7210.71157153708, 7261.237570055068, 7280.781576552712, 7458.179600244077, 10366.688999330032, 11107.777844442904, 11124.443777810915, 11400.144989125683, 11540.884591153981], "value": 9156.401650115122, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1539.592008117849, 1556.8365321641227, 1772.4682595221439, 1863.8788478748872, 1918.9904050479754, 8409.453385529943, 8924.46453212807, 8926.41978271412, 8930.732078037652, 8933.330000249975, 7818.374530037598, 8029.478083924555, 8479.533625650587, 8696.260817830493, 8719.302455803536, 2345.6129738593154, 2378.2508509819436, 2382.0233704181287, 2383.92848214553, 2388.8088952883813, 2327.767223277667, 2327.8021368183727, 2351.1065794997876, 2368.0646144772804, 2371.4071482129466, 2276.8843266799313, 2336.6611841283, 2355.034435881289, 2357.4460001899624, 2368.7039120109985, 2055.660815965364, 2310.7342655594653, 2336.789688927996, 2337.9776493333143, 2349.48311371498, 2328.1036800831635, 2329.4758679297192, 2332.661764044221, 2333.4749681321737, 2334.661474086248, 8757.868631970525, 8786.297096232285, 8848.070952549953, 8849.292056635453, 8852.822943541094, 5125.410577783528, 5278.419373868871, 5279.70961597112, 5280.709560974145, 5290.60320475962, 3452.654734526552, 3495.6329585393423, 3592.4252119660728, 3649.069487280726, 3663.3405986922344, 932.4871320773548, 980.6126580000931, 1042.2131290875416, 1051.6634676903393, 1070.9518071686794, 11554.19120661554, 11688.649340519798, 11690.590829320932, 11705.648830535012, 11709.414529273587, 4457.242268814244, 4524.638028957741, 4683.437987441529, 4713.552212539803, 4718.551737584924, 9863.408195508293, 9869.901300987041, 9872.0, 9872.35829671069, 9889.20886329092, 5275.60432967529, 5277.656952298088, 5277.815276465305, 5278.287431196816, 5285.048691235615, 4258.403823464718, 4384.890377740582, 4535.63714902807, 4824.734639594856, 4853.223484242504, 3793.696504279651, 3814.9809250953567, 3932.623581746423, 3938.5667576566557, 4018.0, 1083.5557421457322, 1247.7504499100216, 1263.9873601264053, 1265.0954567484293, 1280.2126692084487, 779.2558107007794, 824.6536454689045, 846.4582667093065, 893.3657103456488, 905.9094090590888, 5765.164051212634, 5768.077107662838, 5779.913301300484, 5822.884756655169, 6027.276726792726, 417.19689597524643, 419.3018623991021, 422.32006469583945, 425.73604365293716, 439.0, 3818.6181381861593, 3872.0, 3878.7866667333005, 3882.475865758143, 3928.3518219494035, 10310.845337319946, 10792.0, 10796.244262901679, 10811.675649730521, 10851.457427128613, 10486.580536778596, 10588.417637029883, 10639.734006649896, 10700.0, 10700.464976751287, 7985.640646170937, 8079.757607271791, 8083.797905052421, 8163.183681631789, 8178.550179740052, 5515.979543784252, 5657.801976930952, 5657.830265092054, 5728.455796699307, 5840.970795145997, 9664.130228279488, 9793.902060979299, 9820.901790982, 9926.851097233692, 10068.093871551595, 9790.412575245224, 10098.495075246356, 10166.8474972874, 10181.0, 10210.132138768287, 5661.433856614306, 6990.720371185095, 7373.5575865448245, 7422.665980030803, 7453.776386708408, 7582.279683430065, 7820.804479888048, 8035.758927232192, 8216.958915205385, 8408.369372297224, 7271.890921636285, 7333.120025596749, 7364.77905662831, 7435.107787065371, 7504.137024242282, 7229.204787473269, 7299.5255308404785, 7304.707811687473, 7318.597477138808, 7322.3775979042375, 6662.933370666231, 6830.59016459014, 6927.168739751262, 6994.2656021117045, 6995.580265184105, 4150.418941348274, 4887.3890763654545, 4947.3073769671555, 4983.401991760867, 5068.670536415121, 4305.956940430556, 4777.235642297216, 4862.4651288357545, 4982.078315511642, 5153.639245252796], "value": 4430.301886954793, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.72874548533576, 300.5821907548508, 308.5017696420281, 317.0820474725668, 325.73615371549045, 5200.739963001844, 5413.512783849463, 5471.234027236185, 5474.42518535554, 5502.147167189087, 4185.702432246006, 5355.705436201008, 5382.300300960869, 5396.757145928433, 5433.8369848904495, 4113.300738874398, 4114.773687447189, 4126.46355973723, 4139.26151016573, 4139.503259608851, 3822.2928758179823, 4068.2270368630016, 4132.669386449077, 4142.875713728593, 4153.667706583481, 3351.7821341612716, 3768.8492460301554, 4068.65416439603, 4083.1629515949307, 4091.3453847384426, 3471.583409990792, 3921.705872059593, 4012.8394864205397, 4059.219434687234, 4081.3877918312196, 2882.380288238018, 2924.327404696932, 3485.860565577374, 3500.0549851540195, 3527.1358517163203, 3920.8039598019986, 4885.487023862479, 5170.62978310748, 5221.477852214786, 5297.814576489843, 4174.060836311805, 4279.743215407086, 4614.953850461486, 4629.722216666977, 4647.88380290492, 5695.572832037572, 5703.403047146806, 5710.772183980423, 5719.998000699763, 5720.542356611461, 3671.559412870446, 3810.3903375460072, 3822.8279727412055, 3840.2895464339185, 3844.5770965193806, 5206.609504287194, 5513.0, 6039.607425517372, 6048.820480006357, 6081.665508397036, 3885.959871067712, 3903.785291808921, 3918.1967696622123, 3929.5088113985753, 3940.9014774630864, 3655.5796083450746, 3833.8083095845654, 3935.858601005676, 3950.6246906543834, 3987.3620220764537, 4532.161550113239, 4569.771511424416, 4627.629789616823, 4627.907441851212, 4638.257878739386, 5789.942100579025, 5796.565257605696, 5806.229100124418, 5817.941820581825, 5876.4437469700815, 1251.8748125187408, 1299.928503932274, 1309.5285697149018, 1340.785474324123, 1352.7216780142671, 877.2455688108222, 888.2760550151605, 908.7822318093786, 910.6084383715066, 911.3095208388344, 4642.46611639659, 4974.040445934663, 5318.813841515605, 5333.839984800462, 5352.6253162279045, 5279.313689220377, 5835.978703726831, 5839.445252700986, 5868.589198756046, 5877.294724633071, 4429.468463784302, 4680.953190468052, 4955.281484184773, 5285.233641122016, 5287.629865909349, 2386.854309931208, 2394.245812569035, 2428.7291382557364, 2447.6818013658467, 2629.4609605030923, 4576.58810707038, 5145.76844042019, 5167.586593072545, 5183.481651834786, 5247.895042099137, 4254.595813397722, 4389.209942210369, 4468.798904049326, 4858.6356023298395, 5032.798688052437, 4462.2780104136455, 4620.953790462052, 4938.950610493921, 5107.233914912718, 5124.641275110706, 4396.934045989249, 4618.891466048191, 4704.823794051487, 4917.655764096479, 4983.925241121458, 3849.672777813805, 4235.178873084603, 4616.6075883550275, 4796.35249241348, 4888.5779954031605, 4518.6385089193445, 4742.857714268577, 4743.264793956942, 5105.953279577603, 5130.461301563282, 2987.8057926234724, 3365.242820365398, 3405.2462981564017, 3410.9317813643092, 3419.2049174183753, 4334.523202447791, 4503.946448196518, 4575.58819706228, 4924.679895806761, 5133.948660513347, 4329.588689074533, 5169.327987361619, 5275.551578115903, 5734.971325143348, 5761.740721667619, 5238.397584277857, 5733.684647344269, 5741.885162296814, 5762.2221000164445, 5786.07422812348, 5327.520523152934, 5624.0, 5664.37691853904, 5666.526703057317, 5704.914426283692, 5750.9360768258, 5754.798582049608, 5755.510781583613, 5773.420479302859, 5804.258722383183, 5609.691466969356, 5634.211210430625, 5646.491815736604, 5690.004249256281, 5704.144378343116], "value": 3960.1190336399877, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.4356431686592, 677.6510097299891, 711.2602892991289, 764.6329761714377, 831.1563762780777, 14944.701105977874, 15056.473023444154, 15653.84346156538, 15676.608084797886, 15715.764263536043, 13993.440262389493, 14047.22740249286, 14185.716285674305, 14190.361433735481, 14216.78674819876, 677.3159109299594, 833.162671515128, 856.648774002659, 856.7258477287271, 858.2404571953834, 700.075899812248, 830.7341650671757, 850.268768858781, 858.0218550852034, 863.9870401943975, 889.3552174673375, 891.1044400377643, 891.8974317953421, 892.9910700892973, 895.4806212396827, 702.7470110760122, 887.6671248281895, 888.6978427334673, 889.3774357949429, 890.9287257019423, 643.3148696638112, 658.246307977368, 658.3548122839596, 663.3697986912453, 664.8570557330197, 13744.10663306892, 13805.654858628615, 13811.930940345335, 13832.23922684252, 13846.169229846242, 12962.675933101653, 13745.106568073043, 13757.724845503133, 13846.0, 14084.436622535086, 10848.78302433947, 12076.516939322417, 12274.631761047183, 12334.629961101182, 12347.320897350643, 4382.342648602703, 4426.313921342165, 4495.34817451471, 4519.706219095749, 4643.001754622773, 120396.99999999913, 120503.0, 121679.0, 122631.77368226292, 122855.0, 10728.839067413996, 10761.139108871272, 10775.6228532001, 10865.83701244482, 11109.111271098292, 21242.681359779617, 21267.46831329198, 21272.0, 21305.25431609886, 21341.893290533757, 7842.803929901798, 7957.801054973672, 8047.51714897108, 8075.717349892843, 8087.312578430781, 4326.3510473428605, 4454.042380888124, 4535.795889184995, 4592.632589392841, 4600.953990460052, 3318.7510936679846, 3433.776804507699, 3435.7423193260606, 3439.3465241604017, 3441.380551500704, 589.7817807411285, 590.6886711500462, 605.4460168945453, 630.2846269484169, 632.1118828046602, 582.1645938078785, 591.3052163707712, 594.5090072595809, 605.8394525450759, 612.2133059019139, 7086.681099350541, 8169.142240064708, 8600.784980375416, 8879.733607991771, 8965.462072275684, 529.6758104738154, 588.7350692188533, 601.6570554783731, 633.338161621107, 637.5441559285041, 4252.468441444819, 4306.569343065668, 4364.454443194601, 4405.052913623586, 4424.292113261927, 130629.34685326513, 149482.0, 155086.22456887644, 155426.44573554347, 158090.0, 135375.96936045968, 136565.31717341547, 137949.3102534481, 138124.54688632864, 142164.8675269872, 114669.0, 117517.0, 118569.0, 119216.0, 122884.0, 8054.923997974668, 8278.0, 8367.0, 8493.362997775068, 8568.314534837322, 55956.0, 57282.276652017485, 64145.35854641394, 65878.67060664858, 66733.0, 95858.0, 98278.0, 100693.0, 102163.48918255362, 102907.4854625752, 11474.4262786862, 12104.757904841677, 12424.875751242373, 13201.339933003503, 13662.31688415595, 14497.420103195755, 14669.339879705652, 15032.549023529311, 15625.531234062997, 15964.8403515968, 13682.384292706653, 13819.861801381858, 14136.08115472491, 14376.137431754129, 14451.0, 12153.69615759613, 14255.429782808986, 14348.248300590209, 14348.426062957366, 14519.78220326717, 11592.710182245512, 13443.731125377633, 13758.93120534431, 13822.792658110338, 13863.930680346937, 11229.71925701864, 11297.830532541853, 11313.604023859125, 11937.940310298392, 11996.76006479848, 9165.083491650781, 11003.504842282277, 11172.832407514057, 11362.261453005529, 11391.316521008766], "value": 7776.968702652061, "unit": "score"}], "name": "dromaeo_css", "value": 7232.666640343776, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 115.0, 113.0, 112.0, 114.0, 113.0, 111.0, 114.0, 123.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 111.0, 129.0, 161.0, 105.0, 129.0, 128.0, 108.0, 160.0, 106.0], "value": 128.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 183.0, 185.0, 183.0, 184.0, 186.0, 179.0, 184.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 151.0, 58.0, 58.0, 56.0, 70.0, 140.0, 57.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 135.0, 72.0, 75.0, 86.0, 123.0, 68.0, 72.0, 119.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 89.0, 84.0, 88.0, 84.0, 87.0, 85.0, 87.0, 83.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 112.0, 149.0, 94.0, 93.0, 93.0, 95.0, 94.0, 93.0, 94.0], "value": 101.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 86.0, 91.0, 91.0, 88.0, 89.0, 92.0, 90.0, 91.0], "value": 89.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 52.0, 56.0, 55.0, 55.0, 55.0, 56.0, 54.0, 54.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 74.0, 76.0, 73.0, 71.0, 70.0, 70.0, 70.0, 71.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 85.0, 86.0, 84.0, 87.0, 88.0, 89.0, 89.0, 86.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 141.0, 135.0, 139.0, 143.0, 141.0, 146.0, 143.0, 185.0, 135.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 168.0, 169.0, 171.0, 174.0, 171.0, 169.0, 168.0, 168.0, 168.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 68.0, 67.0, 69.0, 67.0, 67.0, 66.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1472.8999999999999, "alertThreshold": 2.0}]} 14:21:11 INFO - Return code: 0 14:21:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:21:11 INFO - # TBPL SUCCESS # 14:21:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:21:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:21:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:21:11 INFO - 2016-09-01 14:21:11.866 screenresolution[1668:9905] starting screenresolution argv=screenresolution get 14:21:11 INFO - 2016-09-01 14:21:11.869 screenresolution[1668:9905] Display 0: 1600x1200x32@60 14:21:11 INFO - 2016-09-01 14:21:11.875 screenresolution[1669:9908] starting screenresolution argv=screenresolution list 14:21:11 INFO - Available Modes on Display 0 14:21:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:21:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:21:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:21:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:21:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:21:12 INFO - Intel Iris: 14:21:12 INFO - Chipset Model: Intel Iris 14:21:12 INFO - Type: GPU 14:21:12 INFO - Bus: Built-In 14:21:12 INFO - VRAM (Dynamic, Max): 1536 MB 14:21:12 INFO - Vendor: Intel (0x8086) 14:21:12 INFO - Device ID: 0x0a2e 14:21:12 INFO - Revision ID: 0x0009 14:21:12 INFO - Displays: 14:21:12 INFO - Display: 14:21:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:21:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:21:12 INFO - Display Serial Number: 1600x1200 60 14:21:12 INFO - Main Display: Yes 14:21:12 INFO - Mirror: Off 14:21:12 INFO - Online: Yes 14:21:12 INFO - Rotation: Supported 14:21:12 INFO - Return code: 0 14:21:12 INFO - Running post-action listener: _resource_record_post_action 14:21:12 INFO - [mozharness: 2016-09-01 21:21:12.117970Z] Finished run-tests step (success) 14:21:12 INFO - Running post-run listener: _resource_record_post_run 14:21:12 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38351872; Write bytes: 707037184; Read time: 383; Write time: 2078 14:21:12 INFO - TinderboxPrint: CPU usage
53.5% 14:21:12 INFO - TinderboxPrint: I/O read bytes / time
38,351,872 / 383 14:21:12 INFO - TinderboxPrint: I/O write bytes / time
707,037,184 / 2,078 14:21:12 INFO - TinderboxPrint: CPU idle
2,253.5 (46.5%) 14:21:12 INFO - TinderboxPrint: CPU system
965.4 (19.9%) 14:21:12 INFO - TinderboxPrint: CPU user
1,630.9 (33.6%) 14:21:12 INFO - TinderboxPrint: Swap in / out
444,493,824 / 0 14:21:12 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 402117120; Write bytes: 390854656; Read time: 30036; Write time: 660 14:21:12 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31099904; Write bytes: 295594496; Read time: 289; Write time: 1328 14:21:12 INFO - Running post-run listener: _upload_blobber_files 14:21:12 INFO - Blob upload gear active. 14:21:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:21:12 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 14:21:12 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'] 14:21:12 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 14:21:13 INFO - (blobuploader) - INFO - Open directory for files ... 14:21:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:21:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:21:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:14 INFO - (blobuploader) - INFO - Done attempting. 14:21:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:21:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:21:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:15 INFO - (blobuploader) - INFO - Done attempting. 14:21:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:21:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:21:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:17 INFO - (blobuploader) - INFO - Done attempting. 14:21:17 INFO - (blobuploader) - INFO - Iteration through files over. 14:21:17 INFO - Return code: 0 14:21:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:21:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:21:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/73a1b3c85be9573a9420df7af50d15a249d414ad53750867c4ca67d13de3fb2264ab5b81f3f04c9c231c67bc8c4496eb612d6571d1fa6d18f34b424013fa738c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d364633ebef65a9f5aa8300739beca27553d20ce1e7877a0decc97c98787d427c333a8cb3b0e3280f0843fcc609aa678af3f6e63cb5ae36273856835fd312809", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d93b66016412f7cc2470aa7c86eb31642ce8ad86349e30e2ce2c9397ef3df8463dca066bc873f5f8fd561d7b347762ddaf82cecd15890fed8bb512e4999a1035"} 14:21:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:21:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:21:17 INFO - Contents: 14:21:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/73a1b3c85be9573a9420df7af50d15a249d414ad53750867c4ca67d13de3fb2264ab5b81f3f04c9c231c67bc8c4496eb612d6571d1fa6d18f34b424013fa738c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d364633ebef65a9f5aa8300739beca27553d20ce1e7877a0decc97c98787d427c333a8cb3b0e3280f0843fcc609aa678af3f6e63cb5ae36273856835fd312809", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d93b66016412f7cc2470aa7c86eb31642ce8ad86349e30e2ce2c9397ef3df8463dca066bc873f5f8fd561d7b347762ddaf82cecd15890fed8bb512e4999a1035"} 14:21:17 INFO - Running post-run listener: copy_logs_to_upload_dir 14:21:17 INFO - Copying logs to upload dir... 14:21:17 INFO - mkdir: /builds/slave/test/build/upload/logs 14:21:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.126054 ========= master_lag: 1.52 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-09-01 14:21:19.343752) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 14:21:19.347079) ========= 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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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/73a1b3c85be9573a9420df7af50d15a249d414ad53750867c4ca67d13de3fb2264ab5b81f3f04c9c231c67bc8c4496eb612d6571d1fa6d18f34b424013fa738c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d364633ebef65a9f5aa8300739beca27553d20ce1e7877a0decc97c98787d427c333a8cb3b0e3280f0843fcc609aa678af3f6e63cb5ae36273856835fd312809", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d93b66016412f7cc2470aa7c86eb31642ce8ad86349e30e2ce2c9397ef3df8463dca066bc873f5f8fd561d7b347762ddaf82cecd15890fed8bb512e4999a1035"} build_url:https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011161 build_url: 'https://queue.taskcluster.net/v1/task/Or1O4hiXRZajtxWSuAdSQQ/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/73a1b3c85be9573a9420df7af50d15a249d414ad53750867c4ca67d13de3fb2264ab5b81f3f04c9c231c67bc8c4496eb612d6571d1fa6d18f34b424013fa738c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d364633ebef65a9f5aa8300739beca27553d20ce1e7877a0decc97c98787d427c333a8cb3b0e3280f0843fcc609aa678af3f6e63cb5ae36273856835fd312809", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d93b66016412f7cc2470aa7c86eb31642ce8ad86349e30e2ce2c9397ef3df8463dca066bc873f5f8fd561d7b347762ddaf82cecd15890fed8bb512e4999a1035"}' ========= master_lag: 0.39 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 14:21:19.748918) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 14:21:19.749240) ========= 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.4jVDMUuc0n/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.Ua86KsXNyT/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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.004929 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 14:21:19.774690) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 14:21:19.775006) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 14:21:19.775339) ========= ========= Total master_lag: 2.00 =========