builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0122 starttime: 1471495156.74 results: success (0) buildid: 20160817174714 builduid: 67e56ec9b9ce46d6b3f730c0aea653bf revision: 3ebc785598eee4e31ff83b7cb859cb5b9c9147ce ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:16.736109) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:16.736701) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:16.737148) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:16.762522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:16.762978) ========= 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.saYLK3TiXm/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.I09sMGPx3K/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-17 21:39:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-08-17 21:39:17 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.325898 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:17.159223) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:17.159563) ========= 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.saYLK3TiXm/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.I09sMGPx3K/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.021094 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:17.200716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-17 21:39:17.201087) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3ebc785598eee4e31ff83b7cb859cb5b9c9147ce --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 3ebc785598eee4e31ff83b7cb859cb5b9c9147ce --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.saYLK3TiXm/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.I09sMGPx3K/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-17 21:39:17,277 truncating revision to first 12 chars 2016-08-17 21:39:17,278 Setting DEBUG logging. 2016-08-17 21:39:17,278 attempt 1/10 2016-08-17 21:39:17,278 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3ebc785598ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-17 21:39:18,888 unpacking tar archive at: fx-team-3ebc785598ee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.893454 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-17 21:39:19.117719) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:19.118067) ========= script_repo_revision: 3ebc785598eee4e31ff83b7cb859cb5b9c9147ce ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:19.118468) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:19.118765) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 21:39:19.138454) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-17 21:39:19.138765) ========= /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.saYLK3TiXm/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.I09sMGPx3K/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 21:39:19 INFO - MultiFileLogger online at 20160817 21:39:19 in /builds/slave/test 21:39:19 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 21:39:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:39:19 INFO - {'append_to_log': False, 21:39:19 INFO - 'base_work_dir': '/builds/slave/test', 21:39:19 INFO - 'blob_upload_branch': 'Fx-Team', 21:39:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:39:19 INFO - 'branch': 'Fx-Team', 21:39:19 INFO - 'buildbot_json_path': 'buildprops.json', 21:39:19 INFO - 'config_files': ('talos/mac_config.py',), 21:39:19 INFO - 'default_actions': ('clobber', 21:39:19 INFO - 'read-buildbot-config', 21:39:19 INFO - 'download-and-extract', 21:39:19 INFO - 'populate-webroot', 21:39:19 INFO - 'create-virtualenv', 21:39:19 INFO - 'install', 21:39:19 INFO - 'run-tests'), 21:39:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:39:19 INFO - 'download_minidump_stackwalk': True, 21:39:19 INFO - 'download_symbols': 'ondemand', 21:39:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:39:19 INFO - 'tooltool.py': '/tools/tooltool.py', 21:39:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:39:19 INFO - '/tools/misc-python/virtualenv.py')}, 21:39:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:39:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:39:19 INFO - 'installer_path': 'installer.exe', 21:39:19 INFO - 'log_level': 'info', 21:39:19 INFO - 'log_name': 'talos', 21:39:19 INFO - 'log_to_console': True, 21:39:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:39:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:39:19 INFO - 'opt_config_files': (), 21:39:19 INFO - 'pip_index': False, 21:39:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:39:19 INFO - 'cmd': ('bash', 21:39:19 INFO - '-c', 21:39:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:39:19 INFO - 'enabled': True, 21:39:19 INFO - 'halt_on_failure': False, 21:39:19 INFO - 'name': 'check_screen_resolution'},), 21:39:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:39:19 INFO - 'cmd': ('bash', 21:39:19 INFO - '-c', 21:39:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:39:19 INFO - 'enabled': True, 21:39:19 INFO - 'halt_on_failure': False, 21:39:19 INFO - 'name': 'check_screen_resolution'},), 21:39:19 INFO - 'run_cmd_checks_enabled': True, 21:39:19 INFO - 'sps_profile': False, 21:39:19 INFO - 'sps_profile_interval': 0, 21:39:19 INFO - 'suite': 'dromaeojs', 21:39:19 INFO - 'system_bits': '32', 21:39:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:39:19 INFO - 'title': 't-yosemite-r7-0122', 21:39:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:39:19 INFO - 'use_talos_json': True, 21:39:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:39:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:39:19 INFO - 'work_dir': 'build'} 21:39:19 INFO - [mozharness: 2016-08-18 04:39:19.334082Z] Running clobber step. 21:39:19 INFO - Running pre-action listener: _resource_record_pre_action 21:39:19 INFO - Running main action method: clobber 21:39:19 INFO - rmtree: /builds/slave/test/build 21:39:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:39:20 INFO - Running post-action listener: _resource_record_post_action 21:39:20 INFO - [mozharness: 2016-08-18 04:39:20.770056Z] Finished clobber step (success) 21:39:20 INFO - [mozharness: 2016-08-18 04:39:20.770219Z] Running read-buildbot-config step. 21:39:20 INFO - Running pre-action listener: _resource_record_pre_action 21:39:20 INFO - Running main action method: read_buildbot_config 21:39:20 INFO - Using buildbot properties: 21:39:20 INFO - { 21:39:20 INFO - "project": "", 21:39:20 INFO - "product": "firefox", 21:39:20 INFO - "script_repo_revision": "production", 21:39:20 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 21:39:20 INFO - "repository": "", 21:39:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 21:39:20 INFO - "buildid": "20160817174714", 21:39:20 INFO - "pgo_build": "False", 21:39:20 INFO - "basedir": "/builds/slave/test", 21:39:20 INFO - "buildnumber": 915, 21:39:20 INFO - "slavename": "t-yosemite-r7-0122", 21:39:20 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 21:39:20 INFO - "platform": "yosemite_r7", 21:39:20 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 21:39:20 INFO - "branch": "fx-team", 21:39:20 INFO - "repo_path": "integration/fx-team", 21:39:20 INFO - "revision": "3ebc785598eee4e31ff83b7cb859cb5b9c9147ce", 21:39:20 INFO - "stage_platform": "macosx64", 21:39:20 INFO - "builduid": "67e56ec9b9ce46d6b3f730c0aea653bf", 21:39:20 INFO - "slavebuilddir": "test" 21:39:20 INFO - } 21:39:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 21:39:20 INFO - Running post-action listener: _resource_record_post_action 21:39:20 INFO - [mozharness: 2016-08-18 04:39:20.773355Z] Finished read-buildbot-config step (success) 21:39:20 INFO - [mozharness: 2016-08-18 04:39:20.773506Z] Running download-and-extract step. 21:39:20 INFO - Running pre-action listener: _resource_record_pre_action 21:39:20 INFO - Running main action method: download_and_extract 21:39:20 INFO - mkdir: /builds/slave/test/build/tests 21:39:20 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')]} 21:39:20 INFO - trying https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 21:39:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/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 21:39:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/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 21:39:21 INFO - Downloaded 1199 bytes. 21:39:21 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:39:21 INFO - Using the following test package requirements: 21:39:21 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:39:21 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:39:21 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 21:39:21 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:39:21 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 21:39:21 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:39:21 INFO - u'jsshell-mac.zip'], 21:39:21 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:39:21 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 21:39:21 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:39:21 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:39:21 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 21:39:21 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:39:21 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 21:39:21 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:39:21 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 21:39:21 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:39:21 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 21:39:21 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:39:21 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')]} 21:39:21 INFO - trying https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:39:21 INFO - Downloading https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/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 21:39:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/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 21:39:22 INFO - Downloaded 16339509 bytes. 21:39:22 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 21:39:29 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 21:39:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:29 INFO - trying https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:39:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/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 21:39:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/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 21:39:30 INFO - Downloaded 16339509 bytes. 21:39:30 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 21:39:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:36 INFO - trying https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:39:36 INFO - Downloading https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/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 21:39:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/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 21:39:37 INFO - Downloaded 14413981 bytes. 21:39:37 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 21:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:38 INFO - trying https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:39:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 21:39:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/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 21:39:40 INFO - Downloaded 111292824 bytes. 21:39:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:39:40 INFO - mkdir: /builds/slave/test/properties 21:39:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:39:40 INFO - Writing to file /builds/slave/test/properties/build_url 21:39:40 INFO - Contents: 21:39:40 INFO - build_url:https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:39:41 INFO - Running post-action listener: _resource_record_post_action 21:39:41 INFO - Running post-action listener: set_extra_try_arguments 21:39:41 INFO - [mozharness: 2016-08-18 04:39:41.621431Z] Finished download-and-extract step (success) 21:39:41 INFO - [mozharness: 2016-08-18 04:39:41.621593Z] Running populate-webroot step. 21:39:41 INFO - Running pre-action listener: _resource_record_pre_action 21:39:41 INFO - Running main action method: populate_webroot 21:39:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:39:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:39:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:39:41 INFO - '--tppagecycles', 21:39:41 INFO - '7'], 21:39:41 INFO - 'tests': ['tsvgm']}}, 21:39:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:39:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:39:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:39:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:39:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:39:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:39:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:41 INFO - 'talos_options': ['--disable-e10s'], 21:39:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:39:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:39:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:41 INFO - 'talos_options': ['--disable-e10s'], 21:39:41 INFO - 'tests': ['damp', 'tps']}, 21:39:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:41 INFO - 'tests': ['damp', 'tps']}, 21:39:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:39:41 INFO - 'tests': ['dromaeo_dom']}, 21:39:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:39:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:39:41 INFO - 'tests': ['basic_compositor_video']}, 21:39:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:39:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:39:41 INFO - 'tests': ['a11yr', 21:39:41 INFO - 'ts_paint', 21:39:41 INFO - 'tpaint', 21:39:41 INFO - 'sessionrestore', 21:39:41 INFO - 'sessionrestore_no_auto_restore', 21:39:41 INFO - 'tabpaint']}, 21:39:41 INFO - 'other-e10s': {'tests': ['a11yr', 21:39:41 INFO - 'ts_paint', 21:39:41 INFO - 'tpaint', 21:39:41 INFO - 'sessionrestore', 21:39:41 INFO - 'sessionrestore_no_auto_restore', 21:39:41 INFO - 'tabpaint']}, 21:39:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:39:41 INFO - 'ts_paint', 21:39:41 INFO - 'tpaint', 21:39:41 INFO - 'sessionrestore', 21:39:41 INFO - 'sessionrestore_no_auto_restore', 21:39:41 INFO - 'tabpaint']}, 21:39:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:39:41 INFO - 'ts_paint', 21:39:41 INFO - 'tpaint', 21:39:41 INFO - 'sessionrestore', 21:39:41 INFO - 'sessionrestore_no_auto_restore', 21:39:41 INFO - 'tabpaint']}, 21:39:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:39:41 INFO - 'tests': ['a11yr', 21:39:41 INFO - 'ts_paint', 21:39:41 INFO - 'tpaint', 21:39:41 INFO - 'sessionrestore', 21:39:41 INFO - 'sessionrestore_no_auto_restore', 21:39:41 INFO - 'tabpaint']}, 21:39:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:39:41 INFO - 'tests': ['a11yr', 21:39:41 INFO - 'ts_paint', 21:39:41 INFO - 'tpaint', 21:39:41 INFO - 'sessionrestore', 21:39:41 INFO - 'sessionrestore_no_auto_restore', 21:39:41 INFO - 'tabpaint']}, 21:39:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:39:41 INFO - 'tests': ['tsvgx', 21:39:41 INFO - 'tsvgr_opacity', 21:39:41 INFO - 'tart', 21:39:41 INFO - 'tscrollx', 21:39:41 INFO - 'cart']}, 21:39:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:39:41 INFO - 'tsvgr_opacity', 21:39:41 INFO - 'tart', 21:39:41 INFO - 'tscrollx', 21:39:41 INFO - 'cart']}, 21:39:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:41 INFO - 'talos_options': ['--disable-e10s'], 21:39:41 INFO - 'tests': ['tp5o']}, 21:39:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:41 INFO - 'tests': ['tp5o']}, 21:39:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:41 INFO - 'talos_options': ['--disable-e10s', 21:39:41 INFO - '--xperf_path', 21:39:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:39:41 INFO - 'tests': ['tp5n']}, 21:39:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:41 INFO - 'talos_options': ['--xperf_path', 21:39:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:39:41 INFO - 'tests': ['tp5n']}}, 21:39:41 INFO - 'talos.zip': {'path': '', 21:39:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:39:41 INFO - Running post-action listener: _resource_record_post_action 21:39:41 INFO - [mozharness: 2016-08-18 04:39:41.640281Z] Finished populate-webroot step (success) 21:39:41 INFO - [mozharness: 2016-08-18 04:39:41.640415Z] Running create-virtualenv step. 21:39:41 INFO - Running pre-action listener: _resource_record_pre_action 21:39:41 INFO - Running main action method: create_virtualenv 21:39:41 INFO - Creating virtualenv /builds/slave/test/build/venv 21:39:41 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 21:39:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:39:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:39:41 INFO - Using real prefix '/tools/python27' 21:39:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:39:42 INFO - Installing distribute.............................................................................................................................................................................................done. 21:39:45 INFO - Installing pip.................done. 21:39:45 INFO - Return code: 0 21:39:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:39:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:45 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 0x109c3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cd1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc08c84940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0d30>, '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 0x109905db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b98b20>, '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.saYLK3TiXm/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.I09sMGPx3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 21:39:45 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 21:39:45 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 21:39:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.saYLK3TiXm/Render', 21:39:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:45 INFO - 'HOME': '/Users/cltbld', 21:39:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:45 INFO - 'LOGNAME': 'cltbld', 21:39:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:45 INFO - 'MOZ_NO_REMOTE': '1', 21:39:45 INFO - 'NO_EM_RESTART': '1', 21:39:45 INFO - 'PAGER': '/bin/cat', 21:39:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:45 INFO - 'PWD': '/builds/slave/test', 21:39:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:45 INFO - 'SHELL': '/bin/bash', 21:39:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I09sMGPx3K/Listeners', 21:39:45 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 21:39:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:45 INFO - 'USER': 'cltbld', 21:39:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:45 INFO - 'XPC_FLAGS': '0x0', 21:39:45 INFO - 'XPC_SERVICE_NAME': '0', 21:39:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:45 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 21:39:45 INFO - Cleaning up... 21:39:45 INFO - Return code: 0 21:39:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:39:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:45 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 0x109c3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cd1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc08c84940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0d30>, '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 0x109905db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b98b20>, '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.saYLK3TiXm/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.I09sMGPx3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 21:39:45 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 21:39:45 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 21:39:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.saYLK3TiXm/Render', 21:39:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:45 INFO - 'HOME': '/Users/cltbld', 21:39:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:45 INFO - 'LOGNAME': 'cltbld', 21:39:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:45 INFO - 'MOZ_NO_REMOTE': '1', 21:39:45 INFO - 'NO_EM_RESTART': '1', 21:39:45 INFO - 'PAGER': '/bin/cat', 21:39:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:45 INFO - 'PWD': '/builds/slave/test', 21:39:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:45 INFO - 'SHELL': '/bin/bash', 21:39:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I09sMGPx3K/Listeners', 21:39:45 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 21:39:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:45 INFO - 'USER': 'cltbld', 21:39:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:45 INFO - 'XPC_FLAGS': '0x0', 21:39:45 INFO - 'XPC_SERVICE_NAME': '0', 21:39:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:45 INFO - Downloading/unpacking psutil>=3.1.1 21:39:45 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 21:39:45 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 21:39:45 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 21:39:45 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 21:39:45 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 21:39:45 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 21:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:39:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:39:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:39:49 INFO - Installing collected packages: psutil 21:39:49 INFO - Running setup.py install for psutil 21:39:49 INFO - building 'psutil._psutil_osx' extension 21:39:49 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 21:39:49 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 21:39:49 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 21:39:49 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 21:39:49 INFO - building 'psutil._psutil_posix' extension 21:39:49 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 21:39:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:39:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:39:49 INFO - ^ 21:39:49 INFO - 1 warning generated. 21:39:49 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 21:39:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:39:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:39:50 INFO - Successfully installed psutil 21:39:50 INFO - Cleaning up... 21:39:50 INFO - Return code: 0 21:39:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:39:50 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')]} 21:39:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:50 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')]} 21:39:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:50 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 0x109c3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cd1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc08c84940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0d30>, '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 0x109905db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b98b20>, '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.saYLK3TiXm/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.I09sMGPx3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 21:39:50 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 21:39:50 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 21:39:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.saYLK3TiXm/Render', 21:39:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:50 INFO - 'HOME': '/Users/cltbld', 21:39:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:50 INFO - 'LOGNAME': 'cltbld', 21:39:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:50 INFO - 'MOZ_NO_REMOTE': '1', 21:39:50 INFO - 'NO_EM_RESTART': '1', 21:39:50 INFO - 'PAGER': '/bin/cat', 21:39:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:50 INFO - 'PWD': '/builds/slave/test', 21:39:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:50 INFO - 'SHELL': '/bin/bash', 21:39:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I09sMGPx3K/Listeners', 21:39:50 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 21:39:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:50 INFO - 'USER': 'cltbld', 21:39:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:50 INFO - 'XPC_FLAGS': '0x0', 21:39:50 INFO - 'XPC_SERVICE_NAME': '0', 21:39:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:39:50 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 21:39:50 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 21:39:50 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 21:39:50 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 21:39:50 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 21:39:50 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 21:39:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:39:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:39:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:39:52 INFO - Installing collected packages: mozsystemmonitor 21:39:52 INFO - Running setup.py install for mozsystemmonitor 21:39:53 INFO - Successfully installed mozsystemmonitor 21:39:53 INFO - Cleaning up... 21:39:53 INFO - Return code: 0 21:39:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:39: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')]} 21:39:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39: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')]} 21:39:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:53 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 0x109c3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cd1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc08c84940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0d30>, '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 0x109905db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b98b20>, '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.saYLK3TiXm/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.I09sMGPx3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 21:39:53 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 21:39:53 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 21:39:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.saYLK3TiXm/Render', 21:39:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:53 INFO - 'HOME': '/Users/cltbld', 21:39:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:53 INFO - 'LOGNAME': 'cltbld', 21:39:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:53 INFO - 'MOZ_NO_REMOTE': '1', 21:39:53 INFO - 'NO_EM_RESTART': '1', 21:39:53 INFO - 'PAGER': '/bin/cat', 21:39:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:53 INFO - 'PWD': '/builds/slave/test', 21:39:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:53 INFO - 'SHELL': '/bin/bash', 21:39:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I09sMGPx3K/Listeners', 21:39:53 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 21:39:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:53 INFO - 'USER': 'cltbld', 21:39:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:53 INFO - 'XPC_FLAGS': '0x0', 21:39:53 INFO - 'XPC_SERVICE_NAME': '0', 21:39:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:53 INFO - Downloading/unpacking jsonschema==2.5.1 21:39:53 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 21:39:53 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 21:39:53 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 21:39:53 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 21:39:53 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 21:39:53 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 21:39:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:39:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:39: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 21:39: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 21:39: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 21:39: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 21:39: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 21:39: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 21:39:56 INFO - Downloading functools32-3.2.3-2.tar.gz 21:39:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:39:56 INFO - warning: no files found matching '*.txt' 21:39:56 INFO - no previously-included directories found matching 'build' 21:39:56 INFO - no previously-included directories found matching 'dist' 21:39:56 INFO - no previously-included directories found matching '.git*' 21:39:56 INFO - Installing collected packages: jsonschema, functools32 21:39:56 INFO - Running setup.py install for functools32 21:39:56 INFO - warning: no files found matching '*.txt' 21:39:56 INFO - no previously-included directories found matching 'build' 21:39:56 INFO - no previously-included directories found matching 'dist' 21:39:56 INFO - no previously-included directories found matching '.git*' 21:39:56 INFO - Successfully installed jsonschema functools32 21:39:56 INFO - Cleaning up... 21:39:56 INFO - Return code: 0 21:39:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:56 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 0x109c3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cd1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc08c84940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0d30>, '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 0x109905db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b98b20>, '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.saYLK3TiXm/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.I09sMGPx3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 21:39:56 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 21:39:56 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 21:39:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.saYLK3TiXm/Render', 21:39:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:56 INFO - 'HOME': '/Users/cltbld', 21:39:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:56 INFO - 'LOGNAME': 'cltbld', 21:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:56 INFO - 'MOZ_NO_REMOTE': '1', 21:39:56 INFO - 'NO_EM_RESTART': '1', 21:39:56 INFO - 'PAGER': '/bin/cat', 21:39:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:56 INFO - 'PWD': '/builds/slave/test', 21:39:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:56 INFO - 'SHELL': '/bin/bash', 21:39:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I09sMGPx3K/Listeners', 21:39:56 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 21:39:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:56 INFO - 'USER': 'cltbld', 21:39:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:56 INFO - 'XPC_FLAGS': '0x0', 21:39:56 INFO - 'XPC_SERVICE_NAME': '0', 21:39:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:39:56 INFO - Cleaning up... 21:39:56 INFO - Return code: 0 21:39:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:56 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 0x109c3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cd1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc08c84940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0d30>, '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 0x109905db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b98b20>, '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.saYLK3TiXm/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.I09sMGPx3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 21:39:56 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 21:39:56 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 21:39:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.saYLK3TiXm/Render', 21:39:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:56 INFO - 'HOME': '/Users/cltbld', 21:39:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:56 INFO - 'LOGNAME': 'cltbld', 21:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:56 INFO - 'MOZ_NO_REMOTE': '1', 21:39:56 INFO - 'NO_EM_RESTART': '1', 21:39:56 INFO - 'PAGER': '/bin/cat', 21:39:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:56 INFO - 'PWD': '/builds/slave/test', 21:39:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:56 INFO - 'SHELL': '/bin/bash', 21:39:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I09sMGPx3K/Listeners', 21:39:56 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 21:39:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:56 INFO - 'USER': 'cltbld', 21:39:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:56 INFO - 'XPC_FLAGS': '0x0', 21:39:56 INFO - 'XPC_SERVICE_NAME': '0', 21:39:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:57 INFO - Downloading/unpacking blobuploader==1.2.4 21:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:59 INFO - Downloading blobuploader-1.2.4.tar.gz 21:39:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:39:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:40:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:40:00 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 21:40:00 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 21:40:00 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 21:40:00 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 21:40:00 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 21:40:00 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 21:40:00 INFO - Downloading docopt-0.6.1.tar.gz 21:40:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:40:00 INFO - Installing collected packages: blobuploader, requests, docopt 21:40:00 INFO - Running setup.py install for blobuploader 21:40:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:40:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:40:01 INFO - Running setup.py install for requests 21:40:01 INFO - Running setup.py install for docopt 21:40:01 INFO - Successfully installed blobuploader requests docopt 21:40:01 INFO - Cleaning up... 21:40:01 INFO - Return code: 0 21:40:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:40:01 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')]} 21:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:01 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')]} 21:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:01 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 0x109c3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cd1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc08c84940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0d30>, '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 0x109905db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b98b20>, '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.saYLK3TiXm/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.I09sMGPx3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 21:40:01 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 21:40:01 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 21:40:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.saYLK3TiXm/Render', 21:40:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:01 INFO - 'HOME': '/Users/cltbld', 21:40:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:01 INFO - 'LOGNAME': 'cltbld', 21:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:01 INFO - 'MOZ_NO_REMOTE': '1', 21:40:01 INFO - 'NO_EM_RESTART': '1', 21:40:01 INFO - 'PAGER': '/bin/cat', 21:40:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:01 INFO - 'PWD': '/builds/slave/test', 21:40:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:01 INFO - 'SHELL': '/bin/bash', 21:40:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I09sMGPx3K/Listeners', 21:40:01 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 21:40:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:01 INFO - 'USER': 'cltbld', 21:40:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:01 INFO - 'XPC_FLAGS': '0x0', 21:40:01 INFO - 'XPC_SERVICE_NAME': '0', 21:40:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:40:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-XmvIKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:40:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-PmDtg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:40:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-fTWV0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:40:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-RacBcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:40:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-PLBpBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:40:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-AFPxGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:40:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-8QoYqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:40:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-f21IB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:40:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-4Zy4s6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:40:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-qgPCOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:40:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-patf_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:40:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-WohjXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:40:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-oIS2di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:40:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-BdVwHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-qqyWp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:40:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-vGPeah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:40:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-zfTuKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:40:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:40:03 INFO - Running setup.py install for manifestparser 21:40:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:40:04 INFO - Running setup.py install for mozcrash 21:40:04 INFO - Running setup.py install for mozdebug 21:40:04 INFO - Running setup.py install for mozdevice 21:40:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:40:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:40:04 INFO - Running setup.py install for mozfile 21:40:04 INFO - Running setup.py install for mozhttpd 21:40:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:40:04 INFO - Running setup.py install for mozinfo 21:40:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:40:04 INFO - Running setup.py install for mozInstall 21:40:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:40:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:40:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:40:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:40:05 INFO - Running setup.py install for mozleak 21:40:05 INFO - Running setup.py install for mozlog 21:40:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:40:05 INFO - Running setup.py install for moznetwork 21:40:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:40:05 INFO - Running setup.py install for mozprocess 21:40:05 INFO - Running setup.py install for mozprofile 21:40:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:40:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:40:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:40:05 INFO - Running setup.py install for mozrunner 21:40:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:40:05 INFO - Running setup.py install for mozscreenshot 21:40:06 INFO - Running setup.py install for moztest 21:40:06 INFO - Running setup.py install for mozversion 21:40:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:40:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:40:06 INFO - Cleaning up... 21:40:06 INFO - Return code: 0 21:40:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:06 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 0x109c3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cd1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc08c84940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0d30>, '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 0x109905db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b98b20>, '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.saYLK3TiXm/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.I09sMGPx3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 21:40:06 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 21:40:06 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 21:40:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.saYLK3TiXm/Render', 21:40:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:06 INFO - 'HOME': '/Users/cltbld', 21:40:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:06 INFO - 'LOGNAME': 'cltbld', 21:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:06 INFO - 'MOZ_NO_REMOTE': '1', 21:40:06 INFO - 'NO_EM_RESTART': '1', 21:40:06 INFO - 'PAGER': '/bin/cat', 21:40:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:06 INFO - 'PWD': '/builds/slave/test', 21:40:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:06 INFO - 'SHELL': '/bin/bash', 21:40:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I09sMGPx3K/Listeners', 21:40:06 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 21:40:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:06 INFO - 'USER': 'cltbld', 21:40:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:06 INFO - 'XPC_FLAGS': '0x0', 21:40:06 INFO - 'XPC_SERVICE_NAME': '0', 21:40:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:40:06 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-UUs1JU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:40:06 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)) 21:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:40:06 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ilFboT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:40:06 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)) 21:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:40:06 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-yZ5buG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:40:07 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)) 21:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:40:07 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-WCOR7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:40:07 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)) 21:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:40:07 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-QTT3vd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:40:07 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)) 21:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:40:07 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-BSa2OY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:40:07 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)) 21:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:40:07 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-t8XIbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:40:07 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)) 21:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:40:07 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-kDtX6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:40:07 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)) 21:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:40:07 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-jhVv04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:40:07 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)) 21:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:40:07 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-uS5DJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:40:07 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)) 21:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:40:07 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-YkadVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:40:07 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)) 21:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:40:07 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-hn0awH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:40:07 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)) 21:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:40:07 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-HfuRfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:40:08 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-UNLoz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:08 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-RxJbAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:40:08 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Ggn7fI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:40:08 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-mILxC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:40:08 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)) 21:40:08 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)) 21:40:08 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)) 21:40:08 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)) 21:40:08 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)) 21:40:08 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)) 21:40:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:40:10 INFO - Downloading blessings-1.6.tar.gz 21:40:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:40:11 INFO - Installing collected packages: blessings 21:40:11 INFO - Running setup.py install for blessings 21:40:11 INFO - Successfully installed blessings 21:40:11 INFO - Cleaning up... 21:40:11 INFO - Return code: 0 21:40:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:40:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:40:11 INFO - Reading from file tmpfile_stdout 21:40:11 INFO - Current package versions: 21:40:11 INFO - blessings == 1.6 21:40:11 INFO - blobuploader == 1.2.4 21:40:11 INFO - docopt == 0.6.1 21:40:11 INFO - functools32 == 3.2.3-2 21:40:11 INFO - jsonschema == 2.5.1 21:40:11 INFO - manifestparser == 1.1 21:40:11 INFO - mozInstall == 1.12 21:40:11 INFO - mozcrash == 0.17 21:40:11 INFO - mozdebug == 0.1 21:40:11 INFO - mozdevice == 0.48 21:40:11 INFO - mozfile == 1.2 21:40:11 INFO - mozhttpd == 0.7 21:40:11 INFO - mozinfo == 0.9 21:40:11 INFO - mozleak == 0.1 21:40:11 INFO - mozlog == 3.3 21:40:11 INFO - moznetwork == 0.27 21:40:11 INFO - mozprocess == 0.23 21:40:11 INFO - mozprofile == 0.28 21:40:11 INFO - mozrunner == 6.12 21:40:11 INFO - mozscreenshot == 0.1 21:40:11 INFO - mozsystemmonitor == 0.3 21:40:11 INFO - moztest == 0.7 21:40:11 INFO - mozversion == 1.4 21:40:11 INFO - psutil == 3.1.1 21:40:11 INFO - requests == 1.2.3 21:40:11 INFO - wsgiref == 0.1.2 21:40:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:11 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 0x109c3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cd1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc08c84940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0d30>, '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 0x109905db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b98b20>, '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.saYLK3TiXm/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.I09sMGPx3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 21:40:11 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 21:40:11 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 21:40:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.saYLK3TiXm/Render', 21:40:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:11 INFO - 'HOME': '/Users/cltbld', 21:40:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:11 INFO - 'LOGNAME': 'cltbld', 21:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:11 INFO - 'MOZ_NO_REMOTE': '1', 21:40:11 INFO - 'NO_EM_RESTART': '1', 21:40:11 INFO - 'PAGER': '/bin/cat', 21:40:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:11 INFO - 'PWD': '/builds/slave/test', 21:40:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:11 INFO - 'SHELL': '/bin/bash', 21:40:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I09sMGPx3K/Listeners', 21:40:11 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 21:40:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:11 INFO - 'USER': 'cltbld', 21:40:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:11 INFO - 'XPC_FLAGS': '0x0', 21:40:11 INFO - 'XPC_SERVICE_NAME': '0', 21:40:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 INFO - Cleaning up... 21:40:11 INFO - Return code: 0 21:40:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109c3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cd1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc08c84940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba0d30>, '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 0x109905db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b98b20>, '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.saYLK3TiXm/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.I09sMGPx3K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 21:40:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:40:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.saYLK3TiXm/Render', 21:40:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:11 INFO - 'HOME': '/Users/cltbld', 21:40:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:11 INFO - 'LOGNAME': 'cltbld', 21:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:11 INFO - 'MOZ_NO_REMOTE': '1', 21:40:11 INFO - 'NO_EM_RESTART': '1', 21:40:11 INFO - 'PAGER': '/bin/cat', 21:40:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:11 INFO - 'PWD': '/builds/slave/test', 21:40:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:11 INFO - 'SHELL': '/bin/bash', 21:40:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I09sMGPx3K/Listeners', 21:40:11 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 21:40:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:11 INFO - 'USER': 'cltbld', 21:40:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:11 INFO - 'XPC_FLAGS': '0x0', 21:40:11 INFO - 'XPC_SERVICE_NAME': '0', 21:40:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:40:12 INFO - Cleaning up... 21:40:12 INFO - Return code: 0 21:40:12 INFO - Running post-action listener: _resource_record_post_action 21:40:12 INFO - Running post-action listener: _start_resource_monitoring 21:40:12 INFO - Starting resource monitoring. 21:40:12 INFO - [mozharness: 2016-08-18 04:40:12.117217Z] Finished create-virtualenv step (success) 21:40:12 INFO - [mozharness: 2016-08-18 04:40:12.118168Z] Running install step. 21:40:12 INFO - Running pre-action listener: _resource_record_pre_action 21:40:12 INFO - Running main action method: install 21:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:40:12 INFO - Reading from file tmpfile_stdout 21:40:12 INFO - Detecting whether we're running mozinstall >=1.0... 21:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:40:12 INFO - Reading from file tmpfile_stdout 21:40:12 INFO - Output received: 21:40:12 INFO - Usage: mozinstall [options] installer 21:40:12 INFO - Options: 21:40:12 INFO - -h, --help show this help message and exit 21:40:12 INFO - -d DEST, --destination=DEST 21:40:12 INFO - Directory to install application into. [default: 21:40:12 INFO - "/builds/slave/test"] 21:40:12 INFO - --app=APP Application being installed. [default: firefox] 21:40:12 INFO - mkdir: /builds/slave/test/build/application 21:40:12 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'] 21:40:12 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 21:40:46 INFO - Reading from file tmpfile_stdout 21:40:46 INFO - Output received: 21:40:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:40:46 INFO - Running post-action listener: _resource_record_post_action 21:40:46 INFO - [mozharness: 2016-08-18 04:40:46.579492Z] Finished install step (success) 21:40:46 INFO - [mozharness: 2016-08-18 04:40:46.579643Z] Running run-tests step. 21:40:46 INFO - Running pre-action listener: _resource_record_pre_action 21:40:46 INFO - Running main action method: run_tests 21:40:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:40:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:40:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:40:46 INFO - 2016-08-17 21:40:46.594 screenresolution[1909:9395] starting screenresolution argv=screenresolution get 21:40:46 INFO - 2016-08-17 21:40:46.597 screenresolution[1909:9395] Display 0: 1600x1200x32@60 21:40:46 INFO - 2016-08-17 21:40:46.603 screenresolution[1910:9397] starting screenresolution argv=screenresolution list 21:40:46 INFO - Available Modes on Display 0 21:40:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:40:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:40:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:40:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:40:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:40:46 INFO - Intel Iris: 21:40:46 INFO - Chipset Model: Intel Iris 21:40:46 INFO - Type: GPU 21:40:46 INFO - Bus: Built-In 21:40:46 INFO - VRAM (Dynamic, Max): 1536 MB 21:40:46 INFO - Vendor: Intel (0x8086) 21:40:46 INFO - Device ID: 0x0a2e 21:40:46 INFO - Revision ID: 0x0009 21:40:46 INFO - Displays: 21:40:46 INFO - Display: 21:40:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:40:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:40:46 INFO - Display Serial Number: 1600x1200 60 21:40:46 INFO - Main Display: Yes 21:40:46 INFO - Mirror: Off 21:40:46 INFO - Online: Yes 21:40:46 INFO - Rotation: Supported 21:40:46 INFO - Return code: 0 21:40:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:40:46 INFO - Python 2.7.3 21:40:46 INFO - Return code: 0 21:40:46 INFO - grabbing minidump binary from tooltool 21:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:40:46 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 0x109ba0d30>, '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 0x109905db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b98b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:40:46 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 21:40:46 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 21:40:46 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 21:40:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:40:46 INFO - Return code: 0 21:40:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:40:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:40:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:40:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:40:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:40:46 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/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--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 21:40:46 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/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0122 --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 21:40:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.saYLK3TiXm/Render', 21:40:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:40:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:40:46 INFO - 'HOME': '/Users/cltbld', 21:40:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:40:46 INFO - 'LOGNAME': 'cltbld', 21:40:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:40:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:46 INFO - 'MOZ_NO_REMOTE': '1', 21:40:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:40:46 INFO - 'NO_EM_RESTART': '1', 21:40:46 INFO - 'PAGER': '/bin/cat', 21:40:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:46 INFO - 'PWD': '/builds/slave/test', 21:40:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:40:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:40:46 INFO - 'SHELL': '/bin/bash', 21:40:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I09sMGPx3K/Listeners', 21:40:46 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 21:40:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:40:46 INFO - 'USER': 'cltbld', 21:40:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:40:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:46 INFO - 'XPC_FLAGS': '0x0', 21:40:46 INFO - 'XPC_SERVICE_NAME': '0', 21:40:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:40:46 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/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--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 21:40:47 INFO - mozversion application_buildid: 20160817174714 21:40:47 INFO - mozversion application_changeset: 3ebc785598eee4e31ff83b7cb859cb5b9c9147ce 21:40:47 INFO - mozversion application_display_name: Nightly 21:40:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:40:47 INFO - mozversion application_name: Firefox 21:40:47 INFO - mozversion application_remotingname: firefox 21:40:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:40:47 INFO - mozversion application_vendor: Mozilla 21:40:47 INFO - mozversion application_version: 51.0a1 21:40:47 INFO - mozversion platform_buildid: 20160817174714 21:40:47 INFO - mozversion platform_changeset: 3ebc785598eee4e31ff83b7cb859cb5b9c9147ce 21:40:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:40:47 INFO - mozversion platform_version: 51.0a1 21:40:47 INFO - using testdate: 1471495247 21:40:47 INFO - actual date: 1471495247 21:40:47 INFO - starting webserver on 'localhost:49890' 21:40:47 INFO - SUITE-START | Running 2 tests 21:40:47 INFO - TEST-START | dromaeo_css 21:40:47 INFO - operating with platform_type : mac_ 21:40:47 INFO - Initialising browser for dromaeo_css test... 21:40:47 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmplZz9O8/profile http://localhost:49890/getInfo.html) 21:40:49 INFO - PROCESS | 1920 | __metrics Screen width/height:1600/1200 21:40:49 INFO - PROCESS | 1920 | colorDepth:24 21:40:49 INFO - PROCESS | 1920 | Browser inner width/height: 1024/689 21:40:49 INFO - PROCESS | 1920 | __metrics 21:40:49 INFO - PROCESS | 1920 | 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" 21:40:49 INFO - PROCESS | 1920 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:40:50 INFO - TEST-INFO | 1920: exit 0 21:40:50 INFO - Browser initialized. 21:40:50 INFO - Running cycle 1/1 for dromaeo_css test... 21:40:50 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmplZz9O8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:41:02 INFO - PROCESS | 1922 | RSS: Main: 227958784 21:41:02 INFO - PROCESS | 1922 | 21:41:54 INFO - PROCESS | 1922 | 1471495314838 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] 21:44:06 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49890/tests/dromaeo/cssquery-ext.html) 21:44:07 INFO - PROCESS | 1922 | RSS: Main: 372768768 21:44:07 INFO - PROCESS | 1922 | 21:47:10 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-ext.html (next: http://localhost:49890/tests/dromaeo/cssquery-jquery.html) 21:47:11 INFO - PROCESS | 1922 | RSS: Main: 368214016 21:47:11 INFO - PROCESS | 1922 | 21:50:13 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49890/tests/dromaeo/cssquery-mootools.html) 21:50:14 INFO - PROCESS | 1922 | RSS: Main: 343887872 21:50:14 INFO - PROCESS | 1922 | 21:53:16 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49890/tests/dromaeo/cssquery-prototype.html) 21:53:17 INFO - PROCESS | 1922 | RSS: Main: 336998400 21:53:17 INFO - PROCESS | 1922 | 21:56:21 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49890/tests/dromaeo/cssquery-yui.html) 21:56:21 INFO - PROCESS | 1922 | RSS: Main: 340185088 21:56:21 INFO - PROCESS | 1922 | 21:59:25 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-yui.html (next: http://localhost:49890/tests/dromaeo/cssquery-dojo.html) 21:59:25 INFO - PROCESS | 1922 | RSS: Main: 507629568 21:59:25 INFO - PROCESS | 1922 | 21:59:25 INFO - PROCESS | 1922 | __start_tp_report 21:59:25 INFO - PROCESS | 1922 | _x_x_mozilla_page_load 21:59:25 INFO - PROCESS | 1922 | _x_x_mozilla_page_load_details 21:59:25 INFO - PROCESS | 1922 | |i|pagename|runs| 21:59:25 INFO - PROCESS | 1922 | |0;dojo.html;2846.5018621741197;3327.0351598036555;3351.731861451084;3354.9161270968216;3364.276680513689;5502.642328248661;5619.719014049301;5621.80323688671;5676.460736230057;5693.629914055584;5619.297587801525;5634.211210430532;5722.313322401307;5729.828105156842;5774.6535207887555;4307.504636966742;5142.10013247682;5200.6099542534275;5230.450802665722;5252.71110088945;5284.2866213061325;5315.255864179018;5336.119540275849;5339.652922560022;5353.250544923714;5118.07874582575;5165.147750621143;5173.948260517384;5179.430262671103;5203.4796520348045;5123.2827404163445;5132.255822905695;5161.69029858206;5222.8694282642855;5237.921431178535;5159.896802063975;5169.121249387613;5170.327857378555;5183.403908550527;5225.712585807779;5667.319921609434;5699.458551437607;5714.51426628737;5727.541796656257;5760.567957403212;5099;6037.516998640098;6190.752369905198;6206.410391012847;6260.467860231885;4996.525330093636;5193.584513238932;6292.811215663537;6316;6323.241211054657;5633.915491267552;5814.360420353758;5830.950428922834;5834.241548598655;5860.3260625027815;6252.718627661766;6253.624782513064;6317.020861766342;6330.683465826783;6355.809325720235;6101.206843110456;6155.538334624921;6155.6614386209185;6160.537959652955;6173.783917562864;6258;6265.686715664198;6278.748850046039;6279.309275979638;6289.245290565162;5979.581429299994;6048.637081775108;6055.939440605625;6102.3983636914;6112.989854784201;6132.110843927606;6141.977005061692;6148.1392605035335;6149.985252433298;6198.907016394758;4996.975015124901;5001.274815151783;5015.1975683890405;5038.445770965191;5062.367204099488;4417.757023363746;4484.1621304247865;4492.9775351123035;4533.705309154894;4548.5906268436;5679.403662615448;5733.082706766897;5760;5793.391693872167;5794.333651630032;6255.40573645503;6338.366163383717;6338.461230795342;6338.809835704937;6342.841428964312;5760.3951585083805;5766.826995190151;5792.420757924174;5815.505682017076;5861.70691465425;5320.787168513293;5380.381256155514;5461.42655021225;5462.071447853875;5476.17857321405;6264.029075493306;6331.3352098029045;6336.904946425715;6337.7781777637565;6340.587861788969;6284.811455656338;6309.3690630936535;6313.242410910537;6316.305206427382;6323.272823625342;6185.443310102112;6321.652309122858;6342.746290148527;6352.142460767737;6353.872922541431;6099.4815440688035;6136.6011209271255;6144.262688477412;6169.198004259418;6180.598261113013;6054.152418661479;6266.655333956493;6270.2475702915945;6294.307626161027;6297.748090076346;5866.50134738552;6106.6336019838955;6131.540134489842;6141.263048434216;6160.322364539987;5941.257342832146;6011.609245399035;6106.358832322542;6106.969465152646;6149;5967.6121052131475;5990.31111422192;6015.428534289235;6021;6024.0662697282;6120.510359171167;6133.509319254538;6135.355787642234;6150;6160.507159427146;5723.170140329712;5745.2818397700285;5827;5851.21008663842;5894.6757928314355 21:59:25 INFO - PROCESS | 1922 | |1;ext.html;434.8478032688561;469.2328043648634;492.80534188995347;511.21273239211615;518.7406296851573;31265.062048138534;41618;42661.93345166369;42817.571824281746;44546;43275.78362108193;43690;43719.56280437195;43720;43751.56248437515;8888.688895888645;9787.559559819825;9982.600695972153;9992.45041522716;10032.347897386682;8862.778430539256;12006.399680016008;12290.324032178227;12295.323757193351;12552.81170782439;12358.62924112278;12416.689582760459;12429.689257768583;12438.626841194779;12448.128630995834;12173;12231.449584768707;12258.570950016796;12267.631971040882;12287.078469114853;10219.875813660567;12048;12124.575639852652;12142.696432589257;12144.967677747401;41221.58778412207;41550.79224603888;41575.79212103951;41634.79182604067;41689;2894.406646637455;3616.692581130607;3616.8372423240753;3692.4276737105793;4143.02638879863;31182.22044448907;32310.353792924245;32574;33056.83471582627;33089.172770680685;3291.358185153896;3378.0879162626197;3421.640727723603;3455.671711187433;3464.7055000325004;455784;459800;461228;461323;465154.461870738;57866;58032;58035.419645803;58210.8357832841;58224;62959.370406296264;63041;63117.368826312064;63136.68431657904;63164;16552.586185345222;16782.244798984077;16793.076380798928;16799.664006720042;16817.66364672724;11638.6508404748;11913.821292680617;12803.551875684307;13052.869471305356;13084.953203743678;13480;16736.414225502293;16801.327946882233;16947.91526042362;17032.829671703123;6027.698615069229;7026.1217347831525;7105.335499187805;7143.607101609357;7216.134063912364;902.4449963272672;1035.6427032673716;1040.5317607076845;1073.6295977887617;1106.1040557148735;30467.69532304693;30583;30584.847075764923;30892.845535772627;31671;1057.962971296001;1089.330062011857;1103.8675358956816;1107.2692023264606;1192.1535709646248;15393.615159621098;15963.680726385408;15972;15972.440964566184;16801.831981680025;617544;620185;621289;621535;622374;59763.701181493816;59794.70102649546;59961.605375246625;60153;60432.395676043554;1249.244207254616;1395.762720337545;1411.9339898376866;1427.336288625794;1445.8554144585457;30024.849875750482;30602.84698576493;32186.517202242452;32348.83825580857;33020.33959320848;49833.50166498434;50259.994800103064;50337.99324013426;50398.49601503938;50554.988900222525;477285;478468;478971;482255.5887220682;484394;26370.604440933785;27037.59443608308;27462.5880611787;27668.723312766615;27898.860505697343;48514.51485485241;48618.27072593843;49090.50909490859;49529;49589;34775;35973.82013089918;39667.603323967545;39837;40978.79510602428;39563;40126.59873401345;40558.18883622252;40580;41871.79064104782;38102.2379552413;38831;39305.41041884431;39352;39448.605513945644;33910;34974;35920.742774002785;35955.64044359523;36013.63986360103;25581.976720930954;25818.83815228357;25896.61155082713;26497.470050598495;26678.733212667627 21:59:25 INFO - PROCESS | 1922 | |2;jquery.html;292.65466749235895;324.90252924122757;355.2699203137551;368.4859620828944;404.2299419605652;15704.371825126995;18562.62874742506;21142.57714845702;21172.89413552932;21810.89094554529;11919.106067044984;14268.643283917907;20065.89637569933;20694.965251737427;20873.582528349463;5220.8694782630455;5430.782768689268;5448.291722076125;5452.154915988009;5465.180222966567;5191.584673226152;5373.650712703681;5416.160495123242;5426.023315803154;5429.0227759003365;5406.513413792758;5419.241306217133;5421.728913554326;5423.566114710833;5506.559475241971;5403.459654034605;5571.4428557144365;5867.031939729982;5922.31893332268;5936.22829032223;5284.445133260991;5286.735663216824;5287.365516138049;5289.709066001406;5289.920651190197;13389;13596.932015339959;23317.883410583006;24645.63031554528;25668;2174.152080688517;2292.518571100071;2425.672534207895;2508.3102146909564;2927.355981684026;8374.916250837474;10133.746656333651;10252.38485690861;10403.907589703123;10449.84325235122;1911.722800193978;1922.5385907382267;1946.4160751774411;1962.3622322745166;2026.2806703620297;69822.30177698209;90990;95648;118907;124122;10775.030247277782;11109;11323.263987840764;11619.883801161881;11739;14389.28053597316;14406.42374305037;14414.783778243334;14423.711525769635;14430;8465.153484651486;8496.872546911802;8511.616977236039;8665;8696.869546956801;7052.682629281695;8447.577621118919;8455;8472.119479377041;8639.740807775777;4399.36209249657;4505.684602077823;4516.029053753458;4578.427696537933;4596.080783843245;1853.1290293562004;1915.8887845049837;1915.9808401916082;1924.8057214775167;1925.2876435718874;822.168693886498;828.4035494443991;971.8590804333332;999.855598544031;1044.9111825494917;21526.24658136989;21546.56906861854;21661.133554657637;21717.34847954564;21739.34781956544;827.6523859978823;890.8129292848574;953.1802649721373;960.7454024683465;991.7768502086973;7885.881711774328;8228.01263848342;10486.161107111413;11381.886181138083;11438.027767639844;464262;537472;538085;538387;581187;73655.1586210349;76696.61651691706;115556.26665600023;130020;135621.3218933899;812.7114874219685;838.714836955438;983.763896664781;1008.7276435362558;1009.7324209084599;15021.399144034553;15320.463783767515;19655.489388913382;20070.788673812705;20100;51882.481175187764;56032.43967560272;56511.71744141253;56839.71580142073;57413.42586574248;76079;77490;79365.0317484135;81279.59360203161;82596;10275.588976440858;11845.940770296438;11936.701582460508;12003.399830008639;12048.698782530508;13159.342032898508;13169.53906613264;13289.069765116348;13357.065005449525;14196.929015354857;10642.829288778074;11452.083833293484;11694.2983420995;11992.940035299767;12071;10433.478326083818;11073.667789966314;11375.54497820078;11481.368524730886;11978.281303121841;11567.421628918351;11677.941610291895;11989.880101198876;12124.393780311126;12141.939290303491;12136.575219867262;12205.450754716237;12421.254724716546;12507.562235322084;12510.562130325396;11680.47437865281;11689.532418703157;11929.403529823301;12091.697707557381;12101.636950891487 21:59:25 INFO - PROCESS | 1922 | |3;mootools.html;1477.3204326010039;1521.7184820808147;1654.4788391656627;1828.4788835181976;1832.5327041604398;8425.452345597532;8961.32790040748;8964.372493925428;8966.73099807006;8977;7823.687052517893;8058.234467725571;8500.957495212515;8740.344474164447;8742.95628521858;2367.573836709396;2406.843555168917;2407.6749638798715;2412.035185925626;2413.3845869303304;2373.4541055557233;2391.306521108885;2415.9516809663796;2416.250962201714;2417.746136655652;2373.5608912351186;2398.46034642205;2407.7953373963232;2410.6986626671664;2412.0231306320966;2104.2319553363063;2280.9621622161876;2375.809935205188;2375.8930848111877;2377.2868139558063;2351.9412014699597;2367.6093444581625;2371.786539211462;2375.8336916415856;2382.225776622587;8741.825163496695;8932.821343573158;8941.552922353922;8950.68672596456;8951.552422378854;5113.667611605234;5269.841904742864;5273.551748101416;5282.366116066059;5287.259783630295;3679.7792132472136;3683.5027271318295;3747.5128233329765;3758.285925674113;3759.1166075972124;1051.989480105197;1100.5927806711488;1109.6948339206783;1125.724197571592;1140.0138879868853;11652.359120248382;11713.297202167898;11732.94133529327;11739.530418783239;11782.823257651056;4518.254488009442;4651.488336283006;4819.7108173509005;4833.613310935117;4851.51484851512;9954.452505112144;9959.352642078242;9963.551640176209;9981.301308908452;9997.550110245056;5317.9202311965355;5321.308229930084;5330.573554115662;5337.466253374709;5338.54622357104;3818.4500564089294;4358.65130789536;4578.221702310615;4888.804447822047;4915.508449155127;3986.123052928409;4004.799760011988;4015.8795236142964;4053.756774593534;4125.29869922114;1244.9875501245053;1252.392589594046;1275.2858399296238;1288.606974872673;1303.3418123847587;825.5758816042279;871.9128087191231;884.8495755721543;904.7873749668822;961.485605201214;5489.725513724377;5605.80379686709;5707.74315155819;6057.212562366864;6142.938570614237;932.398602901125;932.4800575062604;942.3639043645512;955.8805149356331;963.5519483440094;3627.3652110881017;3886.941695874564;3979.3230279835097;4074.246264441088;4123.154753275571;10193.949030254802;10251.897481025244;10595.258331916692;10724.195685323632;10952.178586606036;10834.349939003527;10839.891601084046;10842.40366779819;10858.782824343469;10867;7699.576523291161;8166.1833816619055;8240.546769927592;8262.628181731836;8264;5840.2991641003355;5854.297484301911;5898.469137777621;6101.450869421773;6148.139260503443;10087.394756314645;10132.898671013196;10150.74623134398;10157.288989770645;10165.119452901237;9318.347715659838;9771.511424428892;10173.982601740063;10202.132818710492;10356.482175891326;7105.2894710528535;8005.639746211551;8131.918680813116;8264.752057438287;8285;8052.785483991949;8331.125231850569;8378.329733621418;8425.157484251526;8444.282236010007;7439.442041846775;7447.851042979219;7488.251174882686;7500.774976750707;7526.698932042657;7310.890336645061;7316.341529262392;7325.7912444446065;7352.48532602734;7399.519031262951;6653.9667301663185;6876.518643694893;6955.269696681776;6964.4080253179045;6965.5124141309625;4872.926906096341;4932.3341348918875;4995.975020124876;5077;5233.555147812479;4937.9259311109645;4992.700437973734;5067.5185857343495;5109.9233511498105;5292.867678307919 21:59:25 INFO - PROCESS | 1922 | |4;prototype.html;255.22284643261267;305.62408237867425;306.51723535431694;313.1013989849133;316.357793678832;4978.651494395389;5053;5372.7045012524295;5376.354837419511;5400.324959380077;4564.360989461478;5060.886604946915;5303.575713942885;5311.49540793625;5323.1749078892735;3171.8581310728237;3338.8497517611704;3885.1258466845015;4044.211378781139;4046.7774272415004;3631.2918980798754;3692.8338224779814;3729.1236559408517;3886.300465916134;3975.3043217436975;3585.3367127081574;3832.9041773955737;4065.532463766675;4077.531083925342;4083.9183216335655;4031.6774658027434;4035.656969157625;4061.8984525386804;4070.2470043041835;4085.6935729820084;3338.61605915318;3543.273628906068;3544.7164226861787;3561.821908904544;3575.7496975211748;4286.292761694317;5014.548690617862;5156.871078223037;5160.935625749693;5186.766595503173;4638.886667199881;4639.350490931272;4643.187442197635;4647.86056418308;4667.766611669403;5340.101725740232;5594.552435805166;5596.692181929992;5616.32604087508;5640.435956404368;3627.437747149196;3820.3314419976673;3876.468794826028;3890.9961369894527;3898.688104951597;5464.590155738336;5662.85842853932;5766.596338256324;5844.590878638497;5879.823605291848;3902.0635047588944;3907.667848232932;3920.745151565139;3933.8229779659982;3939.2712348215673;3978.542467616261;3982.7012974027066;3995.860144894915;3998.1803730235815;3998.3602623580086;4333.544977777288;4611.656549001947;4659.720416775004;4667.9766601166775;4675.29870519418;5092.9236061459105;5216.7913283469;5589.133684278943;5607.915881261784;5625.268715067015;1243.8383010208795;1280.225463594512;1312.0553201694765;1368.9452421903013;1369.6370461827623;775.6083177995076;840.5839109640767;842.7471758472434;854.799122206281;856.6530555125184;3826.942595861064;3881.4280216512357;4300.978495107504;4338.891527711769;4363.38912552243;4129.628333450004;4358.934615980763;4460.375547423363;4527.59251667345;4733.86975860864;3884.9805750971063;4251.193242871767;4292.871213863589;4316.352547117895;4330.163742748505;2406.3262286559793;2657.657882769189;3000.2748419658783;3029.2729744861076;3040.8479576021555;5902.2622172228475;5921.289445266596;5930.822075337746;5937.485941084996;5937.762489500372;5411.729413529387;5495.159121694277;5906.586538942319;5912.201852749909;5923.4076592340425;5427.9185812212845;5553.583481238842;5821.388754180834;5899.321578018497;5902.76388944446;4782.976085119552;5370.060239458001;5696.294260226359;5726.455986681259;5769.816882759966;4785.090832741768;4794.736289503971;5083.847484575469;5648.350439699323;5707.6290041147195;4821.662483626113;5611.38274789781;5899.439553242435;5939.851503712442;5993.460588547052;3409.2499650076943;3715.888523344304;3788.8295026723313;3855.0169706724605;3864.265789499986;4093.4064560639135;4745.2882067690125;5006.69959802413;5561.356267516669;5751.84305885293;5545.417731138173;5590.608657394106;5618.49433550966;5632.295963004624;5646.576506762091;5333.386660534088;5567.47109024642;5573.244428005165;5607.5513958882375;5634.7464364104535;5211.905499845075;5216.921746173886;5333.12003519415;5488.9725551371985;5533.391326953952;4748.833790817443;5120.975804839121;5168.922466163086;5564.749586268546;5666.263385759825;4440.3561483585345;5110.361204849394;5267.762950667306;5334.11987022137;5514.869451762459 21:59:25 INFO - PROCESS | 1922 | |5;yui.html;654.7446495866612;746.2052913646967;768.5235154204394;779.0651218537753;806.9112397636255;15052.924735376322;16068.955517891358;16078.356865725356;16107.597310067255;16125.516234512968;14255.643608909782;14415.855841441582;14437.63905902353;14440.638984025405;14450.277486125704;668.1214203322639;803.5781214862186;832.8584140696081;839.6389552492426;855.055164043732;850.2772643253247;850.2815121222557;851.9616617252208;852.4970267542149;854.581255184958;903.5346796399859;904.8914130304373;906.5150144672601;910.1126401758299;910.199024858124;690.892911598703;874.4010352908247;895.9920089898864;902.512643172686;905.5608030105459;664.9202095748541;666.4102269491486;666.7266420767511;666.9499787515956;667.2960027171332;14224.431022759078;14242.572722818331;14301.28493575317;14306.141631502143;14306.57080287593;12599.370031498389;13558.661033474144;13559;13705.520306789214;13758.036937414385;11654.30074195551;12213.290139380493;12257.632271031795;12263.93868030654;12700.1744886583;3708.740388172829;4575.8560359910025;4604.746738929358;4612.4003879495785;4715.3634259374885;111109;112394.31408528879;115709.84290157075;116627;116648;10570.841437378445;10770.515326810313;10842.024217820433;10847.620333288298;11257.21199516026;21021.474463138544;21056.368308950758;21069.367918962456;21082.78917210839;21085;7639.809004774925;7671.309582137523;7684.269994350528;8085.231902969209;8094.1501142380375;4412.801423935931;4512.593866552026;4521.728696278234;4552.567506086916;4624.745638989888;3427.708644765173;3488.8081155536183;3547.3082748863726;3549.9290014199573;3555.6799888010205;529.7192487981376;633.2084893882646;641.9486441084701;658.0524045374646;661.0513912535527;589.2869627750384;600.4265926040621;657.6317262333013;680.5236334565824;687.2062767503542;9083.636654533877;9435.528223588793;9624.278179136592;9735.659251926148;9765.218782497383;645.2515082504273;650.7071817682062;664.3058004385429;664.9534532582672;688.9655517224119;4574.4739354973935;4659.790309436084;4684.48470668233;4694.108119457293;4696.835610753607;135444;160583;163658;165602;170732.14633927;143828;144913;145037;147333;149310;120452.3977380125;122950;123224.1516377273;124067.37966310291;124485.37757311336;8986.775330616525;8997.82004359896;9034.954825226096;9074.364794464589;9186.540672966192;60042;62300.83540392913;66379;68114;69010.65494672494;102134.48932755289;104565.90868182441;104623;107916.46041769741;108306;11346.645878308174;11910.583129590423;11949.522019119138;12301.692457688629;12445.688857778629;11154.717207521238;12807.551735689207;12921.418536166084;13190.538331158363;13323.400446979715;11813.173077884465;12160.878391215974;12262.325572093621;12316.322602256962;12656;12077.214981026205;12106.878931210575;12313.384330783247;12378;12707.919826814454;9017.458952462874;11820.763584728085;12246.571370002008;12528.49886004586;12528.561500347445;8315.95842020786;10187;10332.483375831329;10345.689629311133;10593.099586534923;9052.592633331384;9861.852072218779;10313.22650801178;10485.003924627148;10495.632652857113 21:59:25 INFO - PROCESS | 1922 | __end_tp_report 21:59:25 INFO - PROCESS | 1922 | __start_cc_report 21:59:25 INFO - PROCESS | 1922 | _x_x_mozilla_cycle_collect,1465 21:59:25 INFO - PROCESS | 1922 | __end_cc_report 21:59:25 INFO - PROCESS | 1922 | __startTimestamp1471496365397__endTimestamp 21:59:25 INFO - PROCESS | 1922 | 21:59:25 INFO - PROCESS | 1922 | ------- Summary: start ------- 21:59:25 INFO - PROCESS | 1922 | Number of tests: 6 21:59:25 INFO - PROCESS | 1922 | 21:59:25 INFO - PROCESS | 1922 | [#0] dojo.html Cycles:165 Average:5724.58 Median:5866.50 stddev:655.88 (11.2%) stddev-sans-first:617.81 21:59:25 INFO - PROCESS | 1922 | Values: 2846.5 3327.0 3351.7 3354.9 3364.3 5502.6 5619.7 5621.8 5676.5 5693.6 5619.3 5634.2 5722.3 5729.8 5774.7 4307.5 5142.1 5200.6 5230.5 5252.7 5284.3 5315.3 5336.1 5339.7 5353.3 5118.1 5165.1 5173.9 5179.4 5203.5 5123.3 5132.3 5161.7 5222.9 5237.9 5159.9 5169.1 5170.3 5183.4 5225.7 5667.3 5699.5 5714.5 5727.5 5760.6 5099.0 6037.5 6190.8 6206.4 6260.5 4996.5 5193.6 6292.8 6316.0 6323.2 5633.9 5814.4 5831.0 5834.2 5860.3 6252.7 6253.6 6317.0 6330.7 6355.8 6101.2 6155.5 6155.7 6160.5 6173.8 6258.0 6265.7 6278.7 6279.3 6289.2 5979.6 6048.6 6055.9 6102.4 6113.0 6132.1 6142.0 6148.1 6150.0 6198.9 4997.0 5001.3 5015.2 5038.4 5062.4 4417.8 4484.2 4493.0 4533.7 4548.6 5679.4 5733.1 5760.0 5793.4 5794.3 6255.4 6338.4 6338.5 6338.8 6342.8 5760.4 5766.8 5792.4 5815.5 5861.7 5320.8 5380.4 5461.4 5462.1 5476.2 6264.0 6331.3 6336.9 6337.8 6340.6 6284.8 6309.4 6313.2 6316.3 6323.3 6185.4 6321.7 6342.7 6352.1 6353.9 6099.5 6136.6 6144.3 6169.2 6180.6 6054.2 6266.7 6270.2 6294.3 6297.7 5866.5 6106.6 6131.5 6141.3 6160.3 5941.3 6011.6 6106.4 6107.0 6149.0 5967.6 5990.3 6015.4 6021.0 6024.1 6120.5 6133.5 6135.4 6150.0 6160.5 5723.2 5745.3 5827.0 5851.2 5894.7 21:59:25 INFO - PROCESS | 1922 | 21:59:25 INFO - PROCESS | 1922 | [#1] ext.html Cycles:180 Average:66843.22 Median:35964.73 stddev:139870.41 (388.9%) stddev-sans-first:140173.92 21:59:25 INFO - PROCESS | 1922 | Values: 434.8 469.2 492.8 511.2 518.7 31265.1 41618.0 42661.9 42817.6 44546.0 43275.8 43690.0 43719.6 43720.0 43751.6 8888.7 9787.6 9982.6 9992.5 10032.3 8862.8 12006.4 12290.3 12295.3 12552.8 12358.6 12416.7 12429.7 12438.6 12448.1 12173.0 12231.4 12258.6 12267.6 12287.1 10219.9 12048.0 12124.6 12142.7 12145.0 41221.6 41550.8 41575.8 41634.8 41689.0 2894.4 3616.7 3616.8 3692.4 4143.0 31182.2 32310.4 32574.0 33056.8 33089.2 3291.4 3378.1 3421.6 3455.7 3464.7 455784.0 459800.0 461228.0 461323.0 465154.5 57866.0 58032.0 58035.4 58210.8 58224.0 62959.4 63041.0 63117.4 63136.7 63164.0 16552.6 16782.2 16793.1 16799.7 16817.7 11638.7 11913.8 12803.6 13052.9 13085.0 13480.0 16736.4 16801.3 16947.9 17032.8 6027.7 7026.1 7105.3 7143.6 7216.1 902.4 1035.6 1040.5 1073.6 1106.1 30467.7 30583.0 30584.8 30892.8 31671.0 1058.0 1089.3 1103.9 1107.3 1192.2 15393.6 15963.7 15972.0 15972.4 16801.8 617544.0 620185.0 621289.0 621535.0 622374.0 59763.7 59794.7 59961.6 60153.0 60432.4 1249.2 1395.8 1411.9 1427.3 1445.9 30024.8 30602.8 32186.5 32348.8 33020.3 49833.5 50260.0 50338.0 50398.5 50555.0 477285.0 478468.0 478971.0 482255.6 484394.0 26370.6 27037.6 27462.6 27668.7 27898.9 48514.5 48618.3 49090.5 49529.0 49589.0 34775.0 35973.8 39667.6 39837.0 40978.8 39563.0 40126.6 40558.2 40580.0 41871.8 38102.2 38831.0 39305.4 39352.0 39448.6 33910.0 34974.0 35920.7 35955.6 36013.6 25582.0 25818.8 25896.6 26497.5 26678.7 21:59:25 INFO - PROCESS | 1922 | 21:59:25 INFO - PROCESS | 1922 | [#2] jquery.html Cycles:180 Average:32161.24 Median:11956.75 stddev:88860.10 (743.2%) stddev-sans-first:89077.15 21:59:25 INFO - PROCESS | 1922 | Values: 292.7 324.9 355.3 368.5 404.2 15704.4 18562.6 21142.6 21172.9 21810.9 11919.1 14268.6 20065.9 20695.0 20873.6 5220.9 5430.8 5448.3 5452.2 5465.2 5191.6 5373.7 5416.2 5426.0 5429.0 5406.5 5419.2 5421.7 5423.6 5506.6 5403.5 5571.4 5867.0 5922.3 5936.2 5284.4 5286.7 5287.4 5289.7 5289.9 13389.0 13596.9 23317.9 24645.6 25668.0 2174.2 2292.5 2425.7 2508.3 2927.4 8374.9 10133.7 10252.4 10403.9 10449.8 1911.7 1922.5 1946.4 1962.4 2026.3 69822.3 90990.0 95648.0 118907.0 124122.0 10775.0 11109.0 11323.3 11619.9 11739.0 14389.3 14406.4 14414.8 14423.7 14430.0 8465.2 8496.9 8511.6 8665.0 8696.9 7052.7 8447.6 8455.0 8472.1 8639.7 4399.4 4505.7 4516.0 4578.4 4596.1 1853.1 1915.9 1916.0 1924.8 1925.3 822.2 828.4 971.9 999.9 1044.9 21526.2 21546.6 21661.1 21717.3 21739.3 827.7 890.8 953.2 960.7 991.8 7885.9 8228.0 10486.2 11381.9 11438.0 464262.0 537472.0 538085.0 538387.0 581187.0 73655.2 76696.6 115556.3 130020.0 135621.3 812.7 838.7 983.8 1008.7 1009.7 15021.4 15320.5 19655.5 20070.8 20100.0 51882.5 56032.4 56511.7 56839.7 57413.4 76079.0 77490.0 79365.0 81279.6 82596.0 10275.6 11845.9 11936.7 12003.4 12048.7 13159.3 13169.5 13289.1 13357.1 14196.9 10642.8 11452.1 11694.3 11992.9 12071.0 10433.5 11073.7 11375.5 11481.4 11978.3 11567.4 11677.9 11989.9 12124.4 12141.9 12136.6 12205.5 12421.3 12507.6 12510.6 11680.5 11689.5 11929.4 12091.7 12101.6 21:59:25 INFO - PROCESS | 1922 | 21:59:25 INFO - PROCESS | 1922 | [#3] mootools.html Cycles:180 Average:5738.64 Median:4965.31 stddev:3203.24 (64.5%) stddev-sans-first:3196.22 21:59:25 INFO - PROCESS | 1922 | Values: 1477.3 1521.7 1654.5 1828.5 1832.5 8425.5 8961.3 8964.4 8966.7 8977.0 7823.7 8058.2 8501.0 8740.3 8743.0 2367.6 2406.8 2407.7 2412.0 2413.4 2373.5 2391.3 2416.0 2416.3 2417.7 2373.6 2398.5 2407.8 2410.7 2412.0 2104.2 2281.0 2375.8 2375.9 2377.3 2351.9 2367.6 2371.8 2375.8 2382.2 8741.8 8932.8 8941.6 8950.7 8951.6 5113.7 5269.8 5273.6 5282.4 5287.3 3679.8 3683.5 3747.5 3758.3 3759.1 1052.0 1100.6 1109.7 1125.7 1140.0 11652.4 11713.3 11732.9 11739.5 11782.8 4518.3 4651.5 4819.7 4833.6 4851.5 9954.5 9959.4 9963.6 9981.3 9997.6 5317.9 5321.3 5330.6 5337.5 5338.5 3818.5 4358.7 4578.2 4888.8 4915.5 3986.1 4004.8 4015.9 4053.8 4125.3 1245.0 1252.4 1275.3 1288.6 1303.3 825.6 871.9 884.8 904.8 961.5 5489.7 5605.8 5707.7 6057.2 6142.9 932.4 932.5 942.4 955.9 963.6 3627.4 3886.9 3979.3 4074.2 4123.2 10193.9 10251.9 10595.3 10724.2 10952.2 10834.3 10839.9 10842.4 10858.8 10867.0 7699.6 8166.2 8240.5 8262.6 8264.0 5840.3 5854.3 5898.5 6101.5 6148.1 10087.4 10132.9 10150.7 10157.3 10165.1 9318.3 9771.5 10174.0 10202.1 10356.5 7105.3 8005.6 8131.9 8264.8 8285.0 8052.8 8331.1 8378.3 8425.2 8444.3 7439.4 7447.9 7488.3 7500.8 7526.7 7310.9 7316.3 7325.8 7352.5 7399.5 6654.0 6876.5 6955.3 6964.4 6965.5 4872.9 4932.3 4996.0 5077.0 5233.6 4937.9 4992.7 5067.5 5109.9 5292.9 21:59:25 INFO - PROCESS | 1922 | 21:59:25 INFO - PROCESS | 1922 | [#4] prototype.html Cycles:180 Average:4427.74 Median:4765.90 stddev:1368.75 (28.7%) stddev-sans-first:1336.28 21:59:25 INFO - PROCESS | 1922 | Values: 255.2 305.6 306.5 313.1 316.4 4978.7 5053.0 5372.7 5376.4 5400.3 4564.4 5060.9 5303.6 5311.5 5323.2 3171.9 3338.8 3885.1 4044.2 4046.8 3631.3 3692.8 3729.1 3886.3 3975.3 3585.3 3832.9 4065.5 4077.5 4083.9 4031.7 4035.7 4061.9 4070.2 4085.7 3338.6 3543.3 3544.7 3561.8 3575.7 4286.3 5014.5 5156.9 5160.9 5186.8 4638.9 4639.4 4643.2 4647.9 4667.8 5340.1 5594.6 5596.7 5616.3 5640.4 3627.4 3820.3 3876.5 3891.0 3898.7 5464.6 5662.9 5766.6 5844.6 5879.8 3902.1 3907.7 3920.7 3933.8 3939.3 3978.5 3982.7 3995.9 3998.2 3998.4 4333.5 4611.7 4659.7 4668.0 4675.3 5092.9 5216.8 5589.1 5607.9 5625.3 1243.8 1280.2 1312.1 1368.9 1369.6 775.6 840.6 842.7 854.8 856.7 3826.9 3881.4 4301.0 4338.9 4363.4 4129.6 4358.9 4460.4 4527.6 4733.9 3885.0 4251.2 4292.9 4316.4 4330.2 2406.3 2657.7 3000.3 3029.3 3040.8 5902.3 5921.3 5930.8 5937.5 5937.8 5411.7 5495.2 5906.6 5912.2 5923.4 5427.9 5553.6 5821.4 5899.3 5902.8 4783.0 5370.1 5696.3 5726.5 5769.8 4785.1 4794.7 5083.8 5648.4 5707.6 4821.7 5611.4 5899.4 5939.9 5993.5 3409.2 3715.9 3788.8 3855.0 3864.3 4093.4 4745.3 5006.7 5561.4 5751.8 5545.4 5590.6 5618.5 5632.3 5646.6 5333.4 5567.5 5573.2 5607.6 5634.7 5211.9 5216.9 5333.1 5489.0 5533.4 4748.8 5121.0 5168.9 5564.7 5666.3 4440.4 5110.4 5267.8 5334.1 5514.9 21:59:25 INFO - PROCESS | 1922 | 21:59:25 INFO - PROCESS | 1922 | [#5] yui.html Cycles:180 Average:26300.45 Median:12256.35 stddev:43712.56 (356.7%) stddev-sans-first:43792.78 21:59:25 INFO - PROCESS | 1922 | Values: 654.7 746.2 768.5 779.1 806.9 15052.9 16069.0 16078.4 16107.6 16125.5 14255.6 14415.9 14437.6 14440.6 14450.3 668.1 803.6 832.9 839.6 855.1 850.3 850.3 852.0 852.5 854.6 903.5 904.9 906.5 910.1 910.2 690.9 874.4 896.0 902.5 905.6 664.9 666.4 666.7 666.9 667.3 14224.4 14242.6 14301.3 14306.1 14306.6 12599.4 13558.7 13559.0 13705.5 13758.0 11654.3 12213.3 12257.6 12263.9 12700.2 3708.7 4575.9 4604.7 4612.4 4715.4 111109.0 112394.3 115709.8 116627.0 116648.0 10570.8 10770.5 10842.0 10847.6 11257.2 21021.5 21056.4 21069.4 21082.8 21085.0 7639.8 7671.3 7684.3 8085.2 8094.2 4412.8 4512.6 4521.7 4552.6 4624.7 3427.7 3488.8 3547.3 3549.9 3555.7 529.7 633.2 641.9 658.1 661.1 589.3 600.4 657.6 680.5 687.2 9083.6 9435.5 9624.3 9735.7 9765.2 645.3 650.7 664.3 665.0 689.0 4574.5 4659.8 4684.5 4694.1 4696.8 135444.0 160583.0 163658.0 165602.0 170732.1 143828.0 144913.0 145037.0 147333.0 149310.0 120452.4 122950.0 123224.2 124067.4 124485.4 8986.8 8997.8 9035.0 9074.4 9186.5 60042.0 62300.8 66379.0 68114.0 69010.7 102134.5 104565.9 104623.0 107916.5 108306.0 11346.6 11910.6 11949.5 12301.7 12445.7 11154.7 12807.6 12921.4 13190.5 13323.4 11813.2 12160.9 12262.3 12316.3 12656.0 12077.2 12106.9 12313.4 12378.0 12707.9 9017.5 11820.8 12246.6 12528.5 12528.6 8316.0 10187.0 10332.5 10345.7 10593.1 9052.6 9861.9 10313.2 10485.0 10495.6 21:59:25 INFO - PROCESS | 1922 | -------- Summary: end -------- 21:59:25 INFO - PROCESS | 1922 | 21:59:26 INFO - TEST-INFO | 1922: exit 0 21:59:26 INFO - TEST-OK | dromaeo_css | took 1118893ms 21:59:26 INFO - TEST-START | kraken 21:59:26 INFO - operating with platform_type : mac_ 21:59:26 INFO - Initialising browser for kraken test... 21:59:26 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmph5fEJY/profile http://localhost:49890/getInfo.html) 21:59:28 INFO - PROCESS | 1927 | __metrics Screen width/height:1600/1200 21:59:28 INFO - PROCESS | 1927 | colorDepth:24 21:59:28 INFO - PROCESS | 1927 | Browser inner width/height: 1024/689 21:59:28 INFO - PROCESS | 1927 | __metrics 21:59:28 INFO - PROCESS | 1927 | 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" 21:59:28 INFO - PROCESS | 1927 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:59:29 INFO - TEST-INFO | 1927: exit 0 21:59:29 INFO - Browser initialized. 21:59:29 INFO - Running cycle 1/1 for kraken test... 21:59:29 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmph5fEJY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:59:41 INFO - PROCESS | 1928 | RSS: Main: 228077568 21:59:41 INFO - PROCESS | 1928 | 21:59:44 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49890/tests/kraken/driver.html?testName=audio-beat-detection) 21:59:45 INFO - PROCESS | 1928 | RSS: Main: 286216192 21:59:45 INFO - PROCESS | 1928 | 21:59:47 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49890/tests/kraken/driver.html?testName=audio-dft) 21:59:48 INFO - PROCESS | 1928 | RSS: Main: 298446848 21:59:48 INFO - PROCESS | 1928 | 21:59:51 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49890/tests/kraken/driver.html?testName=audio-fft) 21:59:52 INFO - PROCESS | 1928 | RSS: Main: 387530752 21:59:52 INFO - PROCESS | 1928 | 21:59:54 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49890/tests/kraken/driver.html?testName=audio-oscillator) 21:59:55 INFO - PROCESS | 1928 | RSS: Main: 291102720 21:59:55 INFO - PROCESS | 1928 | 21:59:57 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49890/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:59:58 INFO - PROCESS | 1928 | RSS: Main: 295337984 21:59:58 INFO - PROCESS | 1928 | 22:00:01 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49890/tests/kraken/driver.html?testName=imaging-darkroom) 22:00:01 INFO - PROCESS | 1928 | RSS: Main: 331456512 22:00:01 INFO - PROCESS | 1928 | 22:00:04 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49890/tests/kraken/driver.html?testName=imaging-desaturate) 22:00:05 INFO - PROCESS | 1928 | RSS: Main: 333000704 22:00:05 INFO - PROCESS | 1928 | 22:00:08 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49890/tests/kraken/driver.html?testName=json-parse-financial) 22:00:08 INFO - PROCESS | 1928 | RSS: Main: 332505088 22:00:08 INFO - PROCESS | 1928 | 22:00:10 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49890/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:00:10 INFO - PROCESS | 1928 | RSS: Main: 301154304 22:00:10 INFO - PROCESS | 1928 | 22:00:12 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49890/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:00:13 INFO - PROCESS | 1928 | RSS: Main: 308060160 22:00:13 INFO - PROCESS | 1928 | 22:00:15 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49890/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:00:16 INFO - PROCESS | 1928 | RSS: Main: 302170112 22:00:16 INFO - PROCESS | 1928 | 22:00:19 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49890/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:00:20 INFO - PROCESS | 1928 | RSS: Main: 313294848 22:00:20 INFO - PROCESS | 1928 | 22:00:22 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49890/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:00:23 INFO - PROCESS | 1928 | RSS: Main: 315588608 22:00:23 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49890/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49890/tests/kraken/driver.html?testName=ai-astar) 22:00:25 INFO - PROCESS | 1928 | RSS: Main: 358629376 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | __start_tp_report 22:00:25 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 22:00:25 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 22:00:25 INFO - PROCESS | 1928 | |i|pagename|runs| 22:00:25 INFO - PROCESS | 1928 | |0;ai-astar;101;158;168;112;108;112;112;111;113;112 22:00:25 INFO - PROCESS | 1928 | |1;audio-beat-detection;143;111;107;168;106;108;132;107;141;108 22:00:25 INFO - PROCESS | 1928 | |2;audio-dft;185;185;183;183;186;182;185;183;180;186 22:00:25 INFO - PROCESS | 1928 | |3;audio-fft;79;60;52;90;74;81;89;86;86;57 22:00:25 INFO - PROCESS | 1928 | |4;audio-oscillator;84;70;127;101;106;70;97;88;98;138 22:00:25 INFO - PROCESS | 1928 | |5;imaging-gaussian-blur;88;85;88;85;90;84;88;85;88;83 22:00:25 INFO - PROCESS | 1928 | |6;imaging-darkroom;101;141;104;96;94;94;94;96;93;94 22:00:25 INFO - PROCESS | 1928 | |7;imaging-desaturate;86;85;85;86;87;85;103;135;114;84 22:00:25 INFO - PROCESS | 1928 | |8;json-parse-financial;55;52;53;53;54;55;52;53;54;54 22:00:25 INFO - PROCESS | 1928 | |9;json-stringify-tinderbox;65;67;70;71;76;69;69;70;68;70 22:00:25 INFO - PROCESS | 1928 | |10;stanford-crypto-aes;89;86;86;85;85;85;84;83;89;82 22:00:25 INFO - PROCESS | 1928 | |11;stanford-crypto-ccm;141;139;139;142;137;152;148;148;218;158 22:00:25 INFO - PROCESS | 1928 | |12;stanford-crypto-pbkdf2;169;170;170;170;170;168;168;174;168;179 22:00:25 INFO - PROCESS | 1928 | |13;stanford-crypto-sha256-iterative;71;77;66;59;70;82;90;85;68;67 22:00:25 INFO - PROCESS | 1928 | __end_tp_report 22:00:25 INFO - PROCESS | 1928 | __start_cc_report 22:00:25 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,2137 22:00:25 INFO - PROCESS | 1928 | __end_cc_report 22:00:25 INFO - PROCESS | 1928 | __startTimestamp1471496425732__endTimestamp 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | ------- Summary: start ------- 22:00:25 INFO - PROCESS | 1928 | Number of tests: 14 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#0] ai-astar Cycles:10 Average:120.70 Median:112.00 stddev:22.69 (20.3%) stddev-sans-first:22.92 22:00:25 INFO - PROCESS | 1928 | Values: 101.0 158.0 168.0 112.0 108.0 112.0 112.0 111.0 113.0 112.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#1] audio-beat-detection Cycles:10 Average:123.10 Median:121.50 stddev:21.67 (17.8%) stddev-sans-first:21.75 22:00:25 INFO - PROCESS | 1928 | Values: 143.0 111.0 107.0 168.0 106.0 108.0 132.0 107.0 141.0 108.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#2] audio-dft Cycles:10 Average:183.80 Median:185.00 stddev:1.93 (1.0%) stddev-sans-first:2.00 22:00:25 INFO - PROCESS | 1928 | Values: 185.0 185.0 183.0 183.0 186.0 182.0 185.0 183.0 180.0 186.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#3] audio-fft Cycles:10 Average:75.40 Median:83.50 stddev:14.11 (16.9%) stddev-sans-first:14.91 22:00:25 INFO - PROCESS | 1928 | Values: 79.0 60.0 52.0 90.0 74.0 81.0 89.0 86.0 86.0 57.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:77.00 stddev:22.06 (28.6%) stddev-sans-first:22.82 22:00:25 INFO - PROCESS | 1928 | Values: 84.0 70.0 127.0 101.0 106.0 70.0 97.0 88.0 98.0 138.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:88.00 stddev:2.27 (2.6%) stddev-sans-first:2.33 22:00:25 INFO - PROCESS | 1928 | Values: 88.0 85.0 88.0 85.0 90.0 84.0 88.0 85.0 88.0 83.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:94.00 stddev:14.60 (15.5%) stddev-sans-first:15.48 22:00:25 INFO - PROCESS | 1928 | Values: 101.0 141.0 104.0 96.0 94.0 94.0 94.0 96.0 93.0 94.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:85.00 stddev:17.23 (20.3%) stddev-sans-first:17.97 22:00:25 INFO - PROCESS | 1928 | Values: 86.0 85.0 85.0 86.0 87.0 85.0 103.0 135.0 114.0 84.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.08 (2.0%) stddev-sans-first:1.00 22:00:25 INFO - PROCESS | 1928 | Values: 55.0 52.0 53.0 53.0 54.0 55.0 52.0 53.0 54.0 54.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:70.00 stddev:2.88 (4.1%) stddev-sans-first:2.55 22:00:25 INFO - PROCESS | 1928 | Values: 65.0 67.0 70.0 71.0 76.0 69.0 69.0 70.0 68.0 70.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:85.50 stddev:2.27 (2.7%) stddev-sans-first:2.00 22:00:25 INFO - PROCESS | 1928 | Values: 89.0 86.0 86.0 85.0 85.0 85.0 84.0 83.0 89.0 82.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:148.00 stddev:24.05 (16.3%) stddev-sans-first:25.17 22:00:25 INFO - PROCESS | 1928 | Values: 141.0 139.0 139.0 142.0 137.0 152.0 148.0 148.0 218.0 158.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:170.00 stddev:3.44 (2.0%) stddev-sans-first:3.60 22:00:25 INFO - PROCESS | 1928 | Values: 169.0 170.0 170.0 170.0 170.0 168.0 168.0 174.0 168.0 179.0 22:00:25 INFO - PROCESS | 1928 | 22:00:25 INFO - PROCESS | 1928 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:74.00 stddev:9.70 (13.1%) stddev-sans-first:10.24 22:00:25 INFO - PROCESS | 1928 | Values: 71.0 77.0 66.0 59.0 70.0 82.0 90.0 85.0 68.0 67.0 22:00:25 INFO - PROCESS | 1928 | -------- Summary: end -------- 22:00:25 INFO - PROCESS | 1928 | 22:00:26 INFO - TEST-INFO | 1928: exit 0 22:00:26 INFO - TEST-OK | kraken | took 60509ms 22:00:26 INFO - SUITE-END | took 1179s 22:00:26 INFO - Completed test suite (00:19:39) 22:00:26 INFO - javascript benchmark 22:00:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2846.5018621741197, 3327.0351598036555, 3351.731861451084, 3354.9161270968216, 3364.276680513689, 5502.642328248661, 5619.719014049301, 5621.80323688671, 5676.460736230057, 5693.629914055584, 5619.297587801525, 5634.211210430532, 5722.313322401307, 5729.828105156842, 5774.6535207887555, 4307.504636966742, 5142.10013247682, 5200.6099542534275, 5230.450802665722, 5252.71110088945, 5284.2866213061325, 5315.255864179018, 5336.119540275849, 5339.652922560022, 5353.250544923714, 5118.07874582575, 5165.147750621143, 5173.948260517384, 5179.430262671103, 5203.4796520348045, 5123.2827404163445, 5132.255822905695, 5161.69029858206, 5222.8694282642855, 5237.921431178535, 5159.896802063975, 5169.121249387613, 5170.327857378555, 5183.403908550527, 5225.712585807779, 5667.319921609434, 5699.458551437607, 5714.51426628737, 5727.541796656257, 5760.567957403212, 5099.0, 6037.516998640098, 6190.752369905198, 6206.410391012847, 6260.467860231885, 4996.525330093636, 5193.584513238932, 6292.811215663537, 6316.0, 6323.241211054657, 5633.915491267552, 5814.360420353758, 5830.950428922834, 5834.241548598655, 5860.3260625027815, 6252.718627661766, 6253.624782513064, 6317.020861766342, 6330.683465826783, 6355.809325720235, 6101.206843110456, 6155.538334624921, 6155.6614386209185, 6160.537959652955, 6173.783917562864, 6258.0, 6265.686715664198, 6278.748850046039, 6279.309275979638, 6289.245290565162, 5979.581429299994, 6048.637081775108, 6055.939440605625, 6102.3983636914, 6112.989854784201, 6132.110843927606, 6141.977005061692, 6148.1392605035335, 6149.985252433298, 6198.907016394758, 4996.975015124901, 5001.274815151783, 5015.1975683890405, 5038.445770965191, 5062.367204099488, 4417.757023363746, 4484.1621304247865, 4492.9775351123035, 4533.705309154894, 4548.5906268436, 5679.403662615448, 5733.082706766897, 5760.0, 5793.391693872167, 5794.333651630032, 6255.40573645503, 6338.366163383717, 6338.461230795342, 6338.809835704937, 6342.841428964312, 5760.3951585083805, 5766.826995190151, 5792.420757924174, 5815.505682017076, 5861.70691465425, 5320.787168513293, 5380.381256155514, 5461.42655021225, 5462.071447853875, 5476.17857321405, 6264.029075493306, 6331.3352098029045, 6336.904946425715, 6337.7781777637565, 6340.587861788969, 6284.811455656338, 6309.3690630936535, 6313.242410910537, 6316.305206427382, 6323.272823625342, 6185.443310102112, 6321.652309122858, 6342.746290148527, 6352.142460767737, 6353.872922541431, 6099.4815440688035, 6136.6011209271255, 6144.262688477412, 6169.198004259418, 6180.598261113013, 6054.152418661479, 6266.655333956493, 6270.2475702915945, 6294.307626161027, 6297.748090076346, 5866.50134738552, 6106.6336019838955, 6131.540134489842, 6141.263048434216, 6160.322364539987, 5941.257342832146, 6011.609245399035, 6106.358832322542, 6106.969465152646, 6149.0, 5967.6121052131475, 5990.31111422192, 6015.428534289235, 6021.0, 6024.0662697282, 6120.510359171167, 6133.509319254538, 6135.355787642234, 6150.0, 6160.507159427146, 5723.170140329712, 5745.2818397700285, 5827.0, 5851.21008663842, 5894.6757928314355], "value": 5681.886914822155, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.8478032688561, 469.2328043648634, 492.80534188995347, 511.21273239211615, 518.7406296851573, 31265.062048138534, 41618.0, 42661.93345166369, 42817.571824281746, 44546.0, 43275.78362108193, 43690.0, 43719.56280437195, 43720.0, 43751.56248437515, 8888.688895888645, 9787.559559819825, 9982.600695972153, 9992.45041522716, 10032.347897386682, 8862.778430539256, 12006.399680016008, 12290.324032178227, 12295.323757193351, 12552.81170782439, 12358.62924112278, 12416.689582760459, 12429.689257768583, 12438.626841194779, 12448.128630995834, 12173.0, 12231.449584768707, 12258.570950016796, 12267.631971040882, 12287.078469114853, 10219.875813660567, 12048.0, 12124.575639852652, 12142.696432589257, 12144.967677747401, 41221.58778412207, 41550.79224603888, 41575.79212103951, 41634.79182604067, 41689.0, 2894.406646637455, 3616.692581130607, 3616.8372423240753, 3692.4276737105793, 4143.02638879863, 31182.22044448907, 32310.353792924245, 32574.0, 33056.83471582627, 33089.172770680685, 3291.358185153896, 3378.0879162626197, 3421.640727723603, 3455.671711187433, 3464.7055000325004, 455784.0, 459800.0, 461228.0, 461323.0, 465154.461870738, 57866.0, 58032.0, 58035.419645803, 58210.8357832841, 58224.0, 62959.370406296264, 63041.0, 63117.368826312064, 63136.68431657904, 63164.0, 16552.586185345222, 16782.244798984077, 16793.076380798928, 16799.664006720042, 16817.66364672724, 11638.6508404748, 11913.821292680617, 12803.551875684307, 13052.869471305356, 13084.953203743678, 13480.0, 16736.414225502293, 16801.327946882233, 16947.91526042362, 17032.829671703123, 6027.698615069229, 7026.1217347831525, 7105.335499187805, 7143.607101609357, 7216.134063912364, 902.4449963272672, 1035.6427032673716, 1040.5317607076845, 1073.6295977887617, 1106.1040557148735, 30467.69532304693, 30583.0, 30584.847075764923, 30892.845535772627, 31671.0, 1057.962971296001, 1089.330062011857, 1103.8675358956816, 1107.2692023264606, 1192.1535709646248, 15393.615159621098, 15963.680726385408, 15972.0, 15972.440964566184, 16801.831981680025, 617544.0, 620185.0, 621289.0, 621535.0, 622374.0, 59763.701181493816, 59794.70102649546, 59961.605375246625, 60153.0, 60432.395676043554, 1249.244207254616, 1395.762720337545, 1411.9339898376866, 1427.336288625794, 1445.8554144585457, 30024.849875750482, 30602.84698576493, 32186.517202242452, 32348.83825580857, 33020.33959320848, 49833.50166498434, 50259.994800103064, 50337.99324013426, 50398.49601503938, 50554.988900222525, 477285.0, 478468.0, 478971.0, 482255.5887220682, 484394.0, 26370.604440933785, 27037.59443608308, 27462.5880611787, 27668.723312766615, 27898.860505697343, 48514.51485485241, 48618.27072593843, 49090.50909490859, 49529.0, 49589.0, 34775.0, 35973.82013089918, 39667.603323967545, 39837.0, 40978.79510602428, 39563.0, 40126.59873401345, 40558.18883622252, 40580.0, 41871.79064104782, 38102.2379552413, 38831.0, 39305.41041884431, 39352.0, 39448.605513945644, 33910.0, 34974.0, 35920.742774002785, 35955.64044359523, 36013.63986360103, 25581.976720930954, 25818.83815228357, 25896.61155082713, 26497.470050598495, 26678.733212667627], "value": 20569.626510012124, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.65466749235895, 324.90252924122757, 355.2699203137551, 368.4859620828944, 404.2299419605652, 15704.371825126995, 18562.62874742506, 21142.57714845702, 21172.89413552932, 21810.89094554529, 11919.106067044984, 14268.643283917907, 20065.89637569933, 20694.965251737427, 20873.582528349463, 5220.8694782630455, 5430.782768689268, 5448.291722076125, 5452.154915988009, 5465.180222966567, 5191.584673226152, 5373.650712703681, 5416.160495123242, 5426.023315803154, 5429.0227759003365, 5406.513413792758, 5419.241306217133, 5421.728913554326, 5423.566114710833, 5506.559475241971, 5403.459654034605, 5571.4428557144365, 5867.031939729982, 5922.31893332268, 5936.22829032223, 5284.445133260991, 5286.735663216824, 5287.365516138049, 5289.709066001406, 5289.920651190197, 13389.0, 13596.932015339959, 23317.883410583006, 24645.63031554528, 25668.0, 2174.152080688517, 2292.518571100071, 2425.672534207895, 2508.3102146909564, 2927.355981684026, 8374.916250837474, 10133.746656333651, 10252.38485690861, 10403.907589703123, 10449.84325235122, 1911.722800193978, 1922.5385907382267, 1946.4160751774411, 1962.3622322745166, 2026.2806703620297, 69822.30177698209, 90990.0, 95648.0, 118907.0, 124122.0, 10775.030247277782, 11109.0, 11323.263987840764, 11619.883801161881, 11739.0, 14389.28053597316, 14406.42374305037, 14414.783778243334, 14423.711525769635, 14430.0, 8465.153484651486, 8496.872546911802, 8511.616977236039, 8665.0, 8696.869546956801, 7052.682629281695, 8447.577621118919, 8455.0, 8472.119479377041, 8639.740807775777, 4399.36209249657, 4505.684602077823, 4516.029053753458, 4578.427696537933, 4596.080783843245, 1853.1290293562004, 1915.8887845049837, 1915.9808401916082, 1924.8057214775167, 1925.2876435718874, 822.168693886498, 828.4035494443991, 971.8590804333332, 999.855598544031, 1044.9111825494917, 21526.24658136989, 21546.56906861854, 21661.133554657637, 21717.34847954564, 21739.34781956544, 827.6523859978823, 890.8129292848574, 953.1802649721373, 960.7454024683465, 991.7768502086973, 7885.881711774328, 8228.01263848342, 10486.161107111413, 11381.886181138083, 11438.027767639844, 464262.0, 537472.0, 538085.0, 538387.0, 581187.0, 73655.1586210349, 76696.61651691706, 115556.26665600023, 130020.0, 135621.3218933899, 812.7114874219685, 838.714836955438, 983.763896664781, 1008.7276435362558, 1009.7324209084599, 15021.399144034553, 15320.463783767515, 19655.489388913382, 20070.788673812705, 20100.0, 51882.481175187764, 56032.43967560272, 56511.71744141253, 56839.71580142073, 57413.42586574248, 76079.0, 77490.0, 79365.0317484135, 81279.59360203161, 82596.0, 10275.588976440858, 11845.940770296438, 11936.701582460508, 12003.399830008639, 12048.698782530508, 13159.342032898508, 13169.53906613264, 13289.069765116348, 13357.065005449525, 14196.929015354857, 10642.829288778074, 11452.083833293484, 11694.2983420995, 11992.940035299767, 12071.0, 10433.478326083818, 11073.667789966314, 11375.54497820078, 11481.368524730886, 11978.281303121841, 11567.421628918351, 11677.941610291895, 11989.880101198876, 12124.393780311126, 12141.939290303491, 12136.575219867262, 12205.450754716237, 12421.254724716546, 12507.562235322084, 12510.562130325396, 11680.47437865281, 11689.532418703157, 11929.403529823301, 12091.697707557381, 12101.636950891487], "value": 9490.878935603498, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1477.3204326010039, 1521.7184820808147, 1654.4788391656627, 1828.4788835181976, 1832.5327041604398, 8425.452345597532, 8961.32790040748, 8964.372493925428, 8966.73099807006, 8977.0, 7823.687052517893, 8058.234467725571, 8500.957495212515, 8740.344474164447, 8742.95628521858, 2367.573836709396, 2406.843555168917, 2407.6749638798715, 2412.035185925626, 2413.3845869303304, 2373.4541055557233, 2391.306521108885, 2415.9516809663796, 2416.250962201714, 2417.746136655652, 2373.5608912351186, 2398.46034642205, 2407.7953373963232, 2410.6986626671664, 2412.0231306320966, 2104.2319553363063, 2280.9621622161876, 2375.809935205188, 2375.8930848111877, 2377.2868139558063, 2351.9412014699597, 2367.6093444581625, 2371.786539211462, 2375.8336916415856, 2382.225776622587, 8741.825163496695, 8932.821343573158, 8941.552922353922, 8950.68672596456, 8951.552422378854, 5113.667611605234, 5269.841904742864, 5273.551748101416, 5282.366116066059, 5287.259783630295, 3679.7792132472136, 3683.5027271318295, 3747.5128233329765, 3758.285925674113, 3759.1166075972124, 1051.989480105197, 1100.5927806711488, 1109.6948339206783, 1125.724197571592, 1140.0138879868853, 11652.359120248382, 11713.297202167898, 11732.94133529327, 11739.530418783239, 11782.823257651056, 4518.254488009442, 4651.488336283006, 4819.7108173509005, 4833.613310935117, 4851.51484851512, 9954.452505112144, 9959.352642078242, 9963.551640176209, 9981.301308908452, 9997.550110245056, 5317.9202311965355, 5321.308229930084, 5330.573554115662, 5337.466253374709, 5338.54622357104, 3818.4500564089294, 4358.65130789536, 4578.221702310615, 4888.804447822047, 4915.508449155127, 3986.123052928409, 4004.799760011988, 4015.8795236142964, 4053.756774593534, 4125.29869922114, 1244.9875501245053, 1252.392589594046, 1275.2858399296238, 1288.606974872673, 1303.3418123847587, 825.5758816042279, 871.9128087191231, 884.8495755721543, 904.7873749668822, 961.485605201214, 5489.725513724377, 5605.80379686709, 5707.74315155819, 6057.212562366864, 6142.938570614237, 932.398602901125, 932.4800575062604, 942.3639043645512, 955.8805149356331, 963.5519483440094, 3627.3652110881017, 3886.941695874564, 3979.3230279835097, 4074.246264441088, 4123.154753275571, 10193.949030254802, 10251.897481025244, 10595.258331916692, 10724.195685323632, 10952.178586606036, 10834.349939003527, 10839.891601084046, 10842.40366779819, 10858.782824343469, 10867.0, 7699.576523291161, 8166.1833816619055, 8240.546769927592, 8262.628181731836, 8264.0, 5840.2991641003355, 5854.297484301911, 5898.469137777621, 6101.450869421773, 6148.139260503443, 10087.394756314645, 10132.898671013196, 10150.74623134398, 10157.288989770645, 10165.119452901237, 9318.347715659838, 9771.511424428892, 10173.982601740063, 10202.132818710492, 10356.482175891326, 7105.2894710528535, 8005.639746211551, 8131.918680813116, 8264.752057438287, 8285.0, 8052.785483991949, 8331.125231850569, 8378.329733621418, 8425.157484251526, 8444.282236010007, 7439.442041846775, 7447.851042979219, 7488.251174882686, 7500.774976750707, 7526.698932042657, 7310.890336645061, 7316.341529262392, 7325.7912444446065, 7352.48532602734, 7399.519031262951, 6653.9667301663185, 6876.518643694893, 6955.269696681776, 6964.4080253179045, 6965.5124141309625, 4872.926906096341, 4932.3341348918875, 4995.975020124876, 5077.0, 5233.555147812479, 4937.9259311109645, 4992.700437973734, 5067.5185857343495, 5109.9233511498105, 5292.867678307919], "value": 4620.204988421179, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.22284643261267, 305.62408237867425, 306.51723535431694, 313.1013989849133, 316.357793678832, 4978.651494395389, 5053.0, 5372.7045012524295, 5376.354837419511, 5400.324959380077, 4564.360989461478, 5060.886604946915, 5303.575713942885, 5311.49540793625, 5323.1749078892735, 3171.8581310728237, 3338.8497517611704, 3885.1258466845015, 4044.211378781139, 4046.7774272415004, 3631.2918980798754, 3692.8338224779814, 3729.1236559408517, 3886.300465916134, 3975.3043217436975, 3585.3367127081574, 3832.9041773955737, 4065.532463766675, 4077.531083925342, 4083.9183216335655, 4031.6774658027434, 4035.656969157625, 4061.8984525386804, 4070.2470043041835, 4085.6935729820084, 3338.61605915318, 3543.273628906068, 3544.7164226861787, 3561.821908904544, 3575.7496975211748, 4286.292761694317, 5014.548690617862, 5156.871078223037, 5160.935625749693, 5186.766595503173, 4638.886667199881, 4639.350490931272, 4643.187442197635, 4647.86056418308, 4667.766611669403, 5340.101725740232, 5594.552435805166, 5596.692181929992, 5616.32604087508, 5640.435956404368, 3627.437747149196, 3820.3314419976673, 3876.468794826028, 3890.9961369894527, 3898.688104951597, 5464.590155738336, 5662.85842853932, 5766.596338256324, 5844.590878638497, 5879.823605291848, 3902.0635047588944, 3907.667848232932, 3920.745151565139, 3933.8229779659982, 3939.2712348215673, 3978.542467616261, 3982.7012974027066, 3995.860144894915, 3998.1803730235815, 3998.3602623580086, 4333.544977777288, 4611.656549001947, 4659.720416775004, 4667.9766601166775, 4675.29870519418, 5092.9236061459105, 5216.7913283469, 5589.133684278943, 5607.915881261784, 5625.268715067015, 1243.8383010208795, 1280.225463594512, 1312.0553201694765, 1368.9452421903013, 1369.6370461827623, 775.6083177995076, 840.5839109640767, 842.7471758472434, 854.799122206281, 856.6530555125184, 3826.942595861064, 3881.4280216512357, 4300.978495107504, 4338.891527711769, 4363.38912552243, 4129.628333450004, 4358.934615980763, 4460.375547423363, 4527.59251667345, 4733.86975860864, 3884.9805750971063, 4251.193242871767, 4292.871213863589, 4316.352547117895, 4330.163742748505, 2406.3262286559793, 2657.657882769189, 3000.2748419658783, 3029.2729744861076, 3040.8479576021555, 5902.2622172228475, 5921.289445266596, 5930.822075337746, 5937.485941084996, 5937.762489500372, 5411.729413529387, 5495.159121694277, 5906.586538942319, 5912.201852749909, 5923.4076592340425, 5427.9185812212845, 5553.583481238842, 5821.388754180834, 5899.321578018497, 5902.76388944446, 4782.976085119552, 5370.060239458001, 5696.294260226359, 5726.455986681259, 5769.816882759966, 4785.090832741768, 4794.736289503971, 5083.847484575469, 5648.350439699323, 5707.6290041147195, 4821.662483626113, 5611.38274789781, 5899.439553242435, 5939.851503712442, 5993.460588547052, 3409.2499650076943, 3715.888523344304, 3788.8295026723313, 3855.0169706724605, 3864.265789499986, 4093.4064560639135, 4745.2882067690125, 5006.69959802413, 5561.356267516669, 5751.84305885293, 5545.417731138173, 5590.608657394106, 5618.49433550966, 5632.295963004624, 5646.576506762091, 5333.386660534088, 5567.47109024642, 5573.244428005165, 5607.5513958882375, 5634.7464364104535, 5211.905499845075, 5216.921746173886, 5333.12003519415, 5488.9725551371985, 5533.391326953952, 4748.833790817443, 5120.975804839121, 5168.922466163086, 5564.749586268546, 5666.263385759825, 4440.3561483585345, 5110.361204849394, 5267.762950667306, 5334.11987022137, 5514.869451762459], "value": 3991.0067964251775, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.7446495866612, 746.2052913646967, 768.5235154204394, 779.0651218537753, 806.9112397636255, 15052.924735376322, 16068.955517891358, 16078.356865725356, 16107.597310067255, 16125.516234512968, 14255.643608909782, 14415.855841441582, 14437.63905902353, 14440.638984025405, 14450.277486125704, 668.1214203322639, 803.5781214862186, 832.8584140696081, 839.6389552492426, 855.055164043732, 850.2772643253247, 850.2815121222557, 851.9616617252208, 852.4970267542149, 854.581255184958, 903.5346796399859, 904.8914130304373, 906.5150144672601, 910.1126401758299, 910.199024858124, 690.892911598703, 874.4010352908247, 895.9920089898864, 902.512643172686, 905.5608030105459, 664.9202095748541, 666.4102269491486, 666.7266420767511, 666.9499787515956, 667.2960027171332, 14224.431022759078, 14242.572722818331, 14301.28493575317, 14306.141631502143, 14306.57080287593, 12599.370031498389, 13558.661033474144, 13559.0, 13705.520306789214, 13758.036937414385, 11654.30074195551, 12213.290139380493, 12257.632271031795, 12263.93868030654, 12700.1744886583, 3708.740388172829, 4575.8560359910025, 4604.746738929358, 4612.4003879495785, 4715.3634259374885, 111109.0, 112394.31408528879, 115709.84290157075, 116627.0, 116648.0, 10570.841437378445, 10770.515326810313, 10842.024217820433, 10847.620333288298, 11257.21199516026, 21021.474463138544, 21056.368308950758, 21069.367918962456, 21082.78917210839, 21085.0, 7639.809004774925, 7671.309582137523, 7684.269994350528, 8085.231902969209, 8094.1501142380375, 4412.801423935931, 4512.593866552026, 4521.728696278234, 4552.567506086916, 4624.745638989888, 3427.708644765173, 3488.8081155536183, 3547.3082748863726, 3549.9290014199573, 3555.6799888010205, 529.7192487981376, 633.2084893882646, 641.9486441084701, 658.0524045374646, 661.0513912535527, 589.2869627750384, 600.4265926040621, 657.6317262333013, 680.5236334565824, 687.2062767503542, 9083.636654533877, 9435.528223588793, 9624.278179136592, 9735.659251926148, 9765.218782497383, 645.2515082504273, 650.7071817682062, 664.3058004385429, 664.9534532582672, 688.9655517224119, 4574.4739354973935, 4659.790309436084, 4684.48470668233, 4694.108119457293, 4696.835610753607, 135444.0, 160583.0, 163658.0, 165602.0, 170732.14633927, 143828.0, 144913.0, 145037.0, 147333.0, 149310.0, 120452.3977380125, 122950.0, 123224.1516377273, 124067.37966310291, 124485.37757311336, 8986.775330616525, 8997.82004359896, 9034.954825226096, 9074.364794464589, 9186.540672966192, 60042.0, 62300.83540392913, 66379.0, 68114.0, 69010.65494672494, 102134.48932755289, 104565.90868182441, 104623.0, 107916.46041769741, 108306.0, 11346.645878308174, 11910.583129590423, 11949.522019119138, 12301.692457688629, 12445.688857778629, 11154.717207521238, 12807.551735689207, 12921.418536166084, 13190.538331158363, 13323.400446979715, 11813.173077884465, 12160.878391215974, 12262.325572093621, 12316.322602256962, 12656.0, 12077.214981026205, 12106.878931210575, 12313.384330783247, 12378.0, 12707.919826814454, 9017.458952462874, 11820.763584728085, 12246.571370002008, 12528.49886004586, 12528.561500347445, 8315.95842020786, 10187.0, 10332.483375831329, 10345.689629311133, 10593.099586534923, 9052.592633331384, 9861.852072218779, 10313.22650801178, 10485.003924627148, 10495.632652857113], "value": 7775.49413420447, "unit": "score"}], "name": "dromaeo_css", "value": 7360.785844464799, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 158.0, 168.0, 112.0, 108.0, 112.0, 112.0, 111.0, 113.0, 112.0], "value": 120.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 107.0, 168.0, 106.0, 108.0, 132.0, 107.0, 141.0, 108.0], "value": 123.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 183.0, 186.0, 182.0, 185.0, 183.0, 180.0, 186.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 52.0, 90.0, 74.0, 81.0, 89.0, 86.0, 86.0, 57.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 127.0, 101.0, 106.0, 70.0, 97.0, 88.0, 98.0, 138.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 90.0, 84.0, 88.0, 85.0, 88.0, 83.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 141.0, 104.0, 96.0, 94.0, 94.0, 94.0, 96.0, 93.0, 94.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 86.0, 87.0, 85.0, 103.0, 135.0, 114.0, 84.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 53.0, 54.0, 55.0, 52.0, 53.0, 54.0, 54.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 70.0, 71.0, 76.0, 69.0, 69.0, 70.0, 68.0, 70.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 86.0, 85.0, 85.0, 85.0, 84.0, 83.0, 89.0, 82.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 139.0, 142.0, 137.0, 152.0, 148.0, 148.0, 218.0, 158.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 170.0, 170.0, 170.0, 168.0, 168.0, 174.0, 168.0, 179.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 66.0, 59.0, 70.0, 82.0, 90.0, 85.0, 68.0, 67.0], "value": 73.5, "unit": "score"}], "name": "kraken", "value": 1487.7, "alertThreshold": 2.0}]} 22:00:26 INFO - Return code: 0 22:00:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:00:27 INFO - # TBPL SUCCESS # 22:00:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:00:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:00:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:00:27 INFO - 2016-08-17 22:00:27.058 screenresolution[1932:11455] starting screenresolution argv=screenresolution get 22:00:27 INFO - 2016-08-17 22:00:27.061 screenresolution[1932:11455] Display 0: 1600x1200x32@60 22:00:27 INFO - 2016-08-17 22:00:27.067 screenresolution[1933:11458] starting screenresolution argv=screenresolution list 22:00:27 INFO - Available Modes on Display 0 22:00:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:00:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:00:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:00:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:00:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:00:27 INFO - Intel Iris: 22:00:27 INFO - Chipset Model: Intel Iris 22:00:27 INFO - Type: GPU 22:00:27 INFO - Bus: Built-In 22:00:27 INFO - VRAM (Dynamic, Max): 1536 MB 22:00:27 INFO - Vendor: Intel (0x8086) 22:00:27 INFO - Device ID: 0x0a2e 22:00:27 INFO - Revision ID: 0x0009 22:00:27 INFO - Displays: 22:00:27 INFO - Display: 22:00:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:00:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:00:27 INFO - Display Serial Number: 1600x1200 60 22:00:27 INFO - Main Display: Yes 22:00:27 INFO - Mirror: Off 22:00:27 INFO - Online: Yes 22:00:27 INFO - Rotation: Supported 22:00:27 INFO - Return code: 0 22:00:27 INFO - Running post-action listener: _resource_record_post_action 22:00:27 INFO - [mozharness: 2016-08-18 05:00:27.302722Z] Finished run-tests step (success) 22:00:27 INFO - Running post-run listener: _resource_record_post_run 22:00:27 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 1019904; Write bytes: 706348544; Read time: 14; Write time: 1988 22:00:27 INFO - TinderboxPrint: CPU usage
53.4% 22:00:27 INFO - TinderboxPrint: I/O read bytes / time
1,019,904 / 14 22:00:27 INFO - TinderboxPrint: I/O write bytes / time
706,348,544 / 1,988 22:00:27 INFO - TinderboxPrint: CPU idle
2,262.0 (46.6%) 22:00:27 INFO - TinderboxPrint: CPU system
972.8 (20.0%) 22:00:27 INFO - TinderboxPrint: CPU user
1,622.9 (33.4%) 22:00:27 INFO - TinderboxPrint: Swap in / out
406,208,512 / 0 22:00:27 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398549504; Write bytes: 392701952; Read time: 29663; Write time: 761 22:00:27 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 1007616; Write bytes: 296705536; Read time: 11; Write time: 1187 22:00:27 INFO - Running post-run listener: _upload_blobber_files 22:00:27 INFO - Blob upload gear active. 22:00:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:00:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:00:27 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'] 22:00:27 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 22:00:28 INFO - (blobuploader) - INFO - Open directory for files ... 22:00:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:00:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:00:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:00:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:00:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:00:29 INFO - (blobuploader) - INFO - Done attempting. 22:00:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:00:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:00:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:00:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:00:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:00:30 INFO - (blobuploader) - INFO - Done attempting. 22:00:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:00:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:00:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:00:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:00:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:00:34 INFO - (blobuploader) - INFO - Done attempting. 22:00:34 INFO - (blobuploader) - INFO - Iteration through files over. 22:00:34 INFO - Return code: 0 22:00:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:00:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:00:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5900a1f883a21ae871c98ba135ca3d1d1827a16bf20cbc11ec65075f1cf11f85cd609b4c3ff972e0e0c27f8a0cf25eada5a0e5b2545c1b52e885574ca0257da7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47a1803d0c227e4fb9031e4ee149eaae8502e85ed6937edfae2da662af86d392b6cc2bf77d816edd9c7503e69aa4c06110e718ff0f80a3a1d2f286a2ad547101", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7be5860b945ce087b3505f71a1a1da1566ce4de9c4637bb12d274a2b038b7bfa8e661f80c0167dc11fabfcbad651bfb742f5b5c4813d6cd42c79fcc79c781911"} 22:00:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:00:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:00:34 INFO - Contents: 22:00:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5900a1f883a21ae871c98ba135ca3d1d1827a16bf20cbc11ec65075f1cf11f85cd609b4c3ff972e0e0c27f8a0cf25eada5a0e5b2545c1b52e885574ca0257da7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47a1803d0c227e4fb9031e4ee149eaae8502e85ed6937edfae2da662af86d392b6cc2bf77d816edd9c7503e69aa4c06110e718ff0f80a3a1d2f286a2ad547101", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7be5860b945ce087b3505f71a1a1da1566ce4de9c4637bb12d274a2b038b7bfa8e661f80c0167dc11fabfcbad651bfb742f5b5c4813d6cd42c79fcc79c781911"} 22:00:34 INFO - Running post-run listener: copy_logs_to_upload_dir 22:00:34 INFO - Copying logs to upload dir... 22:00:34 INFO - mkdir: /builds/slave/test/build/upload/logs 22:00:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.969458 ========= master_lag: 0.96 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-17 22:00:35.072625) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 22:00:35.075842) ========= 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.saYLK3TiXm/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.I09sMGPx3K/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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/5900a1f883a21ae871c98ba135ca3d1d1827a16bf20cbc11ec65075f1cf11f85cd609b4c3ff972e0e0c27f8a0cf25eada5a0e5b2545c1b52e885574ca0257da7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47a1803d0c227e4fb9031e4ee149eaae8502e85ed6937edfae2da662af86d392b6cc2bf77d816edd9c7503e69aa4c06110e718ff0f80a3a1d2f286a2ad547101", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7be5860b945ce087b3505f71a1a1da1566ce4de9c4637bb12d274a2b038b7bfa8e661f80c0167dc11fabfcbad651bfb742f5b5c4813d6cd42c79fcc79c781911"} build_url:https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011221 build_url: 'https://queue.taskcluster.net/v1/task/Z9YLd5rXReORn18maaceuA/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/5900a1f883a21ae871c98ba135ca3d1d1827a16bf20cbc11ec65075f1cf11f85cd609b4c3ff972e0e0c27f8a0cf25eada5a0e5b2545c1b52e885574ca0257da7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47a1803d0c227e4fb9031e4ee149eaae8502e85ed6937edfae2da662af86d392b6cc2bf77d816edd9c7503e69aa4c06110e718ff0f80a3a1d2f286a2ad547101", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7be5860b945ce087b3505f71a1a1da1566ce4de9c4637bb12d274a2b038b7bfa8e661f80c0167dc11fabfcbad651bfb742f5b5c4813d6cd42c79fcc79c781911"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 22:00:35.116446) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 22:00:35.117202) ========= 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.saYLK3TiXm/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.I09sMGPx3K/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.005041 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 22:00:35.170595) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 22:00:35.171239) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 22:00:35.171637) ========= ========= Total master_lag: 1.16 =========