builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0104 starttime: 1471953922.18 results: success (0) buildid: 20160823015246 builduid: 3894cab941aa4c09ab58dccbc4a5721c revision: 0faec8f3a8b59106a3437d954d1ccc38da7ec705 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 05:05:22.176282) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 05:05:22.176754) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 05:05:22.177024) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 05:05:22.202113) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 05:05:22.202414) ========= 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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-23 05:05:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-08-23 05:05:22 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.158000 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 05:05:22.496554) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 05:05:22.496877) ========= 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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.028491 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 05:05:22.545354) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-08-23 05:05:22.545709) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0faec8f3a8b59106a3437d954d1ccc38da7ec705 --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 0faec8f3a8b59106a3437d954d1ccc38da7ec705 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-23 05:05:22,623 truncating revision to first 12 chars 2016-08-23 05:05:22,623 Setting DEBUG logging. 2016-08-23 05:05:22,623 attempt 1/10 2016-08-23 05:05:22,623 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0faec8f3a8b5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 05:05:23,671 unpacking tar archive at: fx-team-0faec8f3a8b5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.299982 ========= master_lag: 1.77 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-08-23 05:05:25.619363) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 05:05:25.619752) ========= script_repo_revision: 0faec8f3a8b59106a3437d954d1ccc38da7ec705 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 05:05:25.620176) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-08-23 05:05:25.620453) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-08-23 05:05:27.562469) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-23 05:05:27.562867) ========= /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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 05:05:27 INFO - MultiFileLogger online at 20160823 05:05:27 in /builds/slave/test 05:05:27 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 05:05:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:27 INFO - {'append_to_log': False, 05:05:27 INFO - 'base_work_dir': '/builds/slave/test', 05:05:27 INFO - 'blob_upload_branch': 'Fx-Team', 05:05:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:27 INFO - 'branch': 'Fx-Team', 05:05:27 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:27 INFO - 'config_files': ('talos/mac_config.py',), 05:05:27 INFO - 'default_actions': ('clobber', 05:05:27 INFO - 'read-buildbot-config', 05:05:27 INFO - 'download-and-extract', 05:05:27 INFO - 'populate-webroot', 05:05:27 INFO - 'create-virtualenv', 05:05:27 INFO - 'install', 05:05:27 INFO - 'run-tests'), 05:05:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:27 INFO - 'download_minidump_stackwalk': True, 05:05:27 INFO - 'download_symbols': 'ondemand', 05:05:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:27 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:27 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:27 INFO - 'installer_path': 'installer.exe', 05:05:27 INFO - 'log_level': 'info', 05:05:27 INFO - 'log_name': 'talos', 05:05:27 INFO - 'log_to_console': True, 05:05:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:05:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:05:27 INFO - 'opt_config_files': (), 05:05:27 INFO - 'pip_index': False, 05:05:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:27 INFO - 'cmd': ('bash', 05:05:27 INFO - '-c', 05:05:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:27 INFO - 'enabled': True, 05:05:27 INFO - 'halt_on_failure': False, 05:05:27 INFO - 'name': 'check_screen_resolution'},), 05:05:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:27 INFO - 'cmd': ('bash', 05:05:27 INFO - '-c', 05:05:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:27 INFO - 'enabled': True, 05:05:27 INFO - 'halt_on_failure': False, 05:05:27 INFO - 'name': 'check_screen_resolution'},), 05:05:27 INFO - 'run_cmd_checks_enabled': True, 05:05:27 INFO - 'sps_profile': False, 05:05:27 INFO - 'sps_profile_interval': 0, 05:05:27 INFO - 'suite': 'dromaeojs', 05:05:27 INFO - 'system_bits': '32', 05:05:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:27 INFO - 'title': 't-yosemite-r7-0104', 05:05:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:27 INFO - 'use_talos_json': True, 05:05:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:27 INFO - 'work_dir': 'build'} 05:05:27 INFO - [mozharness: 2016-08-23 12:05:27.764350Z] Running clobber step. 05:05:27 INFO - Running pre-action listener: _resource_record_pre_action 05:05:27 INFO - Running main action method: clobber 05:05:27 INFO - rmtree: /builds/slave/test/build 05:05:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:29 INFO - Running post-action listener: _resource_record_post_action 05:05:29 INFO - [mozharness: 2016-08-23 12:05:29.911613Z] Finished clobber step (success) 05:05:29 INFO - [mozharness: 2016-08-23 12:05:29.911863Z] Running read-buildbot-config step. 05:05:29 INFO - Running pre-action listener: _resource_record_pre_action 05:05:29 INFO - Running main action method: read_buildbot_config 05:05:29 INFO - Using buildbot properties: 05:05:29 INFO - { 05:05:29 INFO - "project": "", 05:05:29 INFO - "product": "firefox", 05:05:29 INFO - "script_repo_revision": "production", 05:05:29 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:05:29 INFO - "repository": "", 05:05:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:05:29 INFO - "buildid": "20160823015246", 05:05:29 INFO - "pgo_build": "False", 05:05:29 INFO - "basedir": "/builds/slave/test", 05:05:29 INFO - "buildnumber": 139, 05:05:29 INFO - "slavename": "t-yosemite-r7-0104", 05:05:29 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:05:29 INFO - "platform": "yosemite_r7", 05:05:29 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:05:29 INFO - "branch": "fx-team", 05:05:29 INFO - "repo_path": "integration/fx-team", 05:05:29 INFO - "revision": "0faec8f3a8b59106a3437d954d1ccc38da7ec705", 05:05:29 INFO - "stage_platform": "macosx64", 05:05:29 INFO - "builduid": "3894cab941aa4c09ab58dccbc4a5721c", 05:05:29 INFO - "slavebuilddir": "test" 05:05:29 INFO - } 05:05:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:05:29 INFO - Running post-action listener: _resource_record_post_action 05:05:29 INFO - [mozharness: 2016-08-23 12:05:29.917490Z] Finished read-buildbot-config step (success) 05:05:29 INFO - [mozharness: 2016-08-23 12:05:29.917691Z] Running download-and-extract step. 05:05:29 INFO - Running pre-action listener: _resource_record_pre_action 05:05:29 INFO - Running main action method: download_and_extract 05:05:29 INFO - mkdir: /builds/slave/test/build/tests 05:05: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')]} 05:05:29 INFO - trying https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:05:29 INFO - Downloading https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/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 05:05:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/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 05:05:31 INFO - Downloaded 1199 bytes. 05:05:31 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:05:31 INFO - Using the following test package requirements: 05:05:31 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:05:31 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:31 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:05:31 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:31 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:05:31 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:31 INFO - u'jsshell-mac.zip'], 05:05:31 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:31 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:05:31 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:05:31 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:31 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:05:31 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:31 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:05:31 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:31 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:05:31 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:31 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:05:31 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:05:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:31 INFO - trying https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:05:31 INFO - Downloading https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/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 05:05:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/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 05:05:32 INFO - Downloaded 16352497 bytes. 05:05:32 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:05:39 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 05:05:39 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')]} 05:05:39 INFO - trying https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:05:39 INFO - Downloading https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/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 05:05:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/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 05:05:39 INFO - Downloaded 16352497 bytes. 05:05:39 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:05: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')]} 05:05:46 INFO - trying https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:05:46 INFO - Downloading https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/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 05:05:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/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 05:05:47 INFO - Downloaded 14413981 bytes. 05:05:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:05:47 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')]} 05:05:47 INFO - trying https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:05:47 INFO - Downloading https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:05:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/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 05:05:50 INFO - Downloaded 111478110 bytes. 05:05:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:05:50 INFO - mkdir: /builds/slave/test/properties 05:05:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:50 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:50 INFO - Contents: 05:05:50 INFO - build_url:https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:05:51 INFO - Running post-action listener: _resource_record_post_action 05:05:51 INFO - Running post-action listener: set_extra_try_arguments 05:05:51 INFO - [mozharness: 2016-08-23 12:05:51.211143Z] Finished download-and-extract step (success) 05:05:51 INFO - [mozharness: 2016-08-23 12:05:51.211299Z] Running populate-webroot step. 05:05:51 INFO - Running pre-action listener: _resource_record_pre_action 05:05:51 INFO - Running main action method: populate_webroot 05:05:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:51 INFO - '--tppagecycles', 05:05:51 INFO - '7'], 05:05:51 INFO - 'tests': ['tsvgm']}}, 05:05:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:05:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:05:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:05:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:05:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:51 INFO - 'talos_options': ['--disable-e10s'], 05:05:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:51 INFO - 'talos_options': ['--disable-e10s'], 05:05:51 INFO - 'tests': ['damp', 'tps']}, 05:05:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:51 INFO - 'tests': ['damp', 'tps']}, 05:05:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:05:51 INFO - 'tests': ['dromaeo_dom']}, 05:05:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:05:51 INFO - 'tests': ['basic_compositor_video']}, 05:05:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:05:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:05:51 INFO - 'tests': ['a11yr', 05:05:51 INFO - 'ts_paint', 05:05:51 INFO - 'tpaint', 05:05:51 INFO - 'sessionrestore', 05:05:51 INFO - 'sessionrestore_no_auto_restore', 05:05:51 INFO - 'tabpaint']}, 05:05:51 INFO - 'other-e10s': {'tests': ['a11yr', 05:05:51 INFO - 'ts_paint', 05:05:51 INFO - 'tpaint', 05:05:51 INFO - 'sessionrestore', 05:05:51 INFO - 'sessionrestore_no_auto_restore', 05:05:51 INFO - 'tabpaint']}, 05:05:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:05:51 INFO - 'ts_paint', 05:05:51 INFO - 'tpaint', 05:05:51 INFO - 'sessionrestore', 05:05:51 INFO - 'sessionrestore_no_auto_restore', 05:05:51 INFO - 'tabpaint']}, 05:05:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:05:51 INFO - 'ts_paint', 05:05:51 INFO - 'tpaint', 05:05:51 INFO - 'sessionrestore', 05:05:51 INFO - 'sessionrestore_no_auto_restore', 05:05:51 INFO - 'tabpaint']}, 05:05:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:05:51 INFO - 'tests': ['a11yr', 05:05:51 INFO - 'ts_paint', 05:05:51 INFO - 'tpaint', 05:05:51 INFO - 'sessionrestore', 05:05:51 INFO - 'sessionrestore_no_auto_restore', 05:05:51 INFO - 'tabpaint']}, 05:05:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:05:51 INFO - 'tests': ['a11yr', 05:05:51 INFO - 'ts_paint', 05:05:51 INFO - 'tpaint', 05:05:51 INFO - 'sessionrestore', 05:05:51 INFO - 'sessionrestore_no_auto_restore', 05:05:51 INFO - 'tabpaint']}, 05:05:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:05:51 INFO - 'tests': ['tsvgx', 05:05:51 INFO - 'tsvgr_opacity', 05:05:51 INFO - 'tart', 05:05:51 INFO - 'tscrollx', 05:05:51 INFO - 'cart']}, 05:05:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:05:51 INFO - 'tsvgr_opacity', 05:05:51 INFO - 'tart', 05:05:51 INFO - 'tscrollx', 05:05:51 INFO - 'cart']}, 05:05:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:51 INFO - 'talos_options': ['--disable-e10s'], 05:05:51 INFO - 'tests': ['tp5o']}, 05:05:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:51 INFO - 'tests': ['tp5o']}, 05:05:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:51 INFO - 'talos_options': ['--disable-e10s', 05:05:51 INFO - '--xperf_path', 05:05:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:51 INFO - 'tests': ['tp5n']}, 05:05:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:51 INFO - 'talos_options': ['--xperf_path', 05:05:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:51 INFO - 'tests': ['tp5n']}}, 05:05:51 INFO - 'talos.zip': {'path': '', 05:05:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:51 INFO - Running post-action listener: _resource_record_post_action 05:05:51 INFO - [mozharness: 2016-08-23 12:05:51.229964Z] Finished populate-webroot step (success) 05:05:51 INFO - [mozharness: 2016-08-23 12:05:51.230101Z] Running create-virtualenv step. 05:05:51 INFO - Running pre-action listener: _resource_record_pre_action 05:05:51 INFO - Running main action method: create_virtualenv 05:05:51 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05:51 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 05:05:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:51 INFO - Using real prefix '/tools/python27' 05:05:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:52 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:54 INFO - Installing pip.................done. 05:05:54 INFO - Return code: 0 05:05:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:54 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 0x102f2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fbf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d7a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e8ed30>, '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 0x102bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e86b20>, '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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 05:05:54 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 05:05:54 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 05:05:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIa6WrW1Kt/Render', 05:05:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:54 INFO - 'HOME': '/Users/cltbld', 05:05:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:54 INFO - 'LOGNAME': 'cltbld', 05:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:54 INFO - 'MOZ_NO_REMOTE': '1', 05:05:54 INFO - 'NO_EM_RESTART': '1', 05:05:54 INFO - 'PAGER': '/bin/cat', 05:05:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:54 INFO - 'PWD': '/builds/slave/test', 05:05:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:54 INFO - 'SHELL': '/bin/bash', 05:05:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kcb1VyLj3V/Listeners', 05:05:54 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 05:05:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:54 INFO - 'USER': 'cltbld', 05:05:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:54 INFO - 'XPC_FLAGS': '0x0', 05:05:54 INFO - 'XPC_SERVICE_NAME': '0', 05:05:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:55 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 05:05:55 INFO - Cleaning up... 05:05:55 INFO - Return code: 0 05:05:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:55 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 0x102f2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fbf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d7a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e8ed30>, '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 0x102bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e86b20>, '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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 05:05:55 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 05:05:55 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 05:05:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIa6WrW1Kt/Render', 05:05:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:55 INFO - 'HOME': '/Users/cltbld', 05:05:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:55 INFO - 'LOGNAME': 'cltbld', 05:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:55 INFO - 'MOZ_NO_REMOTE': '1', 05:05:55 INFO - 'NO_EM_RESTART': '1', 05:05:55 INFO - 'PAGER': '/bin/cat', 05:05:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:55 INFO - 'PWD': '/builds/slave/test', 05:05:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:55 INFO - 'SHELL': '/bin/bash', 05:05:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kcb1VyLj3V/Listeners', 05:05:55 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 05:05:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:55 INFO - 'USER': 'cltbld', 05:05:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:55 INFO - 'XPC_FLAGS': '0x0', 05:05:55 INFO - 'XPC_SERVICE_NAME': '0', 05:05:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:55 INFO - Downloading/unpacking psutil>=3.1.1 05:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:58 INFO - Installing collected packages: psutil 05:05:58 INFO - Running setup.py install for psutil 05:05:58 INFO - building 'psutil._psutil_osx' extension 05:05:58 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 05:05:59 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 05:05:59 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 05:05:59 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 05:05:59 INFO - building 'psutil._psutil_posix' extension 05:05:59 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 05:05:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:05:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:05:59 INFO - ^ 05:05:59 INFO - 1 warning generated. 05:05:59 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 05:05:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:59 INFO - Successfully installed psutil 05:05:59 INFO - Cleaning up... 05:05:59 INFO - Return code: 0 05:05:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fbf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d7a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e8ed30>, '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 0x102bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e86b20>, '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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 05:05:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:05:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIa6WrW1Kt/Render', 05:05:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:59 INFO - 'HOME': '/Users/cltbld', 05:05:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:59 INFO - 'LOGNAME': 'cltbld', 05:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:59 INFO - 'MOZ_NO_REMOTE': '1', 05:05:59 INFO - 'NO_EM_RESTART': '1', 05:05:59 INFO - 'PAGER': '/bin/cat', 05:05:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:59 INFO - 'PWD': '/builds/slave/test', 05:05:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:59 INFO - 'SHELL': '/bin/bash', 05:05:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kcb1VyLj3V/Listeners', 05:05:59 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 05:05:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:59 INFO - 'USER': 'cltbld', 05:05:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:59 INFO - 'XPC_FLAGS': '0x0', 05:05:59 INFO - 'XPC_SERVICE_NAME': '0', 05:05:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:06:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:06:02 INFO - Installing collected packages: mozsystemmonitor 05:06:02 INFO - Running setup.py install for mozsystemmonitor 05:06:02 INFO - Successfully installed mozsystemmonitor 05:06:02 INFO - Cleaning up... 05:06:02 INFO - Return code: 0 05:06:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102f2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fbf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d7a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e8ed30>, '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 0x102bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e86b20>, '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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 05:06:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:06:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIa6WrW1Kt/Render', 05:06:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:02 INFO - 'HOME': '/Users/cltbld', 05:06:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:02 INFO - 'LOGNAME': 'cltbld', 05:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:02 INFO - 'MOZ_NO_REMOTE': '1', 05:06:02 INFO - 'NO_EM_RESTART': '1', 05:06:02 INFO - 'PAGER': '/bin/cat', 05:06:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:02 INFO - 'PWD': '/builds/slave/test', 05:06:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:02 INFO - 'SHELL': '/bin/bash', 05:06:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kcb1VyLj3V/Listeners', 05:06:02 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 05:06:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:02 INFO - 'USER': 'cltbld', 05:06:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:02 INFO - 'XPC_FLAGS': '0x0', 05:06:02 INFO - 'XPC_SERVICE_NAME': '0', 05:06:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:03 INFO - Downloading/unpacking jsonschema==2.5.1 05:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:06:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:06:05 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 05:06:05 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 05:06:05 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 05:06:05 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 05:06:05 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 05:06:05 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 05:06:05 INFO - Downloading functools32-3.2.3-2.tar.gz 05:06:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:06:05 INFO - warning: no files found matching '*.txt' 05:06:05 INFO - no previously-included directories found matching 'build' 05:06:05 INFO - no previously-included directories found matching 'dist' 05:06:05 INFO - no previously-included directories found matching '.git*' 05:06:05 INFO - Installing collected packages: jsonschema, functools32 05:06:06 INFO - Running setup.py install for functools32 05:06:06 INFO - warning: no files found matching '*.txt' 05:06:06 INFO - no previously-included directories found matching 'build' 05:06:06 INFO - no previously-included directories found matching 'dist' 05:06:06 INFO - no previously-included directories found matching '.git*' 05:06:06 INFO - Successfully installed jsonschema functools32 05:06:06 INFO - Cleaning up... 05:06:06 INFO - Return code: 0 05:06:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:06:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:06 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 0x102f2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fbf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d7a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e8ed30>, '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 0x102bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e86b20>, '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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 05:06:06 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 05:06:06 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 05:06:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIa6WrW1Kt/Render', 05:06:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:06 INFO - 'HOME': '/Users/cltbld', 05:06:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:06 INFO - 'LOGNAME': 'cltbld', 05:06:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:06 INFO - 'MOZ_NO_REMOTE': '1', 05:06:06 INFO - 'NO_EM_RESTART': '1', 05:06:06 INFO - 'PAGER': '/bin/cat', 05:06:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:06 INFO - 'PWD': '/builds/slave/test', 05:06:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:06 INFO - 'SHELL': '/bin/bash', 05:06:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kcb1VyLj3V/Listeners', 05:06:06 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 05:06:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:06 INFO - 'USER': 'cltbld', 05:06:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:06 INFO - 'XPC_FLAGS': '0x0', 05:06:06 INFO - 'XPC_SERVICE_NAME': '0', 05:06:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:06:06 INFO - Cleaning up... 05:06:06 INFO - Return code: 0 05:06:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:06:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:06 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 0x102f2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fbf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d7a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e8ed30>, '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 0x102bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e86b20>, '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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 05:06:06 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 05:06:06 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 05:06:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIa6WrW1Kt/Render', 05:06:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:06 INFO - 'HOME': '/Users/cltbld', 05:06:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:06 INFO - 'LOGNAME': 'cltbld', 05:06:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:06 INFO - 'MOZ_NO_REMOTE': '1', 05:06:06 INFO - 'NO_EM_RESTART': '1', 05:06:06 INFO - 'PAGER': '/bin/cat', 05:06:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:06 INFO - 'PWD': '/builds/slave/test', 05:06:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:06 INFO - 'SHELL': '/bin/bash', 05:06:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kcb1VyLj3V/Listeners', 05:06:06 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 05:06:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:06 INFO - 'USER': 'cltbld', 05:06:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:06 INFO - 'XPC_FLAGS': '0x0', 05:06:06 INFO - 'XPC_SERVICE_NAME': '0', 05:06:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:06 INFO - Downloading/unpacking blobuploader==1.2.4 05:06:06 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 05:06:06 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 05:06:06 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 05:06:06 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 05:06:06 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 05:06:06 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 05:06:09 INFO - Downloading blobuploader-1.2.4.tar.gz 05:06:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:06:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:06:09 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 05:06:09 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 05:06:09 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 05:06:09 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 05:06:09 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 05:06:09 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 05:06:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:06:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:06:09 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 05:06:09 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 05:06:09 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 05:06:09 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 05:06:09 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 05:06:09 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 05:06:10 INFO - Downloading docopt-0.6.1.tar.gz 05:06:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:06:10 INFO - Installing collected packages: blobuploader, requests, docopt 05:06:10 INFO - Running setup.py install for blobuploader 05:06:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:06:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:06:10 INFO - Running setup.py install for requests 05:06:11 INFO - Running setup.py install for docopt 05:06:11 INFO - Successfully installed blobuploader requests docopt 05:06:11 INFO - Cleaning up... 05:06:11 INFO - Return code: 0 05:06:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:11 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 0x102f2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fbf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d7a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e8ed30>, '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 0x102bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e86b20>, '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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 05:06:11 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 05:06:11 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 05:06:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIa6WrW1Kt/Render', 05:06:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:11 INFO - 'HOME': '/Users/cltbld', 05:06:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:11 INFO - 'LOGNAME': 'cltbld', 05:06:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:11 INFO - 'MOZ_NO_REMOTE': '1', 05:06:11 INFO - 'NO_EM_RESTART': '1', 05:06:11 INFO - 'PAGER': '/bin/cat', 05:06:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:11 INFO - 'PWD': '/builds/slave/test', 05:06:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:11 INFO - 'SHELL': '/bin/bash', 05:06:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kcb1VyLj3V/Listeners', 05:06:11 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 05:06:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:11 INFO - 'USER': 'cltbld', 05:06:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:11 INFO - 'XPC_FLAGS': '0x0', 05:06:11 INFO - 'XPC_SERVICE_NAME': '0', 05:06:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-BT4rRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-jMkizg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Vni8oD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-9cIdg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-qRpgE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-y_4upd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-WikErG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-PMrXhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Cnnw2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-CrkuM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Wkv6d6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-K04k9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-BS_Dsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-8AeZ_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-9HkDGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-99iKtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ELuRC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:06:13 INFO - Running setup.py install for manifestparser 05:06:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:06:13 INFO - Running setup.py install for mozcrash 05:06:13 INFO - Running setup.py install for mozdebug 05:06:13 INFO - Running setup.py install for mozdevice 05:06:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:06:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:06:13 INFO - Running setup.py install for mozfile 05:06:14 INFO - Running setup.py install for mozhttpd 05:06:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:06:14 INFO - Running setup.py install for mozinfo 05:06:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:06:14 INFO - Running setup.py install for mozInstall 05:06:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:06:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:06:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:06:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:06:14 INFO - Running setup.py install for mozleak 05:06:14 INFO - Running setup.py install for mozlog 05:06:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:06:14 INFO - Running setup.py install for moznetwork 05:06:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:06:15 INFO - Running setup.py install for mozprocess 05:06:15 INFO - Running setup.py install for mozprofile 05:06:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:06:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:06:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:06:15 INFO - Running setup.py install for mozrunner 05:06:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:06:15 INFO - Running setup.py install for mozscreenshot 05:06:15 INFO - Running setup.py install for moztest 05:06:15 INFO - Running setup.py install for mozversion 05:06:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:06:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:06:15 INFO - Cleaning up... 05:06:15 INFO - Return code: 0 05:06:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:15 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 0x102f2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fbf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d7a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e8ed30>, '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 0x102bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e86b20>, '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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 05:06:15 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 05:06:15 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 05:06:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIa6WrW1Kt/Render', 05:06:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:15 INFO - 'HOME': '/Users/cltbld', 05:06:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:15 INFO - 'LOGNAME': 'cltbld', 05:06:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:15 INFO - 'MOZ_NO_REMOTE': '1', 05:06:15 INFO - 'NO_EM_RESTART': '1', 05:06:15 INFO - 'PAGER': '/bin/cat', 05:06:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:15 INFO - 'PWD': '/builds/slave/test', 05:06:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:15 INFO - 'SHELL': '/bin/bash', 05:06:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kcb1VyLj3V/Listeners', 05:06:15 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 05:06:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:15 INFO - 'USER': 'cltbld', 05:06:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:15 INFO - 'XPC_FLAGS': '0x0', 05:06:15 INFO - 'XPC_SERVICE_NAME': '0', 05:06:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-G6WJMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:16 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)) 05:06:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-UMZ6Vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:16 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)) 05:06:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-YkTUU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:16 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)) 05:06:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-R5zmEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:16 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)) 05:06:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-sUVAZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:16 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)) 05:06:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-6VpjOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:16 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)) 05:06:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Ip2s08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:16 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)) 05:06:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-QygOQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-gwaTEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-8xCyZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-nrgwjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Uldw52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-cWosDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-XhZP7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-2mRbOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-l5n9PB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-BmbIpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:18 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)) 05:06:18 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)) 05:06:18 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)) 05:06:18 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)) 05:06:18 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)) 05:06:18 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)) 05:06:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:20 INFO - Downloading blessings-1.6.tar.gz 05:06:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:06:20 INFO - Installing collected packages: blessings 05:06:20 INFO - Running setup.py install for blessings 05:06:20 INFO - Successfully installed blessings 05:06:20 INFO - Cleaning up... 05:06:20 INFO - Return code: 0 05:06:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:06:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:21 INFO - Reading from file tmpfile_stdout 05:06:21 INFO - Current package versions: 05:06:21 INFO - blessings == 1.6 05:06:21 INFO - blobuploader == 1.2.4 05:06:21 INFO - docopt == 0.6.1 05:06:21 INFO - functools32 == 3.2.3-2 05:06:21 INFO - jsonschema == 2.5.1 05:06:21 INFO - manifestparser == 1.1 05:06:21 INFO - mozInstall == 1.12 05:06:21 INFO - mozcrash == 0.17 05:06:21 INFO - mozdebug == 0.1 05:06:21 INFO - mozdevice == 0.48 05:06:21 INFO - mozfile == 1.2 05:06:21 INFO - mozhttpd == 0.7 05:06:21 INFO - mozinfo == 0.9 05:06:21 INFO - mozleak == 0.1 05:06:21 INFO - mozlog == 3.3 05:06:21 INFO - moznetwork == 0.27 05:06:21 INFO - mozprocess == 0.23 05:06:21 INFO - mozprofile == 0.28 05:06:21 INFO - mozrunner == 6.12 05:06:21 INFO - mozscreenshot == 0.1 05:06:21 INFO - mozsystemmonitor == 0.3 05:06:21 INFO - moztest == 0.7 05:06:21 INFO - mozversion == 1.4 05:06:21 INFO - psutil == 3.1.1 05:06:21 INFO - requests == 1.2.3 05:06:21 INFO - wsgiref == 0.1.2 05:06:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:21 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 0x102f2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fbf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d7a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e8ed30>, '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 0x102bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e86b20>, '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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 05:06:21 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 05:06:21 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 05:06:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIa6WrW1Kt/Render', 05:06:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:21 INFO - 'HOME': '/Users/cltbld', 05:06:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:21 INFO - 'LOGNAME': 'cltbld', 05:06:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:21 INFO - 'MOZ_NO_REMOTE': '1', 05:06:21 INFO - 'NO_EM_RESTART': '1', 05:06:21 INFO - 'PAGER': '/bin/cat', 05:06:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:21 INFO - 'PWD': '/builds/slave/test', 05:06:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:21 INFO - 'SHELL': '/bin/bash', 05:06:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kcb1VyLj3V/Listeners', 05:06:21 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 05:06:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:21 INFO - 'USER': 'cltbld', 05:06:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:21 INFO - 'XPC_FLAGS': '0x0', 05:06:21 INFO - 'XPC_SERVICE_NAME': '0', 05:06:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:21 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)) 05:06:21 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)) 05:06:21 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)) 05:06:21 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)) 05:06:21 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)) 05:06:21 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)) 05:06:21 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)) 05:06:21 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)) 05:06:21 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)) 05:06:21 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)) 05:06:21 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)) 05:06:21 INFO - Cleaning up... 05:06:21 INFO - Return code: 0 05:06:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:21 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 0x102f2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fbf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff552d7a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e8ed30>, '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 0x102bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e86b20>, '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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 05:06:21 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 05:06:21 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 05:06:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIa6WrW1Kt/Render', 05:06:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:21 INFO - 'HOME': '/Users/cltbld', 05:06:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:21 INFO - 'LOGNAME': 'cltbld', 05:06:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:21 INFO - 'MOZ_NO_REMOTE': '1', 05:06:21 INFO - 'NO_EM_RESTART': '1', 05:06:21 INFO - 'PAGER': '/bin/cat', 05:06:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:21 INFO - 'PWD': '/builds/slave/test', 05:06:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:21 INFO - 'SHELL': '/bin/bash', 05:06:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kcb1VyLj3V/Listeners', 05:06:21 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 05:06:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:21 INFO - 'USER': 'cltbld', 05:06:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:21 INFO - 'XPC_FLAGS': '0x0', 05:06:21 INFO - 'XPC_SERVICE_NAME': '0', 05:06:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:06:21 INFO - Cleaning up... 05:06:21 INFO - Return code: 0 05:06:21 INFO - Running post-action listener: _resource_record_post_action 05:06:21 INFO - Running post-action listener: _start_resource_monitoring 05:06:21 INFO - Starting resource monitoring. 05:06:21 INFO - [mozharness: 2016-08-23 12:06:21.656040Z] Finished create-virtualenv step (success) 05:06:21 INFO - [mozharness: 2016-08-23 12:06:21.657027Z] Running install step. 05:06:21 INFO - Running pre-action listener: _resource_record_pre_action 05:06:21 INFO - Running main action method: install 05:06:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:21 INFO - Reading from file tmpfile_stdout 05:06:21 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:22 INFO - Reading from file tmpfile_stdout 05:06:22 INFO - Output received: 05:06:22 INFO - Usage: mozinstall [options] installer 05:06:22 INFO - Options: 05:06:22 INFO - -h, --help show this help message and exit 05:06:22 INFO - -d DEST, --destination=DEST 05:06:22 INFO - Directory to install application into. [default: 05:06:22 INFO - "/builds/slave/test"] 05:06:22 INFO - --app=APP Application being installed. [default: firefox] 05:06:22 INFO - mkdir: /builds/slave/test/build/application 05:06:22 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'] 05:06:22 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 05:06:55 INFO - Reading from file tmpfile_stdout 05:06:55 INFO - Output received: 05:06:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:06:55 INFO - Running post-action listener: _resource_record_post_action 05:06:55 INFO - [mozharness: 2016-08-23 12:06:55.911471Z] Finished install step (success) 05:06:55 INFO - [mozharness: 2016-08-23 12:06:55.911599Z] Running run-tests step. 05:06:55 INFO - Running pre-action listener: _resource_record_pre_action 05:06:55 INFO - Running main action method: run_tests 05:06:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:55 INFO - 2016-08-23 05:06:55.926 screenresolution[1631:7910] starting screenresolution argv=screenresolution get 05:06:55 INFO - 2016-08-23 05:06:55.928 screenresolution[1631:7910] Display 0: 1600x1200x32@60 05:06:55 INFO - 2016-08-23 05:06:55.935 screenresolution[1632:7912] starting screenresolution argv=screenresolution list 05:06:55 INFO - Available Modes on Display 0 05:06:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:56 INFO - Intel Iris: 05:06:56 INFO - Chipset Model: Intel Iris 05:06:56 INFO - Type: GPU 05:06:56 INFO - Bus: Built-In 05:06:56 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:56 INFO - Vendor: Intel (0x8086) 05:06:56 INFO - Device ID: 0x0a2e 05:06:56 INFO - Revision ID: 0x0009 05:06:56 INFO - Displays: 05:06:56 INFO - Display: 05:06:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:56 INFO - Display Serial Number: 1600x1200 60 05:06:56 INFO - Main Display: Yes 05:06:56 INFO - Mirror: Off 05:06:56 INFO - Online: Yes 05:06:56 INFO - Rotation: Supported 05:06:56 INFO - Return code: 0 05:06:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:56 INFO - Python 2.7.3 05:06:56 INFO - Return code: 0 05:06:56 INFO - grabbing minidump binary from tooltool 05:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:56 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 0x102e8ed30>, '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 0x102bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e86b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:56 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 05:06:56 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 05:06:56 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 05:06:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:56 INFO - Return code: 0 05:06:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:06:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:06:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:56 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/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 05:06:56 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/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0104 --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 05:06:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tIa6WrW1Kt/Render', 05:06:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:56 INFO - 'HOME': '/Users/cltbld', 05:06:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:56 INFO - 'LOGNAME': 'cltbld', 05:06:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:56 INFO - 'MOZ_NO_REMOTE': '1', 05:06:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:56 INFO - 'NO_EM_RESTART': '1', 05:06:56 INFO - 'PAGER': '/bin/cat', 05:06:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:56 INFO - 'PWD': '/builds/slave/test', 05:06:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:56 INFO - 'SHELL': '/bin/bash', 05:06:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kcb1VyLj3V/Listeners', 05:06:56 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 05:06:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:56 INFO - 'USER': 'cltbld', 05:06:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:56 INFO - 'XPC_FLAGS': '0x0', 05:06:56 INFO - 'XPC_SERVICE_NAME': '0', 05:06:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:56 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/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 05:06:56 INFO - mozversion application_buildid: 20160823015246 05:06:56 INFO - mozversion application_changeset: 0faec8f3a8b59106a3437d954d1ccc38da7ec705 05:06:56 INFO - mozversion application_display_name: Nightly 05:06:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:56 INFO - mozversion application_name: Firefox 05:06:56 INFO - mozversion application_remotingname: firefox 05:06:56 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:06:56 INFO - mozversion application_vendor: Mozilla 05:06:56 INFO - mozversion application_version: 51.0a1 05:06:56 INFO - mozversion platform_buildid: 20160823015246 05:06:56 INFO - mozversion platform_changeset: 0faec8f3a8b59106a3437d954d1ccc38da7ec705 05:06:56 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:06:56 INFO - mozversion platform_version: 51.0a1 05:06:56 INFO - using testdate: 1471954016 05:06:56 INFO - actual date: 1471954016 05:06:56 INFO - starting webserver on 'localhost:49267' 05:06:56 INFO - SUITE-START | Running 2 tests 05:06:56 INFO - TEST-START | dromaeo_css 05:06:56 INFO - operating with platform_type : mac_ 05:06:56 INFO - Initialising browser for dromaeo_css test... 05:06:56 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpJlw7Eq/profile http://localhost:49267/getInfo.html) 05:06:58 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 05:06:58 INFO - PROCESS | 1642 | colorDepth:24 05:06:58 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 05:06:58 INFO - PROCESS | 1642 | __metrics 05:06:59 INFO - PROCESS | 1642 | 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:06:59 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:59 INFO - TEST-INFO | 1642: exit 0 05:06:59 INFO - Browser initialized. 05:06:59 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:59 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpJlw7Eq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:11 INFO - PROCESS | 1645 | RSS: Main: 228593664 05:07:11 INFO - PROCESS | 1645 | 05:08:02 INFO - PROCESS | 1645 | 1471954082883 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] 05:10:14 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 05:10:16 INFO - PROCESS | 1645 | RSS: Main: 326959104 05:10:16 INFO - PROCESS | 1645 | 05:13:19 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 05:13:19 INFO - PROCESS | 1645 | RSS: Main: 373403648 05:13:19 INFO - PROCESS | 1645 | 05:16:22 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 05:16:22 INFO - PROCESS | 1645 | RSS: Main: 338964480 05:16:22 INFO - PROCESS | 1645 | 05:19:25 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 05:19:26 INFO - PROCESS | 1645 | RSS: Main: 349265920 05:19:26 INFO - PROCESS | 1645 | 05:22:29 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 05:22:30 INFO - PROCESS | 1645 | RSS: Main: 333701120 05:22:30 INFO - PROCESS | 1645 | 05:25:33 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 05:25:34 INFO - PROCESS | 1645 | RSS: Main: 416702464 05:25:34 INFO - PROCESS | 1645 | 05:25:34 INFO - PROCESS | 1645 | __start_tp_report 05:25:34 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 05:25:34 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 05:25:34 INFO - PROCESS | 1645 | |i|pagename|runs| 05:25:34 INFO - PROCESS | 1645 | |0;dojo.html;3344.816035118069;3397.1846756778373;3399.320135972806;3401.370746411914;3415.795052296862;5662.150677398392;5708.08670612702;5730.7421166047525;5802.564807639428;5815.47660710536;5645.1814486899375;5729.0833466645345;5736.22560954271;5743;5757.913631295523;5136.948630513703;5139.408967968676;5193.298904647881;5208.401033881095;5245.554127899114;5120.975804839047;5244.213367994793;5281.471852814726;5290;5305.965336759334;5312.096943519592;5318.680879147244;5322.2814919985885;5324.467553244684;5328.094223981932;5125.743712814345;5150.175971844525;5189.532942035235;5312.601554883399;5325.840224793263;5198.792048318062;5212.973935130338;5247.737613119329;5250.789968401259;5277.604179686539;4827.348307978413;5746.626469279484;5785.623934444247;5801.7389217485315;5813.912791308134;6099.298580663236;6174.96912515439;6220.87558248837;6241.469475094623;6244.5316601254635;6123.265208175003;6140.324564297924;6200.534959878028;6247.468965137969;6288.937110628926;6013.398660133952;6014.09788531715;6017;6028.367021462684;6039.939600604026;6201.286852012076;6426.710798014007;6454.963570354938;6462.289148193695;6464.870702585922;6134.171886795315;6185.195924529782;6204.441600255998;6218.533609979179;6234.719437625317;5079.339685840817;5951.791687290924;6284.685765711788;6305.054241863665;6306.369363063749;5116.258142569307;6147.415995480422;6153.630782153085;6217.844553886189;6296.087067375205;6107.664078475637;6176.8764624707255;6190.102435146879;6223.875522489616;6235.376462353728;5086.77109530072;5091.821786237464;5101.821436249714;5105.106606343875;5115.693058416433;4420.9336859947125;4546.999660074778;4567.931481027787;4571.5428457154685;4580.519045500241;5739.62692424991;5740.54075673945;5816.709164541756;5832.504237139806;5933.821985340447;6213.502919766498;6247.9062814057825;6258.874822503524;6271.937280627227;6288;5593.692346920959;5832.88334233313;5833.329167145747;5847.093700476433;5926.170336152942;5420.376656684453;5437.157240627709;5495.368032676213;5538.0585300499015;5555.472230138131;6447.097406363113;6460.321666224979;6464.676766161673;6467.935320646733;6476.838079048061;6214.285357183981;6313.652749098844;6333.36666333363;6336.683165841598;6356.745730170742;6404.327545607644;6481.222253329631;6482.773102941563;6488.772892948724;6499.122618446449;6272.905906411316;6292.811215663537;6292.96166132583;6312.905306420499;6320.462760665395;6173.536984726074;6272.937270627235;6292.087647291209;6316.589421687576;6332.398422149889;6104.664243466652;6168.938310616836;6178.042403427476;6246.406591373812;6255.96872015637;6078.483328917092;6191.25704915413;6237.812865614039;6253.812385628438;6264.937350626436;5817.970910145422;6077.179580756718;6090.604110732788;6093.542984276109;6107.450329470369;6062.514998799998;6140.263168419819;6164.260288765377;6247.68761561911;6262.09199666055;5975.731092100953;6032.095185722177;6039.15451836752;6047.365026672136;6115.969420152871 05:25:34 INFO - PROCESS | 1645 | |1;ext.html;483.83549593138326;500.2046745674377;506.3215291509378;517.9067767801796;526.7261024267383;31089;32557.5116373254;32654.183645408877;32758.836205818938;33364.33271334572;41167.97080073;43597;43673.12653746923;43685;43725.906852328706;9735.075167859042;9905.158061564776;9918.702438926844;9920.206383489303;9926.106650401462;8754.86867696985;8983.146601072887;9776;9887.060729230712;9900.752481187956;8923.15230053144;8979.057198994109;8982.461052336848;11832.585859494919;12149.878501215007;12010.63968080959;12083.637490875288;12088;12089.576864809778;12102.636920892299;11820.468078936383;11907.642770716893;11918.88081119195;11924.70188245292;11926.582569610022;31808.840955795305;32317;32374.352512949845;40382.596174038175;40805;2936.7834851677067;3363.5291059251726;3483.3381657484997;3557.004038869121;3597.1366871950804;31981;32781.508277375855;32904;32923;33082.842100526366;3321.468565029608;3374.3926093303194;3413.6074351449406;3465.9133521661906;3478.9826050869838;501404;502966;506577.94087502605;507964;511584.9573538362;33587.328253434796;34554.13614659654;34608;61312.38687613156;61969.690151549854;35737.64262357343;36009.27981440356;36036.27927441489;36079.94880716702;36107.81946090253;14794.778078328834;14895.180765057808;14971.850281497263;14988.47540336083;14993.475228366955;12723.491060357666;12821.807672884914;12840;12847.614571562868;12858.935705321413;7772.417068719868;7785.299323060952;7812.296893279632;7854.528728276322;7896.526208427513;3032.757379409642;3127;3158.2262345724985;3194.6965038321323;3241.33552621712;935.3873213045466;937.2782957123113;972.4311277902365;995.2833959549113;1047.8742550893942;30552.54171187434;30629.693703062683;30725.846370768002;31246.21884452907;31733.682663173535;1046.2676126711333;1068.7541865371008;1074.0172741941037;1102.6471529110768;1113.3320007995137;15719.921400393154;15783.921080394754;15813.920930395274;15836.445724399591;16163.838361616234;706675;707645;708241;710677;711575;58827;59414;59479;59644;59781;1234.4444999750149;1343.8924886009095;1357.565579014706;1399.7410479061202;1401.0192864994544;31117;31481.120426333953;31532.842335788173;31869.68130318667;32312;50681.986360273324;51248.48751512437;51287.487125129766;51306.74346628243;51328.230076548134;553022;555720;555734;557145;658784;39272.214555709295;41276.79361603293;41361.379579305736;42226.155476889675;42464;46177;46827;47257.76371118122;50227.74886125546;50543.24185137299;39918;40245;40682;41598.58401416068;41694.95762605959;39638;40200.597994019685;40200.798996004836;40546.59453405546;40780.388294176206;38032.29515572686;38299.80850095732;39046.80476597599;39087.80456097701;39334.213315734094;33152.50813713437;34406.48390274198;35472.29055418929;35527.82236088803;35611;23593.174238901556;24580.754192457847;25973.740262597134;26078.478430430907;26344.604830927936 05:25:34 INFO - PROCESS | 1645 | |2;jquery.html;318.8357995632249;355.74386441761953;376.6722951032602;383.61446746020255;404.6041158004884;15539.98990065645;16937.576560585978;20486.795132048675;21053.578928421422;21143.154273829066;20064.398068057944;20701;20954.685679714818;21534.56930861382;21582.676259856114;4605.608523275526;5254.395744489394;5406.8918621627545;5581.6371935824045;5582.413846546115;3864.7101467389916;5373.919391209135;5452.836414907559;5459.143891077033;5464.180372944071;5845.5908086433965;6102.664353460558;6124.755009799603;6140.232470941132;6150.985087460586;5875.618084824473;6097.3597772233725;6128.325884152762;6131.816045518619;6188.195534580521;5926.199963005025;5929.762809487615;5931.6144450611;5936.821895343147;5939.970300148515;17157.742633860504;25659.615105773428;26219.475610487683;26407.339816504547;26698;2185.3880913344287;2411.168146989286;2434.330559096245;2512.748725127491;2652.9204123876316;9838;10312.175025997902;10675.199360048027;12632.49470021198;16011.719062474975;1811.873168878179;1908.6182763447366;1934.6130773845287;1961.2547232051872;1973.7138114973393;119382;130977;132403;134152;134197;10986.285891417032;11400.600978965696;11462.598809041643;11504.424778761197;11677.532898684127;14658;14683.19242441676;14696.485623003144;14699.41202351894;14737.852621473863;8363.540005299767;8607.61265743043;8632.697855575025;8784.95607521971;8786.472811631322;7172.892406613801;8582.65669373218;8607.871901340237;8610;8809;4497.145542346943;4555.521670224579;4558.635309175258;4642.558956899089;4731.834385796481;1851.666699993987;1912.8427301482682;1916.434651777729;1917.2522716140716;1921.4331772127257;847.5380917399908;922.4280945813639;933.6732143749633;962.5331714118647;966.3090890013533;20370.796292037187;21633.783662163176;21661.89169054176;21721.34835954924;21740.673889891663;911.3165126155384;913.7487191022389;922.7093465558328;929.1312622697799;933.537898740128;10982.615608453665;11398.259113157785;11411.372374519315;11532.308061516336;11565.190436669352;592728;593809;603143;616961;618725;103846.96153038373;127355.36322318514;131426;132213.33893330663;132534;916.6883259691737;966.3042609321277;976.4239098931664;983.468926779531;985.8373368394278;14896.702065958836;16374;19255.422337329903;19473.90263048676;19570.90214548918;52472.47527524829;53353.19970200372;53362.46637533575;54454.18318725143;55204.171937420164;77977;78239;79061.20938790539;79229.60385198037;79609.65074540273;7080.787576372717;7440.8466687663495;7468.10382754073;7944;8462.588162373693;9286.117818807203;10593.89406105929;13290.269035203157;13759.931200343934;13790.655233618838;11465.082793376343;11506.712332191428;11822.763544729229;11883.643490695293;11951.282923024646;11293.71765705831;11461.770764584495;11669.824952625548;11830.52677892909;11982.741812109603;9887.901120988698;11501.424928753362;11554.59558915434;11971.76056478883;12130.090243231616;6888.276730943179;7584.2794934481135;7636.961815190888;7708.807279818049;7819.648115834687;10413.687589372332;11422.600208992646;11587.652370428901;11817.881821182022;11902.880971190178 05:25:34 INFO - PROCESS | 1645 | |3;mootools.html;1362.5026865194206;1527.7250094982903;1547.5434746749709;1726.740988851672;1870.3640762140878;7110.7866763997035;7886.684532618696;8852.29181665466;8894.77763055924;8956.507392093432;8693.391462597621;8747.606357713903;8748.387612867102;8758.781030474225;8790.736277911657;2364.1016413762745;2392.437777122375;2393.6888204533475;2397.6283676030243;2398.6162214045758;2344.531093781242;2375.5367703297866;2391.175044609607;2397.4126339046957;2407.7592240775957;2363.5391098735754;2369.1115831563166;2375.133076427109;2378.63131214662;2387.486690361572;2350.3888988862846;2361;2371.146387300562;2375.809935205197;2388.474535602182;2337.415646088478;2360.0087963055557;2360.433495960974;2361.276268324133;2376.4415362389823;8679.305655547541;8850.247728943048;8888.066752990908;8943.68697095605;8952.8657070144;5199.402068762103;5372.865678358034;5394.109971854678;5403.945960540384;5404.8108316208745;3651.5070465487083;3659.6889264412557;3730.794806285653;3735.6451137141926;3736.5516138063335;1105.6130354375985;1117.5082963496036;1123.4775829239443;1130.988690113105;1136.664683918246;11704.946554810023;11768.882311176863;11882.524699012029;11887.465064072137;11905.345206013666;4220.746755194698;4684.580564384076;4739.881502962385;4817.735024573612;4877.244027175722;10151.492425378701;10152.238582106223;10152.746181345525;10154.492275386201;10159.136473399696;5366.114591092426;5370.382406023279;5373.596980226499;5374.91937620936;5377.0321342158;4429.49060857999;4584.770761461914;4753.976230118897;4895.388076490439;4906.681065730716;3899.8245078971513;3900.2004589059175;4015.257177422186;4052.1085361220485;4052.452918855917;1176.9941150294194;1198.7473090620322;1280.4686055286952;1297.1374036265966;1299.7985312276423;841.6843683618642;843.9240468357877;869.3393021303855;930.2186163622454;971.2472833553911;5926.7925622603;5987.2815262168815;6102.962496375627;6149.754009839557;6251.562390632612;956.5886668732372;962.2109869906609;991.3258983891023;1004.231762701534;1014.802113587858;3925.9607403926166;3997.560268370477;4034.596540345942;4108.67130629549;4139.834406623701;10543;10766.407847568458;10864.185186111074;10876.966688164612;10913.345199288067;10784.299020563638;10813.891861081289;10815.134789216843;10820.13438924884;10838.970297821694;7745.690172393041;8105.8378832424205;8114.959425202836;8219.383546234056;8223.753287401387;5973.1338955852025;5995.310539287863;6005.429484198994;6155.292141403616;6367.299597044229;9626.326157169211;10117.19062475015;10206.081452669296;10268.178545716175;10306;9233.676821311223;10292.279540432099;10304.587816487556;10317;10317.587296508356;7009.3691567757105;7203.8919416207755;7345.22875098107;7752.0697516298405;7963.840723185388;8252.958735206286;8550.74347769568;8627.964644242731;8637.56812159377;8643.740687779376;7448.255174482509;7513.737019204302;7530.736424225126;7559.28186822251;7625.466217364953;7274.599897005715;7373.410127189704;7412.851008093754;7475.102987641518;7650.081990161217;6971.895421568579;7132.786016419308;7133.714651414093;7138.964305178441;7144.3570078693165;4963.131451995814;5045.11710450677;5117.360329958755;5195.428502864757;5264.78940842362;5058.392992840735;5135.435102138837;5157.793688252428;5220.034293655686;5306.761195746123 05:25:34 INFO - PROCESS | 1645 | |4;prototype.html;294.47583301722926;297.28354665256734;306.94474994500996;311.3321924472008;311.8752499000401;4848.757562121888;4853.7815798289075;5088.54203121719;5129.69221846689;5182.404023537294;3912.78479683618;4325.394444777734;4997.700137991723;5025.612833903894;5033.395992480899;3257.8371081445953;3952.6442620164107;3958.7624742515395;4013.4581831452674;4018.678505719535;3567.429211326188;3850.53793544775;4010.69919756018;4015.558288588253;4015.799210039501;3955.525336959569;3962.7424217425773;3972.3247048001763;3981;3990.149505726973;3453.6546345365514;3470.7396945228957;3950.407438884176;3967.6230758077936;3982.6017398260087;3501.877434289813;3507.053095664182;3515.806630635314;3516.7186625069935;3519.3137338218803;4737.620990320766;4926.433460152092;5018.7992480300745;5054.570361519275;5061.240813877911;4523.5024147343765;4546.38623785788;4558.9910658965255;4576.244919588264;4582.450105987303;4385.934210986838;5357.312446579354;5396.676199428008;5408.971635636636;5421.7770714007465;3220.484722444421;3692.2061756722433;3836.4245363195464;3866;3876.457295978537;5580.38615752271;5600.411956744565;5620.9437905621235;5684.37471878093;5706.914396284059;3888.202918401721;3888.6694630956686;3900.317444447267;3905.9804700976883;3923.136909879822;3810.447485114643;3875.6435247663658;3929.701124841942;3964.2467931093;3973.443717879499;4454.1982443159895;4481.955180448219;4487.371767952489;4501.668765113857;4543.818247270138;4723.7874295656775;4882.902341953141;5293.523582877559;5408.4050754416985;5415.5938304626525;1263.9824940922463;1308.1104848702973;1314.2968511846116;1325.602319304205;1349.709812390321;787.79123532264;918.2470374293016;937.8358787212211;939.4457270210609;944.5088553951906;5018.573421259161;5031.698098114051;5043.621728370314;5049.141645920203;5087.796488140507;4590.403247577793;5253.789848406097;5310.654807437504;5474.698891561003;5482.780688772405;4298.570142985739;5091.032703786269;5186.8329625833885;5190.6107041972;5249.42256351801;2294.8640422990725;2584.405586715066;2617.698964619055;2749.0069699468127;2941.6617089035035;4865.9026819463415;4995.325631039763;5288.127458969227;5519.475649813261;5785.3965715427385;4783.7129772213775;5016.573591244712;5807.970960145172;5810.302763668303;5813.476787089182;5065.645404821775;5108.744562771846;5254;5581.74882130297;5793.333766616893;4738.573528382462;5624.3250809903075;5624.7187640616985;5665.773369065191;5753.597248192586;5212.139996900469;5534.7786088556;5583.637063590853;5658.40586738403;5668.829935101953;4970.627202959865;5557.5553955682635;5878.382769809279;5912.497437717842;5913.645181289136;3717.7025837933443;3745.2883952049024;3769.962300376961;3775.3959366501226;3775.6979441645153;4857.392825896763;5403.621746477709;5610.214569960291;5627.396192085236;5651.8304450866535;4682.157211701926;5150.433452320317;5525.938435250168;5531.557475401878;5554.75003624846;5382.650127741685;5499.395066542597;5499.890002200013;5530.5022547970875;5533.541536539663;4498.460184777717;4855.854324370274;5070.619703522324;5324.680519168863;5436.700981446058;5186.377634683862;5493.560515158698;5506.531944784578;5514.558835293247;5535.197396377584;4984.028114517634;5047.823326183566;5148.30497882796;5317.468253174651;5417.674939503642 05:25:34 INFO - PROCESS | 1645 | |5;yui.html;718.2888939949452;732.673960087761;752.8456666383391;791.19298315718;860.8880845490086;15351.3091910864;15801.84198158018;16238;16275.918620406881;16312.75530867038;14754.778678319833;14791.556253312418;14968.251587420613;14985.775213371808;14986.25068746561;675.6013951768456;836.4145098431107;854.0349405172146;854.9358798090137;855.5380094748829;683.6615875141787;715.6457553511019;849.5794581682076;861.7199410191681;863.8833757442759;909.8817153770001;912.7991841794827;914.0767824497273;915.249495413761;919.3242966419676;700.9544379615309;909.2589539525266;911.5897845969307;911.6262332443666;914.1863741270275;533.8078291814943;536.0672429971821;538.7817933736843;646.9061986012002;673.579013116802;15484.380624774996;15506.61233469161;15531.301091450912;15540.844591554165;15544.689106217813;14225.430982760678;14409.639759006004;14574.562763117123;14606.634834129125;14703.55889323322;13288;13363.1313964592;13447.663808404868;13563.982701297344;13808.309584520834;4610.216263235258;4727.810887564493;4754.381930349067;4763.952360476385;4804.351412559295;116755.41622291919;119228;119507;119578;120408;10976.98869271973;11552.94223528877;11630.825537616942;11668;11679.474423650956;22819.20132795344;23472.17847375368;23477.41306467318;23487.7651223489;23517.88241058818;8042.155573664681;8128.349732021424;8141.796455088552;8270.710525131592;8295.8340833183;3894.4742459767567;4298.290782020994;4444.400005999213;4570.497245303014;4601.608863246662;3543.4861945018342;3700.5559332880225;3703.5370578677666;3725.012871589031;3726.4969229153717;678.6199728152251;684.5995092870627;689.2383915772998;689.5104475822122;696.1854630082815;541.9078756611385;611.3886113886114;611.9938800611936;623.6227082615044;656.7208936202095;7497.189677999594;8349.457285276438;9164.587593558306;9462;9491.477968711788;530.8380943812174;559.6530151306216;588.9617174883618;597.9312379076375;607.2409488139825;3776.6789822864816;4035.0921042765144;4330.328799036154;4349.586789255016;4532.696119586886;97186;127942.36028819796;131478.34260828825;135278.3236083833;177126;121861.39069304596;121961;122881;124245;127504.72495275114;108877.45561272143;113218;113403.43298283455;113960;114491;9639.228861690908;10028.395456726888;10029.398236105566;10058.195344372287;10066.993300670167;42684.78657606692;66577.33422665711;66784;67990.32009680038;69045.65477172582;93461;93761.5311923436;100975;100978.49510752693;105529.41705874265;12620.55828046014;13086.411111500196;13162.802557961446;13575.52485662997;13604.319784010562;13264.739849714262;13890.513832016235;14457.638559036108;15285.923570382078;15439.150846703538;14021.649458763612;14371.640708982359;14384.136951782926;14507.347169377192;14546.054506457047;10077.922957432449;13962.441502339792;14271.785923210953;14522;14793.706975418827;13735.725285494435;13747.587572372844;13753.724925501634;14620.634484137981;14714.852851471776;11437.792576975615;11446.542138314375;11582.247153935305;11887.049036077266;12153.69615759613;10792.677752207594;11598.478068487108;11744.70638234051;11860.76278474443;11954 05:25:34 INFO - PROCESS | 1645 | __end_tp_report 05:25:34 INFO - PROCESS | 1645 | __start_cc_report 05:25:34 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1376 05:25:34 INFO - PROCESS | 1645 | __end_cc_report 05:25:34 INFO - PROCESS | 1645 | __startTimestamp1471955134003__endTimestamp 05:25:34 INFO - PROCESS | 1645 | 05:25:34 INFO - PROCESS | 1645 | ------- Summary: start ------- 05:25:34 INFO - PROCESS | 1645 | Number of tests: 6 05:25:34 INFO - PROCESS | 1645 | 05:25:34 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5797.43 Median:6047.37 stddev:647.94 (10.7%) stddev-sans-first:620.70 05:25:34 INFO - PROCESS | 1645 | Values: 3344.8 3397.2 3399.3 3401.4 3415.8 5662.2 5708.1 5730.7 5802.6 5815.5 5645.2 5729.1 5736.2 5743.0 5757.9 5136.9 5139.4 5193.3 5208.4 5245.6 5121.0 5244.2 5281.5 5290.0 5306.0 5312.1 5318.7 5322.3 5324.5 5328.1 5125.7 5150.2 5189.5 5312.6 5325.8 5198.8 5213.0 5247.7 5250.8 5277.6 4827.3 5746.6 5785.6 5801.7 5813.9 6099.3 6175.0 6220.9 6241.5 6244.5 6123.3 6140.3 6200.5 6247.5 6288.9 6013.4 6014.1 6017.0 6028.4 6039.9 6201.3 6426.7 6455.0 6462.3 6464.9 6134.2 6185.2 6204.4 6218.5 6234.7 5079.3 5951.8 6284.7 6305.1 6306.4 5116.3 6147.4 6153.6 6217.8 6296.1 6107.7 6176.9 6190.1 6223.9 6235.4 5086.8 5091.8 5101.8 5105.1 5115.7 4420.9 4547.0 4567.9 4571.5 4580.5 5739.6 5740.5 5816.7 5832.5 5933.8 6213.5 6247.9 6258.9 6271.9 6288.0 5593.7 5832.9 5833.3 5847.1 5926.2 5420.4 5437.2 5495.4 5538.1 5555.5 6447.1 6460.3 6464.7 6467.9 6476.8 6214.3 6313.7 6333.4 6336.7 6356.7 6404.3 6481.2 6482.8 6488.8 6499.1 6272.9 6292.8 6293.0 6312.9 6320.5 6173.5 6272.9 6292.1 6316.6 6332.4 6104.7 6168.9 6178.0 6246.4 6256.0 6078.5 6191.3 6237.8 6253.8 6264.9 5818.0 6077.2 6090.6 6093.5 6107.5 6062.5 6140.3 6164.3 6247.7 6262.1 5975.7 6032.1 6039.2 6047.4 6116.0 05:25:34 INFO - PROCESS | 1645 | 05:25:34 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:71606.45 Median:36022.78 stddev:161753.59 (449.0%) stddev-sans-first:162119.21 05:25:34 INFO - PROCESS | 1645 | Values: 483.8 500.2 506.3 517.9 526.7 31089.0 32557.5 32654.2 32758.8 33364.3 41168.0 43597.0 43673.1 43685.0 43725.9 9735.1 9905.2 9918.7 9920.2 9926.1 8754.9 8983.1 9776.0 9887.1 9900.8 8923.2 8979.1 8982.5 11832.6 12149.9 12010.6 12083.6 12088.0 12089.6 12102.6 11820.5 11907.6 11918.9 11924.7 11926.6 31808.8 32317.0 32374.4 40382.6 40805.0 2936.8 3363.5 3483.3 3557.0 3597.1 31981.0 32781.5 32904.0 32923.0 33082.8 3321.5 3374.4 3413.6 3465.9 3479.0 501404.0 502966.0 506577.9 507964.0 511585.0 33587.3 34554.1 34608.0 61312.4 61969.7 35737.6 36009.3 36036.3 36079.9 36107.8 14794.8 14895.2 14971.9 14988.5 14993.5 12723.5 12821.8 12840.0 12847.6 12858.9 7772.4 7785.3 7812.3 7854.5 7896.5 3032.8 3127.0 3158.2 3194.7 3241.3 935.4 937.3 972.4 995.3 1047.9 30552.5 30629.7 30725.8 31246.2 31733.7 1046.3 1068.8 1074.0 1102.6 1113.3 15719.9 15783.9 15813.9 15836.4 16163.8 706675.0 707645.0 708241.0 710677.0 711575.0 58827.0 59414.0 59479.0 59644.0 59781.0 1234.4 1343.9 1357.6 1399.7 1401.0 31117.0 31481.1 31532.8 31869.7 32312.0 50682.0 51248.5 51287.5 51306.7 51328.2 553022.0 555720.0 555734.0 557145.0 658784.0 39272.2 41276.8 41361.4 42226.2 42464.0 46177.0 46827.0 47257.8 50227.7 50543.2 39918.0 40245.0 40682.0 41598.6 41695.0 39638.0 40200.6 40200.8 40546.6 40780.4 38032.3 38299.8 39046.8 39087.8 39334.2 33152.5 34406.5 35472.3 35527.8 35611.0 23593.2 24580.8 25973.7 26078.5 26344.6 05:25:34 INFO - PROCESS | 1645 | 05:25:34 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:35544.40 Median:14675.46 stddev:101205.48 (689.6%) stddev-sans-first:101454.83 05:25:34 INFO - PROCESS | 1645 | Values: 318.8 355.7 376.7 383.6 404.6 15540.0 16937.6 20486.8 21053.6 21143.2 20064.4 20701.0 20954.7 21534.6 21582.7 4605.6 5254.4 5406.9 5581.6 5582.4 3864.7 5373.9 5452.8 5459.1 5464.2 5845.6 6102.7 6124.8 6140.2 6151.0 5875.6 6097.4 6128.3 6131.8 6188.2 5926.2 5929.8 5931.6 5936.8 5940.0 17157.7 25659.6 26219.5 26407.3 26698.0 2185.4 2411.2 2434.3 2512.7 2652.9 9838.0 10312.2 10675.2 12632.5 16011.7 1811.9 1908.6 1934.6 1961.3 1973.7 119382.0 130977.0 132403.0 134152.0 134197.0 10986.3 11400.6 11462.6 11504.4 11677.5 14658.0 14683.2 14696.5 14699.4 14737.9 8363.5 8607.6 8632.7 8785.0 8786.5 7172.9 8582.7 8607.9 8610.0 8809.0 4497.1 4555.5 4558.6 4642.6 4731.8 1851.7 1912.8 1916.4 1917.3 1921.4 847.5 922.4 933.7 962.5 966.3 20370.8 21633.8 21661.9 21721.3 21740.7 911.3 913.7 922.7 929.1 933.5 10982.6 11398.3 11411.4 11532.3 11565.2 592728.0 593809.0 603143.0 616961.0 618725.0 103847.0 127355.4 131426.0 132213.3 132534.0 916.7 966.3 976.4 983.5 985.8 14896.7 16374.0 19255.4 19473.9 19570.9 52472.5 53353.2 53362.5 54454.2 55204.2 77977.0 78239.0 79061.2 79229.6 79609.7 7080.8 7440.8 7468.1 7944.0 8462.6 9286.1 10593.9 13290.3 13759.9 13790.7 11465.1 11506.7 11822.8 11883.6 11951.3 11293.7 11461.8 11669.8 11830.5 11982.7 9887.9 11501.4 11554.6 11971.8 12130.1 6888.3 7584.3 7637.0 7708.8 7819.6 10413.7 11422.6 11587.7 11817.9 11902.9 05:25:34 INFO - PROCESS | 1645 | 05:25:34 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5776.20 Median:4785.86 stddev:3222.03 (67.3%) stddev-sans-first:3214.00 05:25:34 INFO - PROCESS | 1645 | Values: 1362.5 1527.7 1547.5 1726.7 1870.4 7110.8 7886.7 8852.3 8894.8 8956.5 8693.4 8747.6 8748.4 8758.8 8790.7 2364.1 2392.4 2393.7 2397.6 2398.6 2344.5 2375.5 2391.2 2397.4 2407.8 2363.5 2369.1 2375.1 2378.6 2387.5 2350.4 2361.0 2371.1 2375.8 2388.5 2337.4 2360.0 2360.4 2361.3 2376.4 8679.3 8850.2 8888.1 8943.7 8952.9 5199.4 5372.9 5394.1 5403.9 5404.8 3651.5 3659.7 3730.8 3735.6 3736.6 1105.6 1117.5 1123.5 1131.0 1136.7 11704.9 11768.9 11882.5 11887.5 11905.3 4220.7 4684.6 4739.9 4817.7 4877.2 10151.5 10152.2 10152.7 10154.5 10159.1 5366.1 5370.4 5373.6 5374.9 5377.0 4429.5 4584.8 4754.0 4895.4 4906.7 3899.8 3900.2 4015.3 4052.1 4052.5 1177.0 1198.7 1280.5 1297.1 1299.8 841.7 843.9 869.3 930.2 971.2 5926.8 5987.3 6103.0 6149.8 6251.6 956.6 962.2 991.3 1004.2 1014.8 3926.0 3997.6 4034.6 4108.7 4139.8 10543.0 10766.4 10864.2 10877.0 10913.3 10784.3 10813.9 10815.1 10820.1 10839.0 7745.7 8105.8 8115.0 8219.4 8223.8 5973.1 5995.3 6005.4 6155.3 6367.3 9626.3 10117.2 10206.1 10268.2 10306.0 9233.7 10292.3 10304.6 10317.0 10317.6 7009.4 7203.9 7345.2 7752.1 7963.8 8253.0 8550.7 8628.0 8637.6 8643.7 7448.3 7513.7 7530.7 7559.3 7625.5 7274.6 7373.4 7412.9 7475.1 7650.1 6971.9 7132.8 7133.7 7139.0 7144.4 4963.1 5045.1 5117.4 5195.4 5264.8 5058.4 5135.4 5157.8 5220.0 5306.8 05:25:34 INFO - PROCESS | 1645 | 05:25:34 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4416.17 Median:4996.51 stddev:1336.91 (26.8%) stddev-sans-first:1304.38 05:25:34 INFO - PROCESS | 1645 | Values: 294.5 297.3 306.9 311.3 311.9 4848.8 4853.8 5088.5 5129.7 5182.4 3912.8 4325.4 4997.7 5025.6 5033.4 3257.8 3952.6 3958.8 4013.5 4018.7 3567.4 3850.5 4010.7 4015.6 4015.8 3955.5 3962.7 3972.3 3981.0 3990.1 3453.7 3470.7 3950.4 3967.6 3982.6 3501.9 3507.1 3515.8 3516.7 3519.3 4737.6 4926.4 5018.8 5054.6 5061.2 4523.5 4546.4 4559.0 4576.2 4582.5 4385.9 5357.3 5396.7 5409.0 5421.8 3220.5 3692.2 3836.4 3866.0 3876.5 5580.4 5600.4 5620.9 5684.4 5706.9 3888.2 3888.7 3900.3 3906.0 3923.1 3810.4 3875.6 3929.7 3964.2 3973.4 4454.2 4482.0 4487.4 4501.7 4543.8 4723.8 4882.9 5293.5 5408.4 5415.6 1264.0 1308.1 1314.3 1325.6 1349.7 787.8 918.2 937.8 939.4 944.5 5018.6 5031.7 5043.6 5049.1 5087.8 4590.4 5253.8 5310.7 5474.7 5482.8 4298.6 5091.0 5186.8 5190.6 5249.4 2294.9 2584.4 2617.7 2749.0 2941.7 4865.9 4995.3 5288.1 5519.5 5785.4 4783.7 5016.6 5808.0 5810.3 5813.5 5065.6 5108.7 5254.0 5581.7 5793.3 4738.6 5624.3 5624.7 5665.8 5753.6 5212.1 5534.8 5583.6 5658.4 5668.8 4970.6 5557.6 5878.4 5912.5 5913.6 3717.7 3745.3 3770.0 3775.4 3775.7 4857.4 5403.6 5610.2 5627.4 5651.8 4682.2 5150.4 5525.9 5531.6 5554.8 5382.7 5499.4 5499.9 5530.5 5533.5 4498.5 4855.9 5070.6 5324.7 5436.7 5186.4 5493.6 5506.5 5514.6 5535.2 4984.0 5047.8 5148.3 5317.5 5417.7 05:25:34 INFO - PROCESS | 1645 | 05:25:34 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:25103.25 Median:99972.60 stddev:39293.47 (39.3%) stddev-sans-first:39361.04 05:25:34 INFO - PROCESS | 1645 | Values: 718.3 732.7 752.8 791.2 860.9 15351.3 15801.8 16238.0 16275.9 16312.8 14754.8 14791.6 14968.3 14985.8 14986.3 675.6 836.4 854.0 854.9 855.5 683.7 715.6 849.6 861.7 863.9 909.9 912.8 914.1 915.2 919.3 701.0 909.3 911.6 911.6 914.2 533.8 536.1 538.8 646.9 673.6 15484.4 15506.6 15531.3 15540.8 15544.7 14225.4 14409.6 14574.6 14606.6 14703.6 13288.0 13363.1 13447.7 13564.0 13808.3 4610.2 4727.8 4754.4 4764.0 4804.4 116755.4 119228.0 119507.0 119578.0 120408.0 10977.0 11552.9 11630.8 11668.0 11679.5 22819.2 23472.2 23477.4 23487.8 23517.9 8042.2 8128.3 8141.8 8270.7 8295.8 3894.5 4298.3 4444.4 4570.5 4601.6 3543.5 3700.6 3703.5 3725.0 3726.5 678.6 684.6 689.2 689.5 696.2 541.9 611.4 612.0 623.6 656.7 7497.2 8349.5 9164.6 9462.0 9491.5 530.8 559.7 589.0 597.9 607.2 3776.7 4035.1 4330.3 4349.6 4532.7 97186.0 127942.4 131478.3 135278.3 177126.0 121861.4 121961.0 122881.0 124245.0 127504.7 108877.5 113218.0 113403.4 113960.0 114491.0 9639.2 10028.4 10029.4 10058.2 10067.0 42684.8 66577.3 66784.0 67990.3 69045.7 93461.0 93761.5 100975.0 100978.5 105529.4 12620.6 13086.4 13162.8 13575.5 13604.3 13264.7 13890.5 14457.6 15285.9 15439.2 14021.6 14371.6 14384.1 14507.3 14546.1 10077.9 13962.4 14271.8 14522.0 14793.7 13735.7 13747.6 13753.7 14620.6 14714.9 11437.8 11446.5 11582.2 11887.0 12153.7 10792.7 11598.5 11744.7 11860.8 11954.0 05:25:34 INFO - PROCESS | 1645 | -------- Summary: end -------- 05:25:34 INFO - PROCESS | 1645 | 05:25:34 INFO - TEST-INFO | 1645: exit 0 05:25:34 INFO - TEST-OK | dromaeo_css | took 1118112ms 05:25:34 INFO - TEST-START | kraken 05:25:34 INFO - operating with platform_type : mac_ 05:25:34 INFO - Initialising browser for kraken test... 05:25:34 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpXG783u/profile http://localhost:49267/getInfo.html) 05:25:36 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 05:25:36 INFO - PROCESS | 1651 | colorDepth:24 05:25:36 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 05:25:36 INFO - PROCESS | 1651 | __metrics 05:25:37 INFO - PROCESS | 1651 | 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:25:37 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:25:37 INFO - TEST-INFO | 1651: exit 0 05:25:37 INFO - Browser initialized. 05:25:37 INFO - Running cycle 1/1 for kraken test... 05:25:37 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpXG783u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:49 INFO - PROCESS | 1652 | RSS: Main: 228605952 05:25:49 INFO - PROCESS | 1652 | 05:25:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:53 INFO - PROCESS | 1652 | RSS: Main: 294117376 05:25:53 INFO - PROCESS | 1652 | 05:25:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 05:25:56 INFO - PROCESS | 1652 | RSS: Main: 296128512 05:25:56 INFO - PROCESS | 1652 | 05:26:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 05:26:00 INFO - PROCESS | 1652 | RSS: Main: 381333504 05:26:00 INFO - PROCESS | 1652 | 05:26:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 05:26:03 INFO - PROCESS | 1652 | RSS: Main: 282644480 05:26:03 INFO - PROCESS | 1652 | 05:26:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:26:06 INFO - PROCESS | 1652 | RSS: Main: 286715904 05:26:06 INFO - PROCESS | 1652 | 05:26:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 05:26:09 INFO - PROCESS | 1652 | RSS: Main: 324149248 05:26:09 INFO - PROCESS | 1652 | 05:26:12 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 05:26:13 INFO - PROCESS | 1652 | RSS: Main: 325996544 05:26:13 INFO - PROCESS | 1652 | 05:26:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 05:26:16 INFO - PROCESS | 1652 | RSS: Main: 323620864 05:26:16 INFO - PROCESS | 1652 | 05:26:18 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:26:18 INFO - PROCESS | 1652 | RSS: Main: 299917312 05:26:18 INFO - PROCESS | 1652 | 05:26:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:26:21 INFO - PROCESS | 1652 | RSS: Main: 304283648 05:26:21 INFO - PROCESS | 1652 | 05:26:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:24 INFO - PROCESS | 1652 | RSS: Main: 311500800 05:26:24 INFO - PROCESS | 1652 | 05:26:27 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:27 INFO - PROCESS | 1652 | RSS: Main: 316051456 05:26:27 INFO - PROCESS | 1652 | 05:26:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:31 INFO - PROCESS | 1652 | RSS: Main: 318492672 05:26:31 INFO - PROCESS | 1652 | 05:26:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 05:26:33 INFO - PROCESS | 1652 | RSS: Main: 362389504 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | __start_tp_report 05:26:33 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 05:26:33 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 05:26:33 INFO - PROCESS | 1652 | |i|pagename|runs| 05:26:33 INFO - PROCESS | 1652 | |0;ai-astar;98;112;105;112;112;114;116;114;112;113 05:26:33 INFO - PROCESS | 1652 | |1;audio-beat-detection;147;109;131;158;107;129;130;108;161;103 05:26:33 INFO - PROCESS | 1652 | |2;audio-dft;184;186;184;184;184;182;182;180;184;185 05:26:33 INFO - PROCESS | 1652 | |3;audio-fft;72;81;76;151;58;70;78;125;93;57 05:26:33 INFO - PROCESS | 1652 | |4;audio-oscillator;82;90;133;69;71;68;117;70;69;120 05:26:33 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;88;87;134;85;88;85;88;85;101;114 05:26:33 INFO - PROCESS | 1652 | |6;imaging-darkroom;94;93;94;93;93;93;94;94;94;93 05:26:33 INFO - PROCESS | 1652 | |7;imaging-desaturate;84;86;86;84;84;84;86;86;85;86 05:26:33 INFO - PROCESS | 1652 | |8;json-parse-financial;56;53;54;54;53;55;56;55;54;53 05:26:33 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;67;65;73;69;80;74;73;69;69;69 05:26:33 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;88;82;87;88;87;84;88;85;92;87 05:26:33 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;140;137;138;139;137;138;136;142;224;140 05:26:33 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;168;168;168;164;165;167;165;173;167;167 05:26:33 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;59;84;85;84;66;66;65;66;67;64 05:26:33 INFO - PROCESS | 1652 | __end_tp_report 05:26:33 INFO - PROCESS | 1652 | __start_cc_report 05:26:33 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,2178 05:26:33 INFO - PROCESS | 1652 | __end_cc_report 05:26:33 INFO - PROCESS | 1652 | __startTimestamp1471955193184__endTimestamp 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | ------- Summary: start ------- 05:26:33 INFO - PROCESS | 1652 | Number of tests: 14 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:110.80 Median:113.50 stddev:5.33 (4.7%) stddev-sans-first:3.03 05:26:33 INFO - PROCESS | 1652 | Values: 98.0 112.0 105.0 112.0 112.0 114.0 116.0 114.0 112.0 113.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:130.50 stddev:21.58 (16.5%) stddev-sans-first:21.80 05:26:33 INFO - PROCESS | 1652 | Values: 147.0 109.0 131.0 158.0 107.0 129.0 130.0 108.0 161.0 103.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.72 (0.9%) stddev-sans-first:1.81 05:26:33 INFO - PROCESS | 1652 | Values: 184.0 186.0 184.0 184.0 184.0 182.0 182.0 180.0 184.0 185.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:86.10 Median:74.00 stddev:29.93 (40.4%) stddev-sans-first:31.30 05:26:33 INFO - PROCESS | 1652 | Values: 72.0 81.0 76.0 151.0 58.0 70.0 78.0 125.0 93.0 57.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:69.50 stddev:25.07 (36.1%) stddev-sans-first:26.47 05:26:33 INFO - PROCESS | 1652 | Values: 82.0 90.0 133.0 69.0 71.0 68.0 117.0 70.0 69.0 120.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:86.00 stddev:16.42 (19.1%) stddev-sans-first:17.19 05:26:33 INFO - PROCESS | 1652 | Values: 88.0 87.0 134.0 85.0 88.0 85.0 88.0 85.0 101.0 114.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 05:26:33 INFO - PROCESS | 1652 | Values: 94.0 93.0 94.0 93.0 93.0 93.0 94.0 94.0 94.0 93.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:86.00 stddev:0.99 (1.2%) stddev-sans-first:0.97 05:26:33 INFO - PROCESS | 1652 | Values: 84.0 86.0 86.0 84.0 84.0 84.0 86.0 86.0 85.0 86.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:1.16 (2.1%) stddev-sans-first:1.05 05:26:33 INFO - PROCESS | 1652 | Values: 56.0 53.0 54.0 54.0 53.0 55.0 56.0 55.0 54.0 53.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:4.29 (6.0%) stddev-sans-first:4.32 05:26:33 INFO - PROCESS | 1652 | Values: 67.0 65.0 73.0 69.0 80.0 74.0 73.0 69.0 69.0 69.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:87.50 stddev:2.70 (3.1%) stddev-sans-first:2.83 05:26:33 INFO - PROCESS | 1652 | Values: 88.0 82.0 87.0 88.0 87.0 84.0 88.0 85.0 92.0 87.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:139.50 stddev:27.08 (19.4%) stddev-sans-first:28.60 05:26:33 INFO - PROCESS | 1652 | Values: 140.0 137.0 138.0 139.0 137.0 138.0 136.0 142.0 224.0 140.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:167.50 stddev:2.49 (1.5%) stddev-sans-first:2.62 05:26:33 INFO - PROCESS | 1652 | Values: 168.0 168.0 168.0 164.0 165.0 167.0 165.0 173.0 167.0 167.0 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:66.50 stddev:9.73 (14.6%) stddev-sans-first:9.37 05:26:33 INFO - PROCESS | 1652 | Values: 59.0 84.0 85.0 84.0 66.0 66.0 65.0 66.0 67.0 64.0 05:26:33 INFO - PROCESS | 1652 | -------- Summary: end -------- 05:26:33 INFO - PROCESS | 1652 | 05:26:33 INFO - TEST-INFO | 1652: exit 0 05:26:34 INFO - TEST-OK | kraken | took 59364ms 05:26:34 INFO - SUITE-END | took 1177s 05:26:34 INFO - Completed test suite (00:19:37) 05:26:34 INFO - javascript benchmark 05:26:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.816035118069, 3397.1846756778373, 3399.320135972806, 3401.370746411914, 3415.795052296862, 5662.150677398392, 5708.08670612702, 5730.7421166047525, 5802.564807639428, 5815.47660710536, 5645.1814486899375, 5729.0833466645345, 5736.22560954271, 5743.0, 5757.913631295523, 5136.948630513703, 5139.408967968676, 5193.298904647881, 5208.401033881095, 5245.554127899114, 5120.975804839047, 5244.213367994793, 5281.471852814726, 5290.0, 5305.965336759334, 5312.096943519592, 5318.680879147244, 5322.2814919985885, 5324.467553244684, 5328.094223981932, 5125.743712814345, 5150.175971844525, 5189.532942035235, 5312.601554883399, 5325.840224793263, 5198.792048318062, 5212.973935130338, 5247.737613119329, 5250.789968401259, 5277.604179686539, 4827.348307978413, 5746.626469279484, 5785.623934444247, 5801.7389217485315, 5813.912791308134, 6099.298580663236, 6174.96912515439, 6220.87558248837, 6241.469475094623, 6244.5316601254635, 6123.265208175003, 6140.324564297924, 6200.534959878028, 6247.468965137969, 6288.937110628926, 6013.398660133952, 6014.09788531715, 6017.0, 6028.367021462684, 6039.939600604026, 6201.286852012076, 6426.710798014007, 6454.963570354938, 6462.289148193695, 6464.870702585922, 6134.171886795315, 6185.195924529782, 6204.441600255998, 6218.533609979179, 6234.719437625317, 5079.339685840817, 5951.791687290924, 6284.685765711788, 6305.054241863665, 6306.369363063749, 5116.258142569307, 6147.415995480422, 6153.630782153085, 6217.844553886189, 6296.087067375205, 6107.664078475637, 6176.8764624707255, 6190.102435146879, 6223.875522489616, 6235.376462353728, 5086.77109530072, 5091.821786237464, 5101.821436249714, 5105.106606343875, 5115.693058416433, 4420.9336859947125, 4546.999660074778, 4567.931481027787, 4571.5428457154685, 4580.519045500241, 5739.62692424991, 5740.54075673945, 5816.709164541756, 5832.504237139806, 5933.821985340447, 6213.502919766498, 6247.9062814057825, 6258.874822503524, 6271.937280627227, 6288.0, 5593.692346920959, 5832.88334233313, 5833.329167145747, 5847.093700476433, 5926.170336152942, 5420.376656684453, 5437.157240627709, 5495.368032676213, 5538.0585300499015, 5555.472230138131, 6447.097406363113, 6460.321666224979, 6464.676766161673, 6467.935320646733, 6476.838079048061, 6214.285357183981, 6313.652749098844, 6333.36666333363, 6336.683165841598, 6356.745730170742, 6404.327545607644, 6481.222253329631, 6482.773102941563, 6488.772892948724, 6499.122618446449, 6272.905906411316, 6292.811215663537, 6292.96166132583, 6312.905306420499, 6320.462760665395, 6173.536984726074, 6272.937270627235, 6292.087647291209, 6316.589421687576, 6332.398422149889, 6104.664243466652, 6168.938310616836, 6178.042403427476, 6246.406591373812, 6255.96872015637, 6078.483328917092, 6191.25704915413, 6237.812865614039, 6253.812385628438, 6264.937350626436, 5817.970910145422, 6077.179580756718, 6090.604110732788, 6093.542984276109, 6107.450329470369, 6062.514998799998, 6140.263168419819, 6164.260288765377, 6247.68761561911, 6262.09199666055, 5975.731092100953, 6032.095185722177, 6039.15451836752, 6047.365026672136, 6115.969420152871], "value": 5756.545958051847, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.83549593138326, 500.2046745674377, 506.3215291509378, 517.9067767801796, 526.7261024267383, 31089.0, 32557.5116373254, 32654.183645408877, 32758.836205818938, 33364.33271334572, 41167.97080073, 43597.0, 43673.12653746923, 43685.0, 43725.906852328706, 9735.075167859042, 9905.158061564776, 9918.702438926844, 9920.206383489303, 9926.106650401462, 8754.86867696985, 8983.146601072887, 9776.0, 9887.060729230712, 9900.752481187956, 8923.15230053144, 8979.057198994109, 8982.461052336848, 11832.585859494919, 12149.878501215007, 12010.63968080959, 12083.637490875288, 12088.0, 12089.576864809778, 12102.636920892299, 11820.468078936383, 11907.642770716893, 11918.88081119195, 11924.70188245292, 11926.582569610022, 31808.840955795305, 32317.0, 32374.352512949845, 40382.596174038175, 40805.0, 2936.7834851677067, 3363.5291059251726, 3483.3381657484997, 3557.004038869121, 3597.1366871950804, 31981.0, 32781.508277375855, 32904.0, 32923.0, 33082.842100526366, 3321.468565029608, 3374.3926093303194, 3413.6074351449406, 3465.9133521661906, 3478.9826050869838, 501404.0, 502966.0, 506577.94087502605, 507964.0, 511584.9573538362, 33587.328253434796, 34554.13614659654, 34608.0, 61312.38687613156, 61969.690151549854, 35737.64262357343, 36009.27981440356, 36036.27927441489, 36079.94880716702, 36107.81946090253, 14794.778078328834, 14895.180765057808, 14971.850281497263, 14988.47540336083, 14993.475228366955, 12723.491060357666, 12821.807672884914, 12840.0, 12847.614571562868, 12858.935705321413, 7772.417068719868, 7785.299323060952, 7812.296893279632, 7854.528728276322, 7896.526208427513, 3032.757379409642, 3127.0, 3158.2262345724985, 3194.6965038321323, 3241.33552621712, 935.3873213045466, 937.2782957123113, 972.4311277902365, 995.2833959549113, 1047.8742550893942, 30552.54171187434, 30629.693703062683, 30725.846370768002, 31246.21884452907, 31733.682663173535, 1046.2676126711333, 1068.7541865371008, 1074.0172741941037, 1102.6471529110768, 1113.3320007995137, 15719.921400393154, 15783.921080394754, 15813.920930395274, 15836.445724399591, 16163.838361616234, 706675.0, 707645.0, 708241.0, 710677.0, 711575.0, 58827.0, 59414.0, 59479.0, 59644.0, 59781.0, 1234.4444999750149, 1343.8924886009095, 1357.565579014706, 1399.7410479061202, 1401.0192864994544, 31117.0, 31481.120426333953, 31532.842335788173, 31869.68130318667, 32312.0, 50681.986360273324, 51248.48751512437, 51287.487125129766, 51306.74346628243, 51328.230076548134, 553022.0, 555720.0, 555734.0, 557145.0, 658784.0, 39272.214555709295, 41276.79361603293, 41361.379579305736, 42226.155476889675, 42464.0, 46177.0, 46827.0, 47257.76371118122, 50227.74886125546, 50543.24185137299, 39918.0, 40245.0, 40682.0, 41598.58401416068, 41694.95762605959, 39638.0, 40200.597994019685, 40200.798996004836, 40546.59453405546, 40780.388294176206, 38032.29515572686, 38299.80850095732, 39046.80476597599, 39087.80456097701, 39334.213315734094, 33152.50813713437, 34406.48390274198, 35472.29055418929, 35527.82236088803, 35611.0, 23593.174238901556, 24580.754192457847, 25973.740262597134, 26078.478430430907, 26344.604830927936], "value": 19196.56183616836, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.8357995632249, 355.74386441761953, 376.6722951032602, 383.61446746020255, 404.6041158004884, 15539.98990065645, 16937.576560585978, 20486.795132048675, 21053.578928421422, 21143.154273829066, 20064.398068057944, 20701.0, 20954.685679714818, 21534.56930861382, 21582.676259856114, 4605.608523275526, 5254.395744489394, 5406.8918621627545, 5581.6371935824045, 5582.413846546115, 3864.7101467389916, 5373.919391209135, 5452.836414907559, 5459.143891077033, 5464.180372944071, 5845.5908086433965, 6102.664353460558, 6124.755009799603, 6140.232470941132, 6150.985087460586, 5875.618084824473, 6097.3597772233725, 6128.325884152762, 6131.816045518619, 6188.195534580521, 5926.199963005025, 5929.762809487615, 5931.6144450611, 5936.821895343147, 5939.970300148515, 17157.742633860504, 25659.615105773428, 26219.475610487683, 26407.339816504547, 26698.0, 2185.3880913344287, 2411.168146989286, 2434.330559096245, 2512.748725127491, 2652.9204123876316, 9838.0, 10312.175025997902, 10675.199360048027, 12632.49470021198, 16011.719062474975, 1811.873168878179, 1908.6182763447366, 1934.6130773845287, 1961.2547232051872, 1973.7138114973393, 119382.0, 130977.0, 132403.0, 134152.0, 134197.0, 10986.285891417032, 11400.600978965696, 11462.598809041643, 11504.424778761197, 11677.532898684127, 14658.0, 14683.19242441676, 14696.485623003144, 14699.41202351894, 14737.852621473863, 8363.540005299767, 8607.61265743043, 8632.697855575025, 8784.95607521971, 8786.472811631322, 7172.892406613801, 8582.65669373218, 8607.871901340237, 8610.0, 8809.0, 4497.145542346943, 4555.521670224579, 4558.635309175258, 4642.558956899089, 4731.834385796481, 1851.666699993987, 1912.8427301482682, 1916.434651777729, 1917.2522716140716, 1921.4331772127257, 847.5380917399908, 922.4280945813639, 933.6732143749633, 962.5331714118647, 966.3090890013533, 20370.796292037187, 21633.783662163176, 21661.89169054176, 21721.34835954924, 21740.673889891663, 911.3165126155384, 913.7487191022389, 922.7093465558328, 929.1312622697799, 933.537898740128, 10982.615608453665, 11398.259113157785, 11411.372374519315, 11532.308061516336, 11565.190436669352, 592728.0, 593809.0, 603143.0, 616961.0, 618725.0, 103846.96153038373, 127355.36322318514, 131426.0, 132213.33893330663, 132534.0, 916.6883259691737, 966.3042609321277, 976.4239098931664, 983.468926779531, 985.8373368394278, 14896.702065958836, 16374.0, 19255.422337329903, 19473.90263048676, 19570.90214548918, 52472.47527524829, 53353.19970200372, 53362.46637533575, 54454.18318725143, 55204.171937420164, 77977.0, 78239.0, 79061.20938790539, 79229.60385198037, 79609.65074540273, 7080.787576372717, 7440.8466687663495, 7468.10382754073, 7944.0, 8462.588162373693, 9286.117818807203, 10593.89406105929, 13290.269035203157, 13759.931200343934, 13790.655233618838, 11465.082793376343, 11506.712332191428, 11822.763544729229, 11883.643490695293, 11951.282923024646, 11293.71765705831, 11461.770764584495, 11669.824952625548, 11830.52677892909, 11982.741812109603, 9887.901120988698, 11501.424928753362, 11554.59558915434, 11971.76056478883, 12130.090243231616, 6888.276730943179, 7584.2794934481135, 7636.961815190888, 7708.807279818049, 7819.648115834687, 10413.687589372332, 11422.600208992646, 11587.652370428901, 11817.881821182022, 11902.880971190178], "value": 9609.35567043313, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1362.5026865194206, 1527.7250094982903, 1547.5434746749709, 1726.740988851672, 1870.3640762140878, 7110.7866763997035, 7886.684532618696, 8852.29181665466, 8894.77763055924, 8956.507392093432, 8693.391462597621, 8747.606357713903, 8748.387612867102, 8758.781030474225, 8790.736277911657, 2364.1016413762745, 2392.437777122375, 2393.6888204533475, 2397.6283676030243, 2398.6162214045758, 2344.531093781242, 2375.5367703297866, 2391.175044609607, 2397.4126339046957, 2407.7592240775957, 2363.5391098735754, 2369.1115831563166, 2375.133076427109, 2378.63131214662, 2387.486690361572, 2350.3888988862846, 2361.0, 2371.146387300562, 2375.809935205197, 2388.474535602182, 2337.415646088478, 2360.0087963055557, 2360.433495960974, 2361.276268324133, 2376.4415362389823, 8679.305655547541, 8850.247728943048, 8888.066752990908, 8943.68697095605, 8952.8657070144, 5199.402068762103, 5372.865678358034, 5394.109971854678, 5403.945960540384, 5404.8108316208745, 3651.5070465487083, 3659.6889264412557, 3730.794806285653, 3735.6451137141926, 3736.5516138063335, 1105.6130354375985, 1117.5082963496036, 1123.4775829239443, 1130.988690113105, 1136.664683918246, 11704.946554810023, 11768.882311176863, 11882.524699012029, 11887.465064072137, 11905.345206013666, 4220.746755194698, 4684.580564384076, 4739.881502962385, 4817.735024573612, 4877.244027175722, 10151.492425378701, 10152.238582106223, 10152.746181345525, 10154.492275386201, 10159.136473399696, 5366.114591092426, 5370.382406023279, 5373.596980226499, 5374.91937620936, 5377.0321342158, 4429.49060857999, 4584.770761461914, 4753.976230118897, 4895.388076490439, 4906.681065730716, 3899.8245078971513, 3900.2004589059175, 4015.257177422186, 4052.1085361220485, 4052.452918855917, 1176.9941150294194, 1198.7473090620322, 1280.4686055286952, 1297.1374036265966, 1299.7985312276423, 841.6843683618642, 843.9240468357877, 869.3393021303855, 930.2186163622454, 971.2472833553911, 5926.7925622603, 5987.2815262168815, 6102.962496375627, 6149.754009839557, 6251.562390632612, 956.5886668732372, 962.2109869906609, 991.3258983891023, 1004.231762701534, 1014.802113587858, 3925.9607403926166, 3997.560268370477, 4034.596540345942, 4108.67130629549, 4139.834406623701, 10543.0, 10766.407847568458, 10864.185186111074, 10876.966688164612, 10913.345199288067, 10784.299020563638, 10813.891861081289, 10815.134789216843, 10820.13438924884, 10838.970297821694, 7745.690172393041, 8105.8378832424205, 8114.959425202836, 8219.383546234056, 8223.753287401387, 5973.1338955852025, 5995.310539287863, 6005.429484198994, 6155.292141403616, 6367.299597044229, 9626.326157169211, 10117.19062475015, 10206.081452669296, 10268.178545716175, 10306.0, 9233.676821311223, 10292.279540432099, 10304.587816487556, 10317.0, 10317.587296508356, 7009.3691567757105, 7203.8919416207755, 7345.22875098107, 7752.0697516298405, 7963.840723185388, 8252.958735206286, 8550.74347769568, 8627.964644242731, 8637.56812159377, 8643.740687779376, 7448.255174482509, 7513.737019204302, 7530.736424225126, 7559.28186822251, 7625.466217364953, 7274.599897005715, 7373.410127189704, 7412.851008093754, 7475.102987641518, 7650.081990161217, 6971.895421568579, 7132.786016419308, 7133.714651414093, 7138.964305178441, 7144.3570078693165, 4963.131451995814, 5045.11710450677, 5117.360329958755, 5195.428502864757, 5264.78940842362, 5058.392992840735, 5135.435102138837, 5157.793688252428, 5220.034293655686, 5306.761195746123], "value": 4648.647270062763, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.47583301722926, 297.28354665256734, 306.94474994500996, 311.3321924472008, 311.8752499000401, 4848.757562121888, 4853.7815798289075, 5088.54203121719, 5129.69221846689, 5182.404023537294, 3912.78479683618, 4325.394444777734, 4997.700137991723, 5025.612833903894, 5033.395992480899, 3257.8371081445953, 3952.6442620164107, 3958.7624742515395, 4013.4581831452674, 4018.678505719535, 3567.429211326188, 3850.53793544775, 4010.69919756018, 4015.558288588253, 4015.799210039501, 3955.525336959569, 3962.7424217425773, 3972.3247048001763, 3981.0, 3990.149505726973, 3453.6546345365514, 3470.7396945228957, 3950.407438884176, 3967.6230758077936, 3982.6017398260087, 3501.877434289813, 3507.053095664182, 3515.806630635314, 3516.7186625069935, 3519.3137338218803, 4737.620990320766, 4926.433460152092, 5018.7992480300745, 5054.570361519275, 5061.240813877911, 4523.5024147343765, 4546.38623785788, 4558.9910658965255, 4576.244919588264, 4582.450105987303, 4385.934210986838, 5357.312446579354, 5396.676199428008, 5408.971635636636, 5421.7770714007465, 3220.484722444421, 3692.2061756722433, 3836.4245363195464, 3866.0, 3876.457295978537, 5580.38615752271, 5600.411956744565, 5620.9437905621235, 5684.37471878093, 5706.914396284059, 3888.202918401721, 3888.6694630956686, 3900.317444447267, 3905.9804700976883, 3923.136909879822, 3810.447485114643, 3875.6435247663658, 3929.701124841942, 3964.2467931093, 3973.443717879499, 4454.1982443159895, 4481.955180448219, 4487.371767952489, 4501.668765113857, 4543.818247270138, 4723.7874295656775, 4882.902341953141, 5293.523582877559, 5408.4050754416985, 5415.5938304626525, 1263.9824940922463, 1308.1104848702973, 1314.2968511846116, 1325.602319304205, 1349.709812390321, 787.79123532264, 918.2470374293016, 937.8358787212211, 939.4457270210609, 944.5088553951906, 5018.573421259161, 5031.698098114051, 5043.621728370314, 5049.141645920203, 5087.796488140507, 4590.403247577793, 5253.789848406097, 5310.654807437504, 5474.698891561003, 5482.780688772405, 4298.570142985739, 5091.032703786269, 5186.8329625833885, 5190.6107041972, 5249.42256351801, 2294.8640422990725, 2584.405586715066, 2617.698964619055, 2749.0069699468127, 2941.6617089035035, 4865.9026819463415, 4995.325631039763, 5288.127458969227, 5519.475649813261, 5785.3965715427385, 4783.7129772213775, 5016.573591244712, 5807.970960145172, 5810.302763668303, 5813.476787089182, 5065.645404821775, 5108.744562771846, 5254.0, 5581.74882130297, 5793.333766616893, 4738.573528382462, 5624.3250809903075, 5624.7187640616985, 5665.773369065191, 5753.597248192586, 5212.139996900469, 5534.7786088556, 5583.637063590853, 5658.40586738403, 5668.829935101953, 4970.627202959865, 5557.5553955682635, 5878.382769809279, 5912.497437717842, 5913.645181289136, 3717.7025837933443, 3745.2883952049024, 3769.962300376961, 3775.3959366501226, 3775.6979441645153, 4857.392825896763, 5403.621746477709, 5610.214569960291, 5627.396192085236, 5651.8304450866535, 4682.157211701926, 5150.433452320317, 5525.938435250168, 5531.557475401878, 5554.75003624846, 5382.650127741685, 5499.395066542597, 5499.890002200013, 5530.5022547970875, 5533.541536539663, 4498.460184777717, 4855.854324370274, 5070.619703522324, 5324.680519168863, 5436.700981446058, 5186.377634683862, 5493.560515158698, 5506.531944784578, 5514.558835293247, 5535.197396377584, 4984.028114517634, 5047.823326183566, 5148.30497882796, 5317.468253174651, 5417.674939503642], "value": 3993.632360989106, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.2888939949452, 732.673960087761, 752.8456666383391, 791.19298315718, 860.8880845490086, 15351.3091910864, 15801.84198158018, 16238.0, 16275.918620406881, 16312.75530867038, 14754.778678319833, 14791.556253312418, 14968.251587420613, 14985.775213371808, 14986.25068746561, 675.6013951768456, 836.4145098431107, 854.0349405172146, 854.9358798090137, 855.5380094748829, 683.6615875141787, 715.6457553511019, 849.5794581682076, 861.7199410191681, 863.8833757442759, 909.8817153770001, 912.7991841794827, 914.0767824497273, 915.249495413761, 919.3242966419676, 700.9544379615309, 909.2589539525266, 911.5897845969307, 911.6262332443666, 914.1863741270275, 533.8078291814943, 536.0672429971821, 538.7817933736843, 646.9061986012002, 673.579013116802, 15484.380624774996, 15506.61233469161, 15531.301091450912, 15540.844591554165, 15544.689106217813, 14225.430982760678, 14409.639759006004, 14574.562763117123, 14606.634834129125, 14703.55889323322, 13288.0, 13363.1313964592, 13447.663808404868, 13563.982701297344, 13808.309584520834, 4610.216263235258, 4727.810887564493, 4754.381930349067, 4763.952360476385, 4804.351412559295, 116755.41622291919, 119228.0, 119507.0, 119578.0, 120408.0, 10976.98869271973, 11552.94223528877, 11630.825537616942, 11668.0, 11679.474423650956, 22819.20132795344, 23472.17847375368, 23477.41306467318, 23487.7651223489, 23517.88241058818, 8042.155573664681, 8128.349732021424, 8141.796455088552, 8270.710525131592, 8295.8340833183, 3894.4742459767567, 4298.290782020994, 4444.400005999213, 4570.497245303014, 4601.608863246662, 3543.4861945018342, 3700.5559332880225, 3703.5370578677666, 3725.012871589031, 3726.4969229153717, 678.6199728152251, 684.5995092870627, 689.2383915772998, 689.5104475822122, 696.1854630082815, 541.9078756611385, 611.3886113886114, 611.9938800611936, 623.6227082615044, 656.7208936202095, 7497.189677999594, 8349.457285276438, 9164.587593558306, 9462.0, 9491.477968711788, 530.8380943812174, 559.6530151306216, 588.9617174883618, 597.9312379076375, 607.2409488139825, 3776.6789822864816, 4035.0921042765144, 4330.328799036154, 4349.586789255016, 4532.696119586886, 97186.0, 127942.36028819796, 131478.34260828825, 135278.3236083833, 177126.0, 121861.39069304596, 121961.0, 122881.0, 124245.0, 127504.72495275114, 108877.45561272143, 113218.0, 113403.43298283455, 113960.0, 114491.0, 9639.228861690908, 10028.395456726888, 10029.398236105566, 10058.195344372287, 10066.993300670167, 42684.78657606692, 66577.33422665711, 66784.0, 67990.32009680038, 69045.65477172582, 93461.0, 93761.5311923436, 100975.0, 100978.49510752693, 105529.41705874265, 12620.55828046014, 13086.411111500196, 13162.802557961446, 13575.52485662997, 13604.319784010562, 13264.739849714262, 13890.513832016235, 14457.638559036108, 15285.923570382078, 15439.150846703538, 14021.649458763612, 14371.640708982359, 14384.136951782926, 14507.347169377192, 14546.054506457047, 10077.922957432449, 13962.441502339792, 14271.785923210953, 14522.0, 14793.706975418827, 13735.725285494435, 13747.587572372844, 13753.724925501634, 14620.634484137981, 14714.852851471776, 11437.792576975615, 11446.542138314375, 11582.247153935305, 11887.049036077266, 12153.69615759613, 10792.677752207594, 11598.478068487108, 11744.70638234051, 11860.76278474443, 11954.0], "value": 7940.062313619321, "unit": "score"}], "name": "dromaeo_css", "value": 7341.315848037154, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 112.0, 105.0, 112.0, 112.0, 114.0, 116.0, 114.0, 112.0, 113.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 109.0, 131.0, 158.0, 107.0, 129.0, 130.0, 108.0, 161.0, 103.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 184.0, 184.0, 184.0, 182.0, 182.0, 180.0, 184.0, 185.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 81.0, 76.0, 151.0, 58.0, 70.0, 78.0, 125.0, 93.0, 57.0], "value": 86.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 90.0, 133.0, 69.0, 71.0, 68.0, 117.0, 70.0, 69.0, 120.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 134.0, 85.0, 88.0, 85.0, 88.0, 85.0, 101.0, 114.0], "value": 95.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 86.0, 84.0, 84.0, 84.0, 86.0, 86.0, 85.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 54.0, 53.0, 55.0, 56.0, 55.0, 54.0, 53.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 73.0, 69.0, 80.0, 74.0, 73.0, 69.0, 69.0, 69.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 82.0, 87.0, 88.0, 87.0, 84.0, 88.0, 85.0, 92.0, 87.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 138.0, 139.0, 137.0, 138.0, 136.0, 142.0, 224.0, 140.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 168.0, 164.0, 165.0, 167.0, 165.0, 173.0, 167.0, 167.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 84.0, 85.0, 84.0, 66.0, 66.0, 65.0, 66.0, 67.0, 64.0], "value": 70.6, "unit": "score"}], "name": "kraken", "value": 1468.4999999999998, "alertThreshold": 2.0}]} 05:26:34 INFO - Return code: 0 05:26:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:26:34 INFO - # TBPL SUCCESS # 05:26:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:34 INFO - 2016-08-23 05:26:34.510 screenresolution[1656:10336] starting screenresolution argv=screenresolution get 05:26:34 INFO - 2016-08-23 05:26:34.513 screenresolution[1656:10336] Display 0: 1600x1200x32@60 05:26:34 INFO - 2016-08-23 05:26:34.519 screenresolution[1657:10339] starting screenresolution argv=screenresolution list 05:26:34 INFO - Available Modes on Display 0 05:26:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:34 INFO - Intel Iris: 05:26:34 INFO - Chipset Model: Intel Iris 05:26:34 INFO - Type: GPU 05:26:34 INFO - Bus: Built-In 05:26:34 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:34 INFO - Vendor: Intel (0x8086) 05:26:34 INFO - Device ID: 0x0a2e 05:26:34 INFO - Revision ID: 0x0009 05:26:34 INFO - Displays: 05:26:34 INFO - Display: 05:26:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:34 INFO - Display Serial Number: 1600x1200 60 05:26:34 INFO - Main Display: Yes 05:26:34 INFO - Mirror: Off 05:26:34 INFO - Online: Yes 05:26:34 INFO - Rotation: Supported 05:26:34 INFO - Return code: 0 05:26:34 INFO - Running post-action listener: _resource_record_post_action 05:26:34 INFO - [mozharness: 2016-08-23 12:26:34.754167Z] Finished run-tests step (success) 05:26:34 INFO - Running post-run listener: _resource_record_post_run 05:26:34 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32840704; Write bytes: 708190208; Read time: 311; Write time: 2027 05:26:34 INFO - TinderboxPrint: CPU usage
53.4% 05:26:34 INFO - TinderboxPrint: I/O read bytes / time
32,840,704 / 311 05:26:34 INFO - TinderboxPrint: I/O write bytes / time
708,190,208 / 2,027 05:26:34 INFO - TinderboxPrint: CPU idle
2,257.7 (46.6%) 05:26:34 INFO - TinderboxPrint: CPU system
969.0 (20.0%) 05:26:34 INFO - TinderboxPrint: CPU user
1,623.0 (33.5%) 05:26:34 INFO - TinderboxPrint: Swap in / out
435,027,968 / 0 05:26:34 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 404013568; Write bytes: 399634432; Read time: 29787; Write time: 763 05:26:34 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 29199360; Write bytes: 296546304; Read time: 279; Write time: 1227 05:26:35 INFO - Running post-run listener: _upload_blobber_files 05:26:35 INFO - Blob upload gear active. 05:26:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:35 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:26:35 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:26:35 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:26:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:26:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:26:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:36 INFO - (blobuploader) - INFO - Done attempting. 05:26:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:26:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:26:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:37 INFO - (blobuploader) - INFO - Done attempting. 05:26:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:26:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:26:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:40 INFO - (blobuploader) - INFO - Done attempting. 05:26:40 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:40 INFO - Return code: 0 05:26:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d882b3e83bdcdf005cedc24cf7aab30af64fee08f9aa3976706d69cdf500a01e44592e5413e0ec434ca5438bf44e3610fc4bd3427ee5c808d95989608cc96e8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/12f202ce6a918d872391040b9adbdac6edd8698c21e1c25255ce2867ed5b6f0596e072b96ba1bfa665bae19b39af6e55fbf3b6d00daad3869b8d28cb6b403982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e55ec70de7385a4ed8aa04d1ae6c9ec2d81b283242e037f96f78c27955436de500fb999c16f4b1a02e0f6f43950a36bb34dd96ae54f8979ed395ed0f521a301f"} 05:26:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:40 INFO - Contents: 05:26:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d882b3e83bdcdf005cedc24cf7aab30af64fee08f9aa3976706d69cdf500a01e44592e5413e0ec434ca5438bf44e3610fc4bd3427ee5c808d95989608cc96e8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/12f202ce6a918d872391040b9adbdac6edd8698c21e1c25255ce2867ed5b6f0596e072b96ba1bfa665bae19b39af6e55fbf3b6d00daad3869b8d28cb6b403982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e55ec70de7385a4ed8aa04d1ae6c9ec2d81b283242e037f96f78c27955436de500fb999c16f4b1a02e0f6f43950a36bb34dd96ae54f8979ed395ed0f521a301f"} 05:26:40 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:40 INFO - Copying logs to upload dir... 05:26:40 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.633268 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-23 05:26:40.233589) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 05:26:40.237054) ========= 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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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/d882b3e83bdcdf005cedc24cf7aab30af64fee08f9aa3976706d69cdf500a01e44592e5413e0ec434ca5438bf44e3610fc4bd3427ee5c808d95989608cc96e8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/12f202ce6a918d872391040b9adbdac6edd8698c21e1c25255ce2867ed5b6f0596e072b96ba1bfa665bae19b39af6e55fbf3b6d00daad3869b8d28cb6b403982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e55ec70de7385a4ed8aa04d1ae6c9ec2d81b283242e037f96f78c27955436de500fb999c16f4b1a02e0f6f43950a36bb34dd96ae54f8979ed395ed0f521a301f"} build_url:https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011059 build_url: 'https://queue.taskcluster.net/v1/task/fJeurYInStezQYCuBDPMlA/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/d882b3e83bdcdf005cedc24cf7aab30af64fee08f9aa3976706d69cdf500a01e44592e5413e0ec434ca5438bf44e3610fc4bd3427ee5c808d95989608cc96e8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/12f202ce6a918d872391040b9adbdac6edd8698c21e1c25255ce2867ed5b6f0596e072b96ba1bfa665bae19b39af6e55fbf3b6d00daad3869b8d28cb6b403982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e55ec70de7385a4ed8aa04d1ae6c9ec2d81b283242e037f96f78c27955436de500fb999c16f4b1a02e0f6f43950a36bb34dd96ae54f8979ed395ed0f521a301f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 05:26:40.270226) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 05:26:40.270536) ========= 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.tIa6WrW1Kt/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.Kcb1VyLj3V/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.005148 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 05:26:40.317963) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 05:26:40.318237) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 05:26:40.318514) ========= ========= Total master_lag: 2.03 =========