builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0216 starttime: 1472730896.85 results: success (0) buildid: 20160901023821 builduid: d5db6e57313b4d82950ba64429849f58 revision: 9d715b38e5f094c63df4fa560fd00b6ff6852f9b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:56.847568) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:56.848031) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:56.848311) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:56.872066) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:56.872444) ========= 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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-01 04:54:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.22M=0.002s 2016-09-01 04:54:57 (7.22 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129513 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:57.025773) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:57.026280) ========= 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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.020289 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:57.075426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 04:54:57.075899) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9d715b38e5f094c63df4fa560fd00b6ff6852f9b --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 9d715b38e5f094c63df4fa560fd00b6ff6852f9b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uRxthgMyhG/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.cAeZ4kpQmH/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-01 04:54:57,155 truncating revision to first 12 chars 2016-09-01 04:54:57,155 Setting DEBUG logging. 2016-09-01 04:54:57,155 attempt 1/10 2016-09-01 04:54:57,155 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9d715b38e5f0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 04:54:58,481 unpacking tar archive at: fx-team-9d715b38e5f0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.590547 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 04:54:58.690790) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:58.691216) ========= script_repo_revision: 9d715b38e5f094c63df4fa560fd00b6ff6852f9b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:58.691644) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:58.691963) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 04:54:58.711680) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-09-01 04:54:58.712250) ========= /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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 04:54:58 INFO - MultiFileLogger online at 20160901 04:54:58 in /builds/slave/test 04:54:58 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 04:54:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:54:58 INFO - {'append_to_log': False, 04:54:58 INFO - 'base_work_dir': '/builds/slave/test', 04:54:58 INFO - 'blob_upload_branch': 'Fx-Team', 04:54:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:54:58 INFO - 'branch': 'Fx-Team', 04:54:58 INFO - 'buildbot_json_path': 'buildprops.json', 04:54:58 INFO - 'config_files': ('talos/mac_config.py',), 04:54:58 INFO - 'default_actions': ('clobber', 04:54:58 INFO - 'read-buildbot-config', 04:54:58 INFO - 'download-and-extract', 04:54:58 INFO - 'populate-webroot', 04:54:58 INFO - 'create-virtualenv', 04:54:58 INFO - 'install', 04:54:58 INFO - 'run-tests'), 04:54:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:54:58 INFO - 'download_minidump_stackwalk': True, 04:54:58 INFO - 'download_symbols': 'ondemand', 04:54:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:54:58 INFO - 'tooltool.py': '/tools/tooltool.py', 04:54:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:54:58 INFO - '/tools/misc-python/virtualenv.py')}, 04:54:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:54:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:54:58 INFO - 'installer_path': 'installer.exe', 04:54:58 INFO - 'log_level': 'info', 04:54:58 INFO - 'log_name': 'talos', 04:54:58 INFO - 'log_to_console': True, 04:54:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:54:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:54:58 INFO - 'opt_config_files': (), 04:54:58 INFO - 'pip_index': False, 04:54:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:54:58 INFO - 'cmd': ('bash', 04:54:58 INFO - '-c', 04:54:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:54:58 INFO - 'enabled': True, 04:54:58 INFO - 'halt_on_failure': False, 04:54:58 INFO - 'name': 'check_screen_resolution'},), 04:54:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:54:58 INFO - 'cmd': ('bash', 04:54:58 INFO - '-c', 04:54:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:54:58 INFO - 'enabled': True, 04:54:58 INFO - 'halt_on_failure': False, 04:54:58 INFO - 'name': 'check_screen_resolution'},), 04:54:58 INFO - 'run_cmd_checks_enabled': True, 04:54:58 INFO - 'sps_profile': False, 04:54:58 INFO - 'sps_profile_interval': 0, 04:54:58 INFO - 'suite': 'dromaeojs', 04:54:58 INFO - 'system_bits': '32', 04:54:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:54:58 INFO - 'title': 't-yosemite-r7-0216', 04:54:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:54:58 INFO - 'use_talos_json': True, 04:54:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:54:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:54:58 INFO - 'work_dir': 'build'} 04:54:58 INFO - [mozharness: 2016-09-01 11:54:58.916202Z] Running clobber step. 04:54:58 INFO - Running pre-action listener: _resource_record_pre_action 04:54:58 INFO - Running main action method: clobber 04:54:58 INFO - rmtree: /builds/slave/test/build 04:54:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:54:59 INFO - Running post-action listener: _resource_record_post_action 04:54:59 INFO - [mozharness: 2016-09-01 11:54:59.885512Z] Finished clobber step (success) 04:54:59 INFO - [mozharness: 2016-09-01 11:54:59.885735Z] Running read-buildbot-config step. 04:54:59 INFO - Running pre-action listener: _resource_record_pre_action 04:54:59 INFO - Running main action method: read_buildbot_config 04:54:59 INFO - Using buildbot properties: 04:54:59 INFO - { 04:54:59 INFO - "project": "", 04:54:59 INFO - "product": "firefox", 04:54:59 INFO - "script_repo_revision": "production", 04:54:59 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:54:59 INFO - "repository": "", 04:54:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:54:59 INFO - "buildid": "20160901023821", 04:54:59 INFO - "pgo_build": "False", 04:54:59 INFO - "basedir": "/builds/slave/test", 04:54:59 INFO - "buildnumber": 137, 04:54:59 INFO - "slavename": "t-yosemite-r7-0216", 04:54:59 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:54:59 INFO - "platform": "yosemite_r7", 04:54:59 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:54:59 INFO - "branch": "fx-team", 04:54:59 INFO - "repo_path": "integration/fx-team", 04:54:59 INFO - "revision": "9d715b38e5f094c63df4fa560fd00b6ff6852f9b", 04:54:59 INFO - "stage_platform": "macosx64", 04:54:59 INFO - "builduid": "d5db6e57313b4d82950ba64429849f58", 04:54:59 INFO - "slavebuilddir": "test" 04:54:59 INFO - } 04:54:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 04:54:59 INFO - Running post-action listener: _resource_record_post_action 04:54:59 INFO - [mozharness: 2016-09-01 11:54:59.889942Z] Finished read-buildbot-config step (success) 04:54:59 INFO - [mozharness: 2016-09-01 11:54:59.890119Z] Running download-and-extract step. 04:54:59 INFO - Running pre-action listener: _resource_record_pre_action 04:54:59 INFO - Running main action method: download_and_extract 04:54:59 INFO - mkdir: /builds/slave/test/build/tests 04:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:59 INFO - trying https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 04:54:59 INFO - Downloading https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/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 04:54:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/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 04:55:00 INFO - Downloaded 1199 bytes. 04:55:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:55:00 INFO - Using the following test package requirements: 04:55:00 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:55:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:55:00 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 04:55:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:55:00 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 04:55:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:55:00 INFO - u'jsshell-mac.zip'], 04:55:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:55:00 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 04:55:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:55:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:55:00 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 04:55:00 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:55:00 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 04:55:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:55:00 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 04:55:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:55:00 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 04:55:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:55:00 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')]} 04:55:00 INFO - trying https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:55:00 INFO - Downloading https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/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 04:55:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/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 04:55:01 INFO - Downloaded 16399990 bytes. 04:55:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 04:55:08 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 04:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:08 INFO - trying https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:55:08 INFO - Downloading https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/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 04:55:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/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 04:55:09 INFO - Downloaded 16399990 bytes. 04:55:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 04:55:16 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')]} 04:55:16 INFO - trying https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:55:16 INFO - Downloading https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/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 04:55:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/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 04:55:17 INFO - Downloaded 14413998 bytes. 04:55:17 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 04:55:17 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')]} 04:55:17 INFO - trying https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:55:17 INFO - Downloading https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 04:55:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/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 04:55:21 INFO - Downloaded 111593434 bytes. 04:55:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:55:21 INFO - mkdir: /builds/slave/test/properties 04:55:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:55:21 INFO - Writing to file /builds/slave/test/properties/build_url 04:55:21 INFO - Contents: 04:55:21 INFO - build_url:https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:55:21 INFO - Running post-action listener: _resource_record_post_action 04:55:21 INFO - Running post-action listener: set_extra_try_arguments 04:55:21 INFO - [mozharness: 2016-09-01 11:55:21.873783Z] Finished download-and-extract step (success) 04:55:21 INFO - [mozharness: 2016-09-01 11:55:21.873874Z] Running populate-webroot step. 04:55:21 INFO - Running pre-action listener: _resource_record_pre_action 04:55:21 INFO - Running main action method: populate_webroot 04:55:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:55:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:55:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:55:21 INFO - '--tppagecycles', 04:55:21 INFO - '7'], 04:55:21 INFO - 'tests': ['tsvgm']}}, 04:55:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:55:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:55:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:55:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:55:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:55:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:55:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:21 INFO - 'talos_options': ['--disable-e10s'], 04:55:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:55:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:55:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:21 INFO - 'talos_options': ['--disable-e10s'], 04:55:21 INFO - 'tests': ['damp', 'tps']}, 04:55:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:21 INFO - 'tests': ['damp', 'tps']}, 04:55:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:55:21 INFO - 'tests': ['dromaeo_dom']}, 04:55:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:55:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:55:21 INFO - 'tests': ['basic_compositor_video']}, 04:55:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:55:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:55:21 INFO - 'tests': ['a11yr', 04:55:21 INFO - 'ts_paint', 04:55:21 INFO - 'tpaint', 04:55:21 INFO - 'sessionrestore', 04:55:21 INFO - 'sessionrestore_no_auto_restore', 04:55:21 INFO - 'tabpaint']}, 04:55:21 INFO - 'other-e10s': {'tests': ['a11yr', 04:55:21 INFO - 'ts_paint', 04:55:21 INFO - 'tpaint', 04:55:21 INFO - 'sessionrestore', 04:55:21 INFO - 'sessionrestore_no_auto_restore', 04:55:21 INFO - 'tabpaint']}, 04:55:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:55:21 INFO - 'ts_paint', 04:55:21 INFO - 'tpaint', 04:55:21 INFO - 'sessionrestore', 04:55:21 INFO - 'sessionrestore_no_auto_restore', 04:55:21 INFO - 'tabpaint']}, 04:55:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:55:21 INFO - 'ts_paint', 04:55:21 INFO - 'tpaint', 04:55:21 INFO - 'sessionrestore', 04:55:21 INFO - 'sessionrestore_no_auto_restore', 04:55:21 INFO - 'tabpaint']}, 04:55:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:55:21 INFO - 'tests': ['a11yr', 04:55:21 INFO - 'ts_paint', 04:55:21 INFO - 'tpaint', 04:55:21 INFO - 'sessionrestore', 04:55:21 INFO - 'sessionrestore_no_auto_restore', 04:55:21 INFO - 'tabpaint']}, 04:55:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:55:21 INFO - 'tests': ['a11yr', 04:55:21 INFO - 'ts_paint', 04:55:21 INFO - 'tpaint', 04:55:21 INFO - 'sessionrestore', 04:55:21 INFO - 'sessionrestore_no_auto_restore', 04:55:21 INFO - 'tabpaint']}, 04:55:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:55:21 INFO - 'tests': ['tsvgx', 04:55:21 INFO - 'tsvgr_opacity', 04:55:21 INFO - 'tart', 04:55:21 INFO - 'tscrollx', 04:55:21 INFO - 'cart']}, 04:55:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:55:21 INFO - 'tsvgr_opacity', 04:55:21 INFO - 'tart', 04:55:21 INFO - 'tscrollx', 04:55:21 INFO - 'cart']}, 04:55:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:21 INFO - 'talos_options': ['--disable-e10s'], 04:55:21 INFO - 'tests': ['tp5o']}, 04:55:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:21 INFO - 'tests': ['tp5o']}, 04:55:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:21 INFO - 'talos_options': ['--disable-e10s', 04:55:21 INFO - '--xperf_path', 04:55:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:55:21 INFO - 'tests': ['tp5n']}, 04:55:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:21 INFO - 'talos_options': ['--xperf_path', 04:55:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:55:21 INFO - 'tests': ['tp5n']}}, 04:55:21 INFO - 'talos.zip': {'path': '', 04:55:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:55:21 INFO - Running post-action listener: _resource_record_post_action 04:55:21 INFO - [mozharness: 2016-09-01 11:55:21.892695Z] Finished populate-webroot step (success) 04:55:21 INFO - [mozharness: 2016-09-01 11:55:21.892821Z] Running create-virtualenv step. 04:55:21 INFO - Running pre-action listener: _resource_record_pre_action 04:55:21 INFO - Running main action method: create_virtualenv 04:55:21 INFO - Creating virtualenv /builds/slave/test/build/venv 04:55:21 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 04:55:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:55:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:55:22 INFO - Using real prefix '/tools/python27' 04:55:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:55:22 INFO - Installing distribute.............................................................................................................................................................................................done. 04:55:25 INFO - Installing pip.................done. 04:55:25 INFO - Return code: 0 04:55:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:55:25 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')]} 04:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:25 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')]} 04:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:25 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 0x10394fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe033caec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038b3d30>, '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 0x103618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038abb20>, '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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 04:55:25 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 04:55:25 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 04:55:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRxthgMyhG/Render', 04:55:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:25 INFO - 'HOME': '/Users/cltbld', 04:55:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:25 INFO - 'LOGNAME': 'cltbld', 04:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:25 INFO - 'MOZ_NO_REMOTE': '1', 04:55:25 INFO - 'NO_EM_RESTART': '1', 04:55:25 INFO - 'PAGER': '/bin/cat', 04:55:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:25 INFO - 'PWD': '/builds/slave/test', 04:55:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:25 INFO - 'SHELL': '/bin/bash', 04:55:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cAeZ4kpQmH/Listeners', 04:55:25 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:55:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:25 INFO - 'USER': 'cltbld', 04:55:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:25 INFO - 'XPC_FLAGS': '0x0', 04:55:25 INFO - 'XPC_SERVICE_NAME': '0', 04:55:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:25 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 04:55:25 INFO - Cleaning up... 04:55:25 INFO - Return code: 0 04:55:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:55:25 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')]} 04:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:25 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')]} 04:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:25 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 0x10394fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe033caec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038b3d30>, '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 0x103618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038abb20>, '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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 04:55:25 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 04:55:25 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 04:55:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRxthgMyhG/Render', 04:55:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:25 INFO - 'HOME': '/Users/cltbld', 04:55:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:25 INFO - 'LOGNAME': 'cltbld', 04:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:25 INFO - 'MOZ_NO_REMOTE': '1', 04:55:25 INFO - 'NO_EM_RESTART': '1', 04:55:25 INFO - 'PAGER': '/bin/cat', 04:55:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:25 INFO - 'PWD': '/builds/slave/test', 04:55:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:25 INFO - 'SHELL': '/bin/bash', 04:55:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cAeZ4kpQmH/Listeners', 04:55:25 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:55:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:25 INFO - 'USER': 'cltbld', 04:55:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:25 INFO - 'XPC_FLAGS': '0x0', 04:55:25 INFO - 'XPC_SERVICE_NAME': '0', 04:55:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:26 INFO - Downloading/unpacking psutil>=3.1.1 04:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:55:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:55:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:55:29 INFO - Installing collected packages: psutil 04:55:29 INFO - Running setup.py install for psutil 04:55:29 INFO - building 'psutil._psutil_osx' extension 04:55:29 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 04:55:29 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 04:55:29 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 04:55:29 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 04:55:29 INFO - building 'psutil._psutil_posix' extension 04:55:29 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 04:55:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:55:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:55:29 INFO - ^ 04:55:29 INFO - 1 warning generated. 04:55:29 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 04:55:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:55:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:55:29 INFO - Successfully installed psutil 04:55:29 INFO - Cleaning up... 04:55:29 INFO - Return code: 0 04:55:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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')]} 04:55:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10394fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe033caec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038b3d30>, '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 0x103618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038abb20>, '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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 04:55:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:55:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:55:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRxthgMyhG/Render', 04:55:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:29 INFO - 'HOME': '/Users/cltbld', 04:55:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:29 INFO - 'LOGNAME': 'cltbld', 04:55:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:29 INFO - 'MOZ_NO_REMOTE': '1', 04:55:29 INFO - 'NO_EM_RESTART': '1', 04:55:29 INFO - 'PAGER': '/bin/cat', 04:55:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:29 INFO - 'PWD': '/builds/slave/test', 04:55:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:29 INFO - 'SHELL': '/bin/bash', 04:55:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cAeZ4kpQmH/Listeners', 04:55:29 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:55:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:29 INFO - 'USER': 'cltbld', 04:55:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:29 INFO - 'XPC_FLAGS': '0x0', 04:55:29 INFO - 'XPC_SERVICE_NAME': '0', 04:55:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:55:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:55:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:55:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:55:32 INFO - Installing collected packages: mozsystemmonitor 04:55:32 INFO - Running setup.py install for mozsystemmonitor 04:55:32 INFO - Successfully installed mozsystemmonitor 04:55:32 INFO - Cleaning up... 04:55:33 INFO - Return code: 0 04:55:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:55:33 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')]} 04:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:33 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')]} 04:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:33 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 0x10394fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe033caec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038b3d30>, '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 0x103618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038abb20>, '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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 04:55:33 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 04:55:33 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 04:55:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRxthgMyhG/Render', 04:55:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:33 INFO - 'HOME': '/Users/cltbld', 04:55:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:33 INFO - 'LOGNAME': 'cltbld', 04:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:33 INFO - 'MOZ_NO_REMOTE': '1', 04:55:33 INFO - 'NO_EM_RESTART': '1', 04:55:33 INFO - 'PAGER': '/bin/cat', 04:55:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:33 INFO - 'PWD': '/builds/slave/test', 04:55:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:33 INFO - 'SHELL': '/bin/bash', 04:55:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cAeZ4kpQmH/Listeners', 04:55:33 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:55:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:33 INFO - 'USER': 'cltbld', 04:55:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:33 INFO - 'XPC_FLAGS': '0x0', 04:55:33 INFO - 'XPC_SERVICE_NAME': '0', 04:55:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:33 INFO - Downloading/unpacking jsonschema==2.5.1 04:55:33 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 04:55:33 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 04:55:33 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 04:55:33 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 04:55:33 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 04:55:33 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 04:55:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:55:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:55:35 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 04:55:35 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 04:55:35 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 04:55:35 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 04:55:35 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 04:55:35 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 04:55:36 INFO - Downloading functools32-3.2.3-2.tar.gz 04:55:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:55:36 INFO - warning: no files found matching '*.txt' 04:55:36 INFO - no previously-included directories found matching 'build' 04:55:36 INFO - no previously-included directories found matching 'dist' 04:55:36 INFO - no previously-included directories found matching '.git*' 04:55:36 INFO - Installing collected packages: jsonschema, functools32 04:55:36 INFO - Running setup.py install for functools32 04:55:36 INFO - warning: no files found matching '*.txt' 04:55:36 INFO - no previously-included directories found matching 'build' 04:55:36 INFO - no previously-included directories found matching 'dist' 04:55:36 INFO - no previously-included directories found matching '.git*' 04:55:36 INFO - Successfully installed jsonschema functools32 04:55:36 INFO - Cleaning up... 04:55:36 INFO - Return code: 0 04:55:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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')]} 04:55:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:36 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 0x10394fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe033caec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038b3d30>, '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 0x103618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038abb20>, '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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 04:55:36 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 04:55:36 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 04:55:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRxthgMyhG/Render', 04:55:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:36 INFO - 'HOME': '/Users/cltbld', 04:55:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:36 INFO - 'LOGNAME': 'cltbld', 04:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:36 INFO - 'MOZ_NO_REMOTE': '1', 04:55:36 INFO - 'NO_EM_RESTART': '1', 04:55:36 INFO - 'PAGER': '/bin/cat', 04:55:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:36 INFO - 'PWD': '/builds/slave/test', 04:55:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:36 INFO - 'SHELL': '/bin/bash', 04:55:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cAeZ4kpQmH/Listeners', 04:55:36 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:55:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:36 INFO - 'USER': 'cltbld', 04:55:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:36 INFO - 'XPC_FLAGS': '0x0', 04:55:36 INFO - 'XPC_SERVICE_NAME': '0', 04:55:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:55:36 INFO - Cleaning up... 04:55:36 INFO - Return code: 0 04:55:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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')]} 04:55:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:36 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 0x10394fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe033caec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038b3d30>, '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 0x103618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038abb20>, '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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 04:55:36 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 04:55:36 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 04:55:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRxthgMyhG/Render', 04:55:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:36 INFO - 'HOME': '/Users/cltbld', 04:55:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:36 INFO - 'LOGNAME': 'cltbld', 04:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:36 INFO - 'MOZ_NO_REMOTE': '1', 04:55:36 INFO - 'NO_EM_RESTART': '1', 04:55:36 INFO - 'PAGER': '/bin/cat', 04:55:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:36 INFO - 'PWD': '/builds/slave/test', 04:55:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:36 INFO - 'SHELL': '/bin/bash', 04:55:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cAeZ4kpQmH/Listeners', 04:55:36 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:55:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:36 INFO - 'USER': 'cltbld', 04:55:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:36 INFO - 'XPC_FLAGS': '0x0', 04:55:36 INFO - 'XPC_SERVICE_NAME': '0', 04:55:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:37 INFO - Downloading/unpacking blobuploader==1.2.4 04:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:39 INFO - Downloading blobuploader-1.2.4.tar.gz 04:55:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:55:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:55:39 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 04:55:39 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 04:55:39 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 04:55:39 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 04:55:39 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 04:55:39 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 04:55:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:55:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:40 INFO - Downloading docopt-0.6.1.tar.gz 04:55:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:55:40 INFO - Installing collected packages: blobuploader, requests, docopt 04:55:40 INFO - Running setup.py install for blobuploader 04:55:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:55:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:55:40 INFO - Running setup.py install for requests 04:55:41 INFO - Running setup.py install for docopt 04:55:41 INFO - Successfully installed blobuploader requests docopt 04:55:41 INFO - Cleaning up... 04:55:41 INFO - Return code: 0 04:55:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:55:41 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')]} 04:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:41 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')]} 04:55:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:41 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 0x10394fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe033caec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038b3d30>, '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 0x103618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038abb20>, '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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 04:55:41 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 04:55:41 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 04:55:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRxthgMyhG/Render', 04:55:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:41 INFO - 'HOME': '/Users/cltbld', 04:55:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:41 INFO - 'LOGNAME': 'cltbld', 04:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:41 INFO - 'MOZ_NO_REMOTE': '1', 04:55:41 INFO - 'NO_EM_RESTART': '1', 04:55:41 INFO - 'PAGER': '/bin/cat', 04:55:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:41 INFO - 'PWD': '/builds/slave/test', 04:55:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:41 INFO - 'SHELL': '/bin/bash', 04:55:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cAeZ4kpQmH/Listeners', 04:55:41 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:55:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:41 INFO - 'USER': 'cltbld', 04:55:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:41 INFO - 'XPC_FLAGS': '0x0', 04:55:41 INFO - 'XPC_SERVICE_NAME': '0', 04:55:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:55:42 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-q4fAnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:55:42 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-av84CT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:55:42 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-GiZgWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:55:42 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-QaLr6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:55:42 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-c8QIbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:55:42 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-lMFejO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:55:42 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-0Xtuwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:55:42 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Mj8eLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:55:42 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-dZikoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:55:42 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-nniE33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:55:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:55:43 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-tS1FPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:55:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:55:43 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-yzvSfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:55:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:55:43 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-NglW_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:55:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:55:43 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-erLpCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:55:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:43 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-psvz2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:55:43 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-dcz5n2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:55:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:55:43 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-W_KPKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:55:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:55:43 INFO - Running setup.py install for manifestparser 04:55:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:55:43 INFO - Running setup.py install for mozcrash 04:55:44 INFO - Running setup.py install for mozdebug 04:55:44 INFO - Running setup.py install for mozdevice 04:55:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:55:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:55:44 INFO - Running setup.py install for mozfile 04:55:44 INFO - Running setup.py install for mozhttpd 04:55:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:55:44 INFO - Running setup.py install for mozinfo 04:55:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:55:44 INFO - Running setup.py install for mozInstall 04:55:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:55:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:55:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:55:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:55:44 INFO - Running setup.py install for mozleak 04:55:45 INFO - Running setup.py install for mozlog 04:55:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:55:45 INFO - Running setup.py install for moznetwork 04:55:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:55:45 INFO - Running setup.py install for mozprocess 04:55:45 INFO - Running setup.py install for mozprofile 04:55:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:55:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:55:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:55:45 INFO - Running setup.py install for mozrunner 04:55:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:55:45 INFO - Running setup.py install for mozscreenshot 04:55:46 INFO - Running setup.py install for moztest 04:55:46 INFO - Running setup.py install for mozversion 04:55:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:55:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:55:46 INFO - Cleaning up... 04:55:46 INFO - Return code: 0 04:55:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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')]} 04:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:46 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 0x10394fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe033caec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038b3d30>, '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 0x103618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038abb20>, '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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 04:55:46 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 04:55:46 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 04:55:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRxthgMyhG/Render', 04:55:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:46 INFO - 'HOME': '/Users/cltbld', 04:55:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:46 INFO - 'LOGNAME': 'cltbld', 04:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:46 INFO - 'MOZ_NO_REMOTE': '1', 04:55:46 INFO - 'NO_EM_RESTART': '1', 04:55:46 INFO - 'PAGER': '/bin/cat', 04:55:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:46 INFO - 'PWD': '/builds/slave/test', 04:55:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:46 INFO - 'SHELL': '/bin/bash', 04:55:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cAeZ4kpQmH/Listeners', 04:55:46 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:55:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:46 INFO - 'USER': 'cltbld', 04:55:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:46 INFO - 'XPC_FLAGS': '0x0', 04:55:46 INFO - 'XPC_SERVICE_NAME': '0', 04:55:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:55:46 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-b6JBrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:55:46 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)) 04:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:55:46 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-7ieUC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:55:46 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)) 04:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:55:46 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-BYKQB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:55:46 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)) 04:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:55:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-WDwMJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:55:47 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)) 04:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:55:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-vs03pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:55:47 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)) 04:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:55:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-XwtPZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:55:47 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)) 04:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:55:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-KeFU3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:55:47 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)) 04:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:55:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-KRflM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:55:47 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)) 04:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:55:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-YV25y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:55:47 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)) 04:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:55:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-nFbv79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:55:47 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)) 04:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:55:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-nU5jpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:55:47 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)) 04:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:55:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-DIwGfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:55:47 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)) 04:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:55:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-iPKJ0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:55:48 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)) 04:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:55:48 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-2XDkUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:55:48 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)) 04:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:48 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-G2A1kh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:48 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)) 04:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:55:48 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Wcfhpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:55:48 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)) 04:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:55:48 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-svCXYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:55:48 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)) 04:55:48 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)) 04:55:48 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)) 04:55:48 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)) 04:55:48 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)) 04:55:48 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)) 04:55:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:55:48 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 04:55:48 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 04:55:48 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 04:55:48 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 04:55:48 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 04:55:48 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 04:55:50 INFO - Downloading blessings-1.6.tar.gz 04:55:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:55:51 INFO - Installing collected packages: blessings 04:55:51 INFO - Running setup.py install for blessings 04:55:51 INFO - Successfully installed blessings 04:55:51 INFO - Cleaning up... 04:55:51 INFO - Return code: 0 04:55:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:55:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:55:51 INFO - Reading from file tmpfile_stdout 04:55:51 INFO - Current package versions: 04:55:51 INFO - blessings == 1.6 04:55:51 INFO - blobuploader == 1.2.4 04:55:51 INFO - docopt == 0.6.1 04:55:51 INFO - functools32 == 3.2.3-2 04:55:51 INFO - jsonschema == 2.5.1 04:55:51 INFO - manifestparser == 1.1 04:55:51 INFO - mozInstall == 1.12 04:55:51 INFO - mozcrash == 0.17 04:55:51 INFO - mozdebug == 0.1 04:55:51 INFO - mozdevice == 0.48 04:55:51 INFO - mozfile == 1.2 04:55:51 INFO - mozhttpd == 0.7 04:55:51 INFO - mozinfo == 0.9 04:55:51 INFO - mozleak == 0.1 04:55:51 INFO - mozlog == 3.3 04:55:51 INFO - moznetwork == 0.27 04:55:51 INFO - mozprocess == 0.23 04:55:51 INFO - mozprofile == 0.28 04:55:51 INFO - mozrunner == 6.12 04:55:51 INFO - mozscreenshot == 0.1 04:55:51 INFO - mozsystemmonitor == 0.3 04:55:51 INFO - moztest == 0.7 04:55:51 INFO - mozversion == 1.4 04:55:51 INFO - psutil == 3.1.1 04:55:51 INFO - requests == 1.2.3 04:55:51 INFO - wsgiref == 0.1.2 04:55:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:55:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:51 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 0x10394fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe033caec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038b3d30>, '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 0x103618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038abb20>, '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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 04:55:51 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 04:55:51 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 04:55:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRxthgMyhG/Render', 04:55:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:51 INFO - 'HOME': '/Users/cltbld', 04:55:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:51 INFO - 'LOGNAME': 'cltbld', 04:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:51 INFO - 'MOZ_NO_REMOTE': '1', 04:55:51 INFO - 'NO_EM_RESTART': '1', 04:55:51 INFO - 'PAGER': '/bin/cat', 04:55:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:51 INFO - 'PWD': '/builds/slave/test', 04:55:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:51 INFO - 'SHELL': '/bin/bash', 04:55:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cAeZ4kpQmH/Listeners', 04:55:51 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:55:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:51 INFO - 'USER': 'cltbld', 04:55:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:51 INFO - 'XPC_FLAGS': '0x0', 04:55:51 INFO - 'XPC_SERVICE_NAME': '0', 04:55:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:51 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)) 04:55:51 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)) 04:55:51 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)) 04:55:51 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)) 04:55:51 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)) 04:55:51 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)) 04:55:51 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)) 04:55:51 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)) 04:55:51 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)) 04:55:51 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)) 04:55:51 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)) 04:55:51 INFO - Cleaning up... 04:55:51 INFO - Return code: 0 04:55:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:55:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10394fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe033caec70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038b3d30>, '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 0x103618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038abb20>, '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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 04:55:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:55:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRxthgMyhG/Render', 04:55:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:51 INFO - 'HOME': '/Users/cltbld', 04:55:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:51 INFO - 'LOGNAME': 'cltbld', 04:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:51 INFO - 'MOZ_NO_REMOTE': '1', 04:55:51 INFO - 'NO_EM_RESTART': '1', 04:55:51 INFO - 'PAGER': '/bin/cat', 04:55:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:51 INFO - 'PWD': '/builds/slave/test', 04:55:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:51 INFO - 'SHELL': '/bin/bash', 04:55:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cAeZ4kpQmH/Listeners', 04:55:51 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:55:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:51 INFO - 'USER': 'cltbld', 04:55:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:51 INFO - 'XPC_FLAGS': '0x0', 04:55:51 INFO - 'XPC_SERVICE_NAME': '0', 04:55:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:55:52 INFO - Cleaning up... 04:55:52 INFO - Return code: 0 04:55:52 INFO - Running post-action listener: _resource_record_post_action 04:55:52 INFO - Running post-action listener: _start_resource_monitoring 04:55:52 INFO - Starting resource monitoring. 04:55:52 INFO - [mozharness: 2016-09-01 11:55:52.185446Z] Finished create-virtualenv step (success) 04:55:52 INFO - [mozharness: 2016-09-01 11:55:52.186436Z] Running install step. 04:55:52 INFO - Running pre-action listener: _resource_record_pre_action 04:55:52 INFO - Running main action method: install 04:55:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:55:52 INFO - Reading from file tmpfile_stdout 04:55:52 INFO - Detecting whether we're running mozinstall >=1.0... 04:55:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:55:52 INFO - Reading from file tmpfile_stdout 04:55:52 INFO - Output received: 04:55:52 INFO - Usage: mozinstall [options] installer 04:55:52 INFO - Options: 04:55:52 INFO - -h, --help show this help message and exit 04:55:52 INFO - -d DEST, --destination=DEST 04:55:52 INFO - Directory to install application into. [default: 04:55:52 INFO - "/builds/slave/test"] 04:55:52 INFO - --app=APP Application being installed. [default: firefox] 04:55:52 INFO - mkdir: /builds/slave/test/build/application 04:55:52 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'] 04:55:52 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 04:56:26 INFO - Reading from file tmpfile_stdout 04:56:26 INFO - Output received: 04:56:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:56:26 INFO - Running post-action listener: _resource_record_post_action 04:56:26 INFO - [mozharness: 2016-09-01 11:56:26.513359Z] Finished install step (success) 04:56:26 INFO - [mozharness: 2016-09-01 11:56:26.513481Z] Running run-tests step. 04:56:26 INFO - Running pre-action listener: _resource_record_pre_action 04:56:26 INFO - Running main action method: run_tests 04:56:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:56:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:56:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:56:26 INFO - 2016-09-01 04:56:26.527 screenresolution[2108:11710] starting screenresolution argv=screenresolution get 04:56:26 INFO - 2016-09-01 04:56:26.530 screenresolution[2108:11710] Display 0: 1600x1200x32@60 04:56:26 INFO - 2016-09-01 04:56:26.536 screenresolution[2109:11712] starting screenresolution argv=screenresolution list 04:56:26 INFO - Available Modes on Display 0 04:56:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:56:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:56:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:56:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:56:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:56:26 INFO - Intel Iris: 04:56:26 INFO - Chipset Model: Intel Iris 04:56:26 INFO - Type: GPU 04:56:26 INFO - Bus: Built-In 04:56:26 INFO - VRAM (Dynamic, Max): 1536 MB 04:56:26 INFO - Vendor: Intel (0x8086) 04:56:26 INFO - Device ID: 0x0a2e 04:56:26 INFO - Revision ID: 0x0009 04:56:26 INFO - Displays: 04:56:26 INFO - Display: 04:56:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:56:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:56:26 INFO - Display Serial Number: 1600x1200 60 04:56:26 INFO - Main Display: Yes 04:56:26 INFO - Mirror: Off 04:56:26 INFO - Online: Yes 04:56:26 INFO - Rotation: Supported 04:56:26 INFO - Return code: 0 04:56:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:56:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:56:26 INFO - Python 2.7.3 04:56:26 INFO - Return code: 0 04:56:26 INFO - grabbing minidump binary from tooltool 04:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:26 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 0x1038b3d30>, '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 0x103618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1038abb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:56:26 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 04:56:26 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 04:56:26 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 04:56:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:56:26 INFO - Return code: 0 04:56:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:56:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:56:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:56:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:56:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:56:26 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/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--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 04:56:26 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/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0216 --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 04:56:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRxthgMyhG/Render', 04:56:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:26 INFO - 'HOME': '/Users/cltbld', 04:56:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:26 INFO - 'LOGNAME': 'cltbld', 04:56:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:56:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:26 INFO - 'MOZ_NO_REMOTE': '1', 04:56:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:56:26 INFO - 'NO_EM_RESTART': '1', 04:56:26 INFO - 'PAGER': '/bin/cat', 04:56:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:26 INFO - 'PWD': '/builds/slave/test', 04:56:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:56:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:26 INFO - 'SHELL': '/bin/bash', 04:56:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cAeZ4kpQmH/Listeners', 04:56:26 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:56:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:26 INFO - 'USER': 'cltbld', 04:56:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:26 INFO - 'XPC_FLAGS': '0x0', 04:56:26 INFO - 'XPC_SERVICE_NAME': '0', 04:56:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:26 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/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--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 04:56:27 INFO - mozversion application_buildid: 20160901023821 04:56:27 INFO - mozversion application_changeset: 9d715b38e5f094c63df4fa560fd00b6ff6852f9b 04:56:27 INFO - mozversion application_display_name: Nightly 04:56:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:56:27 INFO - mozversion application_name: Firefox 04:56:27 INFO - mozversion application_remotingname: firefox 04:56:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:56:27 INFO - mozversion application_vendor: Mozilla 04:56:27 INFO - mozversion application_version: 51.0a1 04:56:27 INFO - mozversion platform_buildid: 20160901023821 04:56:27 INFO - mozversion platform_changeset: 9d715b38e5f094c63df4fa560fd00b6ff6852f9b 04:56:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:56:27 INFO - mozversion platform_version: 51.0a1 04:56:27 INFO - using testdate: 1472730987 04:56:27 INFO - actual date: 1472730987 04:56:27 INFO - starting webserver on 'localhost:49702' 04:56:27 INFO - SUITE-START | Running 2 tests 04:56:27 INFO - TEST-START | dromaeo_css 04:56:27 INFO - operating with platform_type : mac_ 04:56:27 INFO - Initialising browser for dromaeo_css test... 04:56:27 INFO - TEST-INFO | started process 2120 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpU9Wbf8/profile http://localhost:49702/getInfo.html) 04:56:29 INFO - PROCESS | 2120 | __metrics Screen width/height:1600/1200 04:56:29 INFO - PROCESS | 2120 | colorDepth:24 04:56:29 INFO - PROCESS | 2120 | Browser inner width/height: 1024/689 04:56:29 INFO - PROCESS | 2120 | __metrics 04:56:29 INFO - PROCESS | 2120 | 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" 04:56:29 INFO - PROCESS | 2120 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:56:30 INFO - TEST-INFO | 2120: exit 0 04:56:30 INFO - Browser initialized. 04:56:30 INFO - Running cycle 1/1 for dromaeo_css test... 04:56:30 INFO - TEST-INFO | started process 2122 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpU9Wbf8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:56:41 INFO - PROCESS | 2122 | RSS: Main: 233857024 04:56:41 INFO - PROCESS | 2122 | 04:57:33 INFO - PROCESS | 2122 | 1472731053582 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] 04:59:45 INFO - PROCESS | 2122 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49702/tests/dromaeo/cssquery-ext.html) 04:59:46 INFO - PROCESS | 2122 | RSS: Main: 357392384 04:59:46 INFO - PROCESS | 2122 | 05:02:49 INFO - PROCESS | 2122 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-ext.html (next: http://localhost:49702/tests/dromaeo/cssquery-jquery.html) 05:02:50 INFO - PROCESS | 2122 | RSS: Main: 369303552 05:02:50 INFO - PROCESS | 2122 | 05:05:52 INFO - PROCESS | 2122 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49702/tests/dromaeo/cssquery-mootools.html) 05:05:52 INFO - PROCESS | 2122 | RSS: Main: 363180032 05:05:52 INFO - PROCESS | 2122 | 05:08:55 INFO - PROCESS | 2122 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49702/tests/dromaeo/cssquery-prototype.html) 05:08:55 INFO - PROCESS | 2122 | RSS: Main: 342011904 05:08:55 INFO - PROCESS | 2122 | 05:11:58 INFO - PROCESS | 2122 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49702/tests/dromaeo/cssquery-yui.html) 05:11:59 INFO - PROCESS | 2122 | RSS: Main: 329564160 05:11:59 INFO - PROCESS | 2122 | 05:15:02 INFO - PROCESS | 2122 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-yui.html (next: http://localhost:49702/tests/dromaeo/cssquery-dojo.html) 05:15:03 INFO - PROCESS | 2122 | RSS: Main: 574337024 05:15:03 INFO - PROCESS | 2122 | 05:15:03 INFO - PROCESS | 2122 | __start_tp_report 05:15:03 INFO - PROCESS | 2122 | _x_x_mozilla_page_load 05:15:03 INFO - PROCESS | 2122 | _x_x_mozilla_page_load_details 05:15:03 INFO - PROCESS | 2122 | |i|pagename|runs| 05:15:03 INFO - PROCESS | 2122 | |0;dojo.html;2751.944961100778;3232.095013396249;3272.8690852365908;3297.5713157289547;3331.783434076785;5446.945530544694;5457.836264912054;5526.889462210754;5578.386377498477;5584.106542953128;5464.9453505464935;5467.535259502937;5526.834194974157;5538.501534861862;5604.747786349614;5024.924626130611;5033.84898453047;5080.77136528857;5086.720230387327;5209.244659524366;5136.871578210537;5169.974150129263;5172.32759741235;5196.688198708071;5208.817691380802;4972.105021096201;5055.873603159914;5066.316047333618;5071.96024814912;5148.407933087706;4979.228219625964;5112.079825631385;5116.820911268088;5120.436751957282;5126.154184559543;5007.799688012475;5010.69935803853;5070.645054846163;5099.974500127512;5108.667936584147;4779.091972525209;5379.757910894019;5448.509634132907;5524.972375138099;5567.4432556744405;5614.3543492498475;6048.939510604881;6079.756809727605;6090.512758979316;6098.298695649968;5962.25471816023;6080.513558915276;6081.239845019372;6182.1345011698395;6191.783287584913;5637.774489020475;5653.293338332708;5654.15187721845;5703.971480142573;5714.657120572779;5906.645601263938;6274.592151510137;6281.780137695158;6283.622982621057;6304.338044505353;5628.0713682242795;5830.416958304136;5877.794277200277;5885.14665373527;5947;6083.148359229712;6095.3904609538695;6097.6033431105;6100.633961962297;6129.908051379233;5977.372375900555;5984.7606095756555;5990.9101363479585;5992.131140984533;5998.790042348497;5859.150423188699;5905.852353691193;5939.70301484924;5994.970025149846;6023.909641355295;4755.904881902343;4817.301491283744;4828.058528586962;4857.319975203474;4857.53853383928;4291.527931927485;4306.418633484503;4321.481422229353;4334.804933777988;4365.126974605092;5478.178273258964;5546.4453554644215;5548.91676624851;5602.691851948182;5612.35457922336;6178.258608966953;6187.226596675416;6191.4118158774845;6197.81406557804;6202.534809889276;5613.438656134354;5622.297212848394;5641.238432811518;5649.12438572022;5684.431556844365;4486.798094085774;5198.948010519922;5248.816291429858;5251.684898906078;5324.387695414999;6163.753449862134;6203.348648391854;6221.564490485623;6240.656763878031;6277.497800175884;6119.495348744332;6140.201773769381;6140.289530736458;6154.538409619386;6160.691965401802;6238.407351301619;6280.309165991646;6280.874382512233;6287.685615719287;6295.622262664255;5798.362180160095;5880.617759845596;5950.464458198783;5981.581289309706;6002.069679199731;4927.457979622167;5909.202257695156;6036.7283472244735;6144.446999770043;6168.722407491584;5824.126381042877;5875.735591898289;5878.529717622666;5894.997850365362;5913.763449461973;5734.455226753452;5851.180834683233;5872.765089396376;5880.588358814842;5897.764089436375;5646.491815736604;5680.886382272414;5700.173474846207;5753.769849205984;5803.303603567599;5835.56233282514;5859.589828711949;5864.44287792659;5865.530757539472;5865.882682346244;5709.0865461525955;5722.313322401338;5725.599208055396;5728.541716662574;5734.655920644775 05:15:03 INFO - PROCESS | 2122 | |1;ext.html;481.3525807788523;496.92297693857455;502.65539681352374;508.57788035930184;525.3249574297027;30166.743307275523;39097.609023909754;40222.1955560889;40350.39474407886;42029;41365.58634413655;41706;41807.954801129985;41846.37230441546;41874.79062604683;8601.698940537082;9489.193418559431;9676.951615241915;9689.32174747768;9691.370060946052;8603.784905377353;11691.941540292286;11975;12011.918927296541;12188.085893558018;10351.17190624752;11591.188616796828;11949.820752688716;12016;12024.398780061005;11876.7624647507;11954.581589644407;11960.222585531912;11974.580889668863;11976.820347694704;11757.764844703059;11843;11865.88134118665;11867.406629668481;11906.1070419718;39397;39565.010874728076;39579.40630890539;39582.0104497387;39639;3289.8355082245794;3302.108430723691;3387.8983630491125;3509.350770107538;3526.453399723047;29837.70162298371;30913.536296955564;30931.381372372427;30978;31035;3212.1166679163184;3219.8873039443506;3243;3309.4373956427467;3341.63242043375;460447.6977615124;469925;470629;471629.5674086499;473083.6345818283;53264.86940522413;53580.73209633927;53608.46391536035;53642.46357536453;53658.463415366125;58232;58337.41662583405;58358.54103647358;58424.12363814546;58572.41427585755;15503.844961550465;15674.216289185495;15707.528774136794;15744.448944286894;15747.527574172793;9159.816803664022;11231.932966368182;11250.381229032482;12247.93876030632;12328.19866708661;13391.397387117602;13513.594592162252;14775.778363324343;15136.697266054618;15144.848551514564;5490.149026900836;6313.547883986617;6415.711292991827;6437.935620643827;6447.1618689571;829.324100857799;962.5139304651107;982.0768477631004;999.3504222255592;1000.5697550053546;24331.513369732507;29922.092959419708;30422.54366184509;31237.50099991995;31659;800.9799755006171;956.1346980982083;971.7279161834698;988.8615593816871;1019.6380284998719;13913.652158696113;14320.427182912776;14724.822014238835;14826.703465930621;15018.624534386729;576532;582037;584258;584421;692831;54924.62688432821;55841.72079139578;56070.71964640151;58946.70526647425;59283;1316.4602512969636;1332.6273937844037;1353.2489468345163;1365.822443082393;1368.0423703407655;27923.581146282417;29575.556366654087;29698.40603187881;30242.007768151285;30366;47894;48198.518014819405;48277.793055173905;48312.5168748308;48364.758176208896;594961;598286;598313;598864;598885;23927.641085384083;25025.374365641004;25042.37394065163;25082.74917250877;25114;30411.63147658405;36212;36983.26033479369;43730.78134609306;45772.54227457683;36145.81927090348;36327.09182270464;36361.63638363582;37206.81396593;37410.8129459351;36102.63897360993;36466.27067458689;36925;37552.061198470255;37766.62233377631;35463.82268088643;36002.27995440129;36210.4568431479;36553;36626.08434789152;31619.683803161675;33115.66884331126;33157.33685326328;33237.50143747894;33314.33371332511;24089.277321680376;24315.148969785972;24556.75443245616;25004.49991000206;25077.498450031264 05:15:03 INFO - PROCESS | 2122 | |2;jquery.html;321.87607771008163;351.947207918812;372.5901508340825;382.99042523936896;406.890139662291;14942.25288735563;16751.49745507635;19859;20422.183112675513;20548.38354849355;19826.900865495652;20043.198272069138;20656.586868262624;20852;20961.266355677555;5442.374126975389;5803.564732645047;6046.00240960241;6047.667378294192;6052.788152414666;5875.17747515346;6059.848503787396;6061.909071363933;6061.9393806062035;6066.241719785027;5672.432756724335;5880.26496687914;5942.376050514698;6001.5198784097165;6006.609570377933;5847.26909136358;5875.970620146914;5884.852878678024;5891.322497912752;5895.764169433218;4909.705417674951;5106.923396149061;5185.948140518584;5191.714455704935;5195.9480405195845;24918.875405623035;25432.982680692934;25472.108476203244;25520.23439296824;25821.83801728927;1936.7579052618423;2164.448065743241;2355.470019245673;2632.4603456291416;3078.184281165493;14713.337899794535;15042.247887605685;15238.771418428732;15492.915495915291;15781.68436631261;1528.289345454358;1791.7849858016991;1865.1047497201419;1879.6146789908173;1894.8578856585812;115540.25865665439;121184.99404525536;121333.02228156396;121786.05059015198;122140.80790107718;9077.273818094536;9196.954015229881;10366.740831479274;10500.317479363817;11008.119350451945;13707.588772336845;13720.313984300945;13722.245276509888;13732.940058991231;13746.312684365741;6990.545614535039;8172.101068882418;8443;8463.06906240313;8618.870716939251;8452.8732069019;8484.618192181366;8522.57387130641;8678.86981695275;8725.563721813884;5613.635113717595;5892.793752218652;6140.385961403824;6646.933530664632;6682.064510968371;1812.8821626594229;1827.1321122466989;1827.5339788354152;1830.7093499083114;1847.7505536752367;696.6725638949686;913.7441516375426;922.8246633139682;962.4465932089076;994.229472159068;21057.89471052635;21117.155313787276;21863.781362186175;21956.560868782533;22024.898755062182;853.4111463090447;869.4696235296464;879.9868001979976;916.5371487398954;917.1058218237299;8872;11440.141989350666;11597;11612.361320127306;11614.303141811519;485182;495109.9645533669;497452.5382119271;578875;591240;133592.3320383392;142044;142368;142830;144221.27889360487;929.6746138851348;934.2059249637754;983.9557219925264;1000.3847633705219;1001.9398836069859;18712.438626841216;19462.61074778525;19849.40451786449;19874.602507949472;19921.501962451053;56238.43761562496;56506;57354.13968790555;57686.711566441896;57812.132818008606;77197;79002;82076.58961705153;82456.17543824486;82821.3435731294;7471.813204669927;7577.621118944141;8042.276195142465;8219;8279.793005174919;11635.650930472098;11861.5848445304;12710.30093344838;12888.613341599766;13282.667933301746;10759.515821787882;10939.507722152362;11154.49804758804;11446.45472861153;11494.195406321478;11046.447677615926;11141.387223702774;11453.02649274821;11543.76912461763;11548.422578870855;11174.385408802595;11347.48936297852;11526.82709759371;11761.411929403666;11870.762584748429;7930.048394192734;7955.4828936118965;8181.918180818116;8372.864919445503;8382.245597896219;9338.813223735624;11220.607278745205;11234.988851003449;11337.433128343386;11460.312381256817 05:15:03 INFO - PROCESS | 2122 | |3;mootools.html;1535.761956896681;1545.2582760275068;1774.1572752942354;1854.8237917397842;1937.3316205908957;8519.659213631456;9044.547772611368;9051.094890510945;9060.818783624323;9073.22877555407;7936.682532698699;8093.716719914804;8582.48505089695;8752.081031491682;8843.11568843117;2380.821438392123;2416.1060407649106;2416.6495858100566;2418.2866054513875;2420.4674971506242;2394.928152155438;2418.600930846408;2434.8782560871973;2436.293474892288;2443.108265483095;2412.1436889904094;2416.5770990076667;2422.588160012802;2437.524682686877;2441.9023239070416;2390.34265576966;2401.11158871217;2410.554047501209;2411.05968672218;2416.4321384474633;1991.75103112111;2392.3899405651446;2398.8800559972105;2401.087586717036;2401.231605886117;8725.825483490295;8836.646534138627;8848.557572121368;8851.867221991675;8856.557172141367;5198.246254293144;5355.705436201008;5356.705381204032;5360.195970604441;5387.19192121181;3454.101933497309;3481.234128491728;3536.5932917714276;3537.823108844573;3539.327527769741;1050.369778133114;1056.915446764257;1060.8408738689182;1084.208527774721;1086.4784903246486;11574.958253757202;11709.94610485052;11713.180077394585;11738.706532336675;11767.411629418495;4566.223741963808;4691.272852707836;4835.564799168092;4843.297721830315;4843.709377437294;9924.553395097239;9935.05616966387;9953;9956.054174853378;9965.601375945025;5382.434844341287;5387.218853266255;5389.51494365509;5406.567474602022;5407.297051383295;4446.644268458515;4610.838620648261;4804.111239420648;4926.58124059459;4949.48030456804;3363.360961417323;3877.1857909838686;3913.8434462621176;4027.5368332641533;4035.899102522402;1273.229696033886;1287.5557932513268;1296.9610911672664;1310.8689131086815;1311.5212947274367;742.9145648250412;828.0480088484534;838.4005436113208;915.3683958068912;941.7316064921464;5287.524122828964;5653.434656534314;6027.849303767441;6091.390860913873;6231.75072997075;897.3808072430003;921.1847514949318;929.4423345992351;933.244072301438;937.7280588629358;3848.749831260959;3899.980500097481;3901.180752042046;4001.299772539794;4002.3796311571755;8597.18326758957;8890.511021893728;10094.091531762177;10486.632967846088;10517.053465188017;10477.3189742668;10482.68551943443;10488.790224195473;10492.632757853438;10501.159907207406;7747.457677962601;8236.341092712568;8366.49801011929;8373.665053397917;8408.033076196194;5821.563382746226;5980.102984552352;6046.183765191643;6108.297545782292;6149.72326245329;8236.299914507334;9389.342746007715;9885.456299903575;10138.898611013796;10315.845262321222;9852.802943941224;10120.59517619287;10306.381617102998;10399.480025998519;10421.322614030063;7318.414526837947;7476.476646634684;7524.736634217776;7605.923940760522;7686.46194766361;8454.915450845412;8521.914780852112;8533.06136325016;8539.82920341602;8541.701040463555;6450.258220304725;7446.888296675663;7516.173220945582;7525.6989720410575;7687.423443241667;7292.41660667156;7343.44924130703;7384.667689954073;7384.778456646309;7482.663280152297;6911.7926462206215;7033.824154396181;7043.788686339418;7066.57600543969;7069.575825450489;4889.982815231594;5009.649324547247;5031.597472202142;5079.6952182869145;5082.517160869711;4917.508249175054;4961.181405068123;4969.35398398206;5117.769700363418;5237.476252374732 05:15:03 INFO - PROCESS | 2122 | |4;prototype.html;248.57617761716276;291.42879955287634;309.56196981271495;312.4578855685387;312.904564107947;4654.3716598259225;5165.04446677364;5463.78144874205;5479.301416560869;5486.451354864512;4303.376010478487;5147.7426128693605;5326.893462130764;5331.626786124973;5355;4037.5962403759536;4046;4053.756774593534;4061.928416507588;4077.571854955224;3722.1625134344827;3951.170254246612;4060.9593904060876;4077.2864748669012;4119.443875076871;3254.8047117173046;3479.5824501059783;3778.9433158502643;3964.583718709537;4023.135025969416;3683.876417692616;3713.275911197318;4055.641360144355;4057.6001279558504;4062.6546743526687;3540.5220295260324;3540.8937731868086;3551.289742051574;3568.982155089208;3571.2143328467696;5023.648344615878;5112.935216502852;5242.1088414969545;5244.29202057725;5249.291345668324;4626.768661566908;4639.443266808004;4647.676911008645;4649.698084536335;4672.112298663243;4840.927386089212;5615.606907516475;5624.184493248497;5638.407967163471;5694.886102277931;3661.4141737322166;3868.864589739346;3905.4141878718137;3908.6482216600643;3923.1565213478953;5126.794928202868;5885.470307672287;5888.499477544414;5892.793752218695;5960.284765828085;3276.7050965413227;3935.940960885589;3943.7830919299713;3988.900277493086;3995.640392364643;3375.641236969231;3665.596784353719;3670.5684782934677;3938.822752976123;4057.594240575919;4576.336431217454;4578.092911001538;4583.335416364675;4626.167289887852;4635.072985402933;4974.27872958419;5703.944770217523;5743.62666426681;5771;5831.854203644858;1002.7693630465034;1115.5219334381977;1168.9181757276908;1179.5399794080315;1317.8089177069462;879.3404946290283;888.1606881496923;900.4822227219375;908.5775114571755;909.5725009245776;4439.733615983051;4923.359963204832;5310.60170487215;5313.8937221255355;5357.8392648220615;5036.398974733083;5325.174597937326;5767.769289228384;5768.826935191951;5789.88420231593;4888.573683211633;5079.593632509391;5300.496452836975;5302.973485132549;5321.73391330432;2272.8181745460324;2417.3650720714504;2572.6655534780357;2575.8325708829243;2579.638850560923;4379.562043795595;4534.093181363741;4695.483496815347;4746.125280514178;5051.686561494038;4329.683619263742;4376.8182590700335;4953;4972.378452693413;4990.800367985313;4253.702240843111;4285.5071666758095;4332.090261045216;5027.572656324253;5124;4594.632429405707;4830.830920917751;4831.444383895897;4859.854204373874;4895.853124406273;4200.768957707355;4547.386102876069;4588.241648702907;4654.348391225299;4810.326554282333;4375;4800.783964721526;5025.065349840411;5058.9494105058475;5100.795968161232;2764.8617569121866;3256;3339.3970893971514;3359.3617212729505;3368.8545894396025;4332.3753592402845;4762.666293516221;4881.291547958186;4923.212286034217;5300.203315999027;4877.634177436636;4898.2897479864505;5608.317504748641;5641.379448260606;5683;4879.829205977774;5160;5612.298462692163;5631.408702086222;5653.858653533694;5159.355080614923;5253.317068781034;5303;5479.945200547943;5534.149912794995;5657.547396208376;5657.708628005665;5659.943400566106;5665.178549110438;5665.575081868794;5511.8149597836655;5550.861228469159;5567.830755541382;5568.888622227451;5574.9721251393485 05:15:03 INFO - PROCESS | 2122 | |5;yui.html;700.5971566349349;711.4415184080494;759.7189040055181;793.626995312203;810.4812919731373;15688;16237;16272.26774795134;16277.755833662504;16288.18559072046;14157.71684566308;14263.21552314625;14273.4290628375;14280.000399972007;14760.114393136419;668.7425341243608;688.5042769206188;828.693383448125;835.7033253195119;854.3378881366959;831.2020460358063;839.836231934773;843.2663582683074;843.3169133002257;843.6498852976008;887.2458410351214;887.3256325192867;887.7070566712969;888.1651247827054;888.9599968001455;685.8628274345151;869.5304535550795;883.9867401988976;887.8623813308948;907.1336873285996;569.3509399284818;668.682375871462;668.7993601919453;669.2604671837647;670.0752960913957;14493.927530362385;14510.709785804225;14519.854801452062;14558.85441145596;14591.78112328316;13257;13485.325733713373;13668.384922678504;13708;13851.722965540634;11907.106966977512;12296.631101066892;12353.197042192229;12360.196587221802;12864.678383040498;4421.557844215585;4423;4508.030773383705;4614.700044497097;4622.2835460503675;113607.43196284048;115353.4232328833;116056.09859753489;116388;117825.64348713064;10568.340548626526;10670.839937400946;10691.946540267405;10732.73168170802;11031.338119712844;20747.481312967295;20755.273565425137;20766.58466830655;20783.272585459436;20791.376258712262;7636.656350464243;7676.654550545239;7826.491278066907;7854.253845884746;7897.447178697436;4041.3533834586324;4242.809073591696;4286.350809446025;4333.783310834446;4359.694821362537;3368.545246391755;3824.0631045394057;3982.820773065219;3993.940090898639;3997.4603428537357;579.342446323426;594.2423410152004;687.161662771418;691.4295706042535;706.491326245113;576.8067697321444;601.8766152938637;656.7077650445506;660.3642430140387;672.9730810767514;7423.7379645460405;9364.765880853032;9394.316418739;9484.146426821619;9628.518574071268;536.9382521010056;566.7874547044859;570.3070769015666;611.9020956646915;650.5868773328875;4356.324769660644;4391.934120988188;4644.373009643722;4707.976460117678;4767.237242041326;115779.42110289563;125558;130397;144886.2755686236;155521;86485;117767.82232177739;132224;132888.3355583216;138715;77971.61014194893;85123.57438212894;90203.19593608091;101671.49164254131;107167;9632.325737198329;9917.265079507093;9972.650957216463;10020.248481364073;10124.69625911224;60731.69634151801;60848.39151608427;63150.052749207876;67423.66288168528;67600.6619966897;72041.6397918007;95158;101117.49441252746;102168.97831021594;102247;13563;13639.386227619583;13908.860911390757;13947.860521394656;13954.651133721738;14915.179665118523;14917.47788827386;14940.7758883619;15142.290005296707;15441.305141268895;13748.418777453493;13793.448262069805;13955.860441395456;14082.929585352007;14313.141211527342;13562.864371356554;13715.657108572366;13972.371243293874;13994.650133746738;14085.929570352082;13296.401661925043;13526.729465410834;13670.453181872614;13933.72132557323;14096.295185240902;9553.283503737386;11258.493367798304;11429.028532574825;11675.591354302558;11953.820692689442;9605.372293117727;10183.898161018591;11220.831687524857;11496.310221386744;11717.531298748296 05:15:03 INFO - PROCESS | 2122 | __end_tp_report 05:15:03 INFO - PROCESS | 2122 | __start_cc_report 05:15:03 INFO - PROCESS | 2122 | _x_x_mozilla_cycle_collect,1203 05:15:03 INFO - PROCESS | 2122 | __end_cc_report 05:15:03 INFO - PROCESS | 2122 | __startTimestamp1472732103128__endTimestamp 05:15:03 INFO - PROCESS | 2122 | 05:15:03 INFO - PROCESS | 2122 | ------- Summary: start ------- 05:15:03 INFO - PROCESS | 2122 | Number of tests: 6 05:15:03 INFO - PROCESS | 2122 | 05:15:03 INFO - PROCESS | 2122 | [#0] dojo.html Cycles:165 Average:5573.84 Median:5722.31 stddev:641.30 (11.2%) stddev-sans-first:603.86 05:15:03 INFO - PROCESS | 2122 | Values: 2751.9 3232.1 3272.9 3297.6 3331.8 5446.9 5457.8 5526.9 5578.4 5584.1 5464.9 5467.5 5526.8 5538.5 5604.7 5024.9 5033.8 5080.8 5086.7 5209.2 5136.9 5170.0 5172.3 5196.7 5208.8 4972.1 5055.9 5066.3 5072.0 5148.4 4979.2 5112.1 5116.8 5120.4 5126.2 5007.8 5010.7 5070.6 5100.0 5108.7 4779.1 5379.8 5448.5 5525.0 5567.4 5614.4 6048.9 6079.8 6090.5 6098.3 5962.3 6080.5 6081.2 6182.1 6191.8 5637.8 5653.3 5654.2 5704.0 5714.7 5906.6 6274.6 6281.8 6283.6 6304.3 5628.1 5830.4 5877.8 5885.1 5947.0 6083.1 6095.4 6097.6 6100.6 6129.9 5977.4 5984.8 5990.9 5992.1 5998.8 5859.2 5905.9 5939.7 5995.0 6023.9 4755.9 4817.3 4828.1 4857.3 4857.5 4291.5 4306.4 4321.5 4334.8 4365.1 5478.2 5546.4 5548.9 5602.7 5612.4 6178.3 6187.2 6191.4 6197.8 6202.5 5613.4 5622.3 5641.2 5649.1 5684.4 4486.8 5198.9 5248.8 5251.7 5324.4 6163.8 6203.3 6221.6 6240.7 6277.5 6119.5 6140.2 6140.3 6154.5 6160.7 6238.4 6280.3 6280.9 6287.7 6295.6 5798.4 5880.6 5950.5 5981.6 6002.1 4927.5 5909.2 6036.7 6144.4 6168.7 5824.1 5875.7 5878.5 5895.0 5913.8 5734.5 5851.2 5872.8 5880.6 5897.8 5646.5 5680.9 5700.2 5753.8 5803.3 5835.6 5859.6 5864.4 5865.5 5865.9 5709.1 5722.3 5725.6 5728.5 5734.7 05:15:03 INFO - PROCESS | 2122 | 05:15:03 INFO - PROCESS | 2122 | [#1] ext.html Cycles:180 Average:68251.20 Median:36178.14 stddev:149987.54 (414.6%) stddev-sans-first:150321.99 05:15:03 INFO - PROCESS | 2122 | Values: 481.4 496.9 502.7 508.6 525.3 30166.7 39097.6 40222.2 40350.4 42029.0 41365.6 41706.0 41808.0 41846.4 41874.8 8601.7 9489.2 9677.0 9689.3 9691.4 8603.8 11691.9 11975.0 12011.9 12188.1 10351.2 11591.2 11949.8 12016.0 12024.4 11876.8 11954.6 11960.2 11974.6 11976.8 11757.8 11843.0 11865.9 11867.4 11906.1 39397.0 39565.0 39579.4 39582.0 39639.0 3289.8 3302.1 3387.9 3509.4 3526.5 29837.7 30913.5 30931.4 30978.0 31035.0 3212.1 3219.9 3243.0 3309.4 3341.6 460447.7 469925.0 470629.0 471629.6 473083.6 53264.9 53580.7 53608.5 53642.5 53658.5 58232.0 58337.4 58358.5 58424.1 58572.4 15503.8 15674.2 15707.5 15744.4 15747.5 9159.8 11231.9 11250.4 12247.9 12328.2 13391.4 13513.6 14775.8 15136.7 15144.8 5490.1 6313.5 6415.7 6437.9 6447.2 829.3 962.5 982.1 999.4 1000.6 24331.5 29922.1 30422.5 31237.5 31659.0 801.0 956.1 971.7 988.9 1019.6 13913.7 14320.4 14724.8 14826.7 15018.6 576532.0 582037.0 584258.0 584421.0 692831.0 54924.6 55841.7 56070.7 58946.7 59283.0 1316.5 1332.6 1353.2 1365.8 1368.0 27923.6 29575.6 29698.4 30242.0 30366.0 47894.0 48198.5 48277.8 48312.5 48364.8 594961.0 598286.0 598313.0 598864.0 598885.0 23927.6 25025.4 25042.4 25082.7 25114.0 30411.6 36212.0 36983.3 43730.8 45772.5 36145.8 36327.1 36361.6 37206.8 37410.8 36102.6 36466.3 36925.0 37552.1 37766.6 35463.8 36002.3 36210.5 36553.0 36626.1 31619.7 33115.7 33157.3 33237.5 33314.3 24089.3 24315.1 24556.8 25004.5 25077.5 05:15:03 INFO - PROCESS | 2122 | 05:15:03 INFO - PROCESS | 2122 | [#2] jquery.html Cycles:180 Average:33856.20 Median:14227.15 stddev:89926.95 (632.1%) stddev-sans-first:90143.97 05:15:03 INFO - PROCESS | 2122 | Values: 321.9 351.9 372.6 383.0 406.9 14942.3 16751.5 19859.0 20422.2 20548.4 19826.9 20043.2 20656.6 20852.0 20961.3 5442.4 5803.6 6046.0 6047.7 6052.8 5875.2 6059.8 6061.9 6061.9 6066.2 5672.4 5880.3 5942.4 6001.5 6006.6 5847.3 5876.0 5884.9 5891.3 5895.8 4909.7 5106.9 5185.9 5191.7 5195.9 24918.9 25433.0 25472.1 25520.2 25821.8 1936.8 2164.4 2355.5 2632.5 3078.2 14713.3 15042.2 15238.8 15492.9 15781.7 1528.3 1791.8 1865.1 1879.6 1894.9 115540.3 121185.0 121333.0 121786.1 122140.8 9077.3 9197.0 10366.7 10500.3 11008.1 13707.6 13720.3 13722.2 13732.9 13746.3 6990.5 8172.1 8443.0 8463.1 8618.9 8452.9 8484.6 8522.6 8678.9 8725.6 5613.6 5892.8 6140.4 6646.9 6682.1 1812.9 1827.1 1827.5 1830.7 1847.8 696.7 913.7 922.8 962.4 994.2 21057.9 21117.2 21863.8 21956.6 22024.9 853.4 869.5 880.0 916.5 917.1 8872.0 11440.1 11597.0 11612.4 11614.3 485182.0 495110.0 497452.5 578875.0 591240.0 133592.3 142044.0 142368.0 142830.0 144221.3 929.7 934.2 984.0 1000.4 1001.9 18712.4 19462.6 19849.4 19874.6 19921.5 56238.4 56506.0 57354.1 57686.7 57812.1 77197.0 79002.0 82076.6 82456.2 82821.3 7471.8 7577.6 8042.3 8219.0 8279.8 11635.7 11861.6 12710.3 12888.6 13282.7 10759.5 10939.5 11154.5 11446.5 11494.2 11046.4 11141.4 11453.0 11543.8 11548.4 11174.4 11347.5 11526.8 11761.4 11870.8 7930.0 7955.5 8181.9 8372.9 8382.2 9338.8 11220.6 11235.0 11337.4 11460.3 05:15:03 INFO - PROCESS | 2122 | 05:15:03 INFO - PROCESS | 2122 | [#3] mootools.html Cycles:180 Average:5699.59 Median:5081.11 stddev:3150.61 (62.0%) stddev-sans-first:3143.91 05:15:03 INFO - PROCESS | 2122 | Values: 1535.8 1545.3 1774.2 1854.8 1937.3 8519.7 9044.5 9051.1 9060.8 9073.2 7936.7 8093.7 8582.5 8752.1 8843.1 2380.8 2416.1 2416.6 2418.3 2420.5 2394.9 2418.6 2434.9 2436.3 2443.1 2412.1 2416.6 2422.6 2437.5 2441.9 2390.3 2401.1 2410.6 2411.1 2416.4 1991.8 2392.4 2398.9 2401.1 2401.2 8725.8 8836.6 8848.6 8851.9 8856.6 5198.2 5355.7 5356.7 5360.2 5387.2 3454.1 3481.2 3536.6 3537.8 3539.3 1050.4 1056.9 1060.8 1084.2 1086.5 11575.0 11709.9 11713.2 11738.7 11767.4 4566.2 4691.3 4835.6 4843.3 4843.7 9924.6 9935.1 9953.0 9956.1 9965.6 5382.4 5387.2 5389.5 5406.6 5407.3 4446.6 4610.8 4804.1 4926.6 4949.5 3363.4 3877.2 3913.8 4027.5 4035.9 1273.2 1287.6 1297.0 1310.9 1311.5 742.9 828.0 838.4 915.4 941.7 5287.5 5653.4 6027.8 6091.4 6231.8 897.4 921.2 929.4 933.2 937.7 3848.7 3900.0 3901.2 4001.3 4002.4 8597.2 8890.5 10094.1 10486.6 10517.1 10477.3 10482.7 10488.8 10492.6 10501.2 7747.5 8236.3 8366.5 8373.7 8408.0 5821.6 5980.1 6046.2 6108.3 6149.7 8236.3 9389.3 9885.5 10138.9 10315.8 9852.8 10120.6 10306.4 10399.5 10421.3 7318.4 7476.5 7524.7 7605.9 7686.5 8454.9 8521.9 8533.1 8539.8 8541.7 6450.3 7446.9 7516.2 7525.7 7687.4 7292.4 7343.4 7384.7 7384.8 7482.7 6911.8 7033.8 7043.8 7066.6 7069.6 4890.0 5009.6 5031.6 5079.7 5082.5 4917.5 4961.2 4969.4 5117.8 5237.5 05:15:03 INFO - PROCESS | 2122 | 05:15:03 INFO - PROCESS | 2122 | [#4] prototype.html Cycles:180 Average:4345.81 Median:4805.56 stddev:1342.84 (27.9%) stddev-sans-first:1310.92 05:15:03 INFO - PROCESS | 2122 | Values: 248.6 291.4 309.6 312.5 312.9 4654.4 5165.0 5463.8 5479.3 5486.5 4303.4 5147.7 5326.9 5331.6 5355.0 4037.6 4046.0 4053.8 4061.9 4077.6 3722.2 3951.2 4061.0 4077.3 4119.4 3254.8 3479.6 3778.9 3964.6 4023.1 3683.9 3713.3 4055.6 4057.6 4062.7 3540.5 3540.9 3551.3 3569.0 3571.2 5023.6 5112.9 5242.1 5244.3 5249.3 4626.8 4639.4 4647.7 4649.7 4672.1 4840.9 5615.6 5624.2 5638.4 5694.9 3661.4 3868.9 3905.4 3908.6 3923.2 5126.8 5885.5 5888.5 5892.8 5960.3 3276.7 3935.9 3943.8 3988.9 3995.6 3375.6 3665.6 3670.6 3938.8 4057.6 4576.3 4578.1 4583.3 4626.2 4635.1 4974.3 5703.9 5743.6 5771.0 5831.9 1002.8 1115.5 1168.9 1179.5 1317.8 879.3 888.2 900.5 908.6 909.6 4439.7 4923.4 5310.6 5313.9 5357.8 5036.4 5325.2 5767.8 5768.8 5789.9 4888.6 5079.6 5300.5 5303.0 5321.7 2272.8 2417.4 2572.7 2575.8 2579.6 4379.6 4534.1 4695.5 4746.1 5051.7 4329.7 4376.8 4953.0 4972.4 4990.8 4253.7 4285.5 4332.1 5027.6 5124.0 4594.6 4830.8 4831.4 4859.9 4895.9 4200.8 4547.4 4588.2 4654.3 4810.3 4375.0 4800.8 5025.1 5058.9 5100.8 2764.9 3256.0 3339.4 3359.4 3368.9 4332.4 4762.7 4881.3 4923.2 5300.2 4877.6 4898.3 5608.3 5641.4 5683.0 4879.8 5160.0 5612.3 5631.4 5653.9 5159.4 5253.3 5303.0 5479.9 5534.1 5657.5 5657.7 5659.9 5665.2 5665.6 5511.8 5550.9 5567.8 5568.9 5575.0 05:15:03 INFO - PROCESS | 2122 | 05:15:03 INFO - PROCESS | 2122 | [#5] yui.html Cycles:180 Average:24120.19 Median:3903.44 stddev:37685.91 (965.5%) stddev-sans-first:37750.60 05:15:03 INFO - PROCESS | 2122 | Values: 700.6 711.4 759.7 793.6 810.5 15688.0 16237.0 16272.3 16277.8 16288.2 14157.7 14263.2 14273.4 14280.0 14760.1 668.7 688.5 828.7 835.7 854.3 831.2 839.8 843.3 843.3 843.6 887.2 887.3 887.7 888.2 889.0 685.9 869.5 884.0 887.9 907.1 569.4 668.7 668.8 669.3 670.1 14493.9 14510.7 14519.9 14558.9 14591.8 13257.0 13485.3 13668.4 13708.0 13851.7 11907.1 12296.6 12353.2 12360.2 12864.7 4421.6 4423.0 4508.0 4614.7 4622.3 113607.4 115353.4 116056.1 116388.0 117825.6 10568.3 10670.8 10691.9 10732.7 11031.3 20747.5 20755.3 20766.6 20783.3 20791.4 7636.7 7676.7 7826.5 7854.3 7897.4 4041.4 4242.8 4286.4 4333.8 4359.7 3368.5 3824.1 3982.8 3993.9 3997.5 579.3 594.2 687.2 691.4 706.5 576.8 601.9 656.7 660.4 673.0 7423.7 9364.8 9394.3 9484.1 9628.5 536.9 566.8 570.3 611.9 650.6 4356.3 4391.9 4644.4 4708.0 4767.2 115779.4 125558.0 130397.0 144886.3 155521.0 86485.0 117767.8 132224.0 132888.3 138715.0 77971.6 85123.6 90203.2 101671.5 107167.0 9632.3 9917.3 9972.7 10020.2 10124.7 60731.7 60848.4 63150.1 67423.7 67600.7 72041.6 95158.0 101117.5 102169.0 102247.0 13563.0 13639.4 13908.9 13947.9 13954.7 14915.2 14917.5 14940.8 15142.3 15441.3 13748.4 13793.4 13955.9 14082.9 14313.1 13562.9 13715.7 13972.4 13994.7 14085.9 13296.4 13526.7 13670.5 13933.7 14096.3 9553.3 11258.5 11429.0 11675.6 11953.8 9605.4 10183.9 11220.8 11496.3 11717.5 05:15:03 INFO - PROCESS | 2122 | -------- Summary: end -------- 05:15:03 INFO - PROCESS | 2122 | 05:15:03 INFO - TEST-INFO | 2122: exit 0 05:15:03 INFO - TEST-OK | dromaeo_css | took 1116648ms 05:15:03 INFO - TEST-START | kraken 05:15:03 INFO - operating with platform_type : mac_ 05:15:03 INFO - Initialising browser for kraken test... 05:15:03 INFO - TEST-INFO | started process 2235 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpX8vqxx/profile http://localhost:49702/getInfo.html) 05:15:05 INFO - PROCESS | 2235 | __metrics Screen width/height:1600/1200 05:15:05 INFO - PROCESS | 2235 | colorDepth:24 05:15:05 INFO - PROCESS | 2235 | Browser inner width/height: 1024/689 05:15:05 INFO - PROCESS | 2235 | __metrics 05:15:06 INFO - PROCESS | 2235 | 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" 05:15:06 INFO - PROCESS | 2235 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:15:06 INFO - TEST-INFO | 2235: exit 0 05:15:06 INFO - Browser initialized. 05:15:06 INFO - Running cycle 1/1 for kraken test... 05:15:06 INFO - TEST-INFO | started process 2236 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpX8vqxx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:18 INFO - PROCESS | 2236 | RSS: Main: 234168320 05:15:18 INFO - PROCESS | 2236 | 05:15:21 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-beat-detection) 05:15:22 INFO - PROCESS | 2236 | RSS: Main: 293154816 05:15:22 INFO - PROCESS | 2236 | 05:15:24 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-dft) 05:15:25 INFO - PROCESS | 2236 | RSS: Main: 289222656 05:15:25 INFO - PROCESS | 2236 | 05:15:28 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-fft) 05:15:29 INFO - PROCESS | 2236 | RSS: Main: 378925056 05:15:29 INFO - PROCESS | 2236 | 05:15:31 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-oscillator) 05:15:32 INFO - PROCESS | 2236 | RSS: Main: 281464832 05:15:32 INFO - PROCESS | 2236 | 05:15:34 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49702/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:15:34 INFO - PROCESS | 2236 | RSS: Main: 285257728 05:15:34 INFO - PROCESS | 2236 | 05:15:37 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49702/tests/kraken/driver.html?testName=imaging-darkroom) 05:15:38 INFO - PROCESS | 2236 | RSS: Main: 324112384 05:15:38 INFO - PROCESS | 2236 | 05:15:40 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49702/tests/kraken/driver.html?testName=imaging-desaturate) 05:15:41 INFO - PROCESS | 2236 | RSS: Main: 324726784 05:15:41 INFO - PROCESS | 2236 | 05:15:44 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49702/tests/kraken/driver.html?testName=json-parse-financial) 05:15:44 INFO - PROCESS | 2236 | RSS: Main: 323362816 05:15:44 INFO - PROCESS | 2236 | 05:15:46 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49702/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:15:47 INFO - PROCESS | 2236 | RSS: Main: 298569728 05:15:47 INFO - PROCESS | 2236 | 05:15:48 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:15:49 INFO - PROCESS | 2236 | RSS: Main: 303984640 05:15:49 INFO - PROCESS | 2236 | 05:15:51 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:15:52 INFO - PROCESS | 2236 | RSS: Main: 304345088 05:15:52 INFO - PROCESS | 2236 | 05:15:55 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:15:55 INFO - PROCESS | 2236 | RSS: Main: 319590400 05:15:55 INFO - PROCESS | 2236 | 05:15:58 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:15:59 INFO - PROCESS | 2236 | RSS: Main: 315858944 05:15:59 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49702/tests/kraken/driver.html?testName=ai-astar) 05:16:01 INFO - PROCESS | 2236 | RSS: Main: 359473152 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | __start_tp_report 05:16:01 INFO - PROCESS | 2236 | _x_x_mozilla_page_load 05:16:01 INFO - PROCESS | 2236 | _x_x_mozilla_page_load_details 05:16:01 INFO - PROCESS | 2236 | |i|pagename|runs| 05:16:01 INFO - PROCESS | 2236 | |0;ai-astar;111;111;115;111;112;111;111;112;113;123 05:16:01 INFO - PROCESS | 2236 | |1;audio-beat-detection;178;137;166;164;113;129;129;107;160;108 05:16:01 INFO - PROCESS | 2236 | |2;audio-dft;184;186;184;181;182;181;180;183;180;184 05:16:01 INFO - PROCESS | 2236 | |3;audio-fft;75;79;73;153;57;57;57;67;137;57 05:16:01 INFO - PROCESS | 2236 | |4;audio-oscillator;82;83;130;69;71;80;116;69;70;115 05:16:01 INFO - PROCESS | 2236 | |5;imaging-gaussian-blur;87;86;89;85;88;85;90;87;87;85 05:16:01 INFO - PROCESS | 2236 | |6;imaging-darkroom;94;94;95;94;94;95;94;95;94;94 05:16:01 INFO - PROCESS | 2236 | |7;imaging-desaturate;89;89;92;88;89;89;90;88;86;87 05:16:01 INFO - PROCESS | 2236 | |8;json-parse-financial;53;54;56;56;56;56;55;54;54;54 05:16:01 INFO - PROCESS | 2236 | |9;json-stringify-tinderbox;66;68;77;80;74;72;73;72;72;74 05:16:01 INFO - PROCESS | 2236 | |10;stanford-crypto-aes;85;86;87;84;87;87;85;86;87;83 05:16:01 INFO - PROCESS | 2236 | |11;stanford-crypto-ccm;139;136;137;138;133;137;142;138;179;155 05:16:01 INFO - PROCESS | 2236 | |12;stanford-crypto-pbkdf2;170;168;170;171;169;169;168;167;167;169 05:16:01 INFO - PROCESS | 2236 | |13;stanford-crypto-sha256-iterative;67;66;66;66;65;69;81;87;86;83 05:16:01 INFO - PROCESS | 2236 | __end_tp_report 05:16:01 INFO - PROCESS | 2236 | __start_cc_report 05:16:01 INFO - PROCESS | 2236 | _x_x_mozilla_cycle_collect,2064 05:16:01 INFO - PROCESS | 2236 | __end_cc_report 05:16:01 INFO - PROCESS | 2236 | __startTimestamp1472732161537__endTimestamp 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | ------- Summary: start ------- 05:16:01 INFO - PROCESS | 2236 | Number of tests: 14 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#0] ai-astar Cycles:10 Average:113.00 Median:112.00 stddev:3.74 (3.3%) stddev-sans-first:3.90 05:16:01 INFO - PROCESS | 2236 | Values: 111.0 111.0 115.0 111.0 112.0 111.0 111.0 112.0 113.0 123.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:148.50 stddev:26.21 (17.6%) stddev-sans-first:23.72 05:16:01 INFO - PROCESS | 2236 | Values: 178.0 137.0 166.0 164.0 113.0 129.0 129.0 107.0 160.0 108.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.50 stddev:2.01 (1.1%) stddev-sans-first:2.06 05:16:01 INFO - PROCESS | 2236 | Values: 184.0 186.0 184.0 181.0 182.0 181.0 180.0 183.0 180.0 184.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#3] audio-fft Cycles:10 Average:81.20 Median:62.00 stddev:34.84 (56.2%) stddev-sans-first:36.88 05:16:01 INFO - PROCESS | 2236 | Values: 75.0 79.0 73.0 153.0 57.0 57.0 57.0 67.0 137.0 57.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#4] audio-oscillator Cycles:10 Average:88.50 Median:70.50 stddev:22.94 (32.5%) stddev-sans-first:24.21 05:16:01 INFO - PROCESS | 2236 | Values: 82.0 83.0 130.0 69.0 71.0 80.0 116.0 69.0 70.0 115.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:87.00 stddev:1.73 (2.0%) stddev-sans-first:1.83 05:16:01 INFO - PROCESS | 2236 | Values: 87.0 86.0 89.0 85.0 88.0 85.0 90.0 87.0 87.0 85.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 05:16:01 INFO - PROCESS | 2236 | Values: 94.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 94.0 94.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#7] imaging-desaturate Cycles:10 Average:88.70 Median:89.00 stddev:1.64 (1.8%) stddev-sans-first:1.73 05:16:01 INFO - PROCESS | 2236 | Values: 89.0 89.0 92.0 88.0 89.0 89.0 90.0 88.0 86.0 87.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.50 stddev:1.14 (2.0%) stddev-sans-first:1.00 05:16:01 INFO - PROCESS | 2236 | Values: 53.0 54.0 56.0 56.0 56.0 56.0 55.0 54.0 54.0 54.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#9] json-stringify-tinderbox Cycles:10 Average:72.80 Median:73.50 stddev:3.99 (5.4%) stddev-sans-first:3.40 05:16:01 INFO - PROCESS | 2236 | Values: 66.0 68.0 77.0 80.0 74.0 72.0 73.0 72.0 72.0 74.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:86.50 stddev:1.42 (1.6%) stddev-sans-first:1.48 05:16:01 INFO - PROCESS | 2236 | Values: 85.0 86.0 87.0 84.0 87.0 87.0 85.0 86.0 87.0 83.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:138.50 stddev:13.85 (10.0%) stddev-sans-first:14.60 05:16:01 INFO - PROCESS | 2236 | Values: 139.0 136.0 137.0 138.0 133.0 137.0 142.0 138.0 179.0 155.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:169.00 stddev:1.32 (0.8%) stddev-sans-first:1.32 05:16:01 INFO - PROCESS | 2236 | Values: 170.0 168.0 170.0 171.0 169.0 169.0 168.0 167.0 167.0 169.0 05:16:01 INFO - PROCESS | 2236 | 05:16:01 INFO - PROCESS | 2236 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:75.00 stddev:9.36 (12.5%) stddev-sans-first:9.62 05:16:01 INFO - PROCESS | 2236 | Values: 67.0 66.0 66.0 66.0 65.0 69.0 81.0 87.0 86.0 83.0 05:16:01 INFO - PROCESS | 2236 | -------- Summary: end -------- 05:16:01 INFO - PROCESS | 2236 | 05:16:02 INFO - TEST-INFO | 2236: exit 0 05:16:02 INFO - TEST-OK | kraken | took 58595ms 05:16:02 INFO - SUITE-END | took 1175s 05:16:02 INFO - Completed test suite (00:19:35) 05:16:02 INFO - javascript benchmark 05:16:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2751.944961100778, 3232.095013396249, 3272.8690852365908, 3297.5713157289547, 3331.783434076785, 5446.945530544694, 5457.836264912054, 5526.889462210754, 5578.386377498477, 5584.106542953128, 5464.9453505464935, 5467.535259502937, 5526.834194974157, 5538.501534861862, 5604.747786349614, 5024.924626130611, 5033.84898453047, 5080.77136528857, 5086.720230387327, 5209.244659524366, 5136.871578210537, 5169.974150129263, 5172.32759741235, 5196.688198708071, 5208.817691380802, 4972.105021096201, 5055.873603159914, 5066.316047333618, 5071.96024814912, 5148.407933087706, 4979.228219625964, 5112.079825631385, 5116.820911268088, 5120.436751957282, 5126.154184559543, 5007.799688012475, 5010.69935803853, 5070.645054846163, 5099.974500127512, 5108.667936584147, 4779.091972525209, 5379.757910894019, 5448.509634132907, 5524.972375138099, 5567.4432556744405, 5614.3543492498475, 6048.939510604881, 6079.756809727605, 6090.512758979316, 6098.298695649968, 5962.25471816023, 6080.513558915276, 6081.239845019372, 6182.1345011698395, 6191.783287584913, 5637.774489020475, 5653.293338332708, 5654.15187721845, 5703.971480142573, 5714.657120572779, 5906.645601263938, 6274.592151510137, 6281.780137695158, 6283.622982621057, 6304.338044505353, 5628.0713682242795, 5830.416958304136, 5877.794277200277, 5885.14665373527, 5947.0, 6083.148359229712, 6095.3904609538695, 6097.6033431105, 6100.633961962297, 6129.908051379233, 5977.372375900555, 5984.7606095756555, 5990.9101363479585, 5992.131140984533, 5998.790042348497, 5859.150423188699, 5905.852353691193, 5939.70301484924, 5994.970025149846, 6023.909641355295, 4755.904881902343, 4817.301491283744, 4828.058528586962, 4857.319975203474, 4857.53853383928, 4291.527931927485, 4306.418633484503, 4321.481422229353, 4334.804933777988, 4365.126974605092, 5478.178273258964, 5546.4453554644215, 5548.91676624851, 5602.691851948182, 5612.35457922336, 6178.258608966953, 6187.226596675416, 6191.4118158774845, 6197.81406557804, 6202.534809889276, 5613.438656134354, 5622.297212848394, 5641.238432811518, 5649.12438572022, 5684.431556844365, 4486.798094085774, 5198.948010519922, 5248.816291429858, 5251.684898906078, 5324.387695414999, 6163.753449862134, 6203.348648391854, 6221.564490485623, 6240.656763878031, 6277.497800175884, 6119.495348744332, 6140.201773769381, 6140.289530736458, 6154.538409619386, 6160.691965401802, 6238.407351301619, 6280.309165991646, 6280.874382512233, 6287.685615719287, 6295.622262664255, 5798.362180160095, 5880.617759845596, 5950.464458198783, 5981.581289309706, 6002.069679199731, 4927.457979622167, 5909.202257695156, 6036.7283472244735, 6144.446999770043, 6168.722407491584, 5824.126381042877, 5875.735591898289, 5878.529717622666, 5894.997850365362, 5913.763449461973, 5734.455226753452, 5851.180834683233, 5872.765089396376, 5880.588358814842, 5897.764089436375, 5646.491815736604, 5680.886382272414, 5700.173474846207, 5753.769849205984, 5803.303603567599, 5835.56233282514, 5859.589828711949, 5864.44287792659, 5865.530757539472, 5865.882682346244, 5709.0865461525955, 5722.313322401338, 5725.599208055396, 5728.541716662574, 5734.655920644775], "value": 5531.604265098235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.3525807788523, 496.92297693857455, 502.65539681352374, 508.57788035930184, 525.3249574297027, 30166.743307275523, 39097.609023909754, 40222.1955560889, 40350.39474407886, 42029.0, 41365.58634413655, 41706.0, 41807.954801129985, 41846.37230441546, 41874.79062604683, 8601.698940537082, 9489.193418559431, 9676.951615241915, 9689.32174747768, 9691.370060946052, 8603.784905377353, 11691.941540292286, 11975.0, 12011.918927296541, 12188.085893558018, 10351.17190624752, 11591.188616796828, 11949.820752688716, 12016.0, 12024.398780061005, 11876.7624647507, 11954.581589644407, 11960.222585531912, 11974.580889668863, 11976.820347694704, 11757.764844703059, 11843.0, 11865.88134118665, 11867.406629668481, 11906.1070419718, 39397.0, 39565.010874728076, 39579.40630890539, 39582.0104497387, 39639.0, 3289.8355082245794, 3302.108430723691, 3387.8983630491125, 3509.350770107538, 3526.453399723047, 29837.70162298371, 30913.536296955564, 30931.381372372427, 30978.0, 31035.0, 3212.1166679163184, 3219.8873039443506, 3243.0, 3309.4373956427467, 3341.63242043375, 460447.6977615124, 469925.0, 470629.0, 471629.5674086499, 473083.6345818283, 53264.86940522413, 53580.73209633927, 53608.46391536035, 53642.46357536453, 53658.463415366125, 58232.0, 58337.41662583405, 58358.54103647358, 58424.12363814546, 58572.41427585755, 15503.844961550465, 15674.216289185495, 15707.528774136794, 15744.448944286894, 15747.527574172793, 9159.816803664022, 11231.932966368182, 11250.381229032482, 12247.93876030632, 12328.19866708661, 13391.397387117602, 13513.594592162252, 14775.778363324343, 15136.697266054618, 15144.848551514564, 5490.149026900836, 6313.547883986617, 6415.711292991827, 6437.935620643827, 6447.1618689571, 829.324100857799, 962.5139304651107, 982.0768477631004, 999.3504222255592, 1000.5697550053546, 24331.513369732507, 29922.092959419708, 30422.54366184509, 31237.50099991995, 31659.0, 800.9799755006171, 956.1346980982083, 971.7279161834698, 988.8615593816871, 1019.6380284998719, 13913.652158696113, 14320.427182912776, 14724.822014238835, 14826.703465930621, 15018.624534386729, 576532.0, 582037.0, 584258.0, 584421.0, 692831.0, 54924.62688432821, 55841.72079139578, 56070.71964640151, 58946.70526647425, 59283.0, 1316.4602512969636, 1332.6273937844037, 1353.2489468345163, 1365.822443082393, 1368.0423703407655, 27923.581146282417, 29575.556366654087, 29698.40603187881, 30242.007768151285, 30366.0, 47894.0, 48198.518014819405, 48277.793055173905, 48312.5168748308, 48364.758176208896, 594961.0, 598286.0, 598313.0, 598864.0, 598885.0, 23927.641085384083, 25025.374365641004, 25042.37394065163, 25082.74917250877, 25114.0, 30411.63147658405, 36212.0, 36983.26033479369, 43730.78134609306, 45772.54227457683, 36145.81927090348, 36327.09182270464, 36361.63638363582, 37206.81396593, 37410.8129459351, 36102.63897360993, 36466.27067458689, 36925.0, 37552.061198470255, 37766.62233377631, 35463.82268088643, 36002.27995440129, 36210.4568431479, 36553.0, 36626.08434789152, 31619.683803161675, 33115.66884331126, 33157.33685326328, 33237.50143747894, 33314.33371332511, 24089.277321680376, 24315.148969785972, 24556.75443245616, 25004.49991000206, 25077.498450031264], "value": 19464.62892221935, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.87607771008163, 351.947207918812, 372.5901508340825, 382.99042523936896, 406.890139662291, 14942.25288735563, 16751.49745507635, 19859.0, 20422.183112675513, 20548.38354849355, 19826.900865495652, 20043.198272069138, 20656.586868262624, 20852.0, 20961.266355677555, 5442.374126975389, 5803.564732645047, 6046.00240960241, 6047.667378294192, 6052.788152414666, 5875.17747515346, 6059.848503787396, 6061.909071363933, 6061.9393806062035, 6066.241719785027, 5672.432756724335, 5880.26496687914, 5942.376050514698, 6001.5198784097165, 6006.609570377933, 5847.26909136358, 5875.970620146914, 5884.852878678024, 5891.322497912752, 5895.764169433218, 4909.705417674951, 5106.923396149061, 5185.948140518584, 5191.714455704935, 5195.9480405195845, 24918.875405623035, 25432.982680692934, 25472.108476203244, 25520.23439296824, 25821.83801728927, 1936.7579052618423, 2164.448065743241, 2355.470019245673, 2632.4603456291416, 3078.184281165493, 14713.337899794535, 15042.247887605685, 15238.771418428732, 15492.915495915291, 15781.68436631261, 1528.289345454358, 1791.7849858016991, 1865.1047497201419, 1879.6146789908173, 1894.8578856585812, 115540.25865665439, 121184.99404525536, 121333.02228156396, 121786.05059015198, 122140.80790107718, 9077.273818094536, 9196.954015229881, 10366.740831479274, 10500.317479363817, 11008.119350451945, 13707.588772336845, 13720.313984300945, 13722.245276509888, 13732.940058991231, 13746.312684365741, 6990.545614535039, 8172.101068882418, 8443.0, 8463.06906240313, 8618.870716939251, 8452.8732069019, 8484.618192181366, 8522.57387130641, 8678.86981695275, 8725.563721813884, 5613.635113717595, 5892.793752218652, 6140.385961403824, 6646.933530664632, 6682.064510968371, 1812.8821626594229, 1827.1321122466989, 1827.5339788354152, 1830.7093499083114, 1847.7505536752367, 696.6725638949686, 913.7441516375426, 922.8246633139682, 962.4465932089076, 994.229472159068, 21057.89471052635, 21117.155313787276, 21863.781362186175, 21956.560868782533, 22024.898755062182, 853.4111463090447, 869.4696235296464, 879.9868001979976, 916.5371487398954, 917.1058218237299, 8872.0, 11440.141989350666, 11597.0, 11612.361320127306, 11614.303141811519, 485182.0, 495109.9645533669, 497452.5382119271, 578875.0, 591240.0, 133592.3320383392, 142044.0, 142368.0, 142830.0, 144221.27889360487, 929.6746138851348, 934.2059249637754, 983.9557219925264, 1000.3847633705219, 1001.9398836069859, 18712.438626841216, 19462.61074778525, 19849.40451786449, 19874.602507949472, 19921.501962451053, 56238.43761562496, 56506.0, 57354.13968790555, 57686.711566441896, 57812.132818008606, 77197.0, 79002.0, 82076.58961705153, 82456.17543824486, 82821.3435731294, 7471.813204669927, 7577.621118944141, 8042.276195142465, 8219.0, 8279.793005174919, 11635.650930472098, 11861.5848445304, 12710.30093344838, 12888.613341599766, 13282.667933301746, 10759.515821787882, 10939.507722152362, 11154.49804758804, 11446.45472861153, 11494.195406321478, 11046.447677615926, 11141.387223702774, 11453.02649274821, 11543.76912461763, 11548.422578870855, 11174.385408802595, 11347.48936297852, 11526.82709759371, 11761.411929403666, 11870.762584748429, 7930.048394192734, 7955.4828936118965, 8181.918180818116, 8372.864919445503, 8382.245597896219, 9338.813223735624, 11220.607278745205, 11234.988851003449, 11337.433128343386, 11460.312381256817], "value": 9681.006180294386, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1535.761956896681, 1545.2582760275068, 1774.1572752942354, 1854.8237917397842, 1937.3316205908957, 8519.659213631456, 9044.547772611368, 9051.094890510945, 9060.818783624323, 9073.22877555407, 7936.682532698699, 8093.716719914804, 8582.48505089695, 8752.081031491682, 8843.11568843117, 2380.821438392123, 2416.1060407649106, 2416.6495858100566, 2418.2866054513875, 2420.4674971506242, 2394.928152155438, 2418.600930846408, 2434.8782560871973, 2436.293474892288, 2443.108265483095, 2412.1436889904094, 2416.5770990076667, 2422.588160012802, 2437.524682686877, 2441.9023239070416, 2390.34265576966, 2401.11158871217, 2410.554047501209, 2411.05968672218, 2416.4321384474633, 1991.75103112111, 2392.3899405651446, 2398.8800559972105, 2401.087586717036, 2401.231605886117, 8725.825483490295, 8836.646534138627, 8848.557572121368, 8851.867221991675, 8856.557172141367, 5198.246254293144, 5355.705436201008, 5356.705381204032, 5360.195970604441, 5387.19192121181, 3454.101933497309, 3481.234128491728, 3536.5932917714276, 3537.823108844573, 3539.327527769741, 1050.369778133114, 1056.915446764257, 1060.8408738689182, 1084.208527774721, 1086.4784903246486, 11574.958253757202, 11709.94610485052, 11713.180077394585, 11738.706532336675, 11767.411629418495, 4566.223741963808, 4691.272852707836, 4835.564799168092, 4843.297721830315, 4843.709377437294, 9924.553395097239, 9935.05616966387, 9953.0, 9956.054174853378, 9965.601375945025, 5382.434844341287, 5387.218853266255, 5389.51494365509, 5406.567474602022, 5407.297051383295, 4446.644268458515, 4610.838620648261, 4804.111239420648, 4926.58124059459, 4949.48030456804, 3363.360961417323, 3877.1857909838686, 3913.8434462621176, 4027.5368332641533, 4035.899102522402, 1273.229696033886, 1287.5557932513268, 1296.9610911672664, 1310.8689131086815, 1311.5212947274367, 742.9145648250412, 828.0480088484534, 838.4005436113208, 915.3683958068912, 941.7316064921464, 5287.524122828964, 5653.434656534314, 6027.849303767441, 6091.390860913873, 6231.75072997075, 897.3808072430003, 921.1847514949318, 929.4423345992351, 933.244072301438, 937.7280588629358, 3848.749831260959, 3899.980500097481, 3901.180752042046, 4001.299772539794, 4002.3796311571755, 8597.18326758957, 8890.511021893728, 10094.091531762177, 10486.632967846088, 10517.053465188017, 10477.3189742668, 10482.68551943443, 10488.790224195473, 10492.632757853438, 10501.159907207406, 7747.457677962601, 8236.341092712568, 8366.49801011929, 8373.665053397917, 8408.033076196194, 5821.563382746226, 5980.102984552352, 6046.183765191643, 6108.297545782292, 6149.72326245329, 8236.299914507334, 9389.342746007715, 9885.456299903575, 10138.898611013796, 10315.845262321222, 9852.802943941224, 10120.59517619287, 10306.381617102998, 10399.480025998519, 10421.322614030063, 7318.414526837947, 7476.476646634684, 7524.736634217776, 7605.923940760522, 7686.46194766361, 8454.915450845412, 8521.914780852112, 8533.06136325016, 8539.82920341602, 8541.701040463555, 6450.258220304725, 7446.888296675663, 7516.173220945582, 7525.6989720410575, 7687.423443241667, 7292.41660667156, 7343.44924130703, 7384.667689954073, 7384.778456646309, 7482.663280152297, 6911.7926462206215, 7033.824154396181, 7043.788686339418, 7066.57600543969, 7069.575825450489, 4889.982815231594, 5009.649324547247, 5031.597472202142, 5079.6952182869145, 5082.517160869711, 4917.508249175054, 4961.181405068123, 4969.35398398206, 5117.769700363418, 5237.476252374732], "value": 4598.239382464632, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [248.57617761716276, 291.42879955287634, 309.56196981271495, 312.4578855685387, 312.904564107947, 4654.3716598259225, 5165.04446677364, 5463.78144874205, 5479.301416560869, 5486.451354864512, 4303.376010478487, 5147.7426128693605, 5326.893462130764, 5331.626786124973, 5355.0, 4037.5962403759536, 4046.0, 4053.756774593534, 4061.928416507588, 4077.571854955224, 3722.1625134344827, 3951.170254246612, 4060.9593904060876, 4077.2864748669012, 4119.443875076871, 3254.8047117173046, 3479.5824501059783, 3778.9433158502643, 3964.583718709537, 4023.135025969416, 3683.876417692616, 3713.275911197318, 4055.641360144355, 4057.6001279558504, 4062.6546743526687, 3540.5220295260324, 3540.8937731868086, 3551.289742051574, 3568.982155089208, 3571.2143328467696, 5023.648344615878, 5112.935216502852, 5242.1088414969545, 5244.29202057725, 5249.291345668324, 4626.768661566908, 4639.443266808004, 4647.676911008645, 4649.698084536335, 4672.112298663243, 4840.927386089212, 5615.606907516475, 5624.184493248497, 5638.407967163471, 5694.886102277931, 3661.4141737322166, 3868.864589739346, 3905.4141878718137, 3908.6482216600643, 3923.1565213478953, 5126.794928202868, 5885.470307672287, 5888.499477544414, 5892.793752218695, 5960.284765828085, 3276.7050965413227, 3935.940960885589, 3943.7830919299713, 3988.900277493086, 3995.640392364643, 3375.641236969231, 3665.596784353719, 3670.5684782934677, 3938.822752976123, 4057.594240575919, 4576.336431217454, 4578.092911001538, 4583.335416364675, 4626.167289887852, 4635.072985402933, 4974.27872958419, 5703.944770217523, 5743.62666426681, 5771.0, 5831.854203644858, 1002.7693630465034, 1115.5219334381977, 1168.9181757276908, 1179.5399794080315, 1317.8089177069462, 879.3404946290283, 888.1606881496923, 900.4822227219375, 908.5775114571755, 909.5725009245776, 4439.733615983051, 4923.359963204832, 5310.60170487215, 5313.8937221255355, 5357.8392648220615, 5036.398974733083, 5325.174597937326, 5767.769289228384, 5768.826935191951, 5789.88420231593, 4888.573683211633, 5079.593632509391, 5300.496452836975, 5302.973485132549, 5321.73391330432, 2272.8181745460324, 2417.3650720714504, 2572.6655534780357, 2575.8325708829243, 2579.638850560923, 4379.562043795595, 4534.093181363741, 4695.483496815347, 4746.125280514178, 5051.686561494038, 4329.683619263742, 4376.8182590700335, 4953.0, 4972.378452693413, 4990.800367985313, 4253.702240843111, 4285.5071666758095, 4332.090261045216, 5027.572656324253, 5124.0, 4594.632429405707, 4830.830920917751, 4831.444383895897, 4859.854204373874, 4895.853124406273, 4200.768957707355, 4547.386102876069, 4588.241648702907, 4654.348391225299, 4810.326554282333, 4375.0, 4800.783964721526, 5025.065349840411, 5058.9494105058475, 5100.795968161232, 2764.8617569121866, 3256.0, 3339.3970893971514, 3359.3617212729505, 3368.8545894396025, 4332.3753592402845, 4762.666293516221, 4881.291547958186, 4923.212286034217, 5300.203315999027, 4877.634177436636, 4898.2897479864505, 5608.317504748641, 5641.379448260606, 5683.0, 4879.829205977774, 5160.0, 5612.298462692163, 5631.408702086222, 5653.858653533694, 5159.355080614923, 5253.317068781034, 5303.0, 5479.945200547943, 5534.149912794995, 5657.547396208376, 5657.708628005665, 5659.943400566106, 5665.178549110438, 5665.575081868794, 5511.8149597836655, 5550.861228469159, 5567.830755541382, 5568.888622227451, 5574.9721251393485], "value": 3915.074785103265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.5971566349349, 711.4415184080494, 759.7189040055181, 793.626995312203, 810.4812919731373, 15688.0, 16237.0, 16272.26774795134, 16277.755833662504, 16288.18559072046, 14157.71684566308, 14263.21552314625, 14273.4290628375, 14280.000399972007, 14760.114393136419, 668.7425341243608, 688.5042769206188, 828.693383448125, 835.7033253195119, 854.3378881366959, 831.2020460358063, 839.836231934773, 843.2663582683074, 843.3169133002257, 843.6498852976008, 887.2458410351214, 887.3256325192867, 887.7070566712969, 888.1651247827054, 888.9599968001455, 685.8628274345151, 869.5304535550795, 883.9867401988976, 887.8623813308948, 907.1336873285996, 569.3509399284818, 668.682375871462, 668.7993601919453, 669.2604671837647, 670.0752960913957, 14493.927530362385, 14510.709785804225, 14519.854801452062, 14558.85441145596, 14591.78112328316, 13257.0, 13485.325733713373, 13668.384922678504, 13708.0, 13851.722965540634, 11907.106966977512, 12296.631101066892, 12353.197042192229, 12360.196587221802, 12864.678383040498, 4421.557844215585, 4423.0, 4508.030773383705, 4614.700044497097, 4622.2835460503675, 113607.43196284048, 115353.4232328833, 116056.09859753489, 116388.0, 117825.64348713064, 10568.340548626526, 10670.839937400946, 10691.946540267405, 10732.73168170802, 11031.338119712844, 20747.481312967295, 20755.273565425137, 20766.58466830655, 20783.272585459436, 20791.376258712262, 7636.656350464243, 7676.654550545239, 7826.491278066907, 7854.253845884746, 7897.447178697436, 4041.3533834586324, 4242.809073591696, 4286.350809446025, 4333.783310834446, 4359.694821362537, 3368.545246391755, 3824.0631045394057, 3982.820773065219, 3993.940090898639, 3997.4603428537357, 579.342446323426, 594.2423410152004, 687.161662771418, 691.4295706042535, 706.491326245113, 576.8067697321444, 601.8766152938637, 656.7077650445506, 660.3642430140387, 672.9730810767514, 7423.7379645460405, 9364.765880853032, 9394.316418739, 9484.146426821619, 9628.518574071268, 536.9382521010056, 566.7874547044859, 570.3070769015666, 611.9020956646915, 650.5868773328875, 4356.324769660644, 4391.934120988188, 4644.373009643722, 4707.976460117678, 4767.237242041326, 115779.42110289563, 125558.0, 130397.0, 144886.2755686236, 155521.0, 86485.0, 117767.82232177739, 132224.0, 132888.3355583216, 138715.0, 77971.61014194893, 85123.57438212894, 90203.19593608091, 101671.49164254131, 107167.0, 9632.325737198329, 9917.265079507093, 9972.650957216463, 10020.248481364073, 10124.69625911224, 60731.69634151801, 60848.39151608427, 63150.052749207876, 67423.66288168528, 67600.6619966897, 72041.6397918007, 95158.0, 101117.49441252746, 102168.97831021594, 102247.0, 13563.0, 13639.386227619583, 13908.860911390757, 13947.860521394656, 13954.651133721738, 14915.179665118523, 14917.47788827386, 14940.7758883619, 15142.290005296707, 15441.305141268895, 13748.418777453493, 13793.448262069805, 13955.860441395456, 14082.929585352007, 14313.141211527342, 13562.864371356554, 13715.657108572366, 13972.371243293874, 13994.650133746738, 14085.929570352082, 13296.401661925043, 13526.729465410834, 13670.453181872614, 13933.72132557323, 14096.295185240902, 9553.283503737386, 11258.493367798304, 11429.028532574825, 11675.591354302558, 11953.820692689442, 9605.372293117727, 10183.898161018591, 11220.831687524857, 11496.310221386744, 11717.531298748296], "value": 7789.220387846535, "unit": "score"}], "name": "dromaeo_css", "value": 7257.970359474943, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 115.0, 111.0, 112.0, 111.0, 111.0, 112.0, 113.0, 123.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 137.0, 166.0, 164.0, 113.0, 129.0, 129.0, 107.0, 160.0, 108.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 184.0, 181.0, 182.0, 181.0, 180.0, 183.0, 180.0, 184.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 73.0, 153.0, 57.0, 57.0, 57.0, 67.0, 137.0, 57.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 130.0, 69.0, 71.0, 80.0, 116.0, 69.0, 70.0, 115.0], "value": 88.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 89.0, 85.0, 88.0, 85.0, 90.0, 87.0, 87.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 92.0, 88.0, 89.0, 89.0, 90.0, 88.0, 86.0, 87.0], "value": 88.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 56.0, 56.0, 56.0, 56.0, 55.0, 54.0, 54.0, 54.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 77.0, 80.0, 74.0, 72.0, 73.0, 72.0, 72.0, 74.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 87.0, 84.0, 87.0, 87.0, 85.0, 86.0, 87.0, 83.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 137.0, 138.0, 133.0, 137.0, 142.0, 138.0, 179.0, 155.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 170.0, 171.0, 169.0, 169.0, 168.0, 167.0, 167.0, 169.0], "value": 168.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 65.0, 69.0, 81.0, 87.0, 86.0, 83.0], "value": 73.6, "unit": "score"}], "name": "kraken", "value": 1473.3, "alertThreshold": 2.0}]} 05:16:02 INFO - Return code: 0 05:16:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:16:02 INFO - # TBPL SUCCESS # 05:16:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:16:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:16:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:16:02 INFO - 2016-09-01 05:16:02.849 screenresolution[2246:13853] starting screenresolution argv=screenresolution get 05:16:02 INFO - 2016-09-01 05:16:02.852 screenresolution[2246:13853] Display 0: 1600x1200x32@60 05:16:02 INFO - 2016-09-01 05:16:02.857 screenresolution[2247:13856] starting screenresolution argv=screenresolution list 05:16:02 INFO - Available Modes on Display 0 05:16:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:16:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:16:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:16:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:16:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:16:03 INFO - Intel Iris: 05:16:03 INFO - Chipset Model: Intel Iris 05:16:03 INFO - Type: GPU 05:16:03 INFO - Bus: Built-In 05:16:03 INFO - VRAM (Dynamic, Max): 1536 MB 05:16:03 INFO - Vendor: Intel (0x8086) 05:16:03 INFO - Device ID: 0x0a2e 05:16:03 INFO - Revision ID: 0x0009 05:16:03 INFO - Displays: 05:16:03 INFO - Display: 05:16:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:16:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:16:03 INFO - Display Serial Number: 1600x1200 60 05:16:03 INFO - Main Display: Yes 05:16:03 INFO - Mirror: Off 05:16:03 INFO - Online: Yes 05:16:03 INFO - Rotation: Supported 05:16:03 INFO - Return code: 0 05:16:03 INFO - Running post-action listener: _resource_record_post_action 05:16:03 INFO - [mozharness: 2016-09-01 12:16:03.091526Z] Finished run-tests step (success) 05:16:03 INFO - Running post-run listener: _resource_record_post_run 05:16:03 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 299008; Write bytes: 711779840; Read time: 5; Write time: 2074 05:16:03 INFO - TinderboxPrint: CPU usage
53.4% 05:16:03 INFO - TinderboxPrint: I/O read bytes / time
299,008 / 5 05:16:03 INFO - TinderboxPrint: I/O write bytes / time
711,779,840 / 2,074 05:16:03 INFO - TinderboxPrint: CPU idle
2,256.0 (46.6%) 05:16:03 INFO - TinderboxPrint: CPU system
962.5 (19.9%) 05:16:03 INFO - TinderboxPrint: CPU user
1,623.1 (33.5%) 05:16:03 INFO - TinderboxPrint: Swap in / out
406,392,832 / 0 05:16:03 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400830976; Write bytes: 399604224; Read time: 29735; Write time: 877 05:16:03 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 196608; Write bytes: 297397248; Read time: 1; Write time: 1151 05:16:03 INFO - Running post-run listener: _upload_blobber_files 05:16:03 INFO - Blob upload gear active. 05:16:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:16:03 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 05:16:03 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'] 05:16:03 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 05:16:03 INFO - (blobuploader) - INFO - Open directory for files ... 05:16:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:16:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:16:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:04 INFO - (blobuploader) - INFO - Done attempting. 05:16:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:16:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:16:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:05 INFO - (blobuploader) - INFO - Done attempting. 05:16:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:16:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:16:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:07 INFO - (blobuploader) - INFO - Done attempting. 05:16:07 INFO - (blobuploader) - INFO - Iteration through files over. 05:16:07 INFO - Return code: 0 05:16:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:16:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:16:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c75ce702faaccb75ffb7deb1fffcc65c6ee04f2da933cabbcd4fa89b29e1ed787657ab3c7e7e57c6d331137955c17b25966d127c7f82ce42b75c309a73cd147b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90a38efb2786bd62770710193f3bd80abe81cc5b7168eafe2fda0f9afac70095e7dbfd8120c5e16e18f1d5d9531c37a1272727881ae987445aebb6f25f1111d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e75b3f78432a6cf24bb672ee94df483ad597deb869d237f414211d4c370d60c4a9564987a1b51235bdd20d5b00a9706600f97341e506b2bee395ba2c0319aabd"} 05:16:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:16:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:16:07 INFO - Contents: 05:16:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c75ce702faaccb75ffb7deb1fffcc65c6ee04f2da933cabbcd4fa89b29e1ed787657ab3c7e7e57c6d331137955c17b25966d127c7f82ce42b75c309a73cd147b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90a38efb2786bd62770710193f3bd80abe81cc5b7168eafe2fda0f9afac70095e7dbfd8120c5e16e18f1d5d9531c37a1272727881ae987445aebb6f25f1111d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e75b3f78432a6cf24bb672ee94df483ad597deb869d237f414211d4c370d60c4a9564987a1b51235bdd20d5b00a9706600f97341e506b2bee395ba2c0319aabd"} 05:16:07 INFO - Running post-run listener: copy_logs_to_upload_dir 05:16:07 INFO - Copying logs to upload dir... 05:16:07 INFO - mkdir: /builds/slave/test/build/upload/logs 05:16:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.046103 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-09-01 05:16:07.798442) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 05:16:07.802566) ========= 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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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/c75ce702faaccb75ffb7deb1fffcc65c6ee04f2da933cabbcd4fa89b29e1ed787657ab3c7e7e57c6d331137955c17b25966d127c7f82ce42b75c309a73cd147b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90a38efb2786bd62770710193f3bd80abe81cc5b7168eafe2fda0f9afac70095e7dbfd8120c5e16e18f1d5d9531c37a1272727881ae987445aebb6f25f1111d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e75b3f78432a6cf24bb672ee94df483ad597deb869d237f414211d4c370d60c4a9564987a1b51235bdd20d5b00a9706600f97341e506b2bee395ba2c0319aabd"} build_url:https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011075 build_url: 'https://queue.taskcluster.net/v1/task/FBgWr_bbQXuF8JvWuRIbqA/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/c75ce702faaccb75ffb7deb1fffcc65c6ee04f2da933cabbcd4fa89b29e1ed787657ab3c7e7e57c6d331137955c17b25966d127c7f82ce42b75c309a73cd147b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90a38efb2786bd62770710193f3bd80abe81cc5b7168eafe2fda0f9afac70095e7dbfd8120c5e16e18f1d5d9531c37a1272727881ae987445aebb6f25f1111d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e75b3f78432a6cf24bb672ee94df483ad597deb869d237f414211d4c370d60c4a9564987a1b51235bdd20d5b00a9706600f97341e506b2bee395ba2c0319aabd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 05:16:07.836338) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:16:07.836694) ========= 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.uRxthgMyhG/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.cAeZ4kpQmH/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.005154 ========= master_lag: 0.15 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:16:07.990263) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 05:16:07.990731) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 05:16:07.991730) ========= ========= Total master_lag: 0.29 =========