builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0201 starttime: 1472744796.41 results: success (0) buildid: 20160901073319 builduid: 5a14e9f164704f8298599add5ba2bb9e revision: 9706afcd83f3f60f2dd8c6b05b9e85984ed01452 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:36.407365) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:36.416011) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:36.416308) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:36.459594) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:36.459969) ========= 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.YVUuhNCH1M/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.2zRojsjxOV/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-01 08:46:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-09-01 08:46:36 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106082 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:36.591204) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:36.591551) ========= 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.YVUuhNCH1M/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.2zRojsjxOV/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.032162 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:36.668746) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 08:46:36.669067) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 9706afcd83f3f60f2dd8c6b05b9e85984ed01452 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 9706afcd83f3f60f2dd8c6b05b9e85984ed01452 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YVUuhNCH1M/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.2zRojsjxOV/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-01 08:46:36,748 truncating revision to first 12 chars 2016-09-01 08:46:36,748 Setting DEBUG logging. 2016-09-01 08:46:36,748 attempt 1/10 2016-09-01 08:46:36,748 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/9706afcd83f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 08:46:38,275 unpacking tar archive at: autoland-9706afcd83f3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.784464 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 08:46:38.477073) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:38.477395) ========= script_repo_revision: 9706afcd83f3f60f2dd8c6b05b9e85984ed01452 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:38.477791) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:38.478098) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 08:46:38.496547) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-01 08:46:38.496880) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YVUuhNCH1M/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.2zRojsjxOV/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 08:46:38 INFO - MultiFileLogger online at 20160901 08:46:38 in /builds/slave/test 08:46:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 08:46:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:46:38 INFO - {'append_to_log': False, 08:46:38 INFO - 'base_work_dir': '/builds/slave/test', 08:46:38 INFO - 'blob_upload_branch': 'Autoland', 08:46:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:46:38 INFO - 'branch': 'Autoland', 08:46:38 INFO - 'buildbot_json_path': 'buildprops.json', 08:46:38 INFO - 'config_files': ('talos/mac_config.py',), 08:46:38 INFO - 'default_actions': ('clobber', 08:46:38 INFO - 'read-buildbot-config', 08:46:38 INFO - 'download-and-extract', 08:46:38 INFO - 'populate-webroot', 08:46:38 INFO - 'create-virtualenv', 08:46:38 INFO - 'install', 08:46:38 INFO - 'run-tests'), 08:46:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:46:38 INFO - 'download_minidump_stackwalk': True, 08:46:38 INFO - 'download_symbols': 'ondemand', 08:46:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:46:38 INFO - 'tooltool.py': '/tools/tooltool.py', 08:46:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:46:38 INFO - '/tools/misc-python/virtualenv.py')}, 08:46:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:46:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:46:38 INFO - 'installer_path': 'installer.exe', 08:46:38 INFO - 'log_level': 'info', 08:46:38 INFO - 'log_name': 'talos', 08:46:38 INFO - 'log_to_console': True, 08:46:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:46:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:46:38 INFO - 'opt_config_files': (), 08:46:38 INFO - 'pip_index': False, 08:46:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:46:38 INFO - 'cmd': ('bash', 08:46:38 INFO - '-c', 08:46:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:46:38 INFO - 'enabled': True, 08:46:38 INFO - 'halt_on_failure': False, 08:46:38 INFO - 'name': 'check_screen_resolution'},), 08:46:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:46:38 INFO - 'cmd': ('bash', 08:46:38 INFO - '-c', 08:46:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:46:38 INFO - 'enabled': True, 08:46:38 INFO - 'halt_on_failure': False, 08:46:38 INFO - 'name': 'check_screen_resolution'},), 08:46:38 INFO - 'run_cmd_checks_enabled': True, 08:46:38 INFO - 'sps_profile': False, 08:46:38 INFO - 'sps_profile_interval': 0, 08:46:38 INFO - 'suite': 'dromaeojs', 08:46:38 INFO - 'system_bits': '32', 08:46:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:46:38 INFO - 'title': 't-yosemite-r7-0201', 08:46:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:46:38 INFO - 'use_talos_json': True, 08:46:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:46:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:46:38 INFO - 'work_dir': 'build'} 08:46:38 INFO - [mozharness: 2016-09-01 15:46:38.689497Z] Running clobber step. 08:46:38 INFO - Running pre-action listener: _resource_record_pre_action 08:46:38 INFO - Running main action method: clobber 08:46:38 INFO - rmtree: /builds/slave/test/build 08:46:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:46:40 INFO - Running post-action listener: _resource_record_post_action 08:46:40 INFO - [mozharness: 2016-09-01 15:46:40.702984Z] Finished clobber step (success) 08:46:40 INFO - [mozharness: 2016-09-01 15:46:40.703182Z] Running read-buildbot-config step. 08:46:40 INFO - Running pre-action listener: _resource_record_pre_action 08:46:40 INFO - Running main action method: read_buildbot_config 08:46:40 INFO - Using buildbot properties: 08:46:40 INFO - { 08:46:40 INFO - "project": "", 08:46:40 INFO - "product": "firefox", 08:46:40 INFO - "script_repo_revision": "production", 08:46:40 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 08:46:40 INFO - "repository": "", 08:46:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 08:46:40 INFO - "buildid": "20160901073319", 08:46:40 INFO - "pgo_build": "False", 08:46:40 INFO - "basedir": "/builds/slave/test", 08:46:40 INFO - "buildnumber": 197, 08:46:40 INFO - "slavename": "t-yosemite-r7-0201", 08:46:40 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:46:40 INFO - "platform": "yosemite_r7", 08:46:40 INFO - "scheduler": "tests-autoland-macosx64-talos", 08:46:40 INFO - "branch": "autoland", 08:46:40 INFO - "repo_path": "integration/autoland", 08:46:40 INFO - "revision": "9706afcd83f3f60f2dd8c6b05b9e85984ed01452", 08:46:40 INFO - "stage_platform": "macosx64", 08:46:40 INFO - "builduid": "5a14e9f164704f8298599add5ba2bb9e", 08:46:40 INFO - "slavebuilddir": "test" 08:46:40 INFO - } 08:46:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:46:40 INFO - Running post-action listener: _resource_record_post_action 08:46:40 INFO - [mozharness: 2016-09-01 15:46:40.706440Z] Finished read-buildbot-config step (success) 08:46:40 INFO - [mozharness: 2016-09-01 15:46:40.706560Z] Running download-and-extract step. 08:46:40 INFO - Running pre-action listener: _resource_record_pre_action 08:46:40 INFO - Running main action method: download_and_extract 08:46:40 INFO - mkdir: /builds/slave/test/build/tests 08:46: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')]} 08:46:40 INFO - trying https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:46:40 INFO - Downloading https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/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 08:46:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/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 08:46:42 INFO - Downloaded 1199 bytes. 08:46:42 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:46:42 INFO - Using the following test package requirements: 08:46:42 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:46:42 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:46:42 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:46:42 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:46:42 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:46:42 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:46:42 INFO - u'jsshell-mac.zip'], 08:46:42 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:46:42 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:46:42 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:46:42 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:46:42 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:46:42 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:46:42 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:46:42 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:46:42 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:46:42 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:46:42 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:46:42 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:46:42 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')]} 08:46:42 INFO - trying https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:46:42 INFO - Downloading https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/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 08:46:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/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 08:46:43 INFO - Downloaded 16398080 bytes. 08:46:43 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:46:50 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 08:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:50 INFO - trying https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:46:50 INFO - Downloading https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/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 08:46:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/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 08:46:51 INFO - Downloaded 16398080 bytes. 08:46:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:46:57 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')]} 08:46:57 INFO - trying https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:46:57 INFO - Downloading https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/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 08:46:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/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 08:46:59 INFO - Downloaded 14413998 bytes. 08:46:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 08:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:59 INFO - trying https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:46:59 INFO - Downloading https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:46:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/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 08:47:02 INFO - Downloaded 111594792 bytes. 08:47:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:47:02 INFO - mkdir: /builds/slave/test/properties 08:47:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:47:02 INFO - Writing to file /builds/slave/test/properties/build_url 08:47:02 INFO - Contents: 08:47:02 INFO - build_url:https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:47:03 INFO - Running post-action listener: _resource_record_post_action 08:47:03 INFO - Running post-action listener: set_extra_try_arguments 08:47:03 INFO - [mozharness: 2016-09-01 15:47:03.385093Z] Finished download-and-extract step (success) 08:47:03 INFO - [mozharness: 2016-09-01 15:47:03.385246Z] Running populate-webroot step. 08:47:03 INFO - Running pre-action listener: _resource_record_pre_action 08:47:03 INFO - Running main action method: populate_webroot 08:47:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:47:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:47:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:47:03 INFO - '--tppagecycles', 08:47:03 INFO - '7'], 08:47:03 INFO - 'tests': ['tsvgm']}}, 08:47:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:47:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:47:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:47:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:47:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:47:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:47:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:03 INFO - 'talos_options': ['--disable-e10s'], 08:47:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:47:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:47:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:03 INFO - 'talos_options': ['--disable-e10s'], 08:47:03 INFO - 'tests': ['damp', 'tps']}, 08:47:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:03 INFO - 'tests': ['damp', 'tps']}, 08:47:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:47:03 INFO - 'tests': ['dromaeo_dom']}, 08:47:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:47:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:47:03 INFO - 'tests': ['basic_compositor_video']}, 08:47:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:47:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:47:03 INFO - 'tests': ['a11yr', 08:47:03 INFO - 'ts_paint', 08:47:03 INFO - 'tpaint', 08:47:03 INFO - 'sessionrestore', 08:47:03 INFO - 'sessionrestore_no_auto_restore', 08:47:03 INFO - 'tabpaint']}, 08:47:03 INFO - 'other-e10s': {'tests': ['a11yr', 08:47:03 INFO - 'ts_paint', 08:47:03 INFO - 'tpaint', 08:47:03 INFO - 'sessionrestore', 08:47:03 INFO - 'sessionrestore_no_auto_restore', 08:47:03 INFO - 'tabpaint']}, 08:47:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:47:03 INFO - 'ts_paint', 08:47:03 INFO - 'tpaint', 08:47:03 INFO - 'sessionrestore', 08:47:03 INFO - 'sessionrestore_no_auto_restore', 08:47:03 INFO - 'tabpaint']}, 08:47:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:47:03 INFO - 'ts_paint', 08:47:03 INFO - 'tpaint', 08:47:03 INFO - 'sessionrestore', 08:47:03 INFO - 'sessionrestore_no_auto_restore', 08:47:03 INFO - 'tabpaint']}, 08:47:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:47:03 INFO - 'tests': ['a11yr', 08:47:03 INFO - 'ts_paint', 08:47:03 INFO - 'tpaint', 08:47:03 INFO - 'sessionrestore', 08:47:03 INFO - 'sessionrestore_no_auto_restore', 08:47:03 INFO - 'tabpaint']}, 08:47:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:47:03 INFO - 'tests': ['a11yr', 08:47:03 INFO - 'ts_paint', 08:47:03 INFO - 'tpaint', 08:47:03 INFO - 'sessionrestore', 08:47:03 INFO - 'sessionrestore_no_auto_restore', 08:47:03 INFO - 'tabpaint']}, 08:47:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:47:03 INFO - 'tests': ['tsvgx', 08:47:03 INFO - 'tsvgr_opacity', 08:47:03 INFO - 'tart', 08:47:03 INFO - 'tscrollx', 08:47:03 INFO - 'cart']}, 08:47:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:47:03 INFO - 'tsvgr_opacity', 08:47:03 INFO - 'tart', 08:47:03 INFO - 'tscrollx', 08:47:03 INFO - 'cart']}, 08:47:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:03 INFO - 'talos_options': ['--disable-e10s'], 08:47:03 INFO - 'tests': ['tp5o']}, 08:47:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:03 INFO - 'tests': ['tp5o']}, 08:47:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:03 INFO - 'talos_options': ['--disable-e10s', 08:47:03 INFO - '--xperf_path', 08:47:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:47:03 INFO - 'tests': ['tp5n']}, 08:47:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:03 INFO - 'talos_options': ['--xperf_path', 08:47:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:47:03 INFO - 'tests': ['tp5n']}}, 08:47:03 INFO - 'talos.zip': {'path': '', 08:47:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:47:03 INFO - Running post-action listener: _resource_record_post_action 08:47:03 INFO - [mozharness: 2016-09-01 15:47:03.403623Z] Finished populate-webroot step (success) 08:47:03 INFO - [mozharness: 2016-09-01 15:47:03.403760Z] Running create-virtualenv step. 08:47:03 INFO - Running pre-action listener: _resource_record_pre_action 08:47:03 INFO - Running main action method: create_virtualenv 08:47:03 INFO - Creating virtualenv /builds/slave/test/build/venv 08:47:03 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 08:47:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:47:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:47:03 INFO - Using real prefix '/tools/python27' 08:47:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:47:04 INFO - Installing distribute.............................................................................................................................................................................................done. 08:47:07 INFO - Installing pip.................done. 08:47:07 INFO - Return code: 0 08:47:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:47:07 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')]} 08:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:07 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')]} 08:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:07 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 0x10f306a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f1c53ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f26ad30>, '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 0x10efcfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f262b20>, '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.YVUuhNCH1M/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.2zRojsjxOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 08:47:07 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 08:47:07 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 08:47:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YVUuhNCH1M/Render', 08:47:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:07 INFO - 'HOME': '/Users/cltbld', 08:47:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:07 INFO - 'LOGNAME': 'cltbld', 08:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:07 INFO - 'MOZ_NO_REMOTE': '1', 08:47:07 INFO - 'NO_EM_RESTART': '1', 08:47:07 INFO - 'PAGER': '/bin/cat', 08:47:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:07 INFO - 'PWD': '/builds/slave/test', 08:47:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:07 INFO - 'SHELL': '/bin/bash', 08:47:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zRojsjxOV/Listeners', 08:47:07 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 08:47:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:07 INFO - 'USER': 'cltbld', 08:47:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:07 INFO - 'XPC_FLAGS': '0x0', 08:47:07 INFO - 'XPC_SERVICE_NAME': '0', 08:47:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:07 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 08:47:07 INFO - Cleaning up... 08:47:07 INFO - Return code: 0 08:47:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:47:07 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')]} 08:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:07 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')]} 08:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:07 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 0x10f306a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f1c53ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f26ad30>, '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 0x10efcfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f262b20>, '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.YVUuhNCH1M/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.2zRojsjxOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 08:47:07 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 08:47:07 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 08:47:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YVUuhNCH1M/Render', 08:47:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:07 INFO - 'HOME': '/Users/cltbld', 08:47:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:07 INFO - 'LOGNAME': 'cltbld', 08:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:07 INFO - 'MOZ_NO_REMOTE': '1', 08:47:07 INFO - 'NO_EM_RESTART': '1', 08:47:07 INFO - 'PAGER': '/bin/cat', 08:47:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:07 INFO - 'PWD': '/builds/slave/test', 08:47:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:07 INFO - 'SHELL': '/bin/bash', 08:47:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zRojsjxOV/Listeners', 08:47:07 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 08:47:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:07 INFO - 'USER': 'cltbld', 08:47:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:07 INFO - 'XPC_FLAGS': '0x0', 08:47:07 INFO - 'XPC_SERVICE_NAME': '0', 08:47:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:08 INFO - Downloading/unpacking psutil>=3.1.1 08:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:47:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:47:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:47:11 INFO - Installing collected packages: psutil 08:47:11 INFO - Running setup.py install for psutil 08:47:11 INFO - building 'psutil._psutil_osx' extension 08:47:11 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 08:47:12 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 08:47:12 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 08:47:12 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 08:47:12 INFO - building 'psutil._psutil_posix' extension 08:47:12 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 08:47:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:47:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:47:12 INFO - ^ 08:47:12 INFO - 1 warning generated. 08:47:12 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 08:47:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:47:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:47:12 INFO - Successfully installed psutil 08:47:12 INFO - Cleaning up... 08:47:12 INFO - Return code: 0 08:47:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:47:12 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')]} 08:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:12 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')]} 08:47:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:12 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 0x10f306a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f1c53ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f26ad30>, '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 0x10efcfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f262b20>, '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.YVUuhNCH1M/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.2zRojsjxOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 08:47:12 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 08:47:12 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 08:47:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YVUuhNCH1M/Render', 08:47:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:12 INFO - 'HOME': '/Users/cltbld', 08:47:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:12 INFO - 'LOGNAME': 'cltbld', 08:47:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:12 INFO - 'MOZ_NO_REMOTE': '1', 08:47:12 INFO - 'NO_EM_RESTART': '1', 08:47:12 INFO - 'PAGER': '/bin/cat', 08:47:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:12 INFO - 'PWD': '/builds/slave/test', 08:47:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:12 INFO - 'SHELL': '/bin/bash', 08:47:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zRojsjxOV/Listeners', 08:47:12 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 08:47:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:12 INFO - 'USER': 'cltbld', 08:47:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:12 INFO - 'XPC_FLAGS': '0x0', 08:47:12 INFO - 'XPC_SERVICE_NAME': '0', 08:47:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:47:12 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 08:47:12 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 08:47:12 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 08:47:12 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 08:47:12 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 08:47:12 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 08:47:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:47:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:47:15 INFO - Installing collected packages: mozsystemmonitor 08:47:15 INFO - Running setup.py install for mozsystemmonitor 08:47:15 INFO - Successfully installed mozsystemmonitor 08:47:15 INFO - Cleaning up... 08:47:15 INFO - Return code: 0 08:47:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:15 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 0x10f306a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f1c53ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f26ad30>, '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 0x10efcfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f262b20>, '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.YVUuhNCH1M/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.2zRojsjxOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 08:47:15 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 08:47:15 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 08:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YVUuhNCH1M/Render', 08:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:15 INFO - 'HOME': '/Users/cltbld', 08:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:15 INFO - 'LOGNAME': 'cltbld', 08:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:15 INFO - 'MOZ_NO_REMOTE': '1', 08:47:15 INFO - 'NO_EM_RESTART': '1', 08:47:15 INFO - 'PAGER': '/bin/cat', 08:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:15 INFO - 'PWD': '/builds/slave/test', 08:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:15 INFO - 'SHELL': '/bin/bash', 08:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zRojsjxOV/Listeners', 08:47:15 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 08:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:15 INFO - 'USER': 'cltbld', 08:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:15 INFO - 'XPC_FLAGS': '0x0', 08:47:15 INFO - 'XPC_SERVICE_NAME': '0', 08:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:15 INFO - Downloading/unpacking jsonschema==2.5.1 08:47:15 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 08:47:15 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 08:47:15 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 08:47:15 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 08:47:15 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 08:47:15 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 08:47:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:47:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:18 INFO - Downloading functools32-3.2.3-2.tar.gz 08:47:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:47:18 INFO - warning: no files found matching '*.txt' 08:47:18 INFO - no previously-included directories found matching 'build' 08:47:18 INFO - no previously-included directories found matching 'dist' 08:47:18 INFO - no previously-included directories found matching '.git*' 08:47:18 INFO - Installing collected packages: jsonschema, functools32 08:47:18 INFO - Running setup.py install for functools32 08:47:18 INFO - warning: no files found matching '*.txt' 08:47:18 INFO - no previously-included directories found matching 'build' 08:47:18 INFO - no previously-included directories found matching 'dist' 08:47:18 INFO - no previously-included directories found matching '.git*' 08:47:18 INFO - Successfully installed jsonschema functools32 08:47:18 INFO - Cleaning up... 08:47:18 INFO - Return code: 0 08:47:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:47:18 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')]} 08:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:18 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')]} 08:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:18 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 0x10f306a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f1c53ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f26ad30>, '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 0x10efcfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f262b20>, '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.YVUuhNCH1M/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.2zRojsjxOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 08:47:18 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 08:47:18 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 08:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YVUuhNCH1M/Render', 08:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:18 INFO - 'HOME': '/Users/cltbld', 08:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:18 INFO - 'LOGNAME': 'cltbld', 08:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:18 INFO - 'MOZ_NO_REMOTE': '1', 08:47:18 INFO - 'NO_EM_RESTART': '1', 08:47:18 INFO - 'PAGER': '/bin/cat', 08:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:18 INFO - 'PWD': '/builds/slave/test', 08:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:18 INFO - 'SHELL': '/bin/bash', 08:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zRojsjxOV/Listeners', 08:47:18 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 08:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:18 INFO - 'USER': 'cltbld', 08:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:18 INFO - 'XPC_FLAGS': '0x0', 08:47:18 INFO - 'XPC_SERVICE_NAME': '0', 08:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:47:19 INFO - Cleaning up... 08:47:19 INFO - Return code: 0 08:47:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:47:19 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')]} 08:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:19 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')]} 08:47:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:19 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 0x10f306a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f1c53ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f26ad30>, '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 0x10efcfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f262b20>, '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.YVUuhNCH1M/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.2zRojsjxOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 08:47:19 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 08:47:19 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 08:47:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YVUuhNCH1M/Render', 08:47:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:19 INFO - 'HOME': '/Users/cltbld', 08:47:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:19 INFO - 'LOGNAME': 'cltbld', 08:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:19 INFO - 'MOZ_NO_REMOTE': '1', 08:47:19 INFO - 'NO_EM_RESTART': '1', 08:47:19 INFO - 'PAGER': '/bin/cat', 08:47:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:19 INFO - 'PWD': '/builds/slave/test', 08:47:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:19 INFO - 'SHELL': '/bin/bash', 08:47:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zRojsjxOV/Listeners', 08:47:19 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 08:47:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:19 INFO - 'USER': 'cltbld', 08:47:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:19 INFO - 'XPC_FLAGS': '0x0', 08:47:19 INFO - 'XPC_SERVICE_NAME': '0', 08:47:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:19 INFO - Downloading/unpacking blobuploader==1.2.4 08:47:19 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 08:47:19 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 08:47:19 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 08:47:19 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 08:47:19 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 08:47:19 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 08:47:21 INFO - Downloading blobuploader-1.2.4.tar.gz 08:47:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:47:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:47:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:23 INFO - Downloading docopt-0.6.1.tar.gz 08:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:47:23 INFO - Installing collected packages: blobuploader, requests, docopt 08:47:23 INFO - Running setup.py install for blobuploader 08:47:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:47:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:47:23 INFO - Running setup.py install for requests 08:47:23 INFO - Running setup.py install for docopt 08:47:23 INFO - Successfully installed blobuploader requests docopt 08:47:23 INFO - Cleaning up... 08:47:24 INFO - Return code: 0 08:47:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47:24 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')]} 08:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:24 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')]} 08:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:24 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 0x10f306a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f1c53ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f26ad30>, '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 0x10efcfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f262b20>, '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.YVUuhNCH1M/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.2zRojsjxOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 08:47:24 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 08:47:24 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 08:47:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YVUuhNCH1M/Render', 08:47:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:24 INFO - 'HOME': '/Users/cltbld', 08:47:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:24 INFO - 'LOGNAME': 'cltbld', 08:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:24 INFO - 'MOZ_NO_REMOTE': '1', 08:47:24 INFO - 'NO_EM_RESTART': '1', 08:47:24 INFO - 'PAGER': '/bin/cat', 08:47:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:24 INFO - 'PWD': '/builds/slave/test', 08:47:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:24 INFO - 'SHELL': '/bin/bash', 08:47:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zRojsjxOV/Listeners', 08:47:24 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 08:47:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:24 INFO - 'USER': 'cltbld', 08:47:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:24 INFO - 'XPC_FLAGS': '0x0', 08:47:24 INFO - 'XPC_SERVICE_NAME': '0', 08:47:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:47:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-owJTe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:47:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-LIX5cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:47:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-m9TW3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:47:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-yuZrSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:47:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-mLVvaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:47:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-POptj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:47:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-EIA1Qk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:47:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-D60XDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:47:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-a4UHlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:47:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-4B4kJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:47:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-R2LBSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:47:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-BjNGwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:47:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-IAL3Nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:47:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-XzXsAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-b4u1AA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:47:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-bh4mxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:47:26 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-8ZYYS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:47:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:47:26 INFO - Running setup.py install for manifestparser 08:47:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:47:26 INFO - Running setup.py install for mozcrash 08:47:26 INFO - Running setup.py install for mozdebug 08:47:26 INFO - Running setup.py install for mozdevice 08:47:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:47:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:47:26 INFO - Running setup.py install for mozfile 08:47:26 INFO - Running setup.py install for mozhttpd 08:47:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:47:26 INFO - Running setup.py install for mozinfo 08:47:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:47:27 INFO - Running setup.py install for mozInstall 08:47:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:47:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:47:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:47:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:47:27 INFO - Running setup.py install for mozleak 08:47:27 INFO - Running setup.py install for mozlog 08:47:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:47:27 INFO - Running setup.py install for moznetwork 08:47:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:47:27 INFO - Running setup.py install for mozprocess 08:47:27 INFO - Running setup.py install for mozprofile 08:47:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Running setup.py install for mozrunner 08:47:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Running setup.py install for mozscreenshot 08:47:28 INFO - Running setup.py install for moztest 08:47:28 INFO - Running setup.py install for mozversion 08:47:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:47:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:47:28 INFO - Cleaning up... 08:47:28 INFO - Return code: 0 08:47:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47:28 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')]} 08:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:28 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')]} 08:47:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:28 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 0x10f306a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f1c53ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f26ad30>, '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 0x10efcfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f262b20>, '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.YVUuhNCH1M/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.2zRojsjxOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 08:47:28 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 08:47:28 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 08:47:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YVUuhNCH1M/Render', 08:47:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:28 INFO - 'HOME': '/Users/cltbld', 08:47:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:28 INFO - 'LOGNAME': 'cltbld', 08:47:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:28 INFO - 'MOZ_NO_REMOTE': '1', 08:47:28 INFO - 'NO_EM_RESTART': '1', 08:47:28 INFO - 'PAGER': '/bin/cat', 08:47:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:28 INFO - 'PWD': '/builds/slave/test', 08:47:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:28 INFO - 'SHELL': '/bin/bash', 08:47:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zRojsjxOV/Listeners', 08:47:28 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 08:47:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:28 INFO - 'USER': 'cltbld', 08:47:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:28 INFO - 'XPC_FLAGS': '0x0', 08:47:28 INFO - 'XPC_SERVICE_NAME': '0', 08:47:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:47:28 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-l7UYoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:47:29 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)) 08:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:47:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-1weofy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:47:29 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)) 08:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:47:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-FvYpr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:47:29 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)) 08:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:47:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-WhHc02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:47:29 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)) 08:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:47:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-xwEkY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:47:29 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)) 08:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:47:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-AnK1o6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:47:29 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)) 08:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:47:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-HlUzQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:47:29 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)) 08:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:47:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-7SkBnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:47:29 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)) 08:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:47:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-pGw0KE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:47:29 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)) 08:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:47:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-QD3AJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:47:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-rf0l9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:47:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-WZxjCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:47:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-y4qfiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:47:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-HmCXpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-8nmMhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:47:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-pvS7R8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:47:30 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)) 08:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:47:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-xiZsMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:47:30 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)) 08:47:30 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)) 08:47:30 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)) 08:47:30 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)) 08:47:30 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)) 08:47:30 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)) 08:47:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:33 INFO - Downloading blessings-1.6.tar.gz 08:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:47:33 INFO - Installing collected packages: blessings 08:47:33 INFO - Running setup.py install for blessings 08:47:33 INFO - Successfully installed blessings 08:47:33 INFO - Cleaning up... 08:47:33 INFO - Return code: 0 08:47:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:47:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:47:33 INFO - Reading from file tmpfile_stdout 08:47:33 INFO - Current package versions: 08:47:33 INFO - blessings == 1.6 08:47:33 INFO - blobuploader == 1.2.4 08:47:33 INFO - docopt == 0.6.1 08:47:33 INFO - functools32 == 3.2.3-2 08:47:33 INFO - jsonschema == 2.5.1 08:47:33 INFO - manifestparser == 1.1 08:47:33 INFO - mozInstall == 1.12 08:47:33 INFO - mozcrash == 0.17 08:47:33 INFO - mozdebug == 0.1 08:47:33 INFO - mozdevice == 0.48 08:47:33 INFO - mozfile == 1.2 08:47:33 INFO - mozhttpd == 0.7 08:47:33 INFO - mozinfo == 0.9 08:47:33 INFO - mozleak == 0.1 08:47:33 INFO - mozlog == 3.3 08:47:33 INFO - moznetwork == 0.27 08:47:33 INFO - mozprocess == 0.23 08:47:33 INFO - mozprofile == 0.28 08:47:33 INFO - mozrunner == 6.12 08:47:33 INFO - mozscreenshot == 0.1 08:47:33 INFO - mozsystemmonitor == 0.3 08:47:33 INFO - moztest == 0.7 08:47:33 INFO - mozversion == 1.4 08:47:33 INFO - psutil == 3.1.1 08:47:33 INFO - requests == 1.2.3 08:47:33 INFO - wsgiref == 0.1.2 08:47:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:33 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 0x10f306a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f1c53ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f26ad30>, '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 0x10efcfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f262b20>, '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.YVUuhNCH1M/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.2zRojsjxOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 08:47:33 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 08:47:33 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 08:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YVUuhNCH1M/Render', 08:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:33 INFO - 'HOME': '/Users/cltbld', 08:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:33 INFO - 'LOGNAME': 'cltbld', 08:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:33 INFO - 'MOZ_NO_REMOTE': '1', 08:47:33 INFO - 'NO_EM_RESTART': '1', 08:47:33 INFO - 'PAGER': '/bin/cat', 08:47:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:33 INFO - 'PWD': '/builds/slave/test', 08:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:33 INFO - 'SHELL': '/bin/bash', 08:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zRojsjxOV/Listeners', 08:47:33 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 08:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:33 INFO - 'USER': 'cltbld', 08:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:33 INFO - 'XPC_FLAGS': '0x0', 08:47:33 INFO - 'XPC_SERVICE_NAME': '0', 08:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 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)) 08:47:34 INFO - Cleaning up... 08:47:34 INFO - Return code: 0 08:47:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:34 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 0x10f306a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f39b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9f1c53ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f26ad30>, '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 0x10efcfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f262b20>, '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.YVUuhNCH1M/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.2zRojsjxOV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 08:47:34 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 08:47:34 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 08:47:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YVUuhNCH1M/Render', 08:47:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:34 INFO - 'HOME': '/Users/cltbld', 08:47:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:34 INFO - 'LOGNAME': 'cltbld', 08:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:34 INFO - 'MOZ_NO_REMOTE': '1', 08:47:34 INFO - 'NO_EM_RESTART': '1', 08:47:34 INFO - 'PAGER': '/bin/cat', 08:47:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:34 INFO - 'PWD': '/builds/slave/test', 08:47:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:34 INFO - 'SHELL': '/bin/bash', 08:47:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zRojsjxOV/Listeners', 08:47:34 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 08:47:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:34 INFO - 'USER': 'cltbld', 08:47:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:34 INFO - 'XPC_FLAGS': '0x0', 08:47:34 INFO - 'XPC_SERVICE_NAME': '0', 08:47:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:47:34 INFO - Cleaning up... 08:47:34 INFO - Return code: 0 08:47:34 INFO - Running post-action listener: _resource_record_post_action 08:47:34 INFO - Running post-action listener: _start_resource_monitoring 08:47:34 INFO - Starting resource monitoring. 08:47:34 INFO - [mozharness: 2016-09-01 15:47:34.468244Z] Finished create-virtualenv step (success) 08:47:34 INFO - [mozharness: 2016-09-01 15:47:34.469300Z] Running install step. 08:47:34 INFO - Running pre-action listener: _resource_record_pre_action 08:47:34 INFO - Running main action method: install 08:47:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:47:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:47:34 INFO - Reading from file tmpfile_stdout 08:47:34 INFO - Detecting whether we're running mozinstall >=1.0... 08:47:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:47:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:47:34 INFO - Reading from file tmpfile_stdout 08:47:34 INFO - Output received: 08:47:34 INFO - Usage: mozinstall [options] installer 08:47:34 INFO - Options: 08:47:34 INFO - -h, --help show this help message and exit 08:47:34 INFO - -d DEST, --destination=DEST 08:47:34 INFO - Directory to install application into. [default: 08:47:34 INFO - "/builds/slave/test"] 08:47:34 INFO - --app=APP Application being installed. [default: firefox] 08:47:34 INFO - mkdir: /builds/slave/test/build/application 08:47:34 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'] 08:47:34 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 08:48:09 INFO - Reading from file tmpfile_stdout 08:48:09 INFO - Output received: 08:48:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:48:09 INFO - Running post-action listener: _resource_record_post_action 08:48:09 INFO - [mozharness: 2016-09-01 15:48:09.003034Z] Finished install step (success) 08:48:09 INFO - [mozharness: 2016-09-01 15:48:09.003211Z] Running run-tests step. 08:48:09 INFO - Running pre-action listener: _resource_record_pre_action 08:48:09 INFO - Running main action method: run_tests 08:48:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:48:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:48:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:48:09 INFO - 2016-09-01 08:48:09.017 screenresolution[1640:7640] starting screenresolution argv=screenresolution get 08:48:09 INFO - 2016-09-01 08:48:09.020 screenresolution[1640:7640] Display 0: 1600x1200x32@60 08:48:09 INFO - 2016-09-01 08:48:09.026 screenresolution[1641:7642] starting screenresolution argv=screenresolution list 08:48:09 INFO - Available Modes on Display 0 08:48:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:48:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:48:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:48:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:48:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:48:09 INFO - Intel Iris: 08:48:09 INFO - Chipset Model: Intel Iris 08:48:09 INFO - Type: GPU 08:48:09 INFO - Bus: Built-In 08:48:09 INFO - VRAM (Dynamic, Max): 1536 MB 08:48:09 INFO - Vendor: Intel (0x8086) 08:48:09 INFO - Device ID: 0x0a2e 08:48:09 INFO - Revision ID: 0x0009 08:48:09 INFO - Displays: 08:48:09 INFO - Display: 08:48:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:48:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:48:09 INFO - Display Serial Number: 1600x1200 60 08:48:09 INFO - Main Display: Yes 08:48:09 INFO - Mirror: Off 08:48:09 INFO - Online: Yes 08:48:09 INFO - Rotation: Supported 08:48:09 INFO - Return code: 0 08:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:48:09 INFO - Python 2.7.3 08:48:09 INFO - Return code: 0 08:48:09 INFO - grabbing minidump binary from tooltool 08:48:09 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')]} 08:48:09 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 0x10f26ad30>, '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 0x10efcfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f262b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:48:09 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 08:48:09 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 08:48:09 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 08:48:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:48:09 INFO - Return code: 0 08:48:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:48:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:48:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:48:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:48:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--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 08:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0201 --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 08:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YVUuhNCH1M/Render', 08:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:09 INFO - 'HOME': '/Users/cltbld', 08:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:09 INFO - 'LOGNAME': 'cltbld', 08:48:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:48:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:09 INFO - 'MOZ_NO_REMOTE': '1', 08:48:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:48:09 INFO - 'NO_EM_RESTART': '1', 08:48:09 INFO - 'PAGER': '/bin/cat', 08:48:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:09 INFO - 'PWD': '/builds/slave/test', 08:48:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:09 INFO - 'SHELL': '/bin/bash', 08:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zRojsjxOV/Listeners', 08:48:09 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 08:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:09 INFO - 'USER': 'cltbld', 08:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:09 INFO - 'XPC_FLAGS': '0x0', 08:48:09 INFO - 'XPC_SERVICE_NAME': '0', 08:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--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 08:48:09 INFO - mozversion application_buildid: 20160901073319 08:48:09 INFO - mozversion application_changeset: 9706afcd83f3f60f2dd8c6b05b9e85984ed01452 08:48:09 INFO - mozversion application_display_name: Nightly 08:48:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:48:09 INFO - mozversion application_name: Firefox 08:48:09 INFO - mozversion application_remotingname: firefox 08:48:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:48:09 INFO - mozversion application_vendor: Mozilla 08:48:09 INFO - mozversion application_version: 51.0a1 08:48:09 INFO - mozversion platform_buildid: 20160901073319 08:48:09 INFO - mozversion platform_changeset: 9706afcd83f3f60f2dd8c6b05b9e85984ed01452 08:48:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:48:09 INFO - mozversion platform_version: 51.0a1 08:48:09 INFO - using testdate: 1472744889 08:48:09 INFO - actual date: 1472744889 08:48:09 INFO - starting webserver on 'localhost:49267' 08:48:09 INFO - SUITE-START | Running 2 tests 08:48:09 INFO - TEST-START | dromaeo_css 08:48:09 INFO - operating with platform_type : mac_ 08:48:09 INFO - Initialising browser for dromaeo_css test... 08:48:09 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpAwgusC/profile http://localhost:49267/getInfo.html) 08:48:11 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 08:48:11 INFO - PROCESS | 1651 | colorDepth:24 08:48:11 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 08:48:11 INFO - PROCESS | 1651 | __metrics 08:48:12 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:48:12 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:48:12 INFO - TEST-INFO | 1651: exit 0 08:48:12 INFO - Browser initialized. 08:48:12 INFO - Running cycle 1/1 for dromaeo_css test... 08:48:12 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpAwgusC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:48:24 INFO - PROCESS | 1653 | RSS: Main: 233807872 08:48:24 INFO - PROCESS | 1653 | 08:49:16 INFO - PROCESS | 1653 | 1472744956316 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] 08:51:27 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 08:51:30 INFO - PROCESS | 1653 | RSS: Main: 441020416 08:51:30 INFO - PROCESS | 1653 | 08:54:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 08:54:34 INFO - PROCESS | 1653 | RSS: Main: 372969472 08:54:34 INFO - PROCESS | 1653 | 08:57:36 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 08:57:36 INFO - PROCESS | 1653 | RSS: Main: 341549056 08:57:36 INFO - PROCESS | 1653 | 09:00:39 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 09:00:40 INFO - PROCESS | 1653 | RSS: Main: 343457792 09:00:40 INFO - PROCESS | 1653 | 09:03:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 09:03:43 INFO - PROCESS | 1653 | RSS: Main: 332406784 09:03:43 INFO - PROCESS | 1653 | 09:06:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 09:06:47 INFO - PROCESS | 1653 | RSS: Main: 384864256 09:06:47 INFO - PROCESS | 1653 | 09:06:47 INFO - PROCESS | 1653 | __start_tp_report 09:06:47 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 09:06:47 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 09:06:47 INFO - PROCESS | 1653 | |i|pagename|runs| 09:06:47 INFO - PROCESS | 1653 | |0;dojo.html;3153.6373317068537;3245.5780748502693;3286.622038465577;3287.539744435781;3290.2596915693966;5617.662940223589;5644.576656750744;5697.715114244292;5724.885502289952;5759.136129580565;5734.713264336777;5780.595358324919;5803.303603567567;5805.970970145143;5812.651240925547;5197.7920883164725;5238.4499627539135;5250.973745131269;5273.235380869772;5316.813911513098;5311.336082989626;5335.759890804923;5335.973320133394;5338.839834804962;5339.546138578206;5157.613179011589;5189.87025324368;5200.505951934579;5208.192730126836;5330.067238233293;5261.973690131525;5274.129768588178;5287.444818294062;5295.523402893719;5344.118220493614;5119.385673719177;5143.074246635604;5173.32746742921;5198.064348417283;5202.973985130088;5643.887122257573;5673.26247587815;5677.347105082927;5706.3722990471015;5732.799352022659;6201.5348848836065;6205.255369355661;6208.844778880519;6214.161088253073;6253.96873015632;6116.701002199459;6120.233815636133;6144.662043587601;6154.427221810825;6179.5674302798825;5809.157672137559;5863.149843272787;5902.1441890926435;5947.464728174485;5948.821535353946;6196.628202307786;6243.625382476975;6248.781292654735;6252.999520076766;6258.561900666909;5927.644341339447;6110.725017374229;6176.444120029129;6193.256809182837;6209.96895015522;6120.325235636462;6136.447719705248;6153.953827849186;6170.444659980623;6194.38056194377;6192.349803270682;6201.720922558496;6221.160143380676;6227.595206311666;6229.408206220402;6101.115338275925;6109.297430795426;6255.343188965184;6264.68676566179;6275.811725648146;4976.576990955809;4987.700737955734;4988.725620090857;4993.950060499421;4994.525520075587;4175.144095460423;4200.781773285713;4476.037651904856;4507.414036175276;4539.931901021488;5667.206591077253;5708.942910570841;5743.339515955635;5745.2818397700285;5757.337906140764;6170.228721409824;6232.065190221503;6243.094751261042;6253.499720022387;6259.405356491126;5775.6823374714795;5782.855428614234;5813.534917206698;5822.214001109796;5832.504237139806;5447.12845944647;5460.726963651881;5462.426445223195;5463.590230732711;5465.043617367024;6124.167343420691;6163.859685958112;6278.121063051177;6302.275238347557;6303.023031430227;6141.355157708376;6147.84630384244;6165.876682466415;6229.408206220402;6243.750249989949;6223.564350495421;6239.376062393724;6242.219722534684;6246.968765156145;6260.561760676709;6058.939410605837;6081.939180608138;6122.693865306628;6188.504919606511;6198.349173336735;6156.29202641702;6157.168782214523;6208.130861679459;6242.063690446288;6288.339724328879;5965.164876917322;6089.786857459968;6122.682821117092;6132.6627035513475;6155.6614386209185;5885.47030767233;6120.233815636221;6134.04922237054;6149.569530133027;6155.722992465439;5868.853278668067;5885.823425297248;5948.2862056553495;5951.851203719769;5968.343482217039;6099.703252314951;6119.143319935123;6134.110553969738;6136.447719705069;6136.5090792737365;5934.703264836828;5943.673097979653;5946.940530594638;5953.583249172516;5959.0763431668165 09:06:47 INFO - PROCESS | 1653 | |1;ext.html;467.9555442232988;472.95743383095515;482.5777444735855;532.472851876642;549.0830313376661;30273.697263027363;40072.998175045635;40907.590924090786;41238.58761412385;43349.133017339715;42709;43079;43183.78408107955;43249;43264;8653.783655408633;9595.952020239907;9751.707448776548;9755.02449755026;9773.609055637766;8678.956605216965;8810.779730506756;8829.587266037435;8833.779155521099;11717.355545444998;11766.529338826436;11943.761124777498;12039;12055.939720301387;12079.214851034698;11947.7610447791;12014.098942579254;12014.939925300405;12015.759684806255;12022.098342624251;11758;11835.230710003822;11837.881621183764;11841.822372664417;11848.644540663794;40247.59752402497;40285.79142625726;40328;40367;40452.797736011424;3317.983410082934;3529.876454324112;3745.5879853216125;3746.5316835395574;4014.136960553495;24355.87822060896;31255.531167032514;31432;31467.685323146932;31940.96560826741;3194.5048517479827;3326.334733053399;3362.899113026613;3393.2025973896116;3399.065257054305;478402;485020;487617.56191219174;488933;500287;58582.414175857695;58922.2239442461;58971;59033;59128.408715912294;61985;62250.37749622446;62283.37716622867;62353.37646623566;62508.06173413518;16040.518784436486;16273.186340683154;16278;16280.43018494347;16308.347666093223;12619.558315458915;12682.429290681941;12688.74622507563;12712.42794074269;12726.23642581448;11602.273465735545;14788.264171128376;15153.469628562947;16316.102614356325;16635.750463743054;6099.4815440688035;6143.508519318533;6217.813465596039;6221.791264101179;6223.346548612421;664.4949838122965;796.717165406284;915.1443400420628;916.6287653500343;929.869818225449;27452.862735686595;29350.355542932055;29432.11703648894;29722.25694357615;29747.553786693217;836.3727204596553;877.1360210192955;966.40083148447;1055.171690223171;1078.0274687775573;13262;14994.400223991137;15228.46700365482;15257.684561913784;15280.235988200546;638069;652549;653343;653923;656067;49231.50768492341;56216;56833.7158314214;56848;57067.42932570621;1188.364225139546;1255.0085432508217;1289.7742894993526;1309.0901823232953;1341.161773891318;26319.60520592231;28931;30096;30505.389892202475;31362.372752545278;42170.78914605407;55806.162907557235;55910.88178236494;58462;58673.706631468274;572232;603244;635957;649613;826919;23866;25151;25488.872555637103;25569.872150639127;25666.101686440885;43064.92337691457;43418.565814341455;43537.56462435462;43869.78065109654;44459.55540444684;37445.25109497849;37505.437418439295;37511.43732843955;38426.23147536978;38589;36682.63317366899;37208.190106111826;37859;37949.241015180094;38408.807955960045;36265.27469450649;36851.63148368482;36929.707460239835;37130.07174820651;37922.24155516819;27468.862655686593;30447.54328685116;32158.03525894227;32201.677983220805;34162.31675366429;24827.50344993126;25106.37234069163;25296.62055069212;25392;25591.616125757755 09:06:47 INFO - PROCESS | 1653 | |2;jquery.html;348.01338206185466;366.29305440499843;367.13722751533896;373.9401695728683;396.6747267240864;14738.263086845654;15018.849811501881;20645.967701614896;20781;21435.249766258185;17098.914505427456;20090.995450227503;20122.195112195495;20524.28165014225;20712.689309660367;4787.616990640758;5338.946610533903;5554.444555544454;5563.443655634424;5574.080276754351;3942.4677668514814;4159.542450330461;6139.723712432951;6145.907811382833;6198.81403557894;5848.41515848416;6129.387061293857;6142.385761423844;6148.1392605035335;6152.3847615238565;5855.355910849803;6124.846878828021;6136.478399336062;6159.137720719103;6202.565820392574;6048.9092663609645;6060.30306514752;6061.181740465025;6064.939350606525;6068.878622427571;13237.40431680567;13369.53206637763;23132.65301020486;24837.751622483906;25781.61327580088;2121.3105740634355;2181.3892110209167;2293.472501324688;2383.9868056076098;2401.951960960771;8379.664813407457;9408.529573521297;9739.26955478342;12738.936305318506;14679.55961321162;1829.5060333709957;1846.0527429416356;1858.7676540432446;1865.1699993502762;1876.9906150469296;119765;120706;129355.35322323423;132314.3384283082;135590.96613550806;11386.373749443694;11387.658370248906;11429.314241145557;11493.827592586118;11637.650870473899;14459;14505.564833055025;14510.1293922365;14517.838572914141;14529.418823247162;8116.837663246702;8439.240468357877;8449.493030418194;8563.100874408223;8578.699745509002;8309.37679674027;8329.458585191944;8330.708425205088;8514;8526.74419767408;3956.3076461619103;4160.667146628262;4458.710183838105;4473.485549161888;4529.32060190974;1433.792100145494;1590.83296253894;1857.9814201858078;1865.4722850537598;1930.1025023364202;700.8598280343952;704.9506534542635;865.9653613855376;900.7432881628836;909.3452714045876;20016.69974950377;20492.077856496493;20803.68794468084;20833.791662083186;20940.267090651752;870.9303255739652;910.3581974707815;933.5518950903604;935.4106912644991;980.1570649241646;7829.7259595913865;10633.73415664599;11129.777404451866;11139.498722557342;11176.552937882556;554315;599808;602123;606180;615663;128144;132927;134677;136304.6369536292;138744;936.8922573903952;944.3719926248967;950.339514037751;955.5938726041404;958.1999030809213;17875.821241787417;18571.721424178377;18711;18724.531886702942;18751.71872421942;53773.58018889081;54254;55143;56038;56481.71759141342;78138.82791758014;79381;81346.77979830188;82572.17427825645;82785;7112.822179445348;7154.177269614061;7339.2660733928315;7386.2244464332625;7413.8146546336775;11607.6517704469;11859.754725753672;12132.602871196079;12251.448684809027;13616.114952528054;11378.317300961638;11479.942600286946;11676.649700508999;11779.941100294443;11947.462364193805;9130.949940756898;11235.943820280847;11630.70923226926;11732.94133529327;12009.039276857655;10766.569337226423;11108.111351092055;11439.942800285946;11697.941510292394;11990;7428.848528477878;7486.887696684663;7524.699012039458;7598.696052157852;7714.190010048864;10404.531796069045;11387.943060284977;11581.015613672933;11734.354610496162;11784.057275418118 09:06:47 INFO - PROCESS | 1653 | |3;mootools.html;1531.1119550660617;1541.9151946642933;1750.5186073829695;1856.0233351480663;1915.2530513099894;8521.701740439086;9054.773630659229;9063;9081.727548173565;9086.772830679236;7915.406344524169;8155.592220388971;8606.87089693655;8824.735257942257;8845.425047371933;2370.9881450592766;2420.298113547069;2420.4432980414517;2421.503591763684;2424.5878200705924;2361.2798096580536;2396.4847557775074;2411.3489357873364;2411.445367565461;2411.67442395277;2066.1941842681367;2360.6104992676187;2391.5814732421845;2396.592579261521;2401.7838394544488;2349.6475528670835;2362.14962613459;2375.857448553092;2376.4415362389823;2379.4289370551114;2347.119830063726;2368.4434157972864;2369.4313364792497;2371.1937941099936;2374.25211058516;8811.251043661296;8846.380753347268;8921.866172007425;8926.821463570692;8934.28525717941;4620.330052142421;5260.815871444481;5425.6202065855405;5427.185922111676;5427.674339539641;3233.143217047484;3467.687908088259;3583.4087375583;3668.2663467306647;3669.504616876741;1087.1140020882956;1089.2429761315889;1089.809283375414;1098.549594666183;1106.878243393226;11814.881851181464;11876.703082422922;11895.940520297343;11907.404629768476;11934;4530.388397566287;4707.7881495332795;4809.663323567387;4821.9035619287415;4822.252550854553;10081.445520496296;10089.890112087665;10109.898901010896;10117.494125293706;10119.898801011896;5403.486668766461;5404.567634589223;5406.864828379322;5412.78348866041;5419.241306217133;4377.1683380157665;4555.476120246214;4754.049190161982;4880.829170978998;4940.357753492095;3875.1474675571894;3880.4373365861793;3976.622220889011;4029.315016447211;4070.8371665133654;1111.527600769686;1198.6523908066738;1240.5720026590993;1296.9286689231994;1313.159577870164;749.9625018749041;782.7260458839361;815.4658698552457;902.7652810269377;926.1757036237753;4725.929111063337;5927.645809767661;5933.525317974551;6040.36576159497;6165.506759459233;424.54573606241024;437.8183054032541;441.6180004296325;456.51609294148307;459.36607481675077;3989.341758609855;3993.5407428146136;4096.856610018635;4134.793260337031;4166.0834616384345;10325.432101234353;10825.891741082645;10838.295510791932;10841.67474975752;10850.240483166102;10549.683509494727;10765.977232162932;10773.192010599236;10781.568737250422;10817.242793004572;8121.878171827428;8162.795930101676;8320.542370169578;8323.250907418362;8326.084130745614;4776.355192049029;5751.798687046101;5911.704414779158;5977.402259773988;6059.272887253382;7341.834158923024;8980.820383592421;9774.902250977399;10219.897801021894;10231.641892533726;7465.4027677786735;8958.462492250486;9677.9032209677;10093.394396335949;10110.747231319276;6917.446604271747;7144.892826607709;7355.338019578264;7415.184329723618;7629.16079231273;8256.752297431087;8545.871811922701;8551.1448855114;8554.358423118167;8562.272206862486;7446.14369347532;7584.317411432998;7655.846883062196;7671.77251639735;7709.190534993745;7259.20148783644;7332.120145582564;7378.372838308804;7509.211532788979;7546.622668866645;6964.164300283998;7091.858162836817;7098.36114749675;7100.4319654428555;7113.53762005468;4944.826931057396;5020.623453240948;5054.671446355975;5064.4935506449065;5161.690298582097;4333.848315308949;4970.105381031449;5077.720725359993;5211.452797456364;5296.973515132399 09:06:47 INFO - PROCESS | 1653 | |4;prototype.html;300.86010005347515;302.1765688498841;309.10359956127223;314.99212519687006;315.89575440104767;5343.091674415349;5359.464053594638;5414.296141501599;5423.105187644039;5424.647397919138;4902.450725622674;5002.649814512985;5297.205419187124;5299.258103865462;5341.809857958238;3314.436545787222;3816.274907767522;3979.4428779970826;4004.7797371144575;4034.7982600869987;3354.9161270968298;4060.2488539620117;4061.776602286873;4118.382242663594;4123.154753275587;3348.5814273215847;4048.4939382577177;4057.8173982170874;4088.5298190708154;4116.9707573106725;3673.559172899256;3775.1313100015395;4080.8163632636306;4087.4890638670167;4095.344744840826;2876.964292854571;3313.8508767105536;3381.847816848248;3469.600995885455;3560.394732895414;4910.312556242129;4919.352017074284;4971.825986090469;5179.663321884065;5199.324087868553;4245.490541135084;4646.651501137429;4651.279051746985;4659.79030943605;4674.439067311911;4977.129002424561;5364.195370694427;5519.69641669708;5578.30271216098;5608.579356548275;3875;3890.3580909149955;3894.2211557688574;3894.7273690841653;3900.609939006105;5461.453854614547;5490.56075513958;5657.236273103161;5901.29184497863;5930.229070220844;3516.630753770817;3840.423936409505;3856.614338566177;3984.980075099606;3987.8205480753436;4006.2988976929505;4040.676745860324;4043.272211001989;4066.1664358806374;4067.3085575452246;3896.8246428910766;4261.978690106592;4641.651876109306;4681.578657920803;4732.290156476556;5213.957208558228;5554.389017208104;5595.796903665731;5656.218291238247;5668.343918172755;1179.9587014454626;1289.278004317585;1314.4150852870573;1345.8990575706862;1348.9055766096278;759.5822297736223;798.8881556582083;809.1867672988726;811.5049819610033;818.7871153500014;3957.722959392815;4348.695191548484;4356.825726970949;4395.956040439554;4396.560343965578;4310.396544483775;4336.403412382884;4528.705634133771;4615.561521655504;4773.451053128865;4353.975401438346;4362.280223763107;4383.758893260838;4397.648188144941;4399.912001759947;2353.3528279722873;2393.0875209046803;2413.2760171948344;2468.839525430841;2534.087728417768;4393.428854248928;4575.725456472623;4873.271946960578;4915.827946021872;4954.777035033432;4154;4283.158241955989;4645.512221216791;4739.3601863748645;4877.780499877514;4148.647364973951;4413.565591182878;4413.933790993137;4638.582527572467;4886.364772579542;4516.88707782308;4731.29030645406;4738.715677059387;4741.857744267678;4788.401449818773;4129.628333450004;4262.254105531458;4275.337322714984;4636.21826903855;4800.135975524439;4609.5019118787195;4723.385959825201;4901.338319326987;5114.360704911886;6131.601445906002;3836.7506112102624;3846.2307538492973;3854.903627409225;3860.2665493556133;3869.1487872667967;5554.305711786027;5754.9136762947755;5808.5643576732755;5813.912791308218;5850.678212698343;4558.452985641744;5521;5561.637398837311;5581.972090139686;5584.274044374205;5151.150060240018;5287.471252874681;5527.8894422110525;5528;5577.85653940933;4415.174288455327;4748.406449193851;5440.183972404171;5453.536449401687;5492.066348720807;4812.8796780080775;4955.45489996093;5319.909418569261;5549.250851135045;5594.580406469449;4358.3898254243795;5181.611379146504;5428.4842939920645;5468.699221542694;5470.72646367672 09:06:47 INFO - PROCESS | 1653 | |5;yui.html;695.5930780493411;731.0751898847958;761.9276168763969;790.3637571754736;810.157436266283;15466.304016319264;16089.195540223001;16107.677846443064;16117.032978021327;16125.677486450264;13983.580492585214;14007.509737159176;14030.929845350758;14052.437902483915;14341;686.4782765098511;707.253847191213;709.9254578269284;838.7693384319331;838.8322335532887;842.49029337251;853.4409961475244;856.3406177243519;856.8115014696788;859.3726579596898;899.8020435504211;900.4732231644496;901.8106197698492;902.251131560803;909.522500687138;714.9785506434815;738.0995185873226;882.5146169606691;892.1524551675901;898.6046139698544;680.7447207297264;680.8502129531495;680.9114815074058;681.322084525895;684.1379861374704;14498.27508624575;14517.20155391453;14533.055351402125;14548.854511454856;14552.054116482395;12835.743285134338;13084.803727944089;13197.73604527904;13262.53581124656;13404.932975335158;12213.206141600856;12556.18384804994;12616.81074783879;12640.810387844189;12954.222746635232;4458.732476051415;4506.729596224237;4552.613027892633;4619.353290539327;4736.147493451177;110146.44926775315;110832;110854;114375.42812285968;115428.422857886;10571.36571805694;10705.46472676363;10719.78560428787;10943.671689849318;11031.282966607145;20888.955552222633;20922.267720629705;20924.163033478795;20935.79064209369;20951.371458856258;7550.093988721389;7778.49439786412;7850;7929.484583502054;7955.801104972422;4066.552679205285;4244.257254980428;4346.869593912188;4477.619402350771;4487.820487180476;3345.2305969626636;3399.915002124917;3460.2387474755515;3464.428369319034;3468.6011108722314;637.5919411576594;645.8353119954381;647.6179054357951;662.2847324889108;665.9900101498481;465.7089319175515;558.1488230448517;560.6383882395832;561.8706400135757;581.8283606336141;8475.49147051179;8562.52906090171;8747.300215982707;8747.343949203836;9002.774930626656;486.6374550959487;535.4244187498455;592.6088781404253;617.3086143519287;621.2606997672755;3954.8022598869943;4012.8996775080855;4152.750834949913;4238.554951730086;4246.5753424657905;106853.46573267084;141812.87280690798;163036.18481907752;165504.17247913685;166068.33931660771;143851;144213;144250;144784.82822757668;145034.8244776329;120234.7976520241;121442.39278803549;121699;123562;124940;8020.358371330396;8020.518768873886;8066.919330806617;8093.635786389745;8351.290140337896;54959.72520137374;56329.71835140798;61880;62747.05879411721;64625.35374646194;85227;86469.56765216133;89723.10276897147;91854;96623;10684.305520141166;12008.579699710468;12881.677958051123;13180.80228796588;13330.066895317235;14652.633684157981;14767.852321476648;14857.925710371379;15097.924510377818;15396.69206615839;13727.588172354845;14065.507707230607;14204.928975355057;14223.715525689635;14286.928565357523;13366.465341386236;13832.654183645489;13852.16886986784;14142.505012324931;14300.570982870531;13390;13806;13806.723865522432;13967.720645587235;14289.28553572338;11079.169062320198;11322.547098115983;11347.20569560123;11352.886471135183;11369.374684392438;8906.05532020932;10942.288751231144;11455.140864435367;11472.369019703996;11489.94255028753 09:06:47 INFO - PROCESS | 1653 | __end_tp_report 09:06:47 INFO - PROCESS | 1653 | __start_cc_report 09:06:47 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,2073 09:06:47 INFO - PROCESS | 1653 | __end_cc_report 09:06:47 INFO - PROCESS | 1653 | __startTimestamp1472746007716__endTimestamp 09:06:47 INFO - PROCESS | 1653 | 09:06:47 INFO - PROCESS | 1653 | ------- Summary: start ------- 09:06:47 INFO - PROCESS | 1653 | Number of tests: 6 09:06:47 INFO - PROCESS | 1653 | 09:06:47 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5746.70 Median:5948.82 stddev:634.16 (10.7%) stddev-sans-first:602.60 09:06:47 INFO - PROCESS | 1653 | Values: 3153.6 3245.6 3286.6 3287.5 3290.3 5617.7 5644.6 5697.7 5724.9 5759.1 5734.7 5780.6 5803.3 5806.0 5812.7 5197.8 5238.4 5251.0 5273.2 5316.8 5311.3 5335.8 5336.0 5338.8 5339.5 5157.6 5189.9 5200.5 5208.2 5330.1 5262.0 5274.1 5287.4 5295.5 5344.1 5119.4 5143.1 5173.3 5198.1 5203.0 5643.9 5673.3 5677.3 5706.4 5732.8 6201.5 6205.3 6208.8 6214.2 6254.0 6116.7 6120.2 6144.7 6154.4 6179.6 5809.2 5863.1 5902.1 5947.5 5948.8 6196.6 6243.6 6248.8 6253.0 6258.6 5927.6 6110.7 6176.4 6193.3 6210.0 6120.3 6136.4 6154.0 6170.4 6194.4 6192.3 6201.7 6221.2 6227.6 6229.4 6101.1 6109.3 6255.3 6264.7 6275.8 4976.6 4987.7 4988.7 4994.0 4994.5 4175.1 4200.8 4476.0 4507.4 4539.9 5667.2 5708.9 5743.3 5745.3 5757.3 6170.2 6232.1 6243.1 6253.5 6259.4 5775.7 5782.9 5813.5 5822.2 5832.5 5447.1 5460.7 5462.4 5463.6 5465.0 6124.2 6163.9 6278.1 6302.3 6303.0 6141.4 6147.8 6165.9 6229.4 6243.8 6223.6 6239.4 6242.2 6247.0 6260.6 6058.9 6081.9 6122.7 6188.5 6198.3 6156.3 6157.2 6208.1 6242.1 6288.3 5965.2 6089.8 6122.7 6132.7 6155.7 5885.5 6120.2 6134.0 6149.6 6155.7 5868.9 5885.8 5948.3 5951.9 5968.3 6099.7 6119.1 6134.1 6136.4 6136.5 5934.7 5943.7 5946.9 5953.6 5959.1 09:06:47 INFO - PROCESS | 1653 | 09:06:47 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:72397.95 Median:37508.44 stddev:162421.19 (433.0%) stddev-sans-first:162787.04 09:06:47 INFO - PROCESS | 1653 | Values: 468.0 473.0 482.6 532.5 549.1 30273.7 40073.0 40907.6 41238.6 43349.1 42709.0 43079.0 43183.8 43249.0 43264.0 8653.8 9596.0 9751.7 9755.0 9773.6 8679.0 8810.8 8829.6 8833.8 11717.4 11766.5 11943.8 12039.0 12055.9 12079.2 11947.8 12014.1 12014.9 12015.8 12022.1 11758.0 11835.2 11837.9 11841.8 11848.6 40247.6 40285.8 40328.0 40367.0 40452.8 3318.0 3529.9 3745.6 3746.5 4014.1 24355.9 31255.5 31432.0 31467.7 31941.0 3194.5 3326.3 3362.9 3393.2 3399.1 478402.0 485020.0 487617.6 488933.0 500287.0 58582.4 58922.2 58971.0 59033.0 59128.4 61985.0 62250.4 62283.4 62353.4 62508.1 16040.5 16273.2 16278.0 16280.4 16308.3 12619.6 12682.4 12688.7 12712.4 12726.2 11602.3 14788.3 15153.5 16316.1 16635.8 6099.5 6143.5 6217.8 6221.8 6223.3 664.5 796.7 915.1 916.6 929.9 27452.9 29350.4 29432.1 29722.3 29747.6 836.4 877.1 966.4 1055.2 1078.0 13262.0 14994.4 15228.5 15257.7 15280.2 638069.0 652549.0 653343.0 653923.0 656067.0 49231.5 56216.0 56833.7 56848.0 57067.4 1188.4 1255.0 1289.8 1309.1 1341.2 26319.6 28931.0 30096.0 30505.4 31362.4 42170.8 55806.2 55910.9 58462.0 58673.7 572232.0 603244.0 635957.0 649613.0 826919.0 23866.0 25151.0 25488.9 25569.9 25666.1 43064.9 43418.6 43537.6 43869.8 44459.6 37445.3 37505.4 37511.4 38426.2 38589.0 36682.6 37208.2 37859.0 37949.2 38408.8 36265.3 36851.6 36929.7 37130.1 37922.2 27468.9 30447.5 32158.0 32201.7 34162.3 24827.5 25106.4 25296.6 25392.0 25591.6 09:06:47 INFO - PROCESS | 1653 | 09:06:47 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:35272.26 Median:357.15 stddev:99996.69 (27998.3%) stddev-sans-first:100242.82 09:06:47 INFO - PROCESS | 1653 | Values: 348.0 366.3 367.1 373.9 396.7 14738.3 15018.8 20646.0 20781.0 21435.2 17098.9 20091.0 20122.2 20524.3 20712.7 4787.6 5338.9 5554.4 5563.4 5574.1 3942.5 4159.5 6139.7 6145.9 6198.8 5848.4 6129.4 6142.4 6148.1 6152.4 5855.4 6124.8 6136.5 6159.1 6202.6 6048.9 6060.3 6061.2 6064.9 6068.9 13237.4 13369.5 23132.7 24837.8 25781.6 2121.3 2181.4 2293.5 2384.0 2402.0 8379.7 9408.5 9739.3 12738.9 14679.6 1829.5 1846.1 1858.8 1865.2 1877.0 119765.0 120706.0 129355.4 132314.3 135591.0 11386.4 11387.7 11429.3 11493.8 11637.7 14459.0 14505.6 14510.1 14517.8 14529.4 8116.8 8439.2 8449.5 8563.1 8578.7 8309.4 8329.5 8330.7 8514.0 8526.7 3956.3 4160.7 4458.7 4473.5 4529.3 1433.8 1590.8 1858.0 1865.5 1930.1 700.9 705.0 866.0 900.7 909.3 20016.7 20492.1 20803.7 20833.8 20940.3 870.9 910.4 933.6 935.4 980.2 7829.7 10633.7 11129.8 11139.5 11176.6 554315.0 599808.0 602123.0 606180.0 615663.0 128144.0 132927.0 134677.0 136304.6 138744.0 936.9 944.4 950.3 955.6 958.2 17875.8 18571.7 18711.0 18724.5 18751.7 53773.6 54254.0 55143.0 56038.0 56481.7 78138.8 79381.0 81346.8 82572.2 82785.0 7112.8 7154.2 7339.3 7386.2 7413.8 11607.7 11859.8 12132.6 12251.4 13616.1 11378.3 11479.9 11676.6 11779.9 11947.5 9130.9 11235.9 11630.7 11732.9 12009.0 10766.6 11108.1 11439.9 11697.9 11990.0 7428.8 7486.9 7524.7 7598.7 7714.2 10404.5 11387.9 11581.0 11734.4 11784.1 09:06:47 INFO - PROCESS | 1653 | 09:06:47 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5689.06 Median:4739.99 stddev:3208.05 (67.7%) stddev-sans-first:3201.83 09:06:47 INFO - PROCESS | 1653 | Values: 1531.1 1541.9 1750.5 1856.0 1915.3 8521.7 9054.8 9063.0 9081.7 9086.8 7915.4 8155.6 8606.9 8824.7 8845.4 2371.0 2420.3 2420.4 2421.5 2424.6 2361.3 2396.5 2411.3 2411.4 2411.7 2066.2 2360.6 2391.6 2396.6 2401.8 2349.6 2362.1 2375.9 2376.4 2379.4 2347.1 2368.4 2369.4 2371.2 2374.3 8811.3 8846.4 8921.9 8926.8 8934.3 4620.3 5260.8 5425.6 5427.2 5427.7 3233.1 3467.7 3583.4 3668.3 3669.5 1087.1 1089.2 1089.8 1098.5 1106.9 11814.9 11876.7 11895.9 11907.4 11934.0 4530.4 4707.8 4809.7 4821.9 4822.3 10081.4 10089.9 10109.9 10117.5 10119.9 5403.5 5404.6 5406.9 5412.8 5419.2 4377.2 4555.5 4754.0 4880.8 4940.4 3875.1 3880.4 3976.6 4029.3 4070.8 1111.5 1198.7 1240.6 1296.9 1313.2 750.0 782.7 815.5 902.8 926.2 4725.9 5927.6 5933.5 6040.4 6165.5 424.5 437.8 441.6 456.5 459.4 3989.3 3993.5 4096.9 4134.8 4166.1 10325.4 10825.9 10838.3 10841.7 10850.2 10549.7 10766.0 10773.2 10781.6 10817.2 8121.9 8162.8 8320.5 8323.3 8326.1 4776.4 5751.8 5911.7 5977.4 6059.3 7341.8 8980.8 9774.9 10219.9 10231.6 7465.4 8958.5 9677.9 10093.4 10110.7 6917.4 7144.9 7355.3 7415.2 7629.2 8256.8 8545.9 8551.1 8554.4 8562.3 7446.1 7584.3 7655.8 7671.8 7709.2 7259.2 7332.1 7378.4 7509.2 7546.6 6964.2 7091.9 7098.4 7100.4 7113.5 4944.8 5020.6 5054.7 5064.5 5161.7 4333.8 4970.1 5077.7 5211.5 5297.0 09:06:47 INFO - PROCESS | 1653 | 09:06:47 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4276.11 Median:4612.53 stddev:1295.79 (28.1%) stddev-sans-first:1264.60 09:06:47 INFO - PROCESS | 1653 | Values: 300.9 302.2 309.1 315.0 315.9 5343.1 5359.5 5414.3 5423.1 5424.6 4902.5 5002.6 5297.2 5299.3 5341.8 3314.4 3816.3 3979.4 4004.8 4034.8 3354.9 4060.2 4061.8 4118.4 4123.2 3348.6 4048.5 4057.8 4088.5 4117.0 3673.6 3775.1 4080.8 4087.5 4095.3 2877.0 3313.9 3381.8 3469.6 3560.4 4910.3 4919.4 4971.8 5179.7 5199.3 4245.5 4646.7 4651.3 4659.8 4674.4 4977.1 5364.2 5519.7 5578.3 5608.6 3875.0 3890.4 3894.2 3894.7 3900.6 5461.5 5490.6 5657.2 5901.3 5930.2 3516.6 3840.4 3856.6 3985.0 3987.8 4006.3 4040.7 4043.3 4066.2 4067.3 3896.8 4262.0 4641.7 4681.6 4732.3 5214.0 5554.4 5595.8 5656.2 5668.3 1180.0 1289.3 1314.4 1345.9 1348.9 759.6 798.9 809.2 811.5 818.8 3957.7 4348.7 4356.8 4396.0 4396.6 4310.4 4336.4 4528.7 4615.6 4773.5 4354.0 4362.3 4383.8 4397.6 4399.9 2353.4 2393.1 2413.3 2468.8 2534.1 4393.4 4575.7 4873.3 4915.8 4954.8 4154.0 4283.2 4645.5 4739.4 4877.8 4148.6 4413.6 4413.9 4638.6 4886.4 4516.9 4731.3 4738.7 4741.9 4788.4 4129.6 4262.3 4275.3 4636.2 4800.1 4609.5 4723.4 4901.3 5114.4 6131.6 3836.8 3846.2 3854.9 3860.3 3869.1 5554.3 5754.9 5808.6 5813.9 5850.7 4558.5 5521.0 5561.6 5582.0 5584.3 5151.2 5287.5 5527.9 5528.0 5577.9 4415.2 4748.4 5440.2 5453.5 5492.1 4812.9 4955.5 5319.9 5549.3 5594.6 4358.4 5181.6 5428.5 5468.7 5470.7 09:06:47 INFO - PROCESS | 1653 | 09:06:47 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:25525.96 Median:3430.08 stddev:41748.02 (1217.1%) stddev-sans-first:41823.51 09:06:47 INFO - PROCESS | 1653 | Values: 695.6 731.1 761.9 790.4 810.2 15466.3 16089.2 16107.7 16117.0 16125.7 13983.6 14007.5 14030.9 14052.4 14341.0 686.5 707.3 709.9 838.8 838.8 842.5 853.4 856.3 856.8 859.4 899.8 900.5 901.8 902.3 909.5 715.0 738.1 882.5 892.2 898.6 680.7 680.9 680.9 681.3 684.1 14498.3 14517.2 14533.1 14548.9 14552.1 12835.7 13084.8 13197.7 13262.5 13404.9 12213.2 12556.2 12616.8 12640.8 12954.2 4458.7 4506.7 4552.6 4619.4 4736.1 110146.4 110832.0 110854.0 114375.4 115428.4 10571.4 10705.5 10719.8 10943.7 11031.3 20889.0 20922.3 20924.2 20935.8 20951.4 7550.1 7778.5 7850.0 7929.5 7955.8 4066.6 4244.3 4346.9 4477.6 4487.8 3345.2 3399.9 3460.2 3464.4 3468.6 637.6 645.8 647.6 662.3 666.0 465.7 558.1 560.6 561.9 581.8 8475.5 8562.5 8747.3 8747.3 9002.8 486.6 535.4 592.6 617.3 621.3 3954.8 4012.9 4152.8 4238.6 4246.6 106853.5 141812.9 163036.2 165504.2 166068.3 143851.0 144213.0 144250.0 144784.8 145034.8 120234.8 121442.4 121699.0 123562.0 124940.0 8020.4 8020.5 8066.9 8093.6 8351.3 54959.7 56329.7 61880.0 62747.1 64625.4 85227.0 86469.6 89723.1 91854.0 96623.0 10684.3 12008.6 12881.7 13180.8 13330.1 14652.6 14767.9 14857.9 15097.9 15396.7 13727.6 14065.5 14204.9 14223.7 14286.9 13366.5 13832.7 13852.2 14142.5 14300.6 13390.0 13806.0 13806.7 13967.7 14289.3 11079.2 11322.5 11347.2 11352.9 11369.4 8906.1 10942.3 11455.1 11472.4 11489.9 09:06:47 INFO - PROCESS | 1653 | -------- Summary: end -------- 09:06:47 INFO - PROCESS | 1653 | 09:06:48 INFO - TEST-INFO | 1653: exit 0 09:06:48 INFO - TEST-OK | dromaeo_css | took 1118787ms 09:06:48 INFO - TEST-START | kraken 09:06:48 INFO - operating with platform_type : mac_ 09:06:48 INFO - Initialising browser for kraken test... 09:06:48 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpSWMmQb/profile http://localhost:49267/getInfo.html) 09:06:50 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 09:06:50 INFO - PROCESS | 1660 | colorDepth:24 09:06:50 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 09:06:50 INFO - PROCESS | 1660 | __metrics 09:06:50 INFO - PROCESS | 1660 | 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:06:50 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:06:51 INFO - TEST-INFO | 1660: exit 0 09:06:51 INFO - Browser initialized. 09:06:51 INFO - Running cycle 1/1 for kraken test... 09:06:51 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpSWMmQb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:07:03 INFO - PROCESS | 1661 | RSS: Main: 234000384 09:07:03 INFO - PROCESS | 1661 | 09:07:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 09:07:07 INFO - PROCESS | 1661 | RSS: Main: 293019648 09:07:07 INFO - PROCESS | 1661 | 09:07:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 09:07:10 INFO - PROCESS | 1661 | RSS: Main: 289153024 09:07:10 INFO - PROCESS | 1661 | 09:07:14 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 09:07:14 INFO - PROCESS | 1661 | RSS: Main: 380751872 09:07:14 INFO - PROCESS | 1661 | 09:07:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 09:07:17 INFO - PROCESS | 1661 | RSS: Main: 282742784 09:07:17 INFO - PROCESS | 1661 | 09:07:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:07:20 INFO - PROCESS | 1661 | RSS: Main: 286011392 09:07:20 INFO - PROCESS | 1661 | 09:07:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 09:07:23 INFO - PROCESS | 1661 | RSS: Main: 324050944 09:07:23 INFO - PROCESS | 1661 | 09:07:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 09:07:26 INFO - PROCESS | 1661 | RSS: Main: 324595712 09:07:26 INFO - PROCESS | 1661 | 09:07:29 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 09:07:30 INFO - PROCESS | 1661 | RSS: Main: 323072000 09:07:30 INFO - PROCESS | 1661 | 09:07:31 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:07:32 INFO - PROCESS | 1661 | RSS: Main: 297480192 09:07:32 INFO - PROCESS | 1661 | 09:07:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:07:35 INFO - PROCESS | 1661 | RSS: Main: 303853568 09:07:35 INFO - PROCESS | 1661 | 09:07:37 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:07:38 INFO - PROCESS | 1661 | RSS: Main: 303960064 09:07:38 INFO - PROCESS | 1661 | 09:07:40 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:07:41 INFO - PROCESS | 1661 | RSS: Main: 319479808 09:07:41 INFO - PROCESS | 1661 | 09:07:44 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:07:45 INFO - PROCESS | 1661 | RSS: Main: 315838464 09:07:45 INFO - PROCESS | 1661 | 09:07:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 09:07:47 INFO - PROCESS | 1661 | RSS: Main: 359202816 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | __start_tp_report 09:07:47 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 09:07:47 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 09:07:47 INFO - PROCESS | 1661 | |i|pagename|runs| 09:07:47 INFO - PROCESS | 1661 | |0;ai-astar;112;112;117;112;114;113;111;112;110;123 09:07:47 INFO - PROCESS | 1661 | |1;audio-beat-detection;173;108;160;198;136;130;130;107;157;107 09:07:47 INFO - PROCESS | 1661 | |2;audio-dft;184;184;185;184;183;184;182;183;181;187 09:07:47 INFO - PROCESS | 1661 | |3;audio-fft;79;73;77;156;60;74;81;84;144;56 09:07:47 INFO - PROCESS | 1661 | |4;audio-oscillator;83;83;133;70;77;82;124;68;73;124 09:07:47 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;91;84;88;84;87;84;88;83;87;84 09:07:47 INFO - PROCESS | 1661 | |6;imaging-darkroom;93;93;92;93;92;93;111;147;92;92 09:07:47 INFO - PROCESS | 1661 | |7;imaging-desaturate;84;84;83;86;85;86;86;82;86;84 09:07:47 INFO - PROCESS | 1661 | |8;json-parse-financial;54;53;54;54;54;53;55;53;52;53 09:07:47 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;67;69;76;78;73;71;74;73;70;71 09:07:47 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;88;83;85;84;85;87;86;89;86;85 09:07:47 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;137;162;157;155;126;153;149;148;185;138 09:07:47 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;169;170;170;169;169;175;231;170;170;169 09:07:47 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;68;80;79;73;68;67;68;68;71;68 09:07:47 INFO - PROCESS | 1661 | __end_tp_report 09:07:47 INFO - PROCESS | 1661 | __start_cc_report 09:07:47 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2004 09:07:47 INFO - PROCESS | 1661 | __end_cc_report 09:07:47 INFO - PROCESS | 1661 | __startTimestamp1472746067183__endTimestamp 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | ------- Summary: start ------- 09:07:47 INFO - PROCESS | 1661 | Number of tests: 14 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:113.60 Median:112.50 stddev:3.81 (3.4%) stddev-sans-first:3.99 09:07:47 INFO - PROCESS | 1661 | Values: 112.0 112.0 117.0 112.0 114.0 113.0 111.0 112.0 110.0 123.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:146.50 stddev:30.83 (21.0%) stddev-sans-first:30.39 09:07:47 INFO - PROCESS | 1661 | Values: 173.0 108.0 160.0 198.0 136.0 130.0 130.0 107.0 157.0 107.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.64 (0.9%) stddev-sans-first:1.73 09:07:47 INFO - PROCESS | 1661 | Values: 184.0 184.0 185.0 184.0 183.0 184.0 182.0 183.0 181.0 187.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:88.40 Median:75.50 stddev:33.75 (44.7%) stddev-sans-first:35.63 09:07:47 INFO - PROCESS | 1661 | Values: 79.0 73.0 77.0 156.0 60.0 74.0 81.0 84.0 144.0 56.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:75.00 stddev:25.02 (33.4%) stddev-sans-first:26.34 09:07:47 INFO - PROCESS | 1661 | Values: 83.0 83.0 133.0 70.0 77.0 82.0 124.0 68.0 73.0 124.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:2.58 (3.0%) stddev-sans-first:2.01 09:07:47 INFO - PROCESS | 1661 | Values: 91.0 84.0 88.0 84.0 87.0 84.0 88.0 83.0 87.0 84.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:99.80 Median:92.50 stddev:17.58 (19.0%) stddev-sans-first:18.47 09:07:47 INFO - PROCESS | 1661 | Values: 93.0 93.0 92.0 93.0 92.0 93.0 111.0 147.0 92.0 92.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.50 stddev:1.43 (1.7%) stddev-sans-first:1.50 09:07:47 INFO - PROCESS | 1661 | Values: 84.0 84.0 83.0 86.0 85.0 86.0 86.0 82.0 86.0 84.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:0.85 (1.6%) stddev-sans-first:0.88 09:07:47 INFO - PROCESS | 1661 | Values: 54.0 53.0 54.0 54.0 54.0 53.0 55.0 53.0 52.0 53.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:73.00 stddev:3.29 (4.5%) stddev-sans-first:2.91 09:07:47 INFO - PROCESS | 1661 | Values: 67.0 69.0 76.0 78.0 73.0 71.0 74.0 73.0 70.0 71.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:86.00 stddev:1.81 (2.1%) stddev-sans-first:1.74 09:07:47 INFO - PROCESS | 1661 | Values: 88.0 83.0 85.0 84.0 85.0 87.0 86.0 89.0 86.0 85.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:151.00 Median:154.00 stddev:16.11 (10.5%) stddev-sans-first:16.27 09:07:47 INFO - PROCESS | 1661 | Values: 137.0 162.0 157.0 155.0 126.0 153.0 149.0 148.0 185.0 138.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:170.00 stddev:19.34 (11.4%) stddev-sans-first:20.33 09:07:47 INFO - PROCESS | 1661 | Values: 169.0 170.0 170.0 169.0 169.0 175.0 231.0 170.0 170.0 169.0 09:07:47 INFO - PROCESS | 1661 | 09:07:47 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:69.50 stddev:4.83 (7.0%) stddev-sans-first:5.00 09:07:47 INFO - PROCESS | 1661 | Values: 68.0 80.0 79.0 73.0 68.0 67.0 68.0 68.0 71.0 68.0 09:07:47 INFO - PROCESS | 1661 | -------- Summary: end -------- 09:07:47 INFO - PROCESS | 1661 | 09:07:48 INFO - TEST-INFO | 1661: exit 0 09:07:48 INFO - TEST-OK | kraken | took 59652ms 09:07:48 INFO - SUITE-END | took 1178s 09:07:48 INFO - Completed test suite (00:19:38) 09:07:48 INFO - javascript benchmark 09:07:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3153.6373317068537, 3245.5780748502693, 3286.622038465577, 3287.539744435781, 3290.2596915693966, 5617.662940223589, 5644.576656750744, 5697.715114244292, 5724.885502289952, 5759.136129580565, 5734.713264336777, 5780.595358324919, 5803.303603567567, 5805.970970145143, 5812.651240925547, 5197.7920883164725, 5238.4499627539135, 5250.973745131269, 5273.235380869772, 5316.813911513098, 5311.336082989626, 5335.759890804923, 5335.973320133394, 5338.839834804962, 5339.546138578206, 5157.613179011589, 5189.87025324368, 5200.505951934579, 5208.192730126836, 5330.067238233293, 5261.973690131525, 5274.129768588178, 5287.444818294062, 5295.523402893719, 5344.118220493614, 5119.385673719177, 5143.074246635604, 5173.32746742921, 5198.064348417283, 5202.973985130088, 5643.887122257573, 5673.26247587815, 5677.347105082927, 5706.3722990471015, 5732.799352022659, 6201.5348848836065, 6205.255369355661, 6208.844778880519, 6214.161088253073, 6253.96873015632, 6116.701002199459, 6120.233815636133, 6144.662043587601, 6154.427221810825, 6179.5674302798825, 5809.157672137559, 5863.149843272787, 5902.1441890926435, 5947.464728174485, 5948.821535353946, 6196.628202307786, 6243.625382476975, 6248.781292654735, 6252.999520076766, 6258.561900666909, 5927.644341339447, 6110.725017374229, 6176.444120029129, 6193.256809182837, 6209.96895015522, 6120.325235636462, 6136.447719705248, 6153.953827849186, 6170.444659980623, 6194.38056194377, 6192.349803270682, 6201.720922558496, 6221.160143380676, 6227.595206311666, 6229.408206220402, 6101.115338275925, 6109.297430795426, 6255.343188965184, 6264.68676566179, 6275.811725648146, 4976.576990955809, 4987.700737955734, 4988.725620090857, 4993.950060499421, 4994.525520075587, 4175.144095460423, 4200.781773285713, 4476.037651904856, 4507.414036175276, 4539.931901021488, 5667.206591077253, 5708.942910570841, 5743.339515955635, 5745.2818397700285, 5757.337906140764, 6170.228721409824, 6232.065190221503, 6243.094751261042, 6253.499720022387, 6259.405356491126, 5775.6823374714795, 5782.855428614234, 5813.534917206698, 5822.214001109796, 5832.504237139806, 5447.12845944647, 5460.726963651881, 5462.426445223195, 5463.590230732711, 5465.043617367024, 6124.167343420691, 6163.859685958112, 6278.121063051177, 6302.275238347557, 6303.023031430227, 6141.355157708376, 6147.84630384244, 6165.876682466415, 6229.408206220402, 6243.750249989949, 6223.564350495421, 6239.376062393724, 6242.219722534684, 6246.968765156145, 6260.561760676709, 6058.939410605837, 6081.939180608138, 6122.693865306628, 6188.504919606511, 6198.349173336735, 6156.29202641702, 6157.168782214523, 6208.130861679459, 6242.063690446288, 6288.339724328879, 5965.164876917322, 6089.786857459968, 6122.682821117092, 6132.6627035513475, 6155.6614386209185, 5885.47030767233, 6120.233815636221, 6134.04922237054, 6149.569530133027, 6155.722992465439, 5868.853278668067, 5885.823425297248, 5948.2862056553495, 5951.851203719769, 5968.343482217039, 6099.703252314951, 6119.143319935123, 6134.110553969738, 6136.447719705069, 6136.5090792737365, 5934.703264836828, 5943.673097979653, 5946.940530594638, 5953.583249172516, 5959.0763431668165], "value": 5704.278907775115, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.9555442232988, 472.95743383095515, 482.5777444735855, 532.472851876642, 549.0830313376661, 30273.697263027363, 40072.998175045635, 40907.590924090786, 41238.58761412385, 43349.133017339715, 42709.0, 43079.0, 43183.78408107955, 43249.0, 43264.0, 8653.783655408633, 9595.952020239907, 9751.707448776548, 9755.02449755026, 9773.609055637766, 8678.956605216965, 8810.779730506756, 8829.587266037435, 8833.779155521099, 11717.355545444998, 11766.529338826436, 11943.761124777498, 12039.0, 12055.939720301387, 12079.214851034698, 11947.7610447791, 12014.098942579254, 12014.939925300405, 12015.759684806255, 12022.098342624251, 11758.0, 11835.230710003822, 11837.881621183764, 11841.822372664417, 11848.644540663794, 40247.59752402497, 40285.79142625726, 40328.0, 40367.0, 40452.797736011424, 3317.983410082934, 3529.876454324112, 3745.5879853216125, 3746.5316835395574, 4014.136960553495, 24355.87822060896, 31255.531167032514, 31432.0, 31467.685323146932, 31940.96560826741, 3194.5048517479827, 3326.334733053399, 3362.899113026613, 3393.2025973896116, 3399.065257054305, 478402.0, 485020.0, 487617.56191219174, 488933.0, 500287.0, 58582.414175857695, 58922.2239442461, 58971.0, 59033.0, 59128.408715912294, 61985.0, 62250.37749622446, 62283.37716622867, 62353.37646623566, 62508.06173413518, 16040.518784436486, 16273.186340683154, 16278.0, 16280.43018494347, 16308.347666093223, 12619.558315458915, 12682.429290681941, 12688.74622507563, 12712.42794074269, 12726.23642581448, 11602.273465735545, 14788.264171128376, 15153.469628562947, 16316.102614356325, 16635.750463743054, 6099.4815440688035, 6143.508519318533, 6217.813465596039, 6221.791264101179, 6223.346548612421, 664.4949838122965, 796.717165406284, 915.1443400420628, 916.6287653500343, 929.869818225449, 27452.862735686595, 29350.355542932055, 29432.11703648894, 29722.25694357615, 29747.553786693217, 836.3727204596553, 877.1360210192955, 966.40083148447, 1055.171690223171, 1078.0274687775573, 13262.0, 14994.400223991137, 15228.46700365482, 15257.684561913784, 15280.235988200546, 638069.0, 652549.0, 653343.0, 653923.0, 656067.0, 49231.50768492341, 56216.0, 56833.7158314214, 56848.0, 57067.42932570621, 1188.364225139546, 1255.0085432508217, 1289.7742894993526, 1309.0901823232953, 1341.161773891318, 26319.60520592231, 28931.0, 30096.0, 30505.389892202475, 31362.372752545278, 42170.78914605407, 55806.162907557235, 55910.88178236494, 58462.0, 58673.706631468274, 572232.0, 603244.0, 635957.0, 649613.0, 826919.0, 23866.0, 25151.0, 25488.872555637103, 25569.872150639127, 25666.101686440885, 43064.92337691457, 43418.565814341455, 43537.56462435462, 43869.78065109654, 44459.55540444684, 37445.25109497849, 37505.437418439295, 37511.43732843955, 38426.23147536978, 38589.0, 36682.63317366899, 37208.190106111826, 37859.0, 37949.241015180094, 38408.807955960045, 36265.27469450649, 36851.63148368482, 36929.707460239835, 37130.07174820651, 37922.24155516819, 27468.862655686593, 30447.54328685116, 32158.03525894227, 32201.677983220805, 34162.31675366429, 24827.50344993126, 25106.37234069163, 25296.62055069212, 25392.0, 25591.616125757755], "value": 19827.10819392364, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.01338206185466, 366.29305440499843, 367.13722751533896, 373.9401695728683, 396.6747267240864, 14738.263086845654, 15018.849811501881, 20645.967701614896, 20781.0, 21435.249766258185, 17098.914505427456, 20090.995450227503, 20122.195112195495, 20524.28165014225, 20712.689309660367, 4787.616990640758, 5338.946610533903, 5554.444555544454, 5563.443655634424, 5574.080276754351, 3942.4677668514814, 4159.542450330461, 6139.723712432951, 6145.907811382833, 6198.81403557894, 5848.41515848416, 6129.387061293857, 6142.385761423844, 6148.1392605035335, 6152.3847615238565, 5855.355910849803, 6124.846878828021, 6136.478399336062, 6159.137720719103, 6202.565820392574, 6048.9092663609645, 6060.30306514752, 6061.181740465025, 6064.939350606525, 6068.878622427571, 13237.40431680567, 13369.53206637763, 23132.65301020486, 24837.751622483906, 25781.61327580088, 2121.3105740634355, 2181.3892110209167, 2293.472501324688, 2383.9868056076098, 2401.951960960771, 8379.664813407457, 9408.529573521297, 9739.26955478342, 12738.936305318506, 14679.55961321162, 1829.5060333709957, 1846.0527429416356, 1858.7676540432446, 1865.1699993502762, 1876.9906150469296, 119765.0, 120706.0, 129355.35322323423, 132314.3384283082, 135590.96613550806, 11386.373749443694, 11387.658370248906, 11429.314241145557, 11493.827592586118, 11637.650870473899, 14459.0, 14505.564833055025, 14510.1293922365, 14517.838572914141, 14529.418823247162, 8116.837663246702, 8439.240468357877, 8449.493030418194, 8563.100874408223, 8578.699745509002, 8309.37679674027, 8329.458585191944, 8330.708425205088, 8514.0, 8526.74419767408, 3956.3076461619103, 4160.667146628262, 4458.710183838105, 4473.485549161888, 4529.32060190974, 1433.792100145494, 1590.83296253894, 1857.9814201858078, 1865.4722850537598, 1930.1025023364202, 700.8598280343952, 704.9506534542635, 865.9653613855376, 900.7432881628836, 909.3452714045876, 20016.69974950377, 20492.077856496493, 20803.68794468084, 20833.791662083186, 20940.267090651752, 870.9303255739652, 910.3581974707815, 933.5518950903604, 935.4106912644991, 980.1570649241646, 7829.7259595913865, 10633.73415664599, 11129.777404451866, 11139.498722557342, 11176.552937882556, 554315.0, 599808.0, 602123.0, 606180.0, 615663.0, 128144.0, 132927.0, 134677.0, 136304.6369536292, 138744.0, 936.8922573903952, 944.3719926248967, 950.339514037751, 955.5938726041404, 958.1999030809213, 17875.821241787417, 18571.721424178377, 18711.0, 18724.531886702942, 18751.71872421942, 53773.58018889081, 54254.0, 55143.0, 56038.0, 56481.71759141342, 78138.82791758014, 79381.0, 81346.77979830188, 82572.17427825645, 82785.0, 7112.822179445348, 7154.177269614061, 7339.2660733928315, 7386.2244464332625, 7413.8146546336775, 11607.6517704469, 11859.754725753672, 12132.602871196079, 12251.448684809027, 13616.114952528054, 11378.317300961638, 11479.942600286946, 11676.649700508999, 11779.941100294443, 11947.462364193805, 9130.949940756898, 11235.943820280847, 11630.70923226926, 11732.94133529327, 12009.039276857655, 10766.569337226423, 11108.111351092055, 11439.942800285946, 11697.941510292394, 11990.0, 7428.848528477878, 7486.887696684663, 7524.699012039458, 7598.696052157852, 7714.190010048864, 10404.531796069045, 11387.943060284977, 11581.015613672933, 11734.354610496162, 11784.057275418118], "value": 9394.63254337599, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1531.1119550660617, 1541.9151946642933, 1750.5186073829695, 1856.0233351480663, 1915.2530513099894, 8521.701740439086, 9054.773630659229, 9063.0, 9081.727548173565, 9086.772830679236, 7915.406344524169, 8155.592220388971, 8606.87089693655, 8824.735257942257, 8845.425047371933, 2370.9881450592766, 2420.298113547069, 2420.4432980414517, 2421.503591763684, 2424.5878200705924, 2361.2798096580536, 2396.4847557775074, 2411.3489357873364, 2411.445367565461, 2411.67442395277, 2066.1941842681367, 2360.6104992676187, 2391.5814732421845, 2396.592579261521, 2401.7838394544488, 2349.6475528670835, 2362.14962613459, 2375.857448553092, 2376.4415362389823, 2379.4289370551114, 2347.119830063726, 2368.4434157972864, 2369.4313364792497, 2371.1937941099936, 2374.25211058516, 8811.251043661296, 8846.380753347268, 8921.866172007425, 8926.821463570692, 8934.28525717941, 4620.330052142421, 5260.815871444481, 5425.6202065855405, 5427.185922111676, 5427.674339539641, 3233.143217047484, 3467.687908088259, 3583.4087375583, 3668.2663467306647, 3669.504616876741, 1087.1140020882956, 1089.2429761315889, 1089.809283375414, 1098.549594666183, 1106.878243393226, 11814.881851181464, 11876.703082422922, 11895.940520297343, 11907.404629768476, 11934.0, 4530.388397566287, 4707.7881495332795, 4809.663323567387, 4821.9035619287415, 4822.252550854553, 10081.445520496296, 10089.890112087665, 10109.898901010896, 10117.494125293706, 10119.898801011896, 5403.486668766461, 5404.567634589223, 5406.864828379322, 5412.78348866041, 5419.241306217133, 4377.1683380157665, 4555.476120246214, 4754.049190161982, 4880.829170978998, 4940.357753492095, 3875.1474675571894, 3880.4373365861793, 3976.622220889011, 4029.315016447211, 4070.8371665133654, 1111.527600769686, 1198.6523908066738, 1240.5720026590993, 1296.9286689231994, 1313.159577870164, 749.9625018749041, 782.7260458839361, 815.4658698552457, 902.7652810269377, 926.1757036237753, 4725.929111063337, 5927.645809767661, 5933.525317974551, 6040.36576159497, 6165.506759459233, 424.54573606241024, 437.8183054032541, 441.6180004296325, 456.51609294148307, 459.36607481675077, 3989.341758609855, 3993.5407428146136, 4096.856610018635, 4134.793260337031, 4166.0834616384345, 10325.432101234353, 10825.891741082645, 10838.295510791932, 10841.67474975752, 10850.240483166102, 10549.683509494727, 10765.977232162932, 10773.192010599236, 10781.568737250422, 10817.242793004572, 8121.878171827428, 8162.795930101676, 8320.542370169578, 8323.250907418362, 8326.084130745614, 4776.355192049029, 5751.798687046101, 5911.704414779158, 5977.402259773988, 6059.272887253382, 7341.834158923024, 8980.820383592421, 9774.902250977399, 10219.897801021894, 10231.641892533726, 7465.4027677786735, 8958.462492250486, 9677.9032209677, 10093.394396335949, 10110.747231319276, 6917.446604271747, 7144.892826607709, 7355.338019578264, 7415.184329723618, 7629.16079231273, 8256.752297431087, 8545.871811922701, 8551.1448855114, 8554.358423118167, 8562.272206862486, 7446.14369347532, 7584.317411432998, 7655.846883062196, 7671.77251639735, 7709.190534993745, 7259.20148783644, 7332.120145582564, 7378.372838308804, 7509.211532788979, 7546.622668866645, 6964.164300283998, 7091.858162836817, 7098.36114749675, 7100.4319654428555, 7113.53762005468, 4944.826931057396, 5020.623453240948, 5054.671446355975, 5064.4935506449065, 5161.690298582097, 4333.848315308949, 4970.105381031449, 5077.720725359993, 5211.452797456364, 5296.973515132399], "value": 4495.403453936626, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.86010005347515, 302.1765688498841, 309.10359956127223, 314.99212519687006, 315.89575440104767, 5343.091674415349, 5359.464053594638, 5414.296141501599, 5423.105187644039, 5424.647397919138, 4902.450725622674, 5002.649814512985, 5297.205419187124, 5299.258103865462, 5341.809857958238, 3314.436545787222, 3816.274907767522, 3979.4428779970826, 4004.7797371144575, 4034.7982600869987, 3354.9161270968298, 4060.2488539620117, 4061.776602286873, 4118.382242663594, 4123.154753275587, 3348.5814273215847, 4048.4939382577177, 4057.8173982170874, 4088.5298190708154, 4116.9707573106725, 3673.559172899256, 3775.1313100015395, 4080.8163632636306, 4087.4890638670167, 4095.344744840826, 2876.964292854571, 3313.8508767105536, 3381.847816848248, 3469.600995885455, 3560.394732895414, 4910.312556242129, 4919.352017074284, 4971.825986090469, 5179.663321884065, 5199.324087868553, 4245.490541135084, 4646.651501137429, 4651.279051746985, 4659.79030943605, 4674.439067311911, 4977.129002424561, 5364.195370694427, 5519.69641669708, 5578.30271216098, 5608.579356548275, 3875.0, 3890.3580909149955, 3894.2211557688574, 3894.7273690841653, 3900.609939006105, 5461.453854614547, 5490.56075513958, 5657.236273103161, 5901.29184497863, 5930.229070220844, 3516.630753770817, 3840.423936409505, 3856.614338566177, 3984.980075099606, 3987.8205480753436, 4006.2988976929505, 4040.676745860324, 4043.272211001989, 4066.1664358806374, 4067.3085575452246, 3896.8246428910766, 4261.978690106592, 4641.651876109306, 4681.578657920803, 4732.290156476556, 5213.957208558228, 5554.389017208104, 5595.796903665731, 5656.218291238247, 5668.343918172755, 1179.9587014454626, 1289.278004317585, 1314.4150852870573, 1345.8990575706862, 1348.9055766096278, 759.5822297736223, 798.8881556582083, 809.1867672988726, 811.5049819610033, 818.7871153500014, 3957.722959392815, 4348.695191548484, 4356.825726970949, 4395.956040439554, 4396.560343965578, 4310.396544483775, 4336.403412382884, 4528.705634133771, 4615.561521655504, 4773.451053128865, 4353.975401438346, 4362.280223763107, 4383.758893260838, 4397.648188144941, 4399.912001759947, 2353.3528279722873, 2393.0875209046803, 2413.2760171948344, 2468.839525430841, 2534.087728417768, 4393.428854248928, 4575.725456472623, 4873.271946960578, 4915.827946021872, 4954.777035033432, 4154.0, 4283.158241955989, 4645.512221216791, 4739.3601863748645, 4877.780499877514, 4148.647364973951, 4413.565591182878, 4413.933790993137, 4638.582527572467, 4886.364772579542, 4516.88707782308, 4731.29030645406, 4738.715677059387, 4741.857744267678, 4788.401449818773, 4129.628333450004, 4262.254105531458, 4275.337322714984, 4636.21826903855, 4800.135975524439, 4609.5019118787195, 4723.385959825201, 4901.338319326987, 5114.360704911886, 6131.601445906002, 3836.7506112102624, 3846.2307538492973, 3854.903627409225, 3860.2665493556133, 3869.1487872667967, 5554.305711786027, 5754.9136762947755, 5808.5643576732755, 5813.912791308218, 5850.678212698343, 4558.452985641744, 5521.0, 5561.637398837311, 5581.972090139686, 5584.274044374205, 5151.150060240018, 5287.471252874681, 5527.8894422110525, 5528.0, 5577.85653940933, 4415.174288455327, 4748.406449193851, 5440.183972404171, 5453.536449401687, 5492.066348720807, 4812.8796780080775, 4955.45489996093, 5319.909418569261, 5549.250851135045, 5594.580406469449, 4358.3898254243795, 5181.611379146504, 5428.4842939920645, 5468.699221542694, 5470.72646367672], "value": 3866.2187728388353, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.5930780493411, 731.0751898847958, 761.9276168763969, 790.3637571754736, 810.157436266283, 15466.304016319264, 16089.195540223001, 16107.677846443064, 16117.032978021327, 16125.677486450264, 13983.580492585214, 14007.509737159176, 14030.929845350758, 14052.437902483915, 14341.0, 686.4782765098511, 707.253847191213, 709.9254578269284, 838.7693384319331, 838.8322335532887, 842.49029337251, 853.4409961475244, 856.3406177243519, 856.8115014696788, 859.3726579596898, 899.8020435504211, 900.4732231644496, 901.8106197698492, 902.251131560803, 909.522500687138, 714.9785506434815, 738.0995185873226, 882.5146169606691, 892.1524551675901, 898.6046139698544, 680.7447207297264, 680.8502129531495, 680.9114815074058, 681.322084525895, 684.1379861374704, 14498.27508624575, 14517.20155391453, 14533.055351402125, 14548.854511454856, 14552.054116482395, 12835.743285134338, 13084.803727944089, 13197.73604527904, 13262.53581124656, 13404.932975335158, 12213.206141600856, 12556.18384804994, 12616.81074783879, 12640.810387844189, 12954.222746635232, 4458.732476051415, 4506.729596224237, 4552.613027892633, 4619.353290539327, 4736.147493451177, 110146.44926775315, 110832.0, 110854.0, 114375.42812285968, 115428.422857886, 10571.36571805694, 10705.46472676363, 10719.78560428787, 10943.671689849318, 11031.282966607145, 20888.955552222633, 20922.267720629705, 20924.163033478795, 20935.79064209369, 20951.371458856258, 7550.093988721389, 7778.49439786412, 7850.0, 7929.484583502054, 7955.801104972422, 4066.552679205285, 4244.257254980428, 4346.869593912188, 4477.619402350771, 4487.820487180476, 3345.2305969626636, 3399.915002124917, 3460.2387474755515, 3464.428369319034, 3468.6011108722314, 637.5919411576594, 645.8353119954381, 647.6179054357951, 662.2847324889108, 665.9900101498481, 465.7089319175515, 558.1488230448517, 560.6383882395832, 561.8706400135757, 581.8283606336141, 8475.49147051179, 8562.52906090171, 8747.300215982707, 8747.343949203836, 9002.774930626656, 486.6374550959487, 535.4244187498455, 592.6088781404253, 617.3086143519287, 621.2606997672755, 3954.8022598869943, 4012.8996775080855, 4152.750834949913, 4238.554951730086, 4246.5753424657905, 106853.46573267084, 141812.87280690798, 163036.18481907752, 165504.17247913685, 166068.33931660771, 143851.0, 144213.0, 144250.0, 144784.82822757668, 145034.8244776329, 120234.7976520241, 121442.39278803549, 121699.0, 123562.0, 124940.0, 8020.358371330396, 8020.518768873886, 8066.919330806617, 8093.635786389745, 8351.290140337896, 54959.72520137374, 56329.71835140798, 61880.0, 62747.05879411721, 64625.35374646194, 85227.0, 86469.56765216133, 89723.10276897147, 91854.0, 96623.0, 10684.305520141166, 12008.579699710468, 12881.677958051123, 13180.80228796588, 13330.066895317235, 14652.633684157981, 14767.852321476648, 14857.925710371379, 15097.924510377818, 15396.69206615839, 13727.588172354845, 14065.507707230607, 14204.928975355057, 14223.715525689635, 14286.928565357523, 13366.465341386236, 13832.654183645489, 13852.16886986784, 14142.505012324931, 14300.570982870531, 13390.0, 13806.0, 13806.723865522432, 13967.720645587235, 14289.28553572338, 11079.169062320198, 11322.547098115983, 11347.20569560123, 11352.886471135183, 11369.374684392438, 8906.05532020932, 10942.288751231144, 11455.140864435367, 11472.369019703996, 11489.94255028753], "value": 7731.334775328292, "unit": "score"}], "name": "dromaeo_css", "value": 7229.635588682302, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 117.0, 112.0, 114.0, 113.0, 111.0, 112.0, 110.0, 123.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 108.0, 160.0, 198.0, 136.0, 130.0, 130.0, 107.0, 157.0, 107.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 185.0, 184.0, 183.0, 184.0, 182.0, 183.0, 181.0, 187.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 77.0, 156.0, 60.0, 74.0, 81.0, 84.0, 144.0, 56.0], "value": 88.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 133.0, 70.0, 77.0, 82.0, 124.0, 68.0, 73.0, 124.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 84.0, 88.0, 84.0, 87.0, 84.0, 88.0, 83.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 93.0, 92.0, 93.0, 111.0, 147.0, 92.0, 92.0], "value": 99.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 86.0, 85.0, 86.0, 86.0, 82.0, 86.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 54.0, 54.0, 53.0, 55.0, 53.0, 52.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 76.0, 78.0, 73.0, 71.0, 74.0, 73.0, 70.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 85.0, 84.0, 85.0, 87.0, 86.0, 89.0, 86.0, 85.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 162.0, 157.0, 155.0, 126.0, 153.0, 149.0, 148.0, 185.0, 138.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 170.0, 169.0, 169.0, 175.0, 231.0, 170.0, 170.0, 169.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 80.0, 79.0, 73.0, 68.0, 67.0, 68.0, 68.0, 71.0, 68.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1498.1000000000001, "alertThreshold": 2.0}]} 09:07:48 INFO - Return code: 0 09:07:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:07:48 INFO - # TBPL SUCCESS # 09:07:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:07:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:07:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:07:48 INFO - 2016-09-01 09:07:48.534 screenresolution[1665:9916] starting screenresolution argv=screenresolution get 09:07:48 INFO - 2016-09-01 09:07:48.537 screenresolution[1665:9916] Display 0: 1600x1200x32@60 09:07:48 INFO - 2016-09-01 09:07:48.543 screenresolution[1666:9919] starting screenresolution argv=screenresolution list 09:07:48 INFO - Available Modes on Display 0 09:07:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:07:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:07:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:07:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:07:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:07:48 INFO - Intel Iris: 09:07:48 INFO - Chipset Model: Intel Iris 09:07:48 INFO - Type: GPU 09:07:48 INFO - Bus: Built-In 09:07:48 INFO - VRAM (Dynamic, Max): 1536 MB 09:07:48 INFO - Vendor: Intel (0x8086) 09:07:48 INFO - Device ID: 0x0a2e 09:07:48 INFO - Revision ID: 0x0009 09:07:48 INFO - Displays: 09:07:48 INFO - Display: 09:07:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:07:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:07:48 INFO - Display Serial Number: 1600x1200 60 09:07:48 INFO - Main Display: Yes 09:07:48 INFO - Mirror: Off 09:07:48 INFO - Online: Yes 09:07:48 INFO - Rotation: Supported 09:07:48 INFO - Return code: 0 09:07:48 INFO - Running post-action listener: _resource_record_post_action 09:07:48 INFO - [mozharness: 2016-09-01 16:07:48.776951Z] Finished run-tests step (success) 09:07:48 INFO - Running post-run listener: _resource_record_post_run 09:07:48 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37118976; Write bytes: 705209344; Read time: 247; Write time: 1988 09:07:48 INFO - TinderboxPrint: CPU usage
53.8% 09:07:48 INFO - TinderboxPrint: I/O read bytes / time
37,118,976 / 247 09:07:48 INFO - TinderboxPrint: I/O write bytes / time
705,209,344 / 1,988 09:07:48 INFO - TinderboxPrint: CPU idle
2,241.7 (46.2%) 09:07:48 INFO - TinderboxPrint: CPU system
967.8 (19.9%) 09:07:48 INFO - TinderboxPrint: CPU user
1,644.1 (33.9%) 09:07:48 INFO - TinderboxPrint: Swap in / out
443,043,840 / 0 09:07:48 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 402057728; Write bytes: 391173632; Read time: 29730; Write time: 823 09:07:48 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29850624; Write bytes: 295341568; Read time: 177; Write time: 1119 09:07:49 INFO - Running post-run listener: _upload_blobber_files 09:07:49 INFO - Blob upload gear active. 09:07:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:07:49 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:07:49 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:07:49 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:07:49 INFO - (blobuploader) - INFO - Open directory for files ... 09:07:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:07:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:07:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:50 INFO - (blobuploader) - INFO - Done attempting. 09:07:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:07:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:07:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:52 INFO - (blobuploader) - INFO - Done attempting. 09:07:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:07:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:07:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:54 INFO - (blobuploader) - INFO - Done attempting. 09:07:54 INFO - (blobuploader) - INFO - Iteration through files over. 09:07:54 INFO - Return code: 0 09:07:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:07:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:07:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9efff83709c2d1bbe4aae0ec0bdfae98dee3de5a2a40bdce80faeded2679546bf48bdbf3525691b980549180182794da776bd31efb94d4c0c723c393ab1d4bba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b86522cff3178ebd0add385fba546efc57d0d753f05aa990da8ea8fbb9b4542ece55bfee792f38ebfdc02357ac82da385c2a90497f7c0ba8d01b46a58e4ae97e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7142d2ca177f0e743ce0d39d93f595aa08dd7d820fbd5b6eb3135c740dd9aad8426dd1c023c56e865340753fb30f5ef2351dd5edb7ec158d279aa42382ca8800"} 09:07:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:07:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:07:54 INFO - Contents: 09:07:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9efff83709c2d1bbe4aae0ec0bdfae98dee3de5a2a40bdce80faeded2679546bf48bdbf3525691b980549180182794da776bd31efb94d4c0c723c393ab1d4bba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b86522cff3178ebd0add385fba546efc57d0d753f05aa990da8ea8fbb9b4542ece55bfee792f38ebfdc02357ac82da385c2a90497f7c0ba8d01b46a58e4ae97e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7142d2ca177f0e743ce0d39d93f595aa08dd7d820fbd5b6eb3135c740dd9aad8426dd1c023c56e865340753fb30f5ef2351dd5edb7ec158d279aa42382ca8800"} 09:07:54 INFO - Running post-run listener: copy_logs_to_upload_dir 09:07:54 INFO - Copying logs to upload dir... 09:07:54 INFO - mkdir: /builds/slave/test/build/upload/logs 09:07:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.155408 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-01 09:07:54.692928) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 09:07:54.696866) ========= 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.YVUuhNCH1M/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.2zRojsjxOV/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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/Autoland/sha512/9efff83709c2d1bbe4aae0ec0bdfae98dee3de5a2a40bdce80faeded2679546bf48bdbf3525691b980549180182794da776bd31efb94d4c0c723c393ab1d4bba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b86522cff3178ebd0add385fba546efc57d0d753f05aa990da8ea8fbb9b4542ece55bfee792f38ebfdc02357ac82da385c2a90497f7c0ba8d01b46a58e4ae97e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7142d2ca177f0e743ce0d39d93f595aa08dd7d820fbd5b6eb3135c740dd9aad8426dd1c023c56e865340753fb30f5ef2351dd5edb7ec158d279aa42382ca8800"} build_url:https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011056 build_url: 'https://queue.taskcluster.net/v1/task/dYd8H8nASpGzTEcV1rm8aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9efff83709c2d1bbe4aae0ec0bdfae98dee3de5a2a40bdce80faeded2679546bf48bdbf3525691b980549180182794da776bd31efb94d4c0c723c393ab1d4bba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b86522cff3178ebd0add385fba546efc57d0d753f05aa990da8ea8fbb9b4542ece55bfee792f38ebfdc02357ac82da385c2a90497f7c0ba8d01b46a58e4ae97e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7142d2ca177f0e743ce0d39d93f595aa08dd7d820fbd5b6eb3135c740dd9aad8426dd1c023c56e865340753fb30f5ef2351dd5edb7ec158d279aa42382ca8800"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 09:07:54.730906) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 09:07:54.731248) ========= 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.YVUuhNCH1M/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.2zRojsjxOV/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.005159 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 09:07:54.784333) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 09:07:54.784671) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 09:07:54.785006) ========= ========= Total master_lag: 0.21 =========