builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0162 starttime: 1470328453.54 results: success (0) buildid: 20160804070724 builduid: 7534df340e9244e9b500c9b549a7ae2e revision: 027410f730d7567898e603289a3b6cd1cf774959 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:13.537661) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:13.538149) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:13.538427) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:13.561927) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:13.562333) ========= 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.0uHdbBBYb9/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.wRr3STd7ah/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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-04 09:34:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 147M=0s 2016-08-04 09:34:13 (147 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131498 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:13.715853) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:13.716175) ========= 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.0uHdbBBYb9/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.wRr3STd7ah/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.022133 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:13.764763) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-04 09:34:13.765126) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 027410f730d7567898e603289a3b6cd1cf774959 --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 027410f730d7567898e603289a3b6cd1cf774959 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0uHdbBBYb9/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.wRr3STd7ah/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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-04 09:34:13,840 truncating revision to first 12 chars 2016-08-04 09:34:13,840 Setting DEBUG logging. 2016-08-04 09:34:13,840 attempt 1/10 2016-08-04 09:34:13,840 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/027410f730d7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-04 09:34:14,947 unpacking tar archive at: fx-team-027410f730d7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.366107 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-04 09:34:15.153744) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:15.154078) ========= script_repo_revision: 027410f730d7567898e603289a3b6cd1cf774959 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:15.154497) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:15.154782) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 09:34:15.173017) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-04 09:34:15.173475) ========= /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.0uHdbBBYb9/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.wRr3STd7ah/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 09:34:15 INFO - MultiFileLogger online at 20160804 09:34:15 in /builds/slave/test 09:34:15 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 09:34:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:34:15 INFO - {'append_to_log': False, 09:34:15 INFO - 'base_work_dir': '/builds/slave/test', 09:34:15 INFO - 'blob_upload_branch': 'Fx-Team', 09:34:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:34:15 INFO - 'branch': 'Fx-Team', 09:34:15 INFO - 'buildbot_json_path': 'buildprops.json', 09:34:15 INFO - 'config_files': ('talos/mac_config.py',), 09:34:15 INFO - 'default_actions': ('clobber', 09:34:15 INFO - 'read-buildbot-config', 09:34:15 INFO - 'download-and-extract', 09:34:15 INFO - 'populate-webroot', 09:34:15 INFO - 'create-virtualenv', 09:34:15 INFO - 'install', 09:34:15 INFO - 'run-tests'), 09:34:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:34:15 INFO - 'download_minidump_stackwalk': True, 09:34:15 INFO - 'download_symbols': 'ondemand', 09:34:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:34:15 INFO - 'tooltool.py': '/tools/tooltool.py', 09:34:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:34:15 INFO - '/tools/misc-python/virtualenv.py')}, 09:34:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:34:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:34:15 INFO - 'installer_path': 'installer.exe', 09:34:15 INFO - 'log_level': 'info', 09:34:15 INFO - 'log_name': 'talos', 09:34:15 INFO - 'log_to_console': True, 09:34:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:34:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:34:15 INFO - 'opt_config_files': (), 09:34:15 INFO - 'pip_index': False, 09:34:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:34:15 INFO - 'cmd': ('bash', 09:34:15 INFO - '-c', 09:34:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:34:15 INFO - 'enabled': True, 09:34:15 INFO - 'halt_on_failure': False, 09:34:15 INFO - 'name': 'check_screen_resolution'},), 09:34:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:34:15 INFO - 'cmd': ('bash', 09:34:15 INFO - '-c', 09:34:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:34:15 INFO - 'enabled': True, 09:34:15 INFO - 'halt_on_failure': False, 09:34:15 INFO - 'name': 'check_screen_resolution'},), 09:34:15 INFO - 'run_cmd_checks_enabled': True, 09:34:15 INFO - 'sps_profile': False, 09:34:15 INFO - 'sps_profile_interval': 0, 09:34:15 INFO - 'suite': 'dromaeojs', 09:34:15 INFO - 'system_bits': '32', 09:34:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:34:15 INFO - 'title': 't-yosemite-r7-0162', 09:34:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:34:15 INFO - 'use_talos_json': True, 09:34:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:34:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:34:15 INFO - 'work_dir': 'build'} 09:34:15 INFO - [mozharness: 2016-08-04 16:34:15.378042Z] Running clobber step. 09:34:15 INFO - Running pre-action listener: _resource_record_pre_action 09:34:15 INFO - Running main action method: clobber 09:34:15 INFO - rmtree: /builds/slave/test/build 09:34:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:34:16 INFO - Running post-action listener: _resource_record_post_action 09:34:16 INFO - [mozharness: 2016-08-04 16:34:16.920435Z] Finished clobber step (success) 09:34:16 INFO - [mozharness: 2016-08-04 16:34:16.920586Z] Running read-buildbot-config step. 09:34:16 INFO - Running pre-action listener: _resource_record_pre_action 09:34:16 INFO - Running main action method: read_buildbot_config 09:34:16 INFO - Using buildbot properties: 09:34:16 INFO - { 09:34:16 INFO - "project": "", 09:34:16 INFO - "product": "firefox", 09:34:16 INFO - "script_repo_revision": "production", 09:34:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:34:16 INFO - "repository": "", 09:34:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:34:16 INFO - "buildid": "20160804070724", 09:34:16 INFO - "pgo_build": "False", 09:34:16 INFO - "basedir": "/builds/slave/test", 09:34:16 INFO - "buildnumber": 78, 09:34:16 INFO - "slavename": "t-yosemite-r7-0162", 09:34:16 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:34:16 INFO - "platform": "yosemite_r7", 09:34:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:34:16 INFO - "branch": "fx-team", 09:34:16 INFO - "repo_path": "integration/fx-team", 09:34:16 INFO - "revision": "027410f730d7567898e603289a3b6cd1cf774959", 09:34:16 INFO - "stage_platform": "macosx64", 09:34:16 INFO - "builduid": "7534df340e9244e9b500c9b549a7ae2e", 09:34:16 INFO - "slavebuilddir": "test" 09:34:16 INFO - } 09:34:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:34:16 INFO - Running post-action listener: _resource_record_post_action 09:34:16 INFO - [mozharness: 2016-08-04 16:34:16.923952Z] Finished read-buildbot-config step (success) 09:34:16 INFO - [mozharness: 2016-08-04 16:34:16.924056Z] Running download-and-extract step. 09:34:16 INFO - Running pre-action listener: _resource_record_pre_action 09:34:16 INFO - Running main action method: download_and_extract 09:34:16 INFO - mkdir: /builds/slave/test/build/tests 09:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:16 INFO - trying https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:34:16 INFO - Downloading https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/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 09:34:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/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 09:34:17 INFO - Downloaded 1199 bytes. 09:34:17 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:34:17 INFO - Using the following test package requirements: 09:34:17 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:34:17 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:34:17 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:34:17 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:34:17 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:34:17 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:34:17 INFO - u'jsshell-mac.zip'], 09:34:17 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:34:17 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:34:17 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:34:17 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:34:17 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:34:17 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:34:17 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:34:17 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:34:17 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:34:17 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:34:17 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:34:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:17 INFO - trying https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:34:17 INFO - Downloading https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/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 09:34:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/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 09:34:18 INFO - Downloaded 16316079 bytes. 09:34:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:34:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:34:22 INFO - Return code: 0 09:34:22 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 09:34:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:22 INFO - trying https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:34:22 INFO - Downloading https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/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 09:34:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/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 09:34:22 INFO - Downloaded 16316079 bytes. 09:34:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:34:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:34:26 INFO - Return code: 0 09:34:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:26 INFO - trying https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:34:26 INFO - Downloading https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/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 09:34:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/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 09:34:27 INFO - Downloaded 14413723 bytes. 09:34:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:34:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:34:27 INFO - Return code: 0 09:34:27 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')]} 09:34:27 INFO - trying https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:34:27 INFO - Downloading https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:34:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/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 09:34:31 INFO - Downloaded 111151548 bytes. 09:34:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:34:31 INFO - mkdir: /builds/slave/test/properties 09:34:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:34:31 INFO - Writing to file /builds/slave/test/properties/build_url 09:34:31 INFO - Contents: 09:34:31 INFO - build_url:https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:34:31 INFO - Running post-action listener: _resource_record_post_action 09:34:31 INFO - Running post-action listener: set_extra_try_arguments 09:34:31 INFO - [mozharness: 2016-08-04 16:34:31.937224Z] Finished download-and-extract step (success) 09:34:31 INFO - [mozharness: 2016-08-04 16:34:31.937356Z] Running populate-webroot step. 09:34:31 INFO - Running pre-action listener: _resource_record_pre_action 09:34:31 INFO - Running main action method: populate_webroot 09:34:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:34:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:34:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:34:31 INFO - '--tppagecycles', 09:34:31 INFO - '7'], 09:34:31 INFO - 'tests': ['tsvgm']}}, 09:34:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:34:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:34:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:34:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:34:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:34:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:34:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:31 INFO - 'talos_options': ['--disable-e10s'], 09:34:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:31 INFO - 'talos_options': ['--disable-e10s'], 09:34:31 INFO - 'tests': ['damp', 'tps']}, 09:34:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:31 INFO - 'tests': ['damp', 'tps']}, 09:34:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:34:31 INFO - 'tests': ['dromaeo_dom']}, 09:34:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:34:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:34:31 INFO - 'tests': ['basic_compositor_video']}, 09:34:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:34:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:34:31 INFO - 'tests': ['a11yr', 09:34:31 INFO - 'ts_paint', 09:34:31 INFO - 'tpaint', 09:34:31 INFO - 'sessionrestore', 09:34:31 INFO - 'sessionrestore_no_auto_restore', 09:34:31 INFO - 'tabpaint']}, 09:34:31 INFO - 'other-e10s': {'tests': ['a11yr', 09:34:31 INFO - 'ts_paint', 09:34:31 INFO - 'tpaint', 09:34:31 INFO - 'sessionrestore', 09:34:31 INFO - 'sessionrestore_no_auto_restore', 09:34:31 INFO - 'tabpaint']}, 09:34:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:34:31 INFO - 'ts_paint', 09:34:31 INFO - 'tpaint', 09:34:31 INFO - 'sessionrestore', 09:34:31 INFO - 'sessionrestore_no_auto_restore', 09:34:31 INFO - 'tabpaint']}, 09:34:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:34:31 INFO - 'ts_paint', 09:34:31 INFO - 'tpaint', 09:34:31 INFO - 'sessionrestore', 09:34:31 INFO - 'sessionrestore_no_auto_restore', 09:34:31 INFO - 'tabpaint']}, 09:34:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:34:31 INFO - 'tests': ['a11yr', 09:34:31 INFO - 'ts_paint', 09:34:31 INFO - 'tpaint', 09:34:31 INFO - 'sessionrestore', 09:34:31 INFO - 'sessionrestore_no_auto_restore', 09:34:31 INFO - 'tabpaint']}, 09:34:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:34:31 INFO - 'tests': ['a11yr', 09:34:31 INFO - 'ts_paint', 09:34:31 INFO - 'tpaint', 09:34:31 INFO - 'sessionrestore', 09:34:31 INFO - 'sessionrestore_no_auto_restore', 09:34:31 INFO - 'tabpaint']}, 09:34:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:34:31 INFO - 'tests': ['tsvgx', 09:34:31 INFO - 'tsvgr_opacity', 09:34:31 INFO - 'tart', 09:34:31 INFO - 'tscrollx', 09:34:31 INFO - 'cart']}, 09:34:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:34:31 INFO - 'tsvgr_opacity', 09:34:31 INFO - 'tart', 09:34:31 INFO - 'tscrollx', 09:34:31 INFO - 'cart']}, 09:34:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:31 INFO - 'talos_options': ['--disable-e10s'], 09:34:31 INFO - 'tests': ['tp5o']}, 09:34:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:31 INFO - 'tests': ['tp5o']}, 09:34:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:31 INFO - 'talos_options': ['--disable-e10s', 09:34:31 INFO - '--xperf_path', 09:34:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:31 INFO - 'tests': ['tp5n']}, 09:34:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:34:31 INFO - 'talos_options': ['--xperf_path', 09:34:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:31 INFO - 'tests': ['tp5n']}}, 09:34:31 INFO - 'talos.zip': {'path': '', 09:34:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:34:31 INFO - Running post-action listener: _resource_record_post_action 09:34:31 INFO - [mozharness: 2016-08-04 16:34:31.956449Z] Finished populate-webroot step (success) 09:34:31 INFO - [mozharness: 2016-08-04 16:34:31.956545Z] Running create-virtualenv step. 09:34:31 INFO - Running pre-action listener: _resource_record_pre_action 09:34:31 INFO - Running main action method: create_virtualenv 09:34:31 INFO - Creating virtualenv /builds/slave/test/build/venv 09:34:31 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 09:34:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:34:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:34:32 INFO - Using real prefix '/tools/python27' 09:34:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:34:32 INFO - Installing distribute.............................................................................................................................................................................................done. 09:34:35 INFO - Installing pip.................done. 09:34:35 INFO - Return code: 0 09:34:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:34:35 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')]} 09:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:35 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')]} 09:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:35 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 0x1033dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec295a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103343d30>, '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 0x1030a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10333bb20>, '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.0uHdbBBYb9/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.wRr3STd7ah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 09:34:35 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 09:34:35 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 09:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0uHdbBBYb9/Render', 09:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:35 INFO - 'HOME': '/Users/cltbld', 09:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:35 INFO - 'LOGNAME': 'cltbld', 09:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:35 INFO - 'MOZ_NO_REMOTE': '1', 09:34:35 INFO - 'NO_EM_RESTART': '1', 09:34:35 INFO - 'PAGER': '/bin/cat', 09:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:35 INFO - 'PWD': '/builds/slave/test', 09:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:35 INFO - 'SHELL': '/bin/bash', 09:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wRr3STd7ah/Listeners', 09:34:35 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:35 INFO - 'USER': 'cltbld', 09:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:35 INFO - 'XPC_FLAGS': '0x0', 09:34:35 INFO - 'XPC_SERVICE_NAME': '0', 09:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:35 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 09:34:35 INFO - Cleaning up... 09:34:35 INFO - Return code: 0 09:34:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:34:35 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')]} 09:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:35 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')]} 09:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:35 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 0x1033dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec295a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103343d30>, '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 0x1030a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10333bb20>, '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.0uHdbBBYb9/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.wRr3STd7ah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 09:34:35 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 09:34:35 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 09:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0uHdbBBYb9/Render', 09:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:35 INFO - 'HOME': '/Users/cltbld', 09:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:35 INFO - 'LOGNAME': 'cltbld', 09:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:35 INFO - 'MOZ_NO_REMOTE': '1', 09:34:35 INFO - 'NO_EM_RESTART': '1', 09:34:35 INFO - 'PAGER': '/bin/cat', 09:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:35 INFO - 'PWD': '/builds/slave/test', 09:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:35 INFO - 'SHELL': '/bin/bash', 09:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wRr3STd7ah/Listeners', 09:34:35 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:35 INFO - 'USER': 'cltbld', 09:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:35 INFO - 'XPC_FLAGS': '0x0', 09:34:35 INFO - 'XPC_SERVICE_NAME': '0', 09:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:36 INFO - Downloading/unpacking psutil>=3.1.1 09:34:36 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 09:34:36 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 09:34:36 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 09:34:36 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 09:34:36 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 09:34:36 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 09:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:34:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:39 INFO - Installing collected packages: psutil 09:34:39 INFO - Running setup.py install for psutil 09:34:39 INFO - building 'psutil._psutil_osx' extension 09:34:39 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 09:34:40 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 09:34:40 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 09:34:40 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 09:34:40 INFO - building 'psutil._psutil_posix' extension 09:34:40 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 09:34:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:34:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:34:40 INFO - ^ 09:34:40 INFO - 1 warning generated. 09:34:40 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 09:34:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:40 INFO - Successfully installed psutil 09:34:40 INFO - Cleaning up... 09:34:40 INFO - Return code: 0 09:34:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:34:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1033dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec295a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103343d30>, '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 0x1030a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10333bb20>, '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.0uHdbBBYb9/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.wRr3STd7ah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 09:34:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:34:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:34:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0uHdbBBYb9/Render', 09:34:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:40 INFO - 'HOME': '/Users/cltbld', 09:34:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:40 INFO - 'LOGNAME': 'cltbld', 09:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:40 INFO - 'MOZ_NO_REMOTE': '1', 09:34:40 INFO - 'NO_EM_RESTART': '1', 09:34:40 INFO - 'PAGER': '/bin/cat', 09:34:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:40 INFO - 'PWD': '/builds/slave/test', 09:34:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:40 INFO - 'SHELL': '/bin/bash', 09:34:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wRr3STd7ah/Listeners', 09:34:40 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:34:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:40 INFO - 'USER': 'cltbld', 09:34:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:40 INFO - 'XPC_FLAGS': '0x0', 09:34:40 INFO - 'XPC_SERVICE_NAME': '0', 09:34:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:34:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:34:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:34:43 INFO - Installing collected packages: mozsystemmonitor 09:34:43 INFO - Running setup.py install for mozsystemmonitor 09:34:43 INFO - Successfully installed mozsystemmonitor 09:34:43 INFO - Cleaning up... 09:34:43 INFO - Return code: 0 09:34:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:34:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1033dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec295a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103343d30>, '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 0x1030a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10333bb20>, '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.0uHdbBBYb9/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.wRr3STd7ah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 09:34:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:34:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:34:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0uHdbBBYb9/Render', 09:34:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:43 INFO - 'HOME': '/Users/cltbld', 09:34:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:43 INFO - 'LOGNAME': 'cltbld', 09:34:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:43 INFO - 'MOZ_NO_REMOTE': '1', 09:34:43 INFO - 'NO_EM_RESTART': '1', 09:34:43 INFO - 'PAGER': '/bin/cat', 09:34:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:43 INFO - 'PWD': '/builds/slave/test', 09:34:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:43 INFO - 'SHELL': '/bin/bash', 09:34:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wRr3STd7ah/Listeners', 09:34:43 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:34:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:43 INFO - 'USER': 'cltbld', 09:34:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:43 INFO - 'XPC_FLAGS': '0x0', 09:34:43 INFO - 'XPC_SERVICE_NAME': '0', 09:34:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:43 INFO - Downloading/unpacking jsonschema==2.5.1 09:34:43 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 09:34:43 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 09:34:43 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 09:34:43 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 09:34:43 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 09:34:43 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 09:34:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:34:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:34:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:46 INFO - Downloading functools32-3.2.3-2.tar.gz 09:34:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:34:46 INFO - warning: no files found matching '*.txt' 09:34:46 INFO - no previously-included directories found matching 'build' 09:34:46 INFO - no previously-included directories found matching 'dist' 09:34:46 INFO - no previously-included directories found matching '.git*' 09:34:46 INFO - Installing collected packages: jsonschema, functools32 09:34:46 INFO - Running setup.py install for functools32 09:34:46 INFO - warning: no files found matching '*.txt' 09:34:46 INFO - no previously-included directories found matching 'build' 09:34:46 INFO - no previously-included directories found matching 'dist' 09:34:46 INFO - no previously-included directories found matching '.git*' 09:34:46 INFO - Successfully installed jsonschema functools32 09:34:46 INFO - Cleaning up... 09:34:47 INFO - Return code: 0 09:34:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:34: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')]} 09:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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')]} 09:34:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:47 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 0x1033dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec295a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103343d30>, '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 0x1030a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10333bb20>, '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.0uHdbBBYb9/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.wRr3STd7ah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 09:34:47 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 09:34:47 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 09:34:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0uHdbBBYb9/Render', 09:34:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:47 INFO - 'HOME': '/Users/cltbld', 09:34:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:47 INFO - 'LOGNAME': 'cltbld', 09:34:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:47 INFO - 'MOZ_NO_REMOTE': '1', 09:34:47 INFO - 'NO_EM_RESTART': '1', 09:34:47 INFO - 'PAGER': '/bin/cat', 09:34:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:47 INFO - 'PWD': '/builds/slave/test', 09:34:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:47 INFO - 'SHELL': '/bin/bash', 09:34:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wRr3STd7ah/Listeners', 09:34:47 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:34:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:47 INFO - 'USER': 'cltbld', 09:34:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:47 INFO - 'XPC_FLAGS': '0x0', 09:34:47 INFO - 'XPC_SERVICE_NAME': '0', 09:34:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:34:47 INFO - Cleaning up... 09:34:47 INFO - Return code: 0 09:34:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:34: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')]} 09:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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')]} 09:34:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:47 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 0x1033dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec295a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103343d30>, '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 0x1030a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10333bb20>, '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.0uHdbBBYb9/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.wRr3STd7ah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 09:34:47 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 09:34:47 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 09:34:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0uHdbBBYb9/Render', 09:34:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:47 INFO - 'HOME': '/Users/cltbld', 09:34:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:47 INFO - 'LOGNAME': 'cltbld', 09:34:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:47 INFO - 'MOZ_NO_REMOTE': '1', 09:34:47 INFO - 'NO_EM_RESTART': '1', 09:34:47 INFO - 'PAGER': '/bin/cat', 09:34:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:47 INFO - 'PWD': '/builds/slave/test', 09:34:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:47 INFO - 'SHELL': '/bin/bash', 09:34:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wRr3STd7ah/Listeners', 09:34:47 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:34:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:47 INFO - 'USER': 'cltbld', 09:34:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:47 INFO - 'XPC_FLAGS': '0x0', 09:34:47 INFO - 'XPC_SERVICE_NAME': '0', 09:34:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:47 INFO - Downloading/unpacking blobuploader==1.2.4 09:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:50 INFO - Downloading blobuploader-1.2.4.tar.gz 09:34:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:34:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:34:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:51 INFO - Downloading docopt-0.6.1.tar.gz 09:34:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:34:51 INFO - Installing collected packages: blobuploader, requests, docopt 09:34:51 INFO - Running setup.py install for blobuploader 09:34:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:34:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:34:51 INFO - Running setup.py install for requests 09:34:51 INFO - Running setup.py install for docopt 09:34:52 INFO - Successfully installed blobuploader requests docopt 09:34:52 INFO - Cleaning up... 09:34:52 INFO - Return code: 0 09:34:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:52 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')]} 09:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:52 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')]} 09:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:52 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 0x1033dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec295a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103343d30>, '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 0x1030a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10333bb20>, '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.0uHdbBBYb9/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.wRr3STd7ah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 09:34:52 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 09:34:52 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 09:34:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0uHdbBBYb9/Render', 09:34:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:52 INFO - 'HOME': '/Users/cltbld', 09:34:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:52 INFO - 'LOGNAME': 'cltbld', 09:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:52 INFO - 'MOZ_NO_REMOTE': '1', 09:34:52 INFO - 'NO_EM_RESTART': '1', 09:34:52 INFO - 'PAGER': '/bin/cat', 09:34:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:52 INFO - 'PWD': '/builds/slave/test', 09:34:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:52 INFO - 'SHELL': '/bin/bash', 09:34:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wRr3STd7ah/Listeners', 09:34:52 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:34:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:52 INFO - 'USER': 'cltbld', 09:34:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:52 INFO - 'XPC_FLAGS': '0x0', 09:34:52 INFO - 'XPC_SERVICE_NAME': '0', 09:34:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-UdHsn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Qm3Ldc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-1lfYOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-bZswWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-mgqYqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-nbX4yM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-8CgHmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Av0564-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-72IKey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-1GkRQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-TLZIh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-yFCAKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-LEyh3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-15LbwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-ZMmxkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-TYvdOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:54 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Nde5X5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:34:54 INFO - Running setup.py install for manifestparser 09:34:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:34:54 INFO - Running setup.py install for mozcrash 09:34:54 INFO - Running setup.py install for mozdebug 09:34:54 INFO - Running setup.py install for mozdevice 09:34:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:34:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:34:54 INFO - Running setup.py install for mozfile 09:34:54 INFO - Running setup.py install for mozhttpd 09:34:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:34:55 INFO - Running setup.py install for mozinfo 09:34:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:34:55 INFO - Running setup.py install for mozInstall 09:34:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:34:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:34:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:34:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:34:55 INFO - Running setup.py install for mozleak 09:34:55 INFO - Running setup.py install for mozlog 09:34:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:34:55 INFO - Running setup.py install for moznetwork 09:34:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:34:55 INFO - Running setup.py install for mozprocess 09:34:55 INFO - Running setup.py install for mozprofile 09:34:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:34:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:34:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:34:56 INFO - Running setup.py install for mozrunner 09:34:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:34:56 INFO - Running setup.py install for mozscreenshot 09:34:56 INFO - Running setup.py install for moztest 09:34:56 INFO - Running setup.py install for mozversion 09:34:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:34:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:34:56 INFO - Cleaning up... 09:34:56 INFO - Return code: 0 09:34:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34: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')]} 09:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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')]} 09:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:56 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 0x1033dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec295a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103343d30>, '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 0x1030a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10333bb20>, '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.0uHdbBBYb9/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.wRr3STd7ah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 09:34:56 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 09:34:56 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 09:34:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0uHdbBBYb9/Render', 09:34:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:56 INFO - 'HOME': '/Users/cltbld', 09:34:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:56 INFO - 'LOGNAME': 'cltbld', 09:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:56 INFO - 'MOZ_NO_REMOTE': '1', 09:34:56 INFO - 'NO_EM_RESTART': '1', 09:34:56 INFO - 'PAGER': '/bin/cat', 09:34:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:56 INFO - 'PWD': '/builds/slave/test', 09:34:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:56 INFO - 'SHELL': '/bin/bash', 09:34:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wRr3STd7ah/Listeners', 09:34:56 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:34:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:56 INFO - 'USER': 'cltbld', 09:34:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:56 INFO - 'XPC_FLAGS': '0x0', 09:34:56 INFO - 'XPC_SERVICE_NAME': '0', 09:34:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-OIrCp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:57 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)) 09:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-mWq2HP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:57 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)) 09:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-IHGpaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:57 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)) 09:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-xYrx08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:57 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)) 09:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-ceR2mF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:57 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)) 09:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-eHpMVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:57 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)) 09:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-DWSgwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:57 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)) 09:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-aozmwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:57 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)) 09:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-ufEhT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:57 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)) 09:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:57 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-IgnSHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:58 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)) 09:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:58 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-wmTki8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:58 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)) 09:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:58 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-1Zk7EY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:58 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)) 09:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:58 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-xAd9Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:58 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)) 09:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:58 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-PfI3Qa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:58 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)) 09:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:58 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-aVLRFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:58 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)) 09:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:58 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-UUgtlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:58 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)) 09:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:58 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Qms0Zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:58 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)) 09:34:58 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)) 09:34:58 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)) 09:34:58 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)) 09:34:58 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)) 09:34:58 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)) 09:34:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34:58 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 09:34:58 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 09:34:58 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 09:34:58 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 09:34:58 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 09:34:58 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 09:35:01 INFO - Downloading blessings-1.6.tar.gz 09:35:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:35:01 INFO - Installing collected packages: blessings 09:35:01 INFO - Running setup.py install for blessings 09:35:01 INFO - Successfully installed blessings 09:35:01 INFO - Cleaning up... 09:35:01 INFO - Return code: 0 09:35:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:35:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:01 INFO - Reading from file tmpfile_stdout 09:35:01 INFO - Current package versions: 09:35:01 INFO - blessings == 1.6 09:35:01 INFO - blobuploader == 1.2.4 09:35:01 INFO - docopt == 0.6.1 09:35:01 INFO - functools32 == 3.2.3-2 09:35:01 INFO - jsonschema == 2.5.1 09:35:01 INFO - manifestparser == 1.1 09:35:01 INFO - mozInstall == 1.12 09:35:01 INFO - mozcrash == 0.17 09:35:01 INFO - mozdebug == 0.1 09:35:01 INFO - mozdevice == 0.48 09:35:01 INFO - mozfile == 1.2 09:35:01 INFO - mozhttpd == 0.7 09:35:01 INFO - mozinfo == 0.9 09:35:01 INFO - mozleak == 0.1 09:35:01 INFO - mozlog == 3.3 09:35:01 INFO - moznetwork == 0.27 09:35:01 INFO - mozprocess == 0.23 09:35:01 INFO - mozprofile == 0.28 09:35:01 INFO - mozrunner == 6.12 09:35:01 INFO - mozscreenshot == 0.1 09:35:01 INFO - mozsystemmonitor == 0.3 09:35:01 INFO - moztest == 0.7 09:35:01 INFO - mozversion == 1.4 09:35:01 INFO - psutil == 3.1.1 09:35:01 INFO - requests == 1.2.3 09:35:01 INFO - wsgiref == 0.1.2 09:35:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:01 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 0x1033dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec295a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103343d30>, '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 0x1030a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10333bb20>, '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.0uHdbBBYb9/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.wRr3STd7ah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 09:35:01 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 09:35:01 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 09:35:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0uHdbBBYb9/Render', 09:35:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:01 INFO - 'HOME': '/Users/cltbld', 09:35:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:01 INFO - 'LOGNAME': 'cltbld', 09:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:01 INFO - 'MOZ_NO_REMOTE': '1', 09:35:01 INFO - 'NO_EM_RESTART': '1', 09:35:01 INFO - 'PAGER': '/bin/cat', 09:35:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:01 INFO - 'PWD': '/builds/slave/test', 09:35:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:01 INFO - 'SHELL': '/bin/bash', 09:35:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wRr3STd7ah/Listeners', 09:35:01 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:35:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:01 INFO - 'USER': 'cltbld', 09:35:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:01 INFO - 'XPC_FLAGS': '0x0', 09:35:01 INFO - 'XPC_SERVICE_NAME': '0', 09:35:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:02 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)) 09:35:02 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)) 09:35:02 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)) 09:35:02 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)) 09:35:02 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)) 09:35:02 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)) 09:35:02 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)) 09:35:02 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)) 09:35:02 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)) 09:35:02 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)) 09:35:02 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)) 09:35:02 INFO - Cleaning up... 09:35:02 INFO - Return code: 0 09:35:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:35: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')]} 09:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35: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')]} 09:35:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1033dfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec295a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103343d30>, '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 0x1030a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10333bb20>, '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.0uHdbBBYb9/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.wRr3STd7ah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 09:35: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'] in /builds/slave/test/build 09:35: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 09:35:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0uHdbBBYb9/Render', 09:35:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:02 INFO - 'HOME': '/Users/cltbld', 09:35:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:02 INFO - 'LOGNAME': 'cltbld', 09:35:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:02 INFO - 'MOZ_NO_REMOTE': '1', 09:35:02 INFO - 'NO_EM_RESTART': '1', 09:35:02 INFO - 'PAGER': '/bin/cat', 09:35:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:02 INFO - 'PWD': '/builds/slave/test', 09:35:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:02 INFO - 'SHELL': '/bin/bash', 09:35:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wRr3STd7ah/Listeners', 09:35:02 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:35:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:02 INFO - 'USER': 'cltbld', 09:35:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:02 INFO - 'XPC_FLAGS': '0x0', 09:35:02 INFO - 'XPC_SERVICE_NAME': '0', 09:35:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:35:02 INFO - Cleaning up... 09:35:02 INFO - Return code: 0 09:35:02 INFO - Running post-action listener: _resource_record_post_action 09:35:02 INFO - Running post-action listener: _start_resource_monitoring 09:35:02 INFO - Starting resource monitoring. 09:35:02 INFO - [mozharness: 2016-08-04 16:35:02.425822Z] Finished create-virtualenv step (success) 09:35:02 INFO - [mozharness: 2016-08-04 16:35:02.426831Z] Running install step. 09:35:02 INFO - Running pre-action listener: _resource_record_pre_action 09:35:02 INFO - Running main action method: install 09:35:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:02 INFO - Reading from file tmpfile_stdout 09:35:02 INFO - Detecting whether we're running mozinstall >=1.0... 09:35:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:35:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:35:02 INFO - Reading from file tmpfile_stdout 09:35:02 INFO - Output received: 09:35:02 INFO - Usage: mozinstall [options] installer 09:35:02 INFO - Options: 09:35:02 INFO - -h, --help show this help message and exit 09:35:02 INFO - -d DEST, --destination=DEST 09:35:02 INFO - Directory to install application into. [default: 09:35:02 INFO - "/builds/slave/test"] 09:35:02 INFO - --app=APP Application being installed. [default: firefox] 09:35:02 INFO - mkdir: /builds/slave/test/build/application 09:35:02 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'] 09:35:02 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 09:35:37 INFO - Reading from file tmpfile_stdout 09:35:37 INFO - Output received: 09:35:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:35:37 INFO - Running post-action listener: _resource_record_post_action 09:35:37 INFO - [mozharness: 2016-08-04 16:35:37.708829Z] Finished install step (success) 09:35:37 INFO - [mozharness: 2016-08-04 16:35:37.708938Z] Running run-tests step. 09:35:37 INFO - Running pre-action listener: _resource_record_pre_action 09:35:37 INFO - Running main action method: run_tests 09:35:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:35:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:35:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:35:37 INFO - 2016-08-04 09:35:37.723 screenresolution[1961:18790] starting screenresolution argv=screenresolution get 09:35:37 INFO - 2016-08-04 09:35:37.726 screenresolution[1961:18790] Display 0: 1600x1200x32@60 09:35:37 INFO - 2016-08-04 09:35:37.731 screenresolution[1962:18792] starting screenresolution argv=screenresolution list 09:35:37 INFO - Available Modes on Display 0 09:35:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:35:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:35:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:35:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:35:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:35:37 INFO - Intel Iris: 09:35:37 INFO - Chipset Model: Intel Iris 09:35:37 INFO - Type: GPU 09:35:37 INFO - Bus: Built-In 09:35:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:35:37 INFO - Vendor: Intel (0x8086) 09:35:37 INFO - Device ID: 0x0a2e 09:35:37 INFO - Revision ID: 0x0009 09:35:37 INFO - Displays: 09:35:37 INFO - Display: 09:35:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:35:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:35:37 INFO - Display Serial Number: 1600x1200 60 09:35:37 INFO - Main Display: Yes 09:35:37 INFO - Mirror: Off 09:35:37 INFO - Online: Yes 09:35:37 INFO - Rotation: Supported 09:35:37 INFO - Return code: 0 09:35:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:35:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:35:37 INFO - Python 2.7.3 09:35:37 INFO - Return code: 0 09:35:37 INFO - grabbing minidump binary from tooltool 09:35:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:37 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 0x103343d30>, '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 0x1030a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10333bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:35:37 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 09:35:37 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 09:35:37 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 09:35:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:35:38 INFO - Return code: 0 09:35:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:35:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:35:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:35:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:35:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:35:38 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/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0162', '--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 09:35:38 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/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0162 --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 09:35:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0uHdbBBYb9/Render', 09:35:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:38 INFO - 'HOME': '/Users/cltbld', 09:35:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:38 INFO - 'LOGNAME': 'cltbld', 09:35:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:35:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:35:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:38 INFO - 'MOZ_NO_REMOTE': '1', 09:35:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:35:38 INFO - 'NO_EM_RESTART': '1', 09:35:38 INFO - 'PAGER': '/bin/cat', 09:35:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:38 INFO - 'PWD': '/builds/slave/test', 09:35:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:35:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:38 INFO - 'SHELL': '/bin/bash', 09:35:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wRr3STd7ah/Listeners', 09:35:38 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:35:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:38 INFO - 'USER': 'cltbld', 09:35:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:38 INFO - 'XPC_FLAGS': '0x0', 09:35:38 INFO - 'XPC_SERVICE_NAME': '0', 09:35:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:38 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/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0162', '--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 09:35:38 INFO - mozversion application_buildid: 20160804070724 09:35:38 INFO - mozversion application_changeset: 027410f730d7567898e603289a3b6cd1cf774959 09:35:38 INFO - mozversion application_display_name: Nightly 09:35:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:35:38 INFO - mozversion application_name: Firefox 09:35:38 INFO - mozversion application_remotingname: firefox 09:35:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:35:38 INFO - mozversion application_vendor: Mozilla 09:35:38 INFO - mozversion application_version: 51.0a1 09:35:38 INFO - mozversion platform_buildid: 20160804070724 09:35:38 INFO - mozversion platform_changeset: 027410f730d7567898e603289a3b6cd1cf774959 09:35:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:35:38 INFO - mozversion platform_version: 51.0a1 09:35:38 INFO - using testdate: 1470328538 09:35:38 INFO - actual date: 1470328538 09:35:38 INFO - starting webserver on 'localhost:54458' 09:35:38 INFO - SUITE-START | Running 2 tests 09:35:38 INFO - TEST-START | dromaeo_css 09:35:38 INFO - operating with platform_type : mac_ 09:35:38 INFO - Initialising browser for dromaeo_css test... 09:35:38 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpFN2ANa/profile http://localhost:54458/getInfo.html) 09:35:40 INFO - PROCESS | 1972 | __metrics Screen width/height:1600/1200 09:35:40 INFO - PROCESS | 1972 | colorDepth:24 09:35:40 INFO - PROCESS | 1972 | Browser inner width/height: 1024/689 09:35:40 INFO - PROCESS | 1972 | __metrics 09:35:40 INFO - PROCESS | 1972 | 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" 09:35:40 INFO - PROCESS | 1972 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:41 INFO - TEST-INFO | 1972: exit 0 09:35:41 INFO - Browser initialized. 09:35:41 INFO - Running cycle 1/1 for dromaeo_css test... 09:35:41 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpFN2ANa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:53 INFO - PROCESS | 1974 | RSS: Main: 242995200 09:35:53 INFO - PROCESS | 1974 | 09:36:44 INFO - PROCESS | 1974 | 1470328604900 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] 09:38:56 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54458/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54458/tests/dromaeo/cssquery-ext.html) 09:38:58 INFO - PROCESS | 1974 | RSS: Main: 369995776 09:38:58 INFO - PROCESS | 1974 | 09:42:01 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54458/tests/dromaeo/cssquery-ext.html (next: http://localhost:54458/tests/dromaeo/cssquery-jquery.html) 09:42:01 INFO - PROCESS | 1974 | RSS: Main: 397230080 09:42:01 INFO - PROCESS | 1974 | 09:45:04 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54458/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54458/tests/dromaeo/cssquery-mootools.html) 09:45:04 INFO - PROCESS | 1974 | RSS: Main: 369745920 09:45:04 INFO - PROCESS | 1974 | 09:48:07 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54458/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54458/tests/dromaeo/cssquery-prototype.html) 09:48:07 INFO - PROCESS | 1974 | RSS: Main: 358699008 09:48:07 INFO - PROCESS | 1974 | 09:51:11 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54458/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54458/tests/dromaeo/cssquery-yui.html) 09:51:12 INFO - PROCESS | 1974 | RSS: Main: 346009600 09:51:12 INFO - PROCESS | 1974 | 09:54:15 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54458/tests/dromaeo/cssquery-yui.html (next: http://localhost:54458/tests/dromaeo/cssquery-dojo.html) 09:54:15 INFO - PROCESS | 1974 | RSS: Main: 462028800 09:54:15 INFO - PROCESS | 1974 | 09:54:15 INFO - PROCESS | 1974 | __start_tp_report 09:54:15 INFO - PROCESS | 1974 | _x_x_mozilla_page_load 09:54:15 INFO - PROCESS | 1974 | _x_x_mozilla_page_load_details 09:54:15 INFO - PROCESS | 1974 | |i|pagename|runs| 09:54:15 INFO - PROCESS | 1974 | |0;dojo.html;3369;3399.1332210286387;3400.09897377195;3417.128632198789;3454.309138172365;5612.579056570763;5687.402822703619;5715.257016587843;5780.797672081478;5818.06910894257;5724.4561766632105;5760.7407666655;5773.740181691824;5774.364819869811;5777.480026797587;5199.818006369768;5231.476852314776;5265.447128051557;5269.156934890419;5327.200919862033;5253.264542963988;5281.049411106;5304.814331498398;5307.548858347026;5329.067413202693;5244.239585260135;5262.763175657085;5281.63028587999;5289.994900968823;5318.973405132969;4404.471463424377;5288.973555132219;5339.439358867341;5346.492083252124;5383.596230282745;5144.897102057976;5146.202338637517;5201.947980520222;5279.86800329991;5285.286486324336;5619.550435965113;5690.971545142289;5695.943040569583;5714.542836573064;5737.39757325479;6241.687915604247;6308.085327627514;6320.652364119972;6322.462590679797;6332.651704156316;6194.380561943814;6248.468880145193;6293.9370606293805;6309.116723658692;6313.526485513605;5845;5864.266966629171;5865.35481097079;5908.970455147697;5928.170056192136;5932.169496270526;6280.968595157086;6294.276158241769;6298.905516417258;6315.715792789336;6057.939420605826;6113.23584551931;6113.8165855023535;6184.845378865564;6201.81394558164;6250.406211409909;6302.716377763011;6302.960011598035;6306.400891915261;6307.590006649553;6194.5354098442795;6222.471089957314;6234.906476402767;6255.499560035186;6257.186565746424;6132.386102851751;6196;6296.307406185316;6296.779612713533;6318.652474113968;4977.1538838397555;4983.576396006308;5002.474740152305;5004.524570165829;5032.043911656773;4445.933311000273;4456.933146002748;4465.330200469955;4467.7319360838455;4496.3705081288645;5596.692181929951;5661.235733176051;5691.203231547586;5749.310082790008;5762.9135562966585;6202.255729312511;6244.250689917239;6271.4669253114;6290.339514350927;6301.590396624205;5761.164631128547;5776.971115144482;5777.017906955828;5780.6820624865195;5812.680302583314;5229.790808367623;5485.396606373296;5488.506034456918;5498.3127109111365;5506.284183056177;6227.782027629011;6241.282252541016;6270.592411493239;6303.716332765129;6335.3981371769605;6324;6333.145025421693;6336.778212762531;6342.23893132827;6353.459955903615;6260.655663938527;6264.185655864709;6344.270408903035;6349.4920406366455;6368.172137622079;6065.120557519223;6114.723190878713;6168.753249869955;6181.443670069715;6246.46905013079;6277.780277690259;6290.056491526308;6294.590851594632;6304.527160463075;6311;6113.29697084841;6118.449339559461;6126.571140020156;6130.601510901777;6136.969315153396;6019.969900150646;6099.634021958519;6105.389461053859;6124.755009799558;6125.357545073132;5000.849974500771;5965.642061476326;5967.9257733608365;6005.39946005396;6049;4924.65527413092;6131.0173697270475;6137;6189.195404597373;6189.195404597373;4896.632752543503;5812.796552120655;5879.353271140257;5928.110783382527;6027.457528822427 09:54:15 INFO - PROCESS | 1974 | |1;ext.html;501.33322680834493;511.0366958283635;520.4639221601751;521.2910441799153;522.7412430846731;30454.543181852263;32366;40603.390949135784;40677.38983915236;40699.38950915739;42731;42881;42943;42954.7852260739;43016.784916075376;9565.713028609136;9761.219102471821;9769.364991275579;9771.413715177077;9776.364536305118;8603.56982150893;8829.45484540206;11545.365004924768;11785.705357366092;11801.527938882435;11804.940975295112;11883;11892.702682432966;11904.40477976102;11944.820827687592;7751.147373788879;11666.125040621944;11741.882581174206;11751.001164900994;11754.823677644843;11335.319880807096;11613.6515904523;11642.010429113536;11881.88118118825;11910;39935.40096898549;40264.37620614159;40298.79850600728;40316.99207519806;40449;3403.2002479417315;3539.292141571696;3635.8545658173643;3713.257348530278;3875.786831724254;26844.194674159808;31665.8416707915;32407.51388729171;32494;32553.348933021207;3059.388122375534;3323.567936168307;3336.5495658086093;3360.630330663625;3363.2432702641954;515915;516243;516843;518302.4084879589;520707;48707;58083.8383232333;58408.70795646079;58409;58547;62127.06809397772;62209;62241.132766017094;62255.443613910014;62470.68764656148;15815.051096934221;16023.919880400523;16035.839641603434;16038.278277477542;16056.116913569633;11236.381998989971;11522.020628246526;12433.316167610687;12506.124571280105;12518.311492867979;15608.531744047697;16018.75971860423;16030.599235019217;16443.917780411022;16457.177141143133;5666.773329066791;7182.928170718226;7200.731769492593;7268.818279543054;7297.70809167638;938.5166639180827;1047.5652604169336;1078.9514471848786;1083.1876092930302;1123.2361993844102;29498.55752163719;29555.704442955295;30206;30266.697333026386;30344;871.5816408124136;1025.8974102589682;1036.409246729357;1069.1927594665976;1079.5951518180682;14788.408463661548;15323;15341.309641066178;15359.385624575116;15737.213139343216;668860;675201;675288;675944;703660;47057.76471117623;50437.495625044015;60780.696096519234;61917;62000.68999654973;1357.942716784073;1366.6515038665077;1408.0566020766112;1408.492942540705;1409.0559325252107;28926.421471570873;30346.937857175777;31049.844750777007;31813.8409307952;32066.19834504156;50818;51197;51242.48757512526;51320.97358052743;51353.229701555254;511259;512672;513271;513337;514837;38382.42426363551;38889.22221555497;39256.607433925295;39304.41043384409;39531.80234098811;46693.299600506696;47155.76422118005;47917.28124078072;48332.033359331916;48800.51199488102;34717.652823472454;37644.81177594094;38483;39452;40363.79818101008;38530.2293954125;39138.2172356557;39293.80353098331;40094.599054009086;41303.1739365205;37709.434358485196;38241.61758382492;38544.42183367196;38700;38708.225835483696;31793.5230971531;33008;34234.65765342315;34471;34508;25400;25497.745022549538;25669.871650641628;25677;26095.956161753318 09:54:15 INFO - PROCESS | 1974 | |2;jquery.html;351.56932757372203;367.91905780728246;372.184915036071;377.89796754876187;401.4339780908919;14797.704045919088;15380.538583842475;20033.398998030065;21150.68273975888;21384.89307553464;19380.70928936067;19395.321163759272;19929.701054484194;19938.302159424424;20118;5573.832785016456;5889.793857214999;5895.469407753301;5903.439173278532;5905.232319798421;4019.4573732546023;4146.854860079898;5794.82615521535;5901.291844978608;5905.143754155645;6620.867582648344;6633.701483433233;6636.1041259430085;6639.402453779159;6642.269350371455;6316.936830631704;6507.284198738183;6543.083968244449;6553.442957348631;6620.6358650274215;5467.152591348347;5688.488036076773;5691.943080569183;5696.6866822324755;5703.201551782754;19938.80061199384;21427.67858482124;21805.345839624835;21812.89093554522;25734.485310293876;2279.2022792022826;2644.8942042318286;2736.151792944194;3094.1181763197383;3209.133533945845;14723.263836808117;14961.476348327757;15479.613009674735;15516.922415387964;16237.512874613662;1750.9474715758547;1878.19237727777;1880.1351378365969;1894.1192345559382;1947.376839411389;107075.46462267639;109985;110091.44954275257;110092;110251;9159.267258619295;10442.843357349646;10626.73433164177;10947.34315941046;10973.067289280501;12009.459574319177;12685.365731713377;12685.746285074247;12832;12843.678908027186;7676.385889128856;7785.45761678435;7955.641996110189;8366.37252206087;8449.873251901226;6522.315156908457;8411.70559030431;8482.533460659723;8625.611847466878;8634.784130396665;6433.356664333623;6513.12072870166;6525.314841941527;6549.574277671936;6655.901776206969;1493.3951749541452;1678.8992660440413;1755.991220043917;1763.2241813601975;1777.3690339929396;653.859420224654;903.6656437118177;950.9429434233969;1017.255406977038;1050.5692666007053;21655.91720413973;22167.77832221657;22242.999065042113;22245.442819002514;22318;932.9580168892417;955.4983633592419;974.2692980264801;1021.5300961557766;1079.4548752879746;11114.49984750672;11404.201705880674;11654.825177622342;11680.357580333164;11704.356260405759;573725;582847;587855;604278.6790990693;605728;70354;86721;108407;115196;116566;960.9279304052224;969.9757506062405;981.7398389426808;990.7275499237767;994.1202036197873;15433.691326173639;19755.901220493804;20090.799092009478;20658.483537911674;20834.895825520776;49248;50385.992280154926;51398;51590.74204628953;52117.73941130422;48339.79150521265;51141.48858511367;68953.65523172352;69512.6524367375;71043.15827366848;5063.721495317793;5370.489803468664;7757.844843102994;7846.293833555007;8156.265936065783;13185.868141318464;13200.66998325011;13550.525731599346;13678;13710.65723356924;11166.274192177807;11256.887431125584;11710.648680539598;11731.35477548743;11772.352520611448;11298.039666628429;11339;11575;11759.88240117622;11875;9595.760105997128;11369.886301136883;11761.764764704829;11951.820722689341;11993;8000.51996880189;8117;8130.75607731769;8202.630881610223;8250.257476827115;11286.097112231166;11374.94312528432;11720.648380548597;11732.88267117352;11796.882031179579 09:54:15 INFO - PROCESS | 1974 | |3;mootools.html;1521.132954216097;1539.0073402655287;1759.9208035638394;1889.678754611716;1920.6350793349263;8241.423100382975;8824.029356770758;8827;8834.911650883505;8850.867236991451;7761.961190194055;7972.880406793903;7987.400944929124;8058.033036035669;8616.009158946723;2080.6046851098317;2112.8309735221146;2293.1056887813766;2367.1241640593003;2370.893309801063;2343.355577216271;2351.7530659280787;2352.5530149271585;2353.235198560466;2353.7293211280667;2280.5096904165603;2323.256557901472;2334.708161479815;2336.6261398176293;2340.0405833608224;2292.5644127615783;2299.6895419118455;2330.487292795582;2333.3933177374006;2336.6962294901723;2289.267434420986;2303.470201853583;2311.3759284992957;2332.9650055249185;2333.241696448661;8677.349198810114;8751.606177722018;8800.823983520359;8816.823663526759;8843.425177363451;5132.563732082777;5305.15117581189;5306.044911915854;5306.734663266821;5309.893802123975;3442.1050526862946;3579.0873327301633;3632.2735452909524;3644.7266455015715;3648.1791596890753;933.4679232837285;1035.0425856083093;1075.473018221066;1108.7505311304972;1110.7056629993058;11471;11501.769964600744;11510.539578416854;11533.192676512648;11537.423128843524;4375.146846364928;4633.096546173464;4739.834105806281;4748.406449193851;4759.23852183649;9827.950860245654;9852.458114803754;9853.359531630422;9855.26085543587;9862.063104005108;5288.867778305573;5296.549793267538;5297.3643162819935;5299.655522391032;5300.549453296436;4597.908041839144;4753.643476739258;4854.611631069506;4857.0528746894015;4868.853934381903;2929.8535073246253;3628.9274214515563;3720.4605332226674;3819.6371344722206;3834.9041273968373;999.6454803740827;1126.6620013995769;1130.197559732599;1138.1122724275078;1142.1148609827285;793.5873345860235;884.9336299777542;892.4823602310644;932.4917919733762;976.9071938165863;5837.503812176013;5857.4435428634215;6085.9695701521205;6114.174586430864;6322.4942004639515;922.9446233226028;941.9199368053655;963.2005435488528;966.7244835221928;988.3526290279734;3883.203943191639;3988.561258261589;4043.9797801010805;4104.897377565585;4123.278426275389;10296.433696146783;10762.56949722018;10784.78430431387;10805.83791243132;10807.567697292021;10748.731281718019;10750.677479675622;10750.73123171927;10761.461926903623;10789.352638841694;7740.651670674946;7996.320312773363;8148.736945773415;8172.223638754309;8187.877181842277;5933;6085.604435711664;6089.512838972783;6096.420840020369;6104.450599445893;9385.718428447157;9690.515474226402;9917.355371900803;9986.300958932805;10043.849342260019;9887.950560247153;10144.49277536135;10243;10252.538635761557;10316;6077.939220607737;7444.664990075351;7514.8872766909635;7555.471117021975;7603.581803000888;7636.465447418849;7786.922130778619;7838.216178382117;7899.131095579367;7944.324732397811;7407.925920740724;7421.220771818881;7448.553086814808;7480.139783924918;7485.326320631169;7286.817829554304;7306.707731690885;7323.7436689715605;7343.228960959236;7372.152202496782;6895.689693963684;6919.515633905578;6920.653967301715;6990.405815505535;6990.9300906990275;4128.938065929074;4720.787564559534;5011;5035.144025515598;5064.41759197697;4322.827086916488;4950.8267210647455;5037.269595908646;5135.537801597725;5240.764165612481 09:54:15 INFO - PROCESS | 1974 | |4;prototype.html;296.12937962390555;299.283216696013;309.9752019838413;313.8179855683704;314.7057501236344;4608.907821843561;4743.762811859407;4950.579200767935;5273.868153296165;5409.350877894648;4879.121758083544;4909.680870743399;5090.490950904908;5143.5053998810645;5236.036486317631;3406.99493649374;3862.9806850965847;3973.7019723520707;3990.5211374635082;4011.3982902564558;3264.314493956272;3967.0875698589343;3978.3037968355566;3990.700697447703;4036.919261614766;3385.7472735088054;3541.680199469352;3840.635139661728;3931.3906344516645;3946.289667859784;3540.7344449166285;3714.962850371489;3934.4098385242155;3935.291647503448;3940.014996250937;2858.5283428234316;3429.159855835336;3429.382711111999;3441.4665726812386;3445.586529616462;4311.762853043081;4414.2037847451165;4790.952090479085;5028.270900719375;5040.218766091261;4167.2081005167765;4490.326451032339;4493.7977790999485;4536.140182525181;4563.589276965055;5360.91958620621;5400.567954563626;5401.351837779453;5420.539254163401;5435.510804027617;3178.7457003439667;3179.8410079495934;3810.8285127169343;3825.603654666055;3864.632859878307;4675.859724208279;4956.553910148068;5520.850498604317;5692.459216374438;5720.456556627114;3215.887443939451;3826.4069069294305;3851.2105018471148;3862.362710152793;3885.805709714503;3910.8240129194255;3937.4881265435884;3938.3107956107565;3963.5426035857377;3985.3822657488136;3905.33609286422;4164.479964812822;4484.085344526085;4507.752073635981;4567.383403240587;4629.791659375336;5202.401723801736;5415.185912719283;5516.069375718546;5558.720924420968;1120.8038593246313;1296.8378952630922;1334.3661760663801;1334.439535395117;1343.7782765843722;740.3484933258806;769.6959700918106;798.4091772088613;814.226484839405;837.7151768398652;4644.907101857944;4648.604868586208;5010.824621138243;5023.34696489454;5025.195968645073;4659.5573420525;5539.529140023144;5558.305211848519;5566.610337276352;5573.136163894603;4446.9555304447185;4798.136335459651;4984.837660553479;4998.825041123543;5018.397792264952;2936.3540021195304;2961.452131355663;2985.7163569460868;2987.4473222453917;3003.9098827034786;5092.949070509321;5901.642622196919;5904.852378690567;5911.379305172981;5914.349421563625;5123.513266239701;5418.349798024262;5565.568164231193;5776.884462310814;5802.361740208574;5138.304359561335;5782.674961896926;5787.4857611063435;5808.767649293981;5831.4168583141345;4639.396878405786;5217.895642087061;5619.381867994435;5660.660360378391;5711.029125048834;4149.813258403318;4936.481669424802;4970.65205435616;5637.774489020393;5681.715914204356;4977.735130515679;5919.6744179070565;5930.886284700567;5949.434803693643;5970.283565972111;3682.9079273018388;3924.7448915820382;3927.273454410943;3962.96962789671;3965.1276719121747;5545.584081194007;5550.611457197957;5566.832995010156;5567.387587365306;5580.5535557156145;4669.509701481432;5080.923786143137;5398.460153984727;5404.648697834628;5437.265969094122;4257.467816522934;5506;5548.250986116823;5564.275074726847;5569.554435645057;4927.384076990376;5263.6578622389425;5403.756830942539;5425.267588875451;5436.7651247507665;5067.87330316745;5234.450382709913;5399.595030372816;5499.1476321170285;5536.474034966672;5344.953376575315;5382.192671099367;5385.515303622693;5390.975714614211;5419 09:54:15 INFO - PROCESS | 1974 | |5;yui.html;662.7514681994252;717.5120917775913;722.4942540221842;774.945753797234;830.1532436914349;14807.555773326803;16262.837371626296;16286.755698664529;16289.83710162901;16334.673306533878;13925.860741392584;13960.092593981384;14034.508792192275;14057.437702491914;14178.432862685506;699.7306037175673;715.5027256057042;828.204923273658;847.1867007672641;871.2724874729577;853.6798700487317;859.7893516088567;860.6256278518833;863.447393668053;864.6973559254274;906.5286051253345;908.9909100909006;910.5947853205327;911.0570559470916;911.7401540560942;899.5007770687299;901.3059943843269;901.6573701993266;902.1024081039375;902.6975963052386;546.1916363781626;600.1358044416027;633.262249479356;658.0556900847299;678.1692426777158;12535.746425357482;14288;14641.48754793577;14645.267736613127;14658.120512769268;13412;13833.861661383458;13957.930210348883;14028.368723407471;14267.85732142686;12390.155984725154;12832.294223817686;12860;12928.159669621444;13303.40134693941;3806.638369354907;3984.521857377133;4564.475085365192;4604.7927843247135;4710.576048155683;106410.46794765977;106440;106649;110241;114795;10883;10966.890331096747;11003.33979961205;11157.665270041749;11389.715257118472;21744.673829892563;21750.34748957534;21775.782242177374;21781.346559603237;21792.564148716938;7710.864947945928;7743.264389882952;7785.610719464004;7792.220777922163;7858.17489163641;4282.379055037002;4340.544242854518;4351;4404.405405270248;4516.074204788077;3181.5227715842348;3277.4264503711756;3280.3111346617;3284.0968733597974;3284.802911825298;636.2238069555135;638.5211091681239;650.2262307853733;660.7191943424026;664.5414663881907;517.9766910488962;539.700466241232;555.2365497441018;586.0008685191738;586.6743957103763;8740;8816.603252853636;8985.595648195847;9000.774980625536;9015;604.1994357476361;628.5317438508249;630.8549033722178;634.2241723175091;635.4693830651457;3761.2665530221893;4282.865075397991;4494.16857881293;4543.886402839956;4595.333676616872;160340;163017;167498.32501675072;173007;174031.38952915717;142593;143957;144405;146650;149937.50312484332;112686.87313126764;113281.43359283151;117325.02737267257;120833;123505.38247308886;7178.497505174797;8326.750197494084;8382.916170838458;8504.787380315542;8664.610092545976;53126.20310695266;58241;59034.36769109307;60939.39060609515;64431;84906;87746.56126719326;88059;88526;88768.5561572188;13391.397387117408;13419.396127174496;13441.865581344062;13452.058355915384;13755.518556850462;12835.486580536674;15150.611096194092;15214.771778423112;15370.154641494824;15876.68246635084;13411.308375337028;14040.368183431974;14192.787108193592;14728.705425891636;14871;14180.29098545048;14206.289685515476;14218.573442796733;14224.644383890485;14613.561593152222;13699.809931099977;13777.655558610713;13881.033983153196;13940.651483712989;14622.122672639676;10346.534405951901;10641.308314959502;11108.111351091731;11461.770764584495;11641.825372619249;11503.424828758361;11592.188546801643;11593.594224202112;11750.823737644114;11768.411579420823 09:54:15 INFO - PROCESS | 1974 | __end_tp_report 09:54:15 INFO - PROCESS | 1974 | __start_cc_report 09:54:15 INFO - PROCESS | 1974 | _x_x_mozilla_cycle_collect,1365 09:54:15 INFO - PROCESS | 1974 | __end_cc_report 09:54:15 INFO - PROCESS | 1974 | __startTimestamp1470329655399__endTimestamp 09:54:15 INFO - PROCESS | 1974 | 09:54:15 INFO - PROCESS | 1974 | ------- Summary: start ------- 09:54:15 INFO - PROCESS | 1974 | Number of tests: 6 09:54:15 INFO - PROCESS | 1974 | 09:54:15 INFO - PROCESS | 1974 | [#0] dojo.html Cycles:165 Average:5758.85 Median:5965.64 stddev:644.18 (10.8%) stddev-sans-first:618.28 09:54:15 INFO - PROCESS | 1974 | Values: 3369.0 3399.1 3400.1 3417.1 3454.3 5612.6 5687.4 5715.3 5780.8 5818.1 5724.5 5760.7 5773.7 5774.4 5777.5 5199.8 5231.5 5265.4 5269.2 5327.2 5253.3 5281.0 5304.8 5307.5 5329.1 5244.2 5262.8 5281.6 5290.0 5319.0 4404.5 5289.0 5339.4 5346.5 5383.6 5144.9 5146.2 5201.9 5279.9 5285.3 5619.6 5691.0 5695.9 5714.5 5737.4 6241.7 6308.1 6320.7 6322.5 6332.7 6194.4 6248.5 6293.9 6309.1 6313.5 5845.0 5864.3 5865.4 5909.0 5928.2 5932.2 6281.0 6294.3 6298.9 6315.7 6057.9 6113.2 6113.8 6184.8 6201.8 6250.4 6302.7 6303.0 6306.4 6307.6 6194.5 6222.5 6234.9 6255.5 6257.2 6132.4 6196.0 6296.3 6296.8 6318.7 4977.2 4983.6 5002.5 5004.5 5032.0 4445.9 4456.9 4465.3 4467.7 4496.4 5596.7 5661.2 5691.2 5749.3 5762.9 6202.3 6244.3 6271.5 6290.3 6301.6 5761.2 5777.0 5777.0 5780.7 5812.7 5229.8 5485.4 5488.5 5498.3 5506.3 6227.8 6241.3 6270.6 6303.7 6335.4 6324.0 6333.1 6336.8 6342.2 6353.5 6260.7 6264.2 6344.3 6349.5 6368.2 6065.1 6114.7 6168.8 6181.4 6246.5 6277.8 6290.1 6294.6 6304.5 6311.0 6113.3 6118.4 6126.6 6130.6 6137.0 6020.0 6099.6 6105.4 6124.8 6125.4 5000.8 5965.6 5967.9 6005.4 6049.0 4924.7 6131.0 6137.0 6189.2 6189.2 4896.6 5812.8 5879.4 5928.1 6027.5 09:54:15 INFO - PROCESS | 1974 | 09:54:15 INFO - PROCESS | 1974 | [#1] ext.html Cycles:180 Average:70645.49 Median:38432.71 stddev:153728.52 (400.0%) stddev-sans-first:154069.56 09:54:15 INFO - PROCESS | 1974 | Values: 501.3 511.0 520.5 521.3 522.7 30454.5 32366.0 40603.4 40677.4 40699.4 42731.0 42881.0 42943.0 42954.8 43016.8 9565.7 9761.2 9769.4 9771.4 9776.4 8603.6 8829.5 11545.4 11785.7 11801.5 11804.9 11883.0 11892.7 11904.4 11944.8 7751.1 11666.1 11741.9 11751.0 11754.8 11335.3 11613.7 11642.0 11881.9 11910.0 39935.4 40264.4 40298.8 40317.0 40449.0 3403.2 3539.3 3635.9 3713.3 3875.8 26844.2 31665.8 32407.5 32494.0 32553.3 3059.4 3323.6 3336.5 3360.6 3363.2 515915.0 516243.0 516843.0 518302.4 520707.0 48707.0 58083.8 58408.7 58409.0 58547.0 62127.1 62209.0 62241.1 62255.4 62470.7 15815.1 16023.9 16035.8 16038.3 16056.1 11236.4 11522.0 12433.3 12506.1 12518.3 15608.5 16018.8 16030.6 16443.9 16457.2 5666.8 7182.9 7200.7 7268.8 7297.7 938.5 1047.6 1079.0 1083.2 1123.2 29498.6 29555.7 30206.0 30266.7 30344.0 871.6 1025.9 1036.4 1069.2 1079.6 14788.4 15323.0 15341.3 15359.4 15737.2 668860.0 675201.0 675288.0 675944.0 703660.0 47057.8 50437.5 60780.7 61917.0 62000.7 1357.9 1366.7 1408.1 1408.5 1409.1 28926.4 30346.9 31049.8 31813.8 32066.2 50818.0 51197.0 51242.5 51321.0 51353.2 511259.0 512672.0 513271.0 513337.0 514837.0 38382.4 38889.2 39256.6 39304.4 39531.8 46693.3 47155.8 47917.3 48332.0 48800.5 34717.7 37644.8 38483.0 39452.0 40363.8 38530.2 39138.2 39293.8 40094.6 41303.2 37709.4 38241.6 38544.4 38700.0 38708.2 31793.5 33008.0 34234.7 34471.0 34508.0 25400.0 25497.7 25669.9 25677.0 26096.0 09:54:15 INFO - PROCESS | 1974 | 09:54:15 INFO - PROCESS | 1974 | [#2] jquery.html Cycles:180 Average:33361.58 Median:14189.69 stddev:97724.80 (688.7%) stddev-sans-first:97967.51 09:54:15 INFO - PROCESS | 1974 | Values: 351.6 367.9 372.2 377.9 401.4 14797.7 15380.5 20033.4 21150.7 21384.9 19380.7 19395.3 19929.7 19938.3 20118.0 5573.8 5889.8 5895.5 5903.4 5905.2 4019.5 4146.9 5794.8 5901.3 5905.1 6620.9 6633.7 6636.1 6639.4 6642.3 6316.9 6507.3 6543.1 6553.4 6620.6 5467.2 5688.5 5691.9 5696.7 5703.2 19938.8 21427.7 21805.3 21812.9 25734.5 2279.2 2644.9 2736.2 3094.1 3209.1 14723.3 14961.5 15479.6 15516.9 16237.5 1750.9 1878.2 1880.1 1894.1 1947.4 107075.5 109985.0 110091.4 110092.0 110251.0 9159.3 10442.8 10626.7 10947.3 10973.1 12009.5 12685.4 12685.7 12832.0 12843.7 7676.4 7785.5 7955.6 8366.4 8449.9 6522.3 8411.7 8482.5 8625.6 8634.8 6433.4 6513.1 6525.3 6549.6 6655.9 1493.4 1678.9 1756.0 1763.2 1777.4 653.9 903.7 950.9 1017.3 1050.6 21655.9 22167.8 22243.0 22245.4 22318.0 933.0 955.5 974.3 1021.5 1079.5 11114.5 11404.2 11654.8 11680.4 11704.4 573725.0 582847.0 587855.0 604278.7 605728.0 70354.0 86721.0 108407.0 115196.0 116566.0 960.9 970.0 981.7 990.7 994.1 15433.7 19755.9 20090.8 20658.5 20834.9 49248.0 50386.0 51398.0 51590.7 52117.7 48339.8 51141.5 68953.7 69512.7 71043.2 5063.7 5370.5 7757.8 7846.3 8156.3 13185.9 13200.7 13550.5 13678.0 13710.7 11166.3 11256.9 11710.6 11731.4 11772.4 11298.0 11339.0 11575.0 11759.9 11875.0 9595.8 11369.9 11761.8 11951.8 11993.0 8000.5 8117.0 8130.8 8202.6 8250.3 11286.1 11374.9 11720.6 11732.9 11796.9 09:54:15 INFO - PROCESS | 1974 | 09:54:15 INFO - PROCESS | 1974 | [#3] mootools.html Cycles:180 Average:5653.43 Median:5098.49 stddev:3172.92 (62.2%) stddev-sans-first:3166.62 09:54:15 INFO - PROCESS | 1974 | Values: 1521.1 1539.0 1759.9 1889.7 1920.6 8241.4 8824.0 8827.0 8834.9 8850.9 7762.0 7972.9 7987.4 8058.0 8616.0 2080.6 2112.8 2293.1 2367.1 2370.9 2343.4 2351.8 2352.6 2353.2 2353.7 2280.5 2323.3 2334.7 2336.6 2340.0 2292.6 2299.7 2330.5 2333.4 2336.7 2289.3 2303.5 2311.4 2333.0 2333.2 8677.3 8751.6 8800.8 8816.8 8843.4 5132.6 5305.2 5306.0 5306.7 5309.9 3442.1 3579.1 3632.3 3644.7 3648.2 933.5 1035.0 1075.5 1108.8 1110.7 11471.0 11501.8 11510.5 11533.2 11537.4 4375.1 4633.1 4739.8 4748.4 4759.2 9828.0 9852.5 9853.4 9855.3 9862.1 5288.9 5296.5 5297.4 5299.7 5300.5 4597.9 4753.6 4854.6 4857.1 4868.9 2929.9 3628.9 3720.5 3819.6 3834.9 999.6 1126.7 1130.2 1138.1 1142.1 793.6 884.9 892.5 932.5 976.9 5837.5 5857.4 6086.0 6114.2 6322.5 922.9 941.9 963.2 966.7 988.4 3883.2 3988.6 4044.0 4104.9 4123.3 10296.4 10762.6 10784.8 10805.8 10807.6 10748.7 10750.7 10750.7 10761.5 10789.4 7740.7 7996.3 8148.7 8172.2 8187.9 5933.0 6085.6 6089.5 6096.4 6104.5 9385.7 9690.5 9917.4 9986.3 10043.8 9888.0 10144.5 10243.0 10252.5 10316.0 6077.9 7444.7 7514.9 7555.5 7603.6 7636.5 7786.9 7838.2 7899.1 7944.3 7407.9 7421.2 7448.6 7480.1 7485.3 7286.8 7306.7 7323.7 7343.2 7372.2 6895.7 6919.5 6920.7 6990.4 6990.9 4128.9 4720.8 5011.0 5035.1 5064.4 4322.8 4950.8 5037.3 5135.5 5240.8 09:54:15 INFO - PROCESS | 1974 | 09:54:15 INFO - PROCESS | 1974 | [#4] prototype.html Cycles:180 Average:4413.95 Median:4953.57 stddev:1362.21 (27.5%) stddev-sans-first:1330.51 09:54:15 INFO - PROCESS | 1974 | Values: 296.1 299.3 310.0 313.8 314.7 4608.9 4743.8 4950.6 5273.9 5409.4 4879.1 4909.7 5090.5 5143.5 5236.0 3407.0 3863.0 3973.7 3990.5 4011.4 3264.3 3967.1 3978.3 3990.7 4036.9 3385.7 3541.7 3840.6 3931.4 3946.3 3540.7 3715.0 3934.4 3935.3 3940.0 2858.5 3429.2 3429.4 3441.5 3445.6 4311.8 4414.2 4791.0 5028.3 5040.2 4167.2 4490.3 4493.8 4536.1 4563.6 5360.9 5400.6 5401.4 5420.5 5435.5 3178.7 3179.8 3810.8 3825.6 3864.6 4675.9 4956.6 5520.9 5692.5 5720.5 3215.9 3826.4 3851.2 3862.4 3885.8 3910.8 3937.5 3938.3 3963.5 3985.4 3905.3 4164.5 4484.1 4507.8 4567.4 4629.8 5202.4 5415.2 5516.1 5558.7 1120.8 1296.8 1334.4 1334.4 1343.8 740.3 769.7 798.4 814.2 837.7 4644.9 4648.6 5010.8 5023.3 5025.2 4659.6 5539.5 5558.3 5566.6 5573.1 4447.0 4798.1 4984.8 4998.8 5018.4 2936.4 2961.5 2985.7 2987.4 3003.9 5092.9 5901.6 5904.9 5911.4 5914.3 5123.5 5418.3 5565.6 5776.9 5802.4 5138.3 5782.7 5787.5 5808.8 5831.4 4639.4 5217.9 5619.4 5660.7 5711.0 4149.8 4936.5 4970.7 5637.8 5681.7 4977.7 5919.7 5930.9 5949.4 5970.3 3682.9 3924.7 3927.3 3963.0 3965.1 5545.6 5550.6 5566.8 5567.4 5580.6 4669.5 5080.9 5398.5 5404.6 5437.3 4257.5 5506.0 5548.3 5564.3 5569.6 4927.4 5263.7 5403.8 5425.3 5436.8 5067.9 5234.5 5399.6 5499.1 5536.5 5345.0 5382.2 5385.5 5391.0 5419.0 09:54:15 INFO - PROCESS | 1974 | 09:54:15 INFO - PROCESS | 1974 | [#5] yui.html Cycles:180 Average:25875.79 Median:3229.47 stddev:42686.62 (1321.8%) stddev-sans-first:42764.39 09:54:15 INFO - PROCESS | 1974 | Values: 662.8 717.5 722.5 774.9 830.2 14807.6 16262.8 16286.8 16289.8 16334.7 13925.9 13960.1 14034.5 14057.4 14178.4 699.7 715.5 828.2 847.2 871.3 853.7 859.8 860.6 863.4 864.7 906.5 909.0 910.6 911.1 911.7 899.5 901.3 901.7 902.1 902.7 546.2 600.1 633.3 658.1 678.2 12535.7 14288.0 14641.5 14645.3 14658.1 13412.0 13833.9 13957.9 14028.4 14267.9 12390.2 12832.3 12860.0 12928.2 13303.4 3806.6 3984.5 4564.5 4604.8 4710.6 106410.5 106440.0 106649.0 110241.0 114795.0 10883.0 10966.9 11003.3 11157.7 11389.7 21744.7 21750.3 21775.8 21781.3 21792.6 7710.9 7743.3 7785.6 7792.2 7858.2 4282.4 4340.5 4351.0 4404.4 4516.1 3181.5 3277.4 3280.3 3284.1 3284.8 636.2 638.5 650.2 660.7 664.5 518.0 539.7 555.2 586.0 586.7 8740.0 8816.6 8985.6 9000.8 9015.0 604.2 628.5 630.9 634.2 635.5 3761.3 4282.9 4494.2 4543.9 4595.3 160340.0 163017.0 167498.3 173007.0 174031.4 142593.0 143957.0 144405.0 146650.0 149937.5 112686.9 113281.4 117325.0 120833.0 123505.4 7178.5 8326.8 8382.9 8504.8 8664.6 53126.2 58241.0 59034.4 60939.4 64431.0 84906.0 87746.6 88059.0 88526.0 88768.6 13391.4 13419.4 13441.9 13452.1 13755.5 12835.5 15150.6 15214.8 15370.2 15876.7 13411.3 14040.4 14192.8 14728.7 14871.0 14180.3 14206.3 14218.6 14224.6 14613.6 13699.8 13777.7 13881.0 13940.7 14622.1 10346.5 10641.3 11108.1 11461.8 11641.8 11503.4 11592.2 11593.6 11750.8 11768.4 09:54:15 INFO - PROCESS | 1974 | -------- Summary: end -------- 09:54:15 INFO - PROCESS | 1974 | 09:54:16 INFO - TEST-INFO | 1974: exit 0 09:54:16 INFO - TEST-OK | dromaeo_css | took 1117750ms 09:54:16 INFO - TEST-START | kraken 09:54:16 INFO - operating with platform_type : mac_ 09:54:16 INFO - Initialising browser for kraken test... 09:54:16 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpPl9fr8/profile http://localhost:54458/getInfo.html) 09:54:18 INFO - PROCESS | 1977 | __metrics Screen width/height:1600/1200 09:54:18 INFO - PROCESS | 1977 | colorDepth:24 09:54:18 INFO - PROCESS | 1977 | Browser inner width/height: 1024/689 09:54:18 INFO - PROCESS | 1977 | __metrics 09:54:18 INFO - PROCESS | 1977 | 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" 09:54:18 INFO - PROCESS | 1977 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:54:19 INFO - TEST-INFO | 1977: exit 0 09:54:19 INFO - Browser initialized. 09:54:19 INFO - Running cycle 1/1 for kraken test... 09:54:19 INFO - TEST-INFO | started process 1978 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpPl9fr8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:54:31 INFO - PROCESS | 1978 | RSS: Main: 243154944 09:54:31 INFO - PROCESS | 1978 | 09:54:33 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54458/tests/kraken/driver.html?testName=audio-beat-detection) 09:54:34 INFO - PROCESS | 1978 | RSS: Main: 294379520 09:54:34 INFO - PROCESS | 1978 | 09:54:37 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54458/tests/kraken/driver.html?testName=audio-dft) 09:54:38 INFO - PROCESS | 1978 | RSS: Main: 305459200 09:54:38 INFO - PROCESS | 1978 | 09:54:41 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54458/tests/kraken/driver.html?testName=audio-fft) 09:54:42 INFO - PROCESS | 1978 | RSS: Main: 395264000 09:54:42 INFO - PROCESS | 1978 | 09:54:44 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54458/tests/kraken/driver.html?testName=audio-oscillator) 09:54:44 INFO - PROCESS | 1978 | RSS: Main: 297066496 09:54:44 INFO - PROCESS | 1978 | 09:54:46 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54458/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:54:47 INFO - PROCESS | 1978 | RSS: Main: 301506560 09:54:47 INFO - PROCESS | 1978 | 09:54:50 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54458/tests/kraken/driver.html?testName=imaging-darkroom) 09:54:50 INFO - PROCESS | 1978 | RSS: Main: 339357696 09:54:50 INFO - PROCESS | 1978 | 09:54:53 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54458/tests/kraken/driver.html?testName=imaging-desaturate) 09:54:54 INFO - PROCESS | 1978 | RSS: Main: 339709952 09:54:54 INFO - PROCESS | 1978 | 09:54:57 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54458/tests/kraken/driver.html?testName=json-parse-financial) 09:54:57 INFO - PROCESS | 1978 | RSS: Main: 338800640 09:54:57 INFO - PROCESS | 1978 | 09:54:59 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54458/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:55:00 INFO - PROCESS | 1978 | RSS: Main: 313995264 09:55:00 INFO - PROCESS | 1978 | 09:55:01 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54458/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:55:02 INFO - PROCESS | 1978 | RSS: Main: 318603264 09:55:02 INFO - PROCESS | 1978 | 09:55:04 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54458/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:55:05 INFO - PROCESS | 1978 | RSS: Main: 320057344 09:55:05 INFO - PROCESS | 1978 | 09:55:08 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54458/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:55:09 INFO - PROCESS | 1978 | RSS: Main: 328495104 09:55:09 INFO - PROCESS | 1978 | 09:55:11 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54458/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:55:12 INFO - PROCESS | 1978 | RSS: Main: 329756672 09:55:12 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:54458/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54458/tests/kraken/driver.html?testName=ai-astar) 09:55:14 INFO - PROCESS | 1978 | RSS: Main: 373628928 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | __start_tp_report 09:55:14 INFO - PROCESS | 1978 | _x_x_mozilla_page_load 09:55:14 INFO - PROCESS | 1978 | _x_x_mozilla_page_load_details 09:55:14 INFO - PROCESS | 1978 | |i|pagename|runs| 09:55:14 INFO - PROCESS | 1978 | |0;ai-astar;99;112;111;110;111;110;111;111;110;112 09:55:14 INFO - PROCESS | 1978 | |1;audio-beat-detection;143;107;106;173;135;138;112;145;145;106 09:55:14 INFO - PROCESS | 1978 | |2;audio-dft;183;183;185;187;182;185;185;193;221;184 09:55:14 INFO - PROCESS | 1978 | |3;audio-fft;82;58;58;106;57;57;63;64;82;57 09:55:14 INFO - PROCESS | 1978 | |4;audio-oscillator;87;68;92;73;81;67;97;69;68;99 09:55:14 INFO - PROCESS | 1978 | |5;imaging-gaussian-blur;88;86;90;86;88;85;91;86;88;115 09:55:14 INFO - PROCESS | 1978 | |6;imaging-darkroom;94;94;94;94;94;94;93;94;94;101 09:55:14 INFO - PROCESS | 1978 | |7;imaging-desaturate;87;81;86;86;83;84;85;85;86;85 09:55:14 INFO - PROCESS | 1978 | |8;json-parse-financial;54;52;54;53;53;52;54;54;52;52 09:55:14 INFO - PROCESS | 1978 | |9;json-stringify-tinderbox;69;66;79;76;75;75;70;71;71;72 09:55:14 INFO - PROCESS | 1978 | |10;stanford-crypto-aes;85;84;85;83;81;82;82;86;86;81 09:55:14 INFO - PROCESS | 1978 | |11;stanford-crypto-ccm;140;140;138;141;144;146;127;144;161;137 09:55:14 INFO - PROCESS | 1978 | |12;stanford-crypto-pbkdf2;179;203;165;165;166;164;165;165;164;171 09:55:14 INFO - PROCESS | 1978 | |13;stanford-crypto-sha256-iterative;68;68;68;70;64;87;66;59;57;86 09:55:14 INFO - PROCESS | 1978 | __end_tp_report 09:55:14 INFO - PROCESS | 1978 | __start_cc_report 09:55:14 INFO - PROCESS | 1978 | _x_x_mozilla_cycle_collect,2030 09:55:14 INFO - PROCESS | 1978 | __end_cc_report 09:55:14 INFO - PROCESS | 1978 | __startTimestamp1470329714567__endTimestamp 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | ------- Summary: start ------- 09:55:14 INFO - PROCESS | 1978 | Number of tests: 14 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.83 (3.5%) stddev-sans-first:0.78 09:55:14 INFO - PROCESS | 1978 | Values: 99.0 112.0 111.0 110.0 111.0 110.0 111.0 111.0 110.0 112.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#1] audio-beat-detection Cycles:10 Average:131.00 Median:140.50 stddev:22.49 (16.0%) stddev-sans-first:23.43 09:55:14 INFO - PROCESS | 1978 | Values: 143.0 107.0 106.0 173.0 135.0 138.0 112.0 145.0 145.0 106.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#2] audio-dft Cycles:10 Average:188.80 Median:185.00 stddev:11.73 (6.3%) stddev-sans-first:12.25 09:55:14 INFO - PROCESS | 1978 | Values: 183.0 183.0 185.0 187.0 182.0 185.0 185.0 193.0 221.0 184.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#3] audio-fft Cycles:10 Average:68.40 Median:60.50 stddev:16.46 (27.2%) stddev-sans-first:16.71 09:55:14 INFO - PROCESS | 1978 | Values: 82.0 58.0 58.0 106.0 57.0 57.0 63.0 64.0 82.0 57.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:84.00 stddev:12.78 (15.2%) stddev-sans-first:13.31 09:55:14 INFO - PROCESS | 1978 | Values: 87.0 68.0 92.0 73.0 81.0 67.0 97.0 69.0 68.0 99.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:88.00 stddev:8.88 (10.1%) stddev-sans-first:9.38 09:55:14 INFO - PROCESS | 1978 | Values: 88.0 86.0 90.0 86.0 88.0 85.0 91.0 86.0 88.0 115.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:94.00 stddev:2.27 (2.4%) stddev-sans-first:2.40 09:55:14 INFO - PROCESS | 1978 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 101.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.75 (2.0%) stddev-sans-first:1.67 09:55:14 INFO - PROCESS | 1978 | Values: 87.0 81.0 86.0 86.0 83.0 84.0 85.0 85.0 86.0 85.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.50 stddev:0.94 (1.8%) stddev-sans-first:0.93 09:55:14 INFO - PROCESS | 1978 | Values: 54.0 52.0 54.0 53.0 53.0 52.0 54.0 54.0 52.0 52.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:73.50 stddev:3.84 (5.2%) stddev-sans-first:3.87 09:55:14 INFO - PROCESS | 1978 | Values: 69.0 66.0 79.0 76.0 75.0 75.0 70.0 71.0 71.0 72.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.50 stddev:1.96 (2.3%) stddev-sans-first:2.00 09:55:14 INFO - PROCESS | 1978 | Values: 85.0 84.0 85.0 83.0 81.0 82.0 82.0 86.0 86.0 81.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#11] stanford-crypto-ccm Cycles:10 Average:141.80 Median:142.50 stddev:8.56 (6.0%) stddev-sans-first:9.06 09:55:14 INFO - PROCESS | 1978 | Values: 140.0 140.0 138.0 141.0 144.0 146.0 127.0 144.0 161.0 137.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:165.50 stddev:12.27 (7.4%) stddev-sans-first:12.64 09:55:14 INFO - PROCESS | 1978 | Values: 179.0 203.0 165.0 165.0 166.0 164.0 165.0 165.0 164.0 171.0 09:55:14 INFO - PROCESS | 1978 | 09:55:14 INFO - PROCESS | 1978 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:68.00 stddev:9.97 (14.7%) stddev-sans-first:10.56 09:55:14 INFO - PROCESS | 1978 | Values: 68.0 68.0 68.0 70.0 64.0 87.0 66.0 59.0 57.0 86.0 09:55:14 INFO - PROCESS | 1978 | -------- Summary: end -------- 09:55:14 INFO - PROCESS | 1978 | 09:55:15 INFO - TEST-INFO | 1978: exit 0 09:55:15 INFO - TEST-OK | kraken | took 59422ms 09:55:15 INFO - SUITE-END | took 1177s 09:55:15 INFO - Completed test suite (00:19:37) 09:55:15 INFO - javascript benchmark 09:55:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3369.0, 3399.1332210286387, 3400.09897377195, 3417.128632198789, 3454.309138172365, 5612.579056570763, 5687.402822703619, 5715.257016587843, 5780.797672081478, 5818.06910894257, 5724.4561766632105, 5760.7407666655, 5773.740181691824, 5774.364819869811, 5777.480026797587, 5199.818006369768, 5231.476852314776, 5265.447128051557, 5269.156934890419, 5327.200919862033, 5253.264542963988, 5281.049411106, 5304.814331498398, 5307.548858347026, 5329.067413202693, 5244.239585260135, 5262.763175657085, 5281.63028587999, 5289.994900968823, 5318.973405132969, 4404.471463424377, 5288.973555132219, 5339.439358867341, 5346.492083252124, 5383.596230282745, 5144.897102057976, 5146.202338637517, 5201.947980520222, 5279.86800329991, 5285.286486324336, 5619.550435965113, 5690.971545142289, 5695.943040569583, 5714.542836573064, 5737.39757325479, 6241.687915604247, 6308.085327627514, 6320.652364119972, 6322.462590679797, 6332.651704156316, 6194.380561943814, 6248.468880145193, 6293.9370606293805, 6309.116723658692, 6313.526485513605, 5845.0, 5864.266966629171, 5865.35481097079, 5908.970455147697, 5928.170056192136, 5932.169496270526, 6280.968595157086, 6294.276158241769, 6298.905516417258, 6315.715792789336, 6057.939420605826, 6113.23584551931, 6113.8165855023535, 6184.845378865564, 6201.81394558164, 6250.406211409909, 6302.716377763011, 6302.960011598035, 6306.400891915261, 6307.590006649553, 6194.5354098442795, 6222.471089957314, 6234.906476402767, 6255.499560035186, 6257.186565746424, 6132.386102851751, 6196.0, 6296.307406185316, 6296.779612713533, 6318.652474113968, 4977.1538838397555, 4983.576396006308, 5002.474740152305, 5004.524570165829, 5032.043911656773, 4445.933311000273, 4456.933146002748, 4465.330200469955, 4467.7319360838455, 4496.3705081288645, 5596.692181929951, 5661.235733176051, 5691.203231547586, 5749.310082790008, 5762.9135562966585, 6202.255729312511, 6244.250689917239, 6271.4669253114, 6290.339514350927, 6301.590396624205, 5761.164631128547, 5776.971115144482, 5777.017906955828, 5780.6820624865195, 5812.680302583314, 5229.790808367623, 5485.396606373296, 5488.506034456918, 5498.3127109111365, 5506.284183056177, 6227.782027629011, 6241.282252541016, 6270.592411493239, 6303.716332765129, 6335.3981371769605, 6324.0, 6333.145025421693, 6336.778212762531, 6342.23893132827, 6353.459955903615, 6260.655663938527, 6264.185655864709, 6344.270408903035, 6349.4920406366455, 6368.172137622079, 6065.120557519223, 6114.723190878713, 6168.753249869955, 6181.443670069715, 6246.46905013079, 6277.780277690259, 6290.056491526308, 6294.590851594632, 6304.527160463075, 6311.0, 6113.29697084841, 6118.449339559461, 6126.571140020156, 6130.601510901777, 6136.969315153396, 6019.969900150646, 6099.634021958519, 6105.389461053859, 6124.755009799558, 6125.357545073132, 5000.849974500771, 5965.642061476326, 5967.9257733608365, 6005.39946005396, 6049.0, 4924.65527413092, 6131.0173697270475, 6137.0, 6189.195404597373, 6189.195404597373, 4896.632752543503, 5812.796552120655, 5879.353271140257, 5928.110783382527, 6027.457528822427], "value": 5718.819784338231, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.33322680834493, 511.0366958283635, 520.4639221601751, 521.2910441799153, 522.7412430846731, 30454.543181852263, 32366.0, 40603.390949135784, 40677.38983915236, 40699.38950915739, 42731.0, 42881.0, 42943.0, 42954.7852260739, 43016.784916075376, 9565.713028609136, 9761.219102471821, 9769.364991275579, 9771.413715177077, 9776.364536305118, 8603.56982150893, 8829.45484540206, 11545.365004924768, 11785.705357366092, 11801.527938882435, 11804.940975295112, 11883.0, 11892.702682432966, 11904.40477976102, 11944.820827687592, 7751.147373788879, 11666.125040621944, 11741.882581174206, 11751.001164900994, 11754.823677644843, 11335.319880807096, 11613.6515904523, 11642.010429113536, 11881.88118118825, 11910.0, 39935.40096898549, 40264.37620614159, 40298.79850600728, 40316.99207519806, 40449.0, 3403.2002479417315, 3539.292141571696, 3635.8545658173643, 3713.257348530278, 3875.786831724254, 26844.194674159808, 31665.8416707915, 32407.51388729171, 32494.0, 32553.348933021207, 3059.388122375534, 3323.567936168307, 3336.5495658086093, 3360.630330663625, 3363.2432702641954, 515915.0, 516243.0, 516843.0, 518302.4084879589, 520707.0, 48707.0, 58083.8383232333, 58408.70795646079, 58409.0, 58547.0, 62127.06809397772, 62209.0, 62241.132766017094, 62255.443613910014, 62470.68764656148, 15815.051096934221, 16023.919880400523, 16035.839641603434, 16038.278277477542, 16056.116913569633, 11236.381998989971, 11522.020628246526, 12433.316167610687, 12506.124571280105, 12518.311492867979, 15608.531744047697, 16018.75971860423, 16030.599235019217, 16443.917780411022, 16457.177141143133, 5666.773329066791, 7182.928170718226, 7200.731769492593, 7268.818279543054, 7297.70809167638, 938.5166639180827, 1047.5652604169336, 1078.9514471848786, 1083.1876092930302, 1123.2361993844102, 29498.55752163719, 29555.704442955295, 30206.0, 30266.697333026386, 30344.0, 871.5816408124136, 1025.8974102589682, 1036.409246729357, 1069.1927594665976, 1079.5951518180682, 14788.408463661548, 15323.0, 15341.309641066178, 15359.385624575116, 15737.213139343216, 668860.0, 675201.0, 675288.0, 675944.0, 703660.0, 47057.76471117623, 50437.495625044015, 60780.696096519234, 61917.0, 62000.68999654973, 1357.942716784073, 1366.6515038665077, 1408.0566020766112, 1408.492942540705, 1409.0559325252107, 28926.421471570873, 30346.937857175777, 31049.844750777007, 31813.8409307952, 32066.19834504156, 50818.0, 51197.0, 51242.48757512526, 51320.97358052743, 51353.229701555254, 511259.0, 512672.0, 513271.0, 513337.0, 514837.0, 38382.42426363551, 38889.22221555497, 39256.607433925295, 39304.41043384409, 39531.80234098811, 46693.299600506696, 47155.76422118005, 47917.28124078072, 48332.033359331916, 48800.51199488102, 34717.652823472454, 37644.81177594094, 38483.0, 39452.0, 40363.79818101008, 38530.2293954125, 39138.2172356557, 39293.80353098331, 40094.599054009086, 41303.1739365205, 37709.434358485196, 38241.61758382492, 38544.42183367196, 38700.0, 38708.225835483696, 31793.5230971531, 33008.0, 34234.65765342315, 34471.0, 34508.0, 25400.0, 25497.745022549538, 25669.871650641628, 25677.0, 26095.956161753318], "value": 20432.281208875444, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.56932757372203, 367.91905780728246, 372.184915036071, 377.89796754876187, 401.4339780908919, 14797.704045919088, 15380.538583842475, 20033.398998030065, 21150.68273975888, 21384.89307553464, 19380.70928936067, 19395.321163759272, 19929.701054484194, 19938.302159424424, 20118.0, 5573.832785016456, 5889.793857214999, 5895.469407753301, 5903.439173278532, 5905.232319798421, 4019.4573732546023, 4146.854860079898, 5794.82615521535, 5901.291844978608, 5905.143754155645, 6620.867582648344, 6633.701483433233, 6636.1041259430085, 6639.402453779159, 6642.269350371455, 6316.936830631704, 6507.284198738183, 6543.083968244449, 6553.442957348631, 6620.6358650274215, 5467.152591348347, 5688.488036076773, 5691.943080569183, 5696.6866822324755, 5703.201551782754, 19938.80061199384, 21427.67858482124, 21805.345839624835, 21812.89093554522, 25734.485310293876, 2279.2022792022826, 2644.8942042318286, 2736.151792944194, 3094.1181763197383, 3209.133533945845, 14723.263836808117, 14961.476348327757, 15479.613009674735, 15516.922415387964, 16237.512874613662, 1750.9474715758547, 1878.19237727777, 1880.1351378365969, 1894.1192345559382, 1947.376839411389, 107075.46462267639, 109985.0, 110091.44954275257, 110092.0, 110251.0, 9159.267258619295, 10442.843357349646, 10626.73433164177, 10947.34315941046, 10973.067289280501, 12009.459574319177, 12685.365731713377, 12685.746285074247, 12832.0, 12843.678908027186, 7676.385889128856, 7785.45761678435, 7955.641996110189, 8366.37252206087, 8449.873251901226, 6522.315156908457, 8411.70559030431, 8482.533460659723, 8625.611847466878, 8634.784130396665, 6433.356664333623, 6513.12072870166, 6525.314841941527, 6549.574277671936, 6655.901776206969, 1493.3951749541452, 1678.8992660440413, 1755.991220043917, 1763.2241813601975, 1777.3690339929396, 653.859420224654, 903.6656437118177, 950.9429434233969, 1017.255406977038, 1050.5692666007053, 21655.91720413973, 22167.77832221657, 22242.999065042113, 22245.442819002514, 22318.0, 932.9580168892417, 955.4983633592419, 974.2692980264801, 1021.5300961557766, 1079.4548752879746, 11114.49984750672, 11404.201705880674, 11654.825177622342, 11680.357580333164, 11704.356260405759, 573725.0, 582847.0, 587855.0, 604278.6790990693, 605728.0, 70354.0, 86721.0, 108407.0, 115196.0, 116566.0, 960.9279304052224, 969.9757506062405, 981.7398389426808, 990.7275499237767, 994.1202036197873, 15433.691326173639, 19755.901220493804, 20090.799092009478, 20658.483537911674, 20834.895825520776, 49248.0, 50385.992280154926, 51398.0, 51590.74204628953, 52117.73941130422, 48339.79150521265, 51141.48858511367, 68953.65523172352, 69512.6524367375, 71043.15827366848, 5063.721495317793, 5370.489803468664, 7757.844843102994, 7846.293833555007, 8156.265936065783, 13185.868141318464, 13200.66998325011, 13550.525731599346, 13678.0, 13710.65723356924, 11166.274192177807, 11256.887431125584, 11710.648680539598, 11731.35477548743, 11772.352520611448, 11298.039666628429, 11339.0, 11575.0, 11759.88240117622, 11875.0, 9595.760105997128, 11369.886301136883, 11761.764764704829, 11951.820722689341, 11993.0, 8000.51996880189, 8117.0, 8130.75607731769, 8202.630881610223, 8250.257476827115, 11286.097112231166, 11374.94312528432, 11720.648380548597, 11732.88267117352, 11796.882031179579], "value": 9562.946376600532, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1521.132954216097, 1539.0073402655287, 1759.9208035638394, 1889.678754611716, 1920.6350793349263, 8241.423100382975, 8824.029356770758, 8827.0, 8834.911650883505, 8850.867236991451, 7761.961190194055, 7972.880406793903, 7987.400944929124, 8058.033036035669, 8616.009158946723, 2080.6046851098317, 2112.8309735221146, 2293.1056887813766, 2367.1241640593003, 2370.893309801063, 2343.355577216271, 2351.7530659280787, 2352.5530149271585, 2353.235198560466, 2353.7293211280667, 2280.5096904165603, 2323.256557901472, 2334.708161479815, 2336.6261398176293, 2340.0405833608224, 2292.5644127615783, 2299.6895419118455, 2330.487292795582, 2333.3933177374006, 2336.6962294901723, 2289.267434420986, 2303.470201853583, 2311.3759284992957, 2332.9650055249185, 2333.241696448661, 8677.349198810114, 8751.606177722018, 8800.823983520359, 8816.823663526759, 8843.425177363451, 5132.563732082777, 5305.15117581189, 5306.044911915854, 5306.734663266821, 5309.893802123975, 3442.1050526862946, 3579.0873327301633, 3632.2735452909524, 3644.7266455015715, 3648.1791596890753, 933.4679232837285, 1035.0425856083093, 1075.473018221066, 1108.7505311304972, 1110.7056629993058, 11471.0, 11501.769964600744, 11510.539578416854, 11533.192676512648, 11537.423128843524, 4375.146846364928, 4633.096546173464, 4739.834105806281, 4748.406449193851, 4759.23852183649, 9827.950860245654, 9852.458114803754, 9853.359531630422, 9855.26085543587, 9862.063104005108, 5288.867778305573, 5296.549793267538, 5297.3643162819935, 5299.655522391032, 5300.549453296436, 4597.908041839144, 4753.643476739258, 4854.611631069506, 4857.0528746894015, 4868.853934381903, 2929.8535073246253, 3628.9274214515563, 3720.4605332226674, 3819.6371344722206, 3834.9041273968373, 999.6454803740827, 1126.6620013995769, 1130.197559732599, 1138.1122724275078, 1142.1148609827285, 793.5873345860235, 884.9336299777542, 892.4823602310644, 932.4917919733762, 976.9071938165863, 5837.503812176013, 5857.4435428634215, 6085.9695701521205, 6114.174586430864, 6322.4942004639515, 922.9446233226028, 941.9199368053655, 963.2005435488528, 966.7244835221928, 988.3526290279734, 3883.203943191639, 3988.561258261589, 4043.9797801010805, 4104.897377565585, 4123.278426275389, 10296.433696146783, 10762.56949722018, 10784.78430431387, 10805.83791243132, 10807.567697292021, 10748.731281718019, 10750.677479675622, 10750.73123171927, 10761.461926903623, 10789.352638841694, 7740.651670674946, 7996.320312773363, 8148.736945773415, 8172.223638754309, 8187.877181842277, 5933.0, 6085.604435711664, 6089.512838972783, 6096.420840020369, 6104.450599445893, 9385.718428447157, 9690.515474226402, 9917.355371900803, 9986.300958932805, 10043.849342260019, 9887.950560247153, 10144.49277536135, 10243.0, 10252.538635761557, 10316.0, 6077.939220607737, 7444.664990075351, 7514.8872766909635, 7555.471117021975, 7603.581803000888, 7636.465447418849, 7786.922130778619, 7838.216178382117, 7899.131095579367, 7944.324732397811, 7407.925920740724, 7421.220771818881, 7448.553086814808, 7480.139783924918, 7485.326320631169, 7286.817829554304, 7306.707731690885, 7323.7436689715605, 7343.228960959236, 7372.152202496782, 6895.689693963684, 6919.515633905578, 6920.653967301715, 6990.405815505535, 6990.9300906990275, 4128.938065929074, 4720.787564559534, 5011.0, 5035.144025515598, 5064.41759197697, 4322.827086916488, 4950.8267210647455, 5037.269595908646, 5135.537801597725, 5240.764165612481], "value": 4537.346161181459, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.12937962390555, 299.283216696013, 309.9752019838413, 313.8179855683704, 314.7057501236344, 4608.907821843561, 4743.762811859407, 4950.579200767935, 5273.868153296165, 5409.350877894648, 4879.121758083544, 4909.680870743399, 5090.490950904908, 5143.5053998810645, 5236.036486317631, 3406.99493649374, 3862.9806850965847, 3973.7019723520707, 3990.5211374635082, 4011.3982902564558, 3264.314493956272, 3967.0875698589343, 3978.3037968355566, 3990.700697447703, 4036.919261614766, 3385.7472735088054, 3541.680199469352, 3840.635139661728, 3931.3906344516645, 3946.289667859784, 3540.7344449166285, 3714.962850371489, 3934.4098385242155, 3935.291647503448, 3940.014996250937, 2858.5283428234316, 3429.159855835336, 3429.382711111999, 3441.4665726812386, 3445.586529616462, 4311.762853043081, 4414.2037847451165, 4790.952090479085, 5028.270900719375, 5040.218766091261, 4167.2081005167765, 4490.326451032339, 4493.7977790999485, 4536.140182525181, 4563.589276965055, 5360.91958620621, 5400.567954563626, 5401.351837779453, 5420.539254163401, 5435.510804027617, 3178.7457003439667, 3179.8410079495934, 3810.8285127169343, 3825.603654666055, 3864.632859878307, 4675.859724208279, 4956.553910148068, 5520.850498604317, 5692.459216374438, 5720.456556627114, 3215.887443939451, 3826.4069069294305, 3851.2105018471148, 3862.362710152793, 3885.805709714503, 3910.8240129194255, 3937.4881265435884, 3938.3107956107565, 3963.5426035857377, 3985.3822657488136, 3905.33609286422, 4164.479964812822, 4484.085344526085, 4507.752073635981, 4567.383403240587, 4629.791659375336, 5202.401723801736, 5415.185912719283, 5516.069375718546, 5558.720924420968, 1120.8038593246313, 1296.8378952630922, 1334.3661760663801, 1334.439535395117, 1343.7782765843722, 740.3484933258806, 769.6959700918106, 798.4091772088613, 814.226484839405, 837.7151768398652, 4644.907101857944, 4648.604868586208, 5010.824621138243, 5023.34696489454, 5025.195968645073, 4659.5573420525, 5539.529140023144, 5558.305211848519, 5566.610337276352, 5573.136163894603, 4446.9555304447185, 4798.136335459651, 4984.837660553479, 4998.825041123543, 5018.397792264952, 2936.3540021195304, 2961.452131355663, 2985.7163569460868, 2987.4473222453917, 3003.9098827034786, 5092.949070509321, 5901.642622196919, 5904.852378690567, 5911.379305172981, 5914.349421563625, 5123.513266239701, 5418.349798024262, 5565.568164231193, 5776.884462310814, 5802.361740208574, 5138.304359561335, 5782.674961896926, 5787.4857611063435, 5808.767649293981, 5831.4168583141345, 4639.396878405786, 5217.895642087061, 5619.381867994435, 5660.660360378391, 5711.029125048834, 4149.813258403318, 4936.481669424802, 4970.65205435616, 5637.774489020393, 5681.715914204356, 4977.735130515679, 5919.6744179070565, 5930.886284700567, 5949.434803693643, 5970.283565972111, 3682.9079273018388, 3924.7448915820382, 3927.273454410943, 3962.96962789671, 3965.1276719121747, 5545.584081194007, 5550.611457197957, 5566.832995010156, 5567.387587365306, 5580.5535557156145, 4669.509701481432, 5080.923786143137, 5398.460153984727, 5404.648697834628, 5437.265969094122, 4257.467816522934, 5506.0, 5548.250986116823, 5564.275074726847, 5569.554435645057, 4927.384076990376, 5263.6578622389425, 5403.756830942539, 5425.267588875451, 5436.7651247507665, 5067.87330316745, 5234.450382709913, 5399.595030372816, 5499.1476321170285, 5536.474034966672, 5344.953376575315, 5382.192671099367, 5385.515303622693, 5390.975714614211, 5419.0], "value": 3977.9396137652498, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.7514681994252, 717.5120917775913, 722.4942540221842, 774.945753797234, 830.1532436914349, 14807.555773326803, 16262.837371626296, 16286.755698664529, 16289.83710162901, 16334.673306533878, 13925.860741392584, 13960.092593981384, 14034.508792192275, 14057.437702491914, 14178.432862685506, 699.7306037175673, 715.5027256057042, 828.204923273658, 847.1867007672641, 871.2724874729577, 853.6798700487317, 859.7893516088567, 860.6256278518833, 863.447393668053, 864.6973559254274, 906.5286051253345, 908.9909100909006, 910.5947853205327, 911.0570559470916, 911.7401540560942, 899.5007770687299, 901.3059943843269, 901.6573701993266, 902.1024081039375, 902.6975963052386, 546.1916363781626, 600.1358044416027, 633.262249479356, 658.0556900847299, 678.1692426777158, 12535.746425357482, 14288.0, 14641.48754793577, 14645.267736613127, 14658.120512769268, 13412.0, 13833.861661383458, 13957.930210348883, 14028.368723407471, 14267.85732142686, 12390.155984725154, 12832.294223817686, 12860.0, 12928.159669621444, 13303.40134693941, 3806.638369354907, 3984.521857377133, 4564.475085365192, 4604.7927843247135, 4710.576048155683, 106410.46794765977, 106440.0, 106649.0, 110241.0, 114795.0, 10883.0, 10966.890331096747, 11003.33979961205, 11157.665270041749, 11389.715257118472, 21744.673829892563, 21750.34748957534, 21775.782242177374, 21781.346559603237, 21792.564148716938, 7710.864947945928, 7743.264389882952, 7785.610719464004, 7792.220777922163, 7858.17489163641, 4282.379055037002, 4340.544242854518, 4351.0, 4404.405405270248, 4516.074204788077, 3181.5227715842348, 3277.4264503711756, 3280.3111346617, 3284.0968733597974, 3284.802911825298, 636.2238069555135, 638.5211091681239, 650.2262307853733, 660.7191943424026, 664.5414663881907, 517.9766910488962, 539.700466241232, 555.2365497441018, 586.0008685191738, 586.6743957103763, 8740.0, 8816.603252853636, 8985.595648195847, 9000.774980625536, 9015.0, 604.1994357476361, 628.5317438508249, 630.8549033722178, 634.2241723175091, 635.4693830651457, 3761.2665530221893, 4282.865075397991, 4494.16857881293, 4543.886402839956, 4595.333676616872, 160340.0, 163017.0, 167498.32501675072, 173007.0, 174031.38952915717, 142593.0, 143957.0, 144405.0, 146650.0, 149937.50312484332, 112686.87313126764, 113281.43359283151, 117325.02737267257, 120833.0, 123505.38247308886, 7178.497505174797, 8326.750197494084, 8382.916170838458, 8504.787380315542, 8664.610092545976, 53126.20310695266, 58241.0, 59034.36769109307, 60939.39060609515, 64431.0, 84906.0, 87746.56126719326, 88059.0, 88526.0, 88768.5561572188, 13391.397387117408, 13419.396127174496, 13441.865581344062, 13452.058355915384, 13755.518556850462, 12835.486580536674, 15150.611096194092, 15214.771778423112, 15370.154641494824, 15876.68246635084, 13411.308375337028, 14040.368183431974, 14192.787108193592, 14728.705425891636, 14871.0, 14180.29098545048, 14206.289685515476, 14218.573442796733, 14224.644383890485, 14613.561593152222, 13699.809931099977, 13777.655558610713, 13881.033983153196, 13940.651483712989, 14622.122672639676, 10346.534405951901, 10641.308314959502, 11108.111351091731, 11461.770764584495, 11641.825372619249, 11503.424828758361, 11592.188546801643, 11593.594224202112, 11750.823737644114, 11768.411579420823], "value": 7826.567398591173, "unit": "score"}], "name": "dromaeo_css", "value": 7351.610533243155, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 112.0, 111.0, 110.0, 111.0, 110.0, 111.0, 111.0, 110.0, 112.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 106.0, 173.0, 135.0, 138.0, 112.0, 145.0, 145.0, 106.0], "value": 131.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 185.0, 187.0, 182.0, 185.0, 185.0, 193.0, 221.0, 184.0], "value": 188.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 58.0, 106.0, 57.0, 57.0, 63.0, 64.0, 82.0, 57.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 68.0, 92.0, 73.0, 81.0, 67.0, 97.0, 69.0, 68.0, 99.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 90.0, 86.0, 88.0, 85.0, 91.0, 86.0, 88.0, 115.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 101.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 81.0, 86.0, 86.0, 83.0, 84.0, 85.0, 85.0, 86.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 54.0, 53.0, 53.0, 52.0, 54.0, 54.0, 52.0, 52.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 79.0, 76.0, 75.0, 75.0, 70.0, 71.0, 71.0, 72.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 83.0, 81.0, 82.0, 82.0, 86.0, 86.0, 81.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 138.0, 141.0, 144.0, 146.0, 127.0, 144.0, 161.0, 137.0], "value": 141.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 203.0, 165.0, 165.0, 166.0, 164.0, 165.0, 165.0, 164.0, 171.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 70.0, 64.0, 87.0, 66.0, 59.0, 57.0, 86.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1438.3999999999999, "alertThreshold": 2.0}]} 09:55:15 INFO - Return code: 0 09:55:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:55:15 INFO - # TBPL SUCCESS # 09:55:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:55:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:55:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:55:15 INFO - 2016-08-04 09:55:15.815 screenresolution[1982:20518] starting screenresolution argv=screenresolution get 09:55:15 INFO - 2016-08-04 09:55:15.818 screenresolution[1982:20518] Display 0: 1600x1200x32@60 09:55:15 INFO - 2016-08-04 09:55:15.824 screenresolution[1983:20522] starting screenresolution argv=screenresolution list 09:55:15 INFO - Available Modes on Display 0 09:55:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:55:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:55:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:55:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:55:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:55:16 INFO - Intel Iris: 09:55:16 INFO - Chipset Model: Intel Iris 09:55:16 INFO - Type: GPU 09:55:16 INFO - Bus: Built-In 09:55:16 INFO - VRAM (Dynamic, Max): 1536 MB 09:55:16 INFO - Vendor: Intel (0x8086) 09:55:16 INFO - Device ID: 0x0a2e 09:55:16 INFO - Revision ID: 0x0009 09:55:16 INFO - Displays: 09:55:16 INFO - Display: 09:55:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:55:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:55:16 INFO - Display Serial Number: 1600x1200 60 09:55:16 INFO - Main Display: Yes 09:55:16 INFO - Mirror: Off 09:55:16 INFO - Online: Yes 09:55:16 INFO - Rotation: Supported 09:55:16 INFO - Return code: 0 09:55:16 INFO - Running post-action listener: _resource_record_post_action 09:55:16 INFO - [mozharness: 2016-08-04 16:55:16.073673Z] Finished run-tests step (success) 09:55:16 INFO - Running post-run listener: _resource_record_post_run 09:55:16 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 638976; Write bytes: 699688448; Read time: 8; Write time: 2230 09:55:16 INFO - TinderboxPrint: CPU usage
53.5% 09:55:16 INFO - TinderboxPrint: I/O read bytes / time
638,976 / 8 09:55:16 INFO - TinderboxPrint: I/O write bytes / time
699,688,448 / 2,230 09:55:16 INFO - TinderboxPrint: CPU idle
2,257.8 (46.5%) 09:55:16 INFO - TinderboxPrint: CPU system
972.0 (20.0%) 09:55:16 INFO - TinderboxPrint: CPU user
1,623.8 (33.5%) 09:55:16 INFO - TinderboxPrint: Swap in / out
403,869,696 / 0 09:55:16 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 400640512; Write bytes: 395741184; Read time: 30643; Write time: 737 09:55:16 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 487424; Write bytes: 292183552; Read time: 4; Write time: 1436 09:55:16 INFO - Running post-run listener: _upload_blobber_files 09:55:16 INFO - Blob upload gear active. 09:55:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:55:16 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 09:55:16 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'] 09:55:16 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 09:55:16 INFO - (blobuploader) - INFO - Open directory for files ... 09:55:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:55:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:55:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:17 INFO - (blobuploader) - INFO - Done attempting. 09:55:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:55:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:55:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:18 INFO - (blobuploader) - INFO - Done attempting. 09:55:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:55:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:55:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:20 INFO - (blobuploader) - INFO - Done attempting. 09:55:20 INFO - (blobuploader) - INFO - Iteration through files over. 09:55:20 INFO - Return code: 0 09:55:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:55:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:55:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5e1a8afc8dba8ded80f80632fd1df70fcadfa64efae6121ae198bc09494d87af380b2d35d853d09e5f2da0a56e2697f3d00dff6912ed056d04cb302600fe6bae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c49b415834c6ec464d9b8c1f6c323ae5ab99914b7d62e5e977e13443b459ab31be08ccde30266826380b9681aa5a1409825a9e8fc84e97b9d437976d636dfde2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/42e8bc0de643f54405a5926971fc4373d609402bcba92bc1d252aa9d93bfeb251a700cb390969d77a578a30fd5355f2357b32ee03123ce22061c617c394a5030"} 09:55:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:55:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:55:20 INFO - Contents: 09:55:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5e1a8afc8dba8ded80f80632fd1df70fcadfa64efae6121ae198bc09494d87af380b2d35d853d09e5f2da0a56e2697f3d00dff6912ed056d04cb302600fe6bae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c49b415834c6ec464d9b8c1f6c323ae5ab99914b7d62e5e977e13443b459ab31be08ccde30266826380b9681aa5a1409825a9e8fc84e97b9d437976d636dfde2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/42e8bc0de643f54405a5926971fc4373d609402bcba92bc1d252aa9d93bfeb251a700cb390969d77a578a30fd5355f2357b32ee03123ce22061c617c394a5030"} 09:55:20 INFO - Running post-run listener: copy_logs_to_upload_dir 09:55:20 INFO - Copying logs to upload dir... 09:55:20 INFO - mkdir: /builds/slave/test/build/upload/logs 09:55:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.741255 ========= master_lag: 0.24 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-04 09:55:21.152955) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-08-04 09:55:21.156145) ========= 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.0uHdbBBYb9/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.wRr3STd7ah/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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/5e1a8afc8dba8ded80f80632fd1df70fcadfa64efae6121ae198bc09494d87af380b2d35d853d09e5f2da0a56e2697f3d00dff6912ed056d04cb302600fe6bae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c49b415834c6ec464d9b8c1f6c323ae5ab99914b7d62e5e977e13443b459ab31be08ccde30266826380b9681aa5a1409825a9e8fc84e97b9d437976d636dfde2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/42e8bc0de643f54405a5926971fc4373d609402bcba92bc1d252aa9d93bfeb251a700cb390969d77a578a30fd5355f2357b32ee03123ce22061c617c394a5030"} build_url:https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011264 build_url: 'https://queue.taskcluster.net/v1/task/U6DKsIRiRj6eFQlUbXUINA/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/5e1a8afc8dba8ded80f80632fd1df70fcadfa64efae6121ae198bc09494d87af380b2d35d853d09e5f2da0a56e2697f3d00dff6912ed056d04cb302600fe6bae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c49b415834c6ec464d9b8c1f6c323ae5ab99914b7d62e5e977e13443b459ab31be08ccde30266826380b9681aa5a1409825a9e8fc84e97b9d437976d636dfde2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/42e8bc0de643f54405a5926971fc4373d609402bcba92bc1d252aa9d93bfeb251a700cb390969d77a578a30fd5355f2357b32ee03123ce22061c617c394a5030"}' ========= master_lag: 5.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-08-04 09:55:26.237068) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:55:26.237404) ========= 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.0uHdbBBYb9/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.wRr3STd7ah/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.005105 ========= master_lag: 0.12 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:55:26.358124) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 09:55:26.358430) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 09:55:26.359014) ========= ========= Total master_lag: 5.49 =========