builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0077 starttime: 1472226135.83 results: success (0) buildid: 20160826062517 builduid: 950795c823814dcaae95dce96cf7b3d8 revision: 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:15.825346) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:15.825821) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:15.826113) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:15.851390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:15.851699) ========= 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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-26 08:42:15-- 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% 117M=0s 2016-08-26 08:42:15 (117 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129628 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:16.004530) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:16.004843) ========= 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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.028595 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:16.056676) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 08:42:16.057300) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-26 08:42:16,137 truncating revision to first 12 chars 2016-08-26 08:42:16,137 Setting DEBUG logging. 2016-08-26 08:42:16,138 attempt 1/10 2016-08-26 08:42:16,138 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/717ef7ea17e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 08:42:17,652 unpacking tar archive at: mozilla-central-717ef7ea17e8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.791981 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 08:42:17.872623) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:17.873069) ========= script_repo_revision: 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:17.873473) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:17.873741) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 08:42:17.892621) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 39 secs) (at 2016-08-26 08:42:17.892970) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:42:18 INFO - MultiFileLogger online at 20160826 08:42:18 in /builds/slave/test 08:42:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:42:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:42:18 INFO - {'append_to_log': False, 08:42:18 INFO - 'base_work_dir': '/builds/slave/test', 08:42:18 INFO - 'blob_upload_branch': 'Firefox', 08:42:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:42:18 INFO - 'branch': 'Firefox', 08:42:18 INFO - 'buildbot_json_path': 'buildprops.json', 08:42:18 INFO - 'config_files': ('talos/mac_config.py',), 08:42:18 INFO - 'default_actions': ('clobber', 08:42:18 INFO - 'read-buildbot-config', 08:42:18 INFO - 'download-and-extract', 08:42:18 INFO - 'populate-webroot', 08:42:18 INFO - 'create-virtualenv', 08:42:18 INFO - 'install', 08:42:18 INFO - 'run-tests'), 08:42:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:42:18 INFO - 'download_minidump_stackwalk': True, 08:42:18 INFO - 'download_symbols': 'ondemand', 08:42:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:42:18 INFO - 'tooltool.py': '/tools/tooltool.py', 08:42:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:42:18 INFO - '/tools/misc-python/virtualenv.py')}, 08:42:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:42:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:42:18 INFO - 'installer_path': 'installer.exe', 08:42:18 INFO - 'log_level': 'info', 08:42:18 INFO - 'log_name': 'talos', 08:42:18 INFO - 'log_to_console': True, 08:42:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:42:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:42:18 INFO - 'opt_config_files': (), 08:42:18 INFO - 'pip_index': False, 08:42:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:42:18 INFO - 'cmd': ('bash', 08:42:18 INFO - '-c', 08:42:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:42:18 INFO - 'enabled': True, 08:42:18 INFO - 'halt_on_failure': False, 08:42:18 INFO - 'name': 'check_screen_resolution'},), 08:42:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:42:18 INFO - 'cmd': ('bash', 08:42:18 INFO - '-c', 08:42:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:42:18 INFO - 'enabled': True, 08:42:18 INFO - 'halt_on_failure': False, 08:42:18 INFO - 'name': 'check_screen_resolution'},), 08:42:18 INFO - 'run_cmd_checks_enabled': True, 08:42:18 INFO - 'sps_profile': False, 08:42:18 INFO - 'sps_profile_interval': 0, 08:42:18 INFO - 'suite': 'dromaeojs', 08:42:18 INFO - 'system_bits': '32', 08:42:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:42:18 INFO - 'title': 't-yosemite-r7-0077', 08:42:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:42:18 INFO - 'use_talos_json': True, 08:42:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:42:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:42:18 INFO - 'work_dir': 'build'} 08:42:18 INFO - [mozharness: 2016-08-26 15:42:18.089529Z] Running clobber step. 08:42:18 INFO - Running pre-action listener: _resource_record_pre_action 08:42:18 INFO - Running main action method: clobber 08:42:18 INFO - rmtree: /builds/slave/test/build 08:42:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:42:20 INFO - Running post-action listener: _resource_record_post_action 08:42:20 INFO - [mozharness: 2016-08-26 15:42:20.386645Z] Finished clobber step (success) 08:42:20 INFO - [mozharness: 2016-08-26 15:42:20.386787Z] Running read-buildbot-config step. 08:42:20 INFO - Running pre-action listener: _resource_record_pre_action 08:42:20 INFO - Running main action method: read_buildbot_config 08:42:20 INFO - Using buildbot properties: 08:42:20 INFO - { 08:42:20 INFO - "project": "", 08:42:20 INFO - "product": "firefox", 08:42:20 INFO - "script_repo_revision": "production", 08:42:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:42:20 INFO - "repository": "", 08:42:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:42:20 INFO - "buildid": "20160826062517", 08:42:20 INFO - "pgo_build": "False", 08:42:20 INFO - "basedir": "/builds/slave/test", 08:42:20 INFO - "buildnumber": 15, 08:42:20 INFO - "slavename": "t-yosemite-r7-0077", 08:42:20 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:42:20 INFO - "platform": "yosemite_r7", 08:42:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:42:20 INFO - "branch": "mozilla-central", 08:42:20 INFO - "repo_path": "mozilla-central", 08:42:20 INFO - "revision": "717ef7ea17e82c6fe24fed6fe89a532dcdb28a19", 08:42:20 INFO - "stage_platform": "macosx64", 08:42:20 INFO - "builduid": "950795c823814dcaae95dce96cf7b3d8", 08:42:20 INFO - "slavebuilddir": "test" 08:42:20 INFO - } 08:42:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:42:20 INFO - Running post-action listener: _resource_record_post_action 08:42:20 INFO - [mozharness: 2016-08-26 15:42:20.392481Z] Finished read-buildbot-config step (success) 08:42:20 INFO - [mozharness: 2016-08-26 15:42:20.392786Z] Running download-and-extract step. 08:42:20 INFO - Running pre-action listener: _resource_record_pre_action 08:42:20 INFO - Running main action method: download_and_extract 08:42:20 INFO - mkdir: /builds/slave/test/build/tests 08:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:20 INFO - trying https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:42:20 INFO - Downloading https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/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:42:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/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:42:21 INFO - Downloaded 1199 bytes. 08:42:21 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:42:21 INFO - Using the following test package requirements: 08:42:21 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:42:21 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:42:21 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:42:21 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:42:21 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:42:21 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:42:21 INFO - u'jsshell-mac.zip'], 08:42:21 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:42:21 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:42:21 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:42:21 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:42:21 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:42:21 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:42:21 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:42:21 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:42:21 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:42:21 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:42:21 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:42:21 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:21 INFO - trying https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:42:21 INFO - Downloading https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/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:42:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/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:42:22 INFO - Downloaded 16367248 bytes. 08:42:22 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:42:29 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:42:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:29 INFO - trying https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:42:29 INFO - Downloading https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/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:42:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/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:42:30 INFO - Downloaded 16367248 bytes. 08:42:30 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:37 INFO - trying https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:42:37 INFO - Downloading https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/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:42:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/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:42:38 INFO - Downloaded 14413998 bytes. 08:42:38 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:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:38 INFO - trying https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:42:38 INFO - Downloading https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:42:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/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:42:41 INFO - Downloaded 111823203 bytes. 08:42:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:42:41 INFO - mkdir: /builds/slave/test/properties 08:42:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:42:41 INFO - Writing to file /builds/slave/test/properties/build_url 08:42:41 INFO - Contents: 08:42:41 INFO - build_url:https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:42:42 INFO - Running post-action listener: _resource_record_post_action 08:42:42 INFO - Running post-action listener: set_extra_try_arguments 08:42:42 INFO - [mozharness: 2016-08-26 15:42:42.027595Z] Finished download-and-extract step (success) 08:42:42 INFO - [mozharness: 2016-08-26 15:42:42.027702Z] Running populate-webroot step. 08:42:42 INFO - Running pre-action listener: _resource_record_pre_action 08:42:42 INFO - Running main action method: populate_webroot 08:42:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:42:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:42:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:42:42 INFO - '--tppagecycles', 08:42:42 INFO - '7'], 08:42:42 INFO - 'tests': ['tsvgm']}}, 08:42:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:42:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:42:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:42:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:42:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:42:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:42:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:42 INFO - 'talos_options': ['--disable-e10s'], 08:42:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:42 INFO - 'talos_options': ['--disable-e10s'], 08:42:42 INFO - 'tests': ['damp', 'tps']}, 08:42:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:42 INFO - 'tests': ['damp', 'tps']}, 08:42:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:42:42 INFO - 'tests': ['dromaeo_dom']}, 08:42:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:42:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:42:42 INFO - 'tests': ['basic_compositor_video']}, 08:42:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:42:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:42:42 INFO - 'tests': ['a11yr', 08:42:42 INFO - 'ts_paint', 08:42:42 INFO - 'tpaint', 08:42:42 INFO - 'sessionrestore', 08:42:42 INFO - 'sessionrestore_no_auto_restore', 08:42:42 INFO - 'tabpaint']}, 08:42:42 INFO - 'other-e10s': {'tests': ['a11yr', 08:42:42 INFO - 'ts_paint', 08:42:42 INFO - 'tpaint', 08:42:42 INFO - 'sessionrestore', 08:42:42 INFO - 'sessionrestore_no_auto_restore', 08:42:42 INFO - 'tabpaint']}, 08:42:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:42:42 INFO - 'ts_paint', 08:42:42 INFO - 'tpaint', 08:42:42 INFO - 'sessionrestore', 08:42:42 INFO - 'sessionrestore_no_auto_restore', 08:42:42 INFO - 'tabpaint']}, 08:42:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:42:42 INFO - 'ts_paint', 08:42:42 INFO - 'tpaint', 08:42:42 INFO - 'sessionrestore', 08:42:42 INFO - 'sessionrestore_no_auto_restore', 08:42:42 INFO - 'tabpaint']}, 08:42:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:42:42 INFO - 'tests': ['a11yr', 08:42:42 INFO - 'ts_paint', 08:42:42 INFO - 'tpaint', 08:42:42 INFO - 'sessionrestore', 08:42:42 INFO - 'sessionrestore_no_auto_restore', 08:42:42 INFO - 'tabpaint']}, 08:42:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:42:42 INFO - 'tests': ['a11yr', 08:42:42 INFO - 'ts_paint', 08:42:42 INFO - 'tpaint', 08:42:42 INFO - 'sessionrestore', 08:42:42 INFO - 'sessionrestore_no_auto_restore', 08:42:42 INFO - 'tabpaint']}, 08:42:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:42:42 INFO - 'tests': ['tsvgx', 08:42:42 INFO - 'tsvgr_opacity', 08:42:42 INFO - 'tart', 08:42:42 INFO - 'tscrollx', 08:42:42 INFO - 'cart']}, 08:42:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:42:42 INFO - 'tsvgr_opacity', 08:42:42 INFO - 'tart', 08:42:42 INFO - 'tscrollx', 08:42:42 INFO - 'cart']}, 08:42:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:42 INFO - 'talos_options': ['--disable-e10s'], 08:42:42 INFO - 'tests': ['tp5o']}, 08:42:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:42 INFO - 'tests': ['tp5o']}, 08:42:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:42 INFO - 'talos_options': ['--disable-e10s', 08:42:42 INFO - '--xperf_path', 08:42:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:42 INFO - 'tests': ['tp5n']}, 08:42:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:42 INFO - 'talos_options': ['--xperf_path', 08:42:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:42 INFO - 'tests': ['tp5n']}}, 08:42:42 INFO - 'talos.zip': {'path': '', 08:42:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:42:42 INFO - Running post-action listener: _resource_record_post_action 08:42:42 INFO - [mozharness: 2016-08-26 15:42:42.046669Z] Finished populate-webroot step (success) 08:42:42 INFO - [mozharness: 2016-08-26 15:42:42.046773Z] Running create-virtualenv step. 08:42:42 INFO - Running pre-action listener: _resource_record_pre_action 08:42:42 INFO - Running main action method: create_virtualenv 08:42:42 INFO - Creating virtualenv /builds/slave/test/build/venv 08:42:42 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:42:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:42:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:42:42 INFO - Using real prefix '/tools/python27' 08:42:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:42:42 INFO - Installing distribute.............................................................................................................................................................................................done. 08:42:45 INFO - Installing pip.................done. 08:42:45 INFO - Return code: 0 08:42:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b0ad7dbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48d30>, '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 0x1047addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a40b20>, '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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:42:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:42:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:42:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oRSbnkWM1C/Render', 08:42:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:45 INFO - 'HOME': '/Users/cltbld', 08:42:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:45 INFO - 'LOGNAME': 'cltbld', 08:42:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:45 INFO - 'MOZ_NO_REMOTE': '1', 08:42:45 INFO - 'NO_EM_RESTART': '1', 08:42:45 INFO - 'PAGER': '/bin/cat', 08:42:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:45 INFO - 'PWD': '/builds/slave/test', 08:42:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:45 INFO - 'SHELL': '/bin/bash', 08:42:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Okv1JJ9ic/Listeners', 08:42:45 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:42:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:45 INFO - 'USER': 'cltbld', 08:42:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:45 INFO - 'XPC_FLAGS': '0x0', 08:42:45 INFO - 'XPC_SERVICE_NAME': '0', 08:42:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:46 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:42:46 INFO - Cleaning up... 08:42:46 INFO - Return code: 0 08:42:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:46 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 0x104ae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b0ad7dbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48d30>, '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 0x1047addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a40b20>, '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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:42:46 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:42:46 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:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oRSbnkWM1C/Render', 08:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:46 INFO - 'HOME': '/Users/cltbld', 08:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:46 INFO - 'LOGNAME': 'cltbld', 08:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:46 INFO - 'MOZ_NO_REMOTE': '1', 08:42:46 INFO - 'NO_EM_RESTART': '1', 08:42:46 INFO - 'PAGER': '/bin/cat', 08:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:46 INFO - 'PWD': '/builds/slave/test', 08:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:46 INFO - 'SHELL': '/bin/bash', 08:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Okv1JJ9ic/Listeners', 08:42:46 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:46 INFO - 'USER': 'cltbld', 08:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:46 INFO - 'XPC_FLAGS': '0x0', 08:42:46 INFO - 'XPC_SERVICE_NAME': '0', 08:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:46 INFO - Downloading/unpacking psutil>=3.1.1 08:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:42:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:42:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:42:49 INFO - Installing collected packages: psutil 08:42:49 INFO - Running setup.py install for psutil 08:42:49 INFO - building 'psutil._psutil_osx' extension 08:42:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:42:50 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:42:50 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:42:50 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:42:50 INFO - building 'psutil._psutil_posix' extension 08:42:50 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:42:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:42:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:42:50 INFO - ^ 08:42:50 INFO - 1 warning generated. 08:42:50 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:42:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:42:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:42:50 INFO - Successfully installed psutil 08:42:50 INFO - Cleaning up... 08:42:50 INFO - Return code: 0 08:42:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:42: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:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42: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:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b0ad7dbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48d30>, '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 0x1047addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a40b20>, '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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:42:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:42:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:42:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oRSbnkWM1C/Render', 08:42:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:50 INFO - 'HOME': '/Users/cltbld', 08:42:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:50 INFO - 'LOGNAME': 'cltbld', 08:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:50 INFO - 'MOZ_NO_REMOTE': '1', 08:42:50 INFO - 'NO_EM_RESTART': '1', 08:42:50 INFO - 'PAGER': '/bin/cat', 08:42:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:50 INFO - 'PWD': '/builds/slave/test', 08:42:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:50 INFO - 'SHELL': '/bin/bash', 08:42:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Okv1JJ9ic/Listeners', 08:42:50 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:42:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:50 INFO - 'USER': 'cltbld', 08:42:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:50 INFO - 'XPC_FLAGS': '0x0', 08:42:50 INFO - 'XPC_SERVICE_NAME': '0', 08:42:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:42:51 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:42:51 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:42:51 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:42:51 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:42:51 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:42:51 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:42:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:42:53 INFO - Installing collected packages: mozsystemmonitor 08:42:53 INFO - Running setup.py install for mozsystemmonitor 08:42:53 INFO - Successfully installed mozsystemmonitor 08:42:53 INFO - Cleaning up... 08:42:54 INFO - Return code: 0 08:42:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104ae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b0ad7dbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48d30>, '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 0x1047addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a40b20>, '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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:42:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:42:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:42:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oRSbnkWM1C/Render', 08:42:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:54 INFO - 'HOME': '/Users/cltbld', 08:42:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:54 INFO - 'LOGNAME': 'cltbld', 08:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:54 INFO - 'MOZ_NO_REMOTE': '1', 08:42:54 INFO - 'NO_EM_RESTART': '1', 08:42:54 INFO - 'PAGER': '/bin/cat', 08:42:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:54 INFO - 'PWD': '/builds/slave/test', 08:42:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:54 INFO - 'SHELL': '/bin/bash', 08:42:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Okv1JJ9ic/Listeners', 08:42:54 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:42:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:54 INFO - 'USER': 'cltbld', 08:42:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:54 INFO - 'XPC_FLAGS': '0x0', 08:42:54 INFO - 'XPC_SERVICE_NAME': '0', 08:42:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:54 INFO - Downloading/unpacking jsonschema==2.5.1 08:42:54 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:42:54 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:42:54 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:42:54 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:42:54 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:42:54 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:42:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:42:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:42:56 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:42:56 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:42:56 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:42:56 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:42:56 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:42:56 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:42:57 INFO - Downloading functools32-3.2.3-2.tar.gz 08:42:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:42:57 INFO - warning: no files found matching '*.txt' 08:42:57 INFO - no previously-included directories found matching 'build' 08:42:57 INFO - no previously-included directories found matching 'dist' 08:42:57 INFO - no previously-included directories found matching '.git*' 08:42:57 INFO - Installing collected packages: jsonschema, functools32 08:42:57 INFO - Running setup.py install for functools32 08:42:57 INFO - warning: no files found matching '*.txt' 08:42:57 INFO - no previously-included directories found matching 'build' 08:42:57 INFO - no previously-included directories found matching 'dist' 08:42:57 INFO - no previously-included directories found matching '.git*' 08:42:57 INFO - Successfully installed jsonschema functools32 08:42:57 INFO - Cleaning up... 08:42:57 INFO - Return code: 0 08:42:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:42: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:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42: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:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:57 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 0x104ae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b0ad7dbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48d30>, '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 0x1047addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a40b20>, '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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:42:57 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:42:57 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:42:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oRSbnkWM1C/Render', 08:42:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:57 INFO - 'HOME': '/Users/cltbld', 08:42:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:57 INFO - 'LOGNAME': 'cltbld', 08:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:57 INFO - 'MOZ_NO_REMOTE': '1', 08:42:57 INFO - 'NO_EM_RESTART': '1', 08:42:57 INFO - 'PAGER': '/bin/cat', 08:42:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:57 INFO - 'PWD': '/builds/slave/test', 08:42:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:57 INFO - 'SHELL': '/bin/bash', 08:42:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Okv1JJ9ic/Listeners', 08:42:57 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:42:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:57 INFO - 'USER': 'cltbld', 08:42:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:57 INFO - 'XPC_FLAGS': '0x0', 08:42:57 INFO - 'XPC_SERVICE_NAME': '0', 08:42:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:42:57 INFO - Cleaning up... 08:42:57 INFO - Return code: 0 08:42:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:42: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:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42: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:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:57 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 0x104ae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b0ad7dbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48d30>, '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 0x1047addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a40b20>, '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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:42:57 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:42:57 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:42:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oRSbnkWM1C/Render', 08:42:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:57 INFO - 'HOME': '/Users/cltbld', 08:42:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:57 INFO - 'LOGNAME': 'cltbld', 08:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:57 INFO - 'MOZ_NO_REMOTE': '1', 08:42:57 INFO - 'NO_EM_RESTART': '1', 08:42:57 INFO - 'PAGER': '/bin/cat', 08:42:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:57 INFO - 'PWD': '/builds/slave/test', 08:42:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:57 INFO - 'SHELL': '/bin/bash', 08:42:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Okv1JJ9ic/Listeners', 08:42:57 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:42:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:57 INFO - 'USER': 'cltbld', 08:42:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:57 INFO - 'XPC_FLAGS': '0x0', 08:42:57 INFO - 'XPC_SERVICE_NAME': '0', 08:42:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:58 INFO - Downloading/unpacking blobuploader==1.2.4 08:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:00 INFO - Downloading blobuploader-1.2.4.tar.gz 08:43:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:43:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:43:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:43:01 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:43:01 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:43:01 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:43:01 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:43:01 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:43:01 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:43:01 INFO - Downloading docopt-0.6.1.tar.gz 08:43:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:43:01 INFO - Installing collected packages: blobuploader, requests, docopt 08:43:01 INFO - Running setup.py install for blobuploader 08:43:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:43:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:43:01 INFO - Running setup.py install for requests 08:43:02 INFO - Running setup.py install for docopt 08:43:02 INFO - Successfully installed blobuploader requests docopt 08:43:02 INFO - Cleaning up... 08:43:02 INFO - Return code: 0 08:43:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:02 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 0x104ae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b0ad7dbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48d30>, '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 0x1047addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a40b20>, '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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:43:02 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:43:02 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:43:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oRSbnkWM1C/Render', 08:43:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:02 INFO - 'HOME': '/Users/cltbld', 08:43:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:02 INFO - 'LOGNAME': 'cltbld', 08:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:02 INFO - 'MOZ_NO_REMOTE': '1', 08:43:02 INFO - 'NO_EM_RESTART': '1', 08:43:02 INFO - 'PAGER': '/bin/cat', 08:43:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:02 INFO - 'PWD': '/builds/slave/test', 08:43:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:02 INFO - 'SHELL': '/bin/bash', 08:43:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Okv1JJ9ic/Listeners', 08:43:02 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:43:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:02 INFO - 'USER': 'cltbld', 08:43:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:02 INFO - 'XPC_FLAGS': '0x0', 08:43:02 INFO - 'XPC_SERVICE_NAME': '0', 08:43:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-zEzWnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Y3coTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:03 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-4Wg1cH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:03 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-o1SPEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:03 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-GMKU0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:03 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-XAg0T6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:03 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-yswilx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:03 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-NST5jQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:03 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Sbisfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:03 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-QbpfqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:03 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-1Ht6Ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:04 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-DjTW6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:04 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-BoLA7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:04 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-ocCZR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:04 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-FdzE7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:04 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-ZO7spQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:04 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-2W9urG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:43:04 INFO - Running setup.py install for manifestparser 08:43:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:43:04 INFO - Running setup.py install for mozcrash 08:43:04 INFO - Running setup.py install for mozdebug 08:43:05 INFO - Running setup.py install for mozdevice 08:43:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:43:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:43:05 INFO - Running setup.py install for mozfile 08:43:05 INFO - Running setup.py install for mozhttpd 08:43:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:43:05 INFO - Running setup.py install for mozinfo 08:43:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:43:05 INFO - Running setup.py install for mozInstall 08:43:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:43:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:43:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:43:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:43:05 INFO - Running setup.py install for mozleak 08:43:05 INFO - Running setup.py install for mozlog 08:43:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:43:06 INFO - Running setup.py install for moznetwork 08:43:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:43:06 INFO - Running setup.py install for mozprocess 08:43:06 INFO - Running setup.py install for mozprofile 08:43:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:43:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:43:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:43:06 INFO - Running setup.py install for mozrunner 08:43:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:43:06 INFO - Running setup.py install for mozscreenshot 08:43:06 INFO - Running setup.py install for moztest 08:43:07 INFO - Running setup.py install for mozversion 08:43:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:43:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:43:07 INFO - Cleaning up... 08:43:07 INFO - Return code: 0 08:43:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43: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:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43: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:43:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:07 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 0x104ae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b0ad7dbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48d30>, '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 0x1047addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a40b20>, '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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:43:07 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:43:07 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:43:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oRSbnkWM1C/Render', 08:43:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:07 INFO - 'HOME': '/Users/cltbld', 08:43:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:07 INFO - 'LOGNAME': 'cltbld', 08:43:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:07 INFO - 'MOZ_NO_REMOTE': '1', 08:43:07 INFO - 'NO_EM_RESTART': '1', 08:43:07 INFO - 'PAGER': '/bin/cat', 08:43:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:07 INFO - 'PWD': '/builds/slave/test', 08:43:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:07 INFO - 'SHELL': '/bin/bash', 08:43:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Okv1JJ9ic/Listeners', 08:43:07 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:43:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:07 INFO - 'USER': 'cltbld', 08:43:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:07 INFO - 'XPC_FLAGS': '0x0', 08:43:07 INFO - 'XPC_SERVICE_NAME': '0', 08:43:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:07 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-QHIszH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:07 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:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:07 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-xBY6h1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:07 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:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:07 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-bXD8nH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:07 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Hfk3Uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:07 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-GLzVGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:08 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:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:08 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-xEPCqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:08 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:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:08 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-ajn1BB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:08 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:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:08 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-6UPVzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:08 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:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:08 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-TIUsmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:08 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:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:08 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-3ebKED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:08 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:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:08 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-vjSe3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:08 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:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:08 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-grJN01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:08 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:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:08 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-DGlAh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:08 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-5IZNfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:09 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:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:09 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-hsA282-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:09 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:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:09 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-eHdnDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:09 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:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:09 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Y5aNPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:09 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:43:09 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:43:09 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:43:09 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:43:09 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:43:09 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:43:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:11 INFO - Downloading blessings-1.6.tar.gz 08:43:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:43:11 INFO - Installing collected packages: blessings 08:43:11 INFO - Running setup.py install for blessings 08:43:12 INFO - Successfully installed blessings 08:43:12 INFO - Cleaning up... 08:43:12 INFO - Return code: 0 08:43:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:43:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:43:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:43:12 INFO - Reading from file tmpfile_stdout 08:43:12 INFO - Current package versions: 08:43:12 INFO - blessings == 1.6 08:43:12 INFO - blobuploader == 1.2.4 08:43:12 INFO - docopt == 0.6.1 08:43:12 INFO - functools32 == 3.2.3-2 08:43:12 INFO - jsonschema == 2.5.1 08:43:12 INFO - manifestparser == 1.1 08:43:12 INFO - mozInstall == 1.12 08:43:12 INFO - mozcrash == 0.17 08:43:12 INFO - mozdebug == 0.1 08:43:12 INFO - mozdevice == 0.48 08:43:12 INFO - mozfile == 1.2 08:43:12 INFO - mozhttpd == 0.7 08:43:12 INFO - mozinfo == 0.9 08:43:12 INFO - mozleak == 0.1 08:43:12 INFO - mozlog == 3.3 08:43:12 INFO - moznetwork == 0.27 08:43:12 INFO - mozprocess == 0.23 08:43:12 INFO - mozprofile == 0.28 08:43:12 INFO - mozrunner == 6.12 08:43:12 INFO - mozscreenshot == 0.1 08:43:12 INFO - mozsystemmonitor == 0.3 08:43:12 INFO - moztest == 0.7 08:43:12 INFO - mozversion == 1.4 08:43:12 INFO - psutil == 3.1.1 08:43:12 INFO - requests == 1.2.3 08:43:12 INFO - wsgiref == 0.1.2 08:43:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43: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:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:13 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:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:13 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 0x104ae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b0ad7dbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48d30>, '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 0x1047addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a40b20>, '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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:43:13 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:43:13 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:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oRSbnkWM1C/Render', 08:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:13 INFO - 'HOME': '/Users/cltbld', 08:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:13 INFO - 'LOGNAME': 'cltbld', 08:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:13 INFO - 'MOZ_NO_REMOTE': '1', 08:43:13 INFO - 'NO_EM_RESTART': '1', 08:43:13 INFO - 'PAGER': '/bin/cat', 08:43:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:13 INFO - 'PWD': '/builds/slave/test', 08:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:13 INFO - 'SHELL': '/bin/bash', 08:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Okv1JJ9ic/Listeners', 08:43:13 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:13 INFO - 'USER': 'cltbld', 08:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:13 INFO - 'XPC_FLAGS': '0x0', 08:43:13 INFO - 'XPC_SERVICE_NAME': '0', 08:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:13 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:43:13 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:43:13 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:43:13 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:43:13 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:43:13 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:43:13 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:43:13 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:43:13 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:43:13 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:43:13 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:43:13 INFO - Cleaning up... 08:43:13 INFO - Return code: 0 08:43:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:43:13 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:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:13 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:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:13 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 0x104ae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b0ad7dbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48d30>, '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 0x1047addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a40b20>, '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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:43:13 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:43:13 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:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oRSbnkWM1C/Render', 08:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:13 INFO - 'HOME': '/Users/cltbld', 08:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:13 INFO - 'LOGNAME': 'cltbld', 08:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:13 INFO - 'MOZ_NO_REMOTE': '1', 08:43:13 INFO - 'NO_EM_RESTART': '1', 08:43:13 INFO - 'PAGER': '/bin/cat', 08:43:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:13 INFO - 'PWD': '/builds/slave/test', 08:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:13 INFO - 'SHELL': '/bin/bash', 08:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Okv1JJ9ic/Listeners', 08:43:13 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:13 INFO - 'USER': 'cltbld', 08:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:13 INFO - 'XPC_FLAGS': '0x0', 08:43:13 INFO - 'XPC_SERVICE_NAME': '0', 08:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:43:13 INFO - Cleaning up... 08:43:13 INFO - Return code: 0 08:43:13 INFO - Running post-action listener: _resource_record_post_action 08:43:13 INFO - Running post-action listener: _start_resource_monitoring 08:43:13 INFO - Starting resource monitoring. 08:43:13 INFO - [mozharness: 2016-08-26 15:43:13.807766Z] Finished create-virtualenv step (success) 08:43:13 INFO - [mozharness: 2016-08-26 15:43:13.808723Z] Running install step. 08:43:13 INFO - Running pre-action listener: _resource_record_pre_action 08:43:13 INFO - Running main action method: install 08:43:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:43:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:43:14 INFO - Reading from file tmpfile_stdout 08:43:14 INFO - Detecting whether we're running mozinstall >=1.0... 08:43:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:43:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:43:14 INFO - Reading from file tmpfile_stdout 08:43:14 INFO - Output received: 08:43:14 INFO - Usage: mozinstall [options] installer 08:43:14 INFO - Options: 08:43:14 INFO - -h, --help show this help message and exit 08:43:14 INFO - -d DEST, --destination=DEST 08:43:14 INFO - Directory to install application into. [default: 08:43:14 INFO - "/builds/slave/test"] 08:43:14 INFO - --app=APP Application being installed. [default: firefox] 08:43:14 INFO - mkdir: /builds/slave/test/build/application 08:43:14 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:43:14 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:43:48 INFO - Reading from file tmpfile_stdout 08:43:48 INFO - Output received: 08:43:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:43:48 INFO - Running post-action listener: _resource_record_post_action 08:43:48 INFO - [mozharness: 2016-08-26 15:43:48.636318Z] Finished install step (success) 08:43:48 INFO - [mozharness: 2016-08-26 15:43:48.636473Z] Running run-tests step. 08:43:48 INFO - Running pre-action listener: _resource_record_pre_action 08:43:48 INFO - Running main action method: run_tests 08:43:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:43:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:43:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:43:48 INFO - 2016-08-26 08:43:48.651 screenresolution[1632:7439] starting screenresolution argv=screenresolution get 08:43:48 INFO - 2016-08-26 08:43:48.653 screenresolution[1632:7439] Display 0: 1600x1200x32@60 08:43:48 INFO - 2016-08-26 08:43:48.659 screenresolution[1633:7441] starting screenresolution argv=screenresolution list 08:43:48 INFO - Available Modes on Display 0 08:43:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:43:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:43:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:43:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:43:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:43:48 INFO - Intel Iris: 08:43:48 INFO - Chipset Model: Intel Iris 08:43:48 INFO - Type: GPU 08:43:48 INFO - Bus: Built-In 08:43:48 INFO - VRAM (Dynamic, Max): 1536 MB 08:43:48 INFO - Vendor: Intel (0x8086) 08:43:48 INFO - Device ID: 0x0a2e 08:43:48 INFO - Revision ID: 0x0009 08:43:48 INFO - Displays: 08:43:48 INFO - Display: 08:43:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:43:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:43:48 INFO - Display Serial Number: 1600x1200 60 08:43:48 INFO - Main Display: Yes 08:43:48 INFO - Mirror: Off 08:43:48 INFO - Online: Yes 08:43:48 INFO - Rotation: Supported 08:43:48 INFO - Return code: 0 08:43:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:43:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:43:48 INFO - Python 2.7.3 08:43:48 INFO - Return code: 0 08:43:48 INFO - grabbing minidump binary from tooltool 08:43:48 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:43:48 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 0x104a48d30>, '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 0x1047addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a40b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:43:48 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:43:48 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:43:48 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:43:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:43:49 INFO - Return code: 0 08:43:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:43:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:43:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:43:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:43:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--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:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0077 --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:43:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oRSbnkWM1C/Render', 08:43:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:49 INFO - 'HOME': '/Users/cltbld', 08:43:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:49 INFO - 'LOGNAME': 'cltbld', 08:43:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:43:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:49 INFO - 'MOZ_NO_REMOTE': '1', 08:43:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:43:49 INFO - 'NO_EM_RESTART': '1', 08:43:49 INFO - 'PAGER': '/bin/cat', 08:43:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:49 INFO - 'PWD': '/builds/slave/test', 08:43:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:43:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:49 INFO - 'SHELL': '/bin/bash', 08:43:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Okv1JJ9ic/Listeners', 08:43:49 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:43:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:49 INFO - 'USER': 'cltbld', 08:43:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:49 INFO - 'XPC_FLAGS': '0x0', 08:43:49 INFO - 'XPC_SERVICE_NAME': '0', 08:43:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--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:43:49 INFO - mozversion application_buildid: 20160826062517 08:43:49 INFO - mozversion application_changeset: 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 08:43:49 INFO - mozversion application_display_name: Nightly 08:43:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:43:49 INFO - mozversion application_name: Firefox 08:43:49 INFO - mozversion application_remotingname: firefox 08:43:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:43:49 INFO - mozversion application_vendor: Mozilla 08:43:49 INFO - mozversion application_version: 51.0a1 08:43:49 INFO - mozversion platform_buildid: 20160826062517 08:43:49 INFO - mozversion platform_changeset: 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 08:43:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:43:49 INFO - mozversion platform_version: 51.0a1 08:43:49 INFO - using testdate: 1472226229 08:43:49 INFO - actual date: 1472226229 08:43:49 INFO - starting webserver on 'localhost:49269' 08:43:49 INFO - SUITE-START | Running 2 tests 08:43:49 INFO - TEST-START | dromaeo_css 08:43:49 INFO - operating with platform_type : mac_ 08:43:49 INFO - Initialising browser for dromaeo_css test... 08:43:49 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpakT1yD/profile http://localhost:49269/getInfo.html) 08:43:51 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 08:43:51 INFO - PROCESS | 1643 | colorDepth:24 08:43:51 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 08:43:51 INFO - PROCESS | 1643 | __metrics 08:43:51 INFO - PROCESS | 1643 | 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:43:51 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:43:52 INFO - TEST-INFO | 1643: exit 0 08:43:52 INFO - Browser initialized. 08:43:52 INFO - Running cycle 1/1 for dromaeo_css test... 08:43:52 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpakT1yD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:04 INFO - PROCESS | 1645 | RSS: Main: 233836544 08:44:04 INFO - PROCESS | 1645 | 08:44:56 INFO - PROCESS | 1645 | 1472226296091 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:47:07 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 08:47:08 INFO - PROCESS | 1645 | RSS: Main: 375111680 08:47:08 INFO - PROCESS | 1645 | 08:50:11 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 08:50:12 INFO - PROCESS | 1645 | RSS: Main: 371482624 08:50:12 INFO - PROCESS | 1645 | 08:53:14 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 08:53:14 INFO - PROCESS | 1645 | RSS: Main: 336859136 08:53:14 INFO - PROCESS | 1645 | 08:56:17 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 08:56:17 INFO - PROCESS | 1645 | RSS: Main: 347414528 08:56:17 INFO - PROCESS | 1645 | 08:59:21 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 08:59:22 INFO - PROCESS | 1645 | RSS: Main: 325484544 08:59:22 INFO - PROCESS | 1645 | 09:02:25 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 09:02:25 INFO - PROCESS | 1645 | RSS: Main: 508874752 09:02:25 INFO - PROCESS | 1645 | 09:02:25 INFO - PROCESS | 1645 | __start_tp_report 09:02:25 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 09:02:25 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 09:02:25 INFO - PROCESS | 1645 | |i|pagename|runs| 09:02:25 INFO - PROCESS | 1645 | |0;dojo.html;3353.161709572607;3354.3794398036366;3380.577427821522;3410.7782994105396;3446.8104254266023;5414.377346605142;5474.069408200606;5510.118381059031;5529.944700552998;5561.0546207144735;5411.053065713494;5490.286262785842;5550.222968784364;5552.305961754791;5587.469190426903;4993.051320249159;5006.27409025691;5075.974620126913;5082.059818933509;5199.948000519984;5141.588672906178;5162.845114646567;5186.636935414523;5196.636235463519;5203.739813009334;5023.673461225045;5074.923876141861;5099.872503187413;5120.411152717448;5136.409312929024;4978.5270399312;5030.899382012376;5050.570701490377;5067.467915868854;5113.130767769489;4971.130052240844;5006.4743201964;5007.32401125847;5078.26365177051;5118.820841270538;5543.667379957215;5555.500004999529;5564.4713752193475;5574.804881829157;5584.46947539983;6037.773261289834;6057.30341010785;6109.283207920386;6173.598716083485;6233.158523599302;6229.4704950079295;6275.937240627627;6284.968575157095;6301.779437719658;6312.936870631281;5773.971130144364;5780;5784.71076446176;5795.565332600072;5796.594238403356;6191.628502289877;6198.597091189058;6257.0614407838275;6271.310155882849;6283.6858157091965;6154.015357542772;6160.784372546939;6166.290876549165;6178.845528861814;6186.226721659793;6200.658963756947;6266.154069200691;6284;6290.968545157245;6292.244930608356;6086.147939288503;6102.246889800143;6123.648682309606;6188.4739797116845;6211.565190436626;6219.346968568235;6240.781572644935;6252.749890004349;6258.687065646699;6277.623342599459;4962.652614317035;4965.82619608312;4978.5270399312;4981.676191047571;4995.0509403214;4388.078503514298;4414.359917811964;4415.602595766396;4470.575295346936;4474.328850672425;4725.267583524559;5757.050086735725;5757.8560535986935;5771.249737534094;5811.244538210006;6227.221597300338;6277.05844123376;6277.748890044439;6285.528585356025;6289.465395441439;5573;5618.634788738719;5684.204211410406;5762.711864406847;5782.161586569924;5382.4886635769535;5436.700981446058;5456.972715136399;5467;5509.090999984957;6364.299927008025;6375.71309291083;6376.585521941059;6391.808245752635;6407.199100112486;6099.461111717566;6245.781397651061;6258.749650013949;6299.244090709144;6311.274203466476;6196.721147548281;6217.160683307696;6270.529710271839;6272.968635156795;6285.214348206474;6133.570650054453;6215.098810672517;6218.471429928507;6227.719752611234;6229.626222426669;6262;6275.278342990615;6275.3724627536885;6285.779997700059;6285.96857015712;6057.909131363122;6110.816675499742;6123;6127.448529632355;6146.600470969552;5936.525077993664;6038.305594856472;6086.573939824169;6118.877622447615;6160.537959652955;4958.231474121372;6015.4586087252355;6018.8796224074395;6027;6076.726547305293;6084.209052823105;6153.323134455296;6160.845978850564;6164.352742961924;6167.629942203482;4951.900961980812;5781.82654520365;5885.411458854081;5888.558358123244;5911.793087241926 09:02:25 INFO - PROCESS | 1645 | |1;ext.html;502.87428142964256;518.7924830067973;534.1640332879044;541.7535021565188;557.5595279729014;24869;32090.35819283613;32134;32217.51673724896;32221.67778322216;41365.58634413655;42848.78575607125;42917.35623965643;44817.32774008392;45020.0995980081;9762.707118786448;9923.007699230091;9924.05721456464;9952.502374881227;9953.502324883762;8846.20384165425;9723.805523889554;9738.220942324597;9826.263030272723;9836.262280328969;8920.241779448756;9063.957644870858;9068.367693815111;9071.50106744132;12097.274163550172;10485.475726213659;12119.212251203688;12383.504659813596;12402.19385739924;12453;12067.456964436622;12108.636740897788;12161.51353945841;12178.96478799303;12211.145219834616;35040.12399690002;35313.470297945554;35339;35358.46962295568;35361.82319088414;3187.756774857807;3317.6018877734828;3417.8803741869165;3597.4963505109304;3805.923881522354;31222.843885780425;31539.684603153903;32021.679783202337;32107.51838722421;32327.191820204447;3082.1985689084336;3179.300553878143;3218.1793642621215;3251.561039259693;3313.088900552343;442676;442677.9999999968;444167;445017.54982450407;455078.7246063782;46426.76786616045;56095.43904560984;56121;56287.71856140693;56422.871542568915;60800;60877.39122608806;60914;61009.08486372708;61148.082778758355;16135.677286454205;16344.019358838508;16351.83648163527;16363.263653135638;16385.50843474674;12695;12792.424340904681;12796.36018199105;12813.487460501661;12820.16668916536;12907.677308067186;14588.051776634486;16159;16291.185440727917;16341.67316653684;5098.235264710249;5883.470487656132;5996.190514280604;6087.391260873877;6175.6912154393;858.7552547523926;954.346272803132;1006.6879267427121;1014.9289549731448;1023.744063984004;26686.065987690727;28287.0099546519;29469.705302947124;30323.090307290815;30815.2296192597;1039.3133749258102;1136.8408422820812;1139.7093741096132;1159.8724140344555;1164.3305099567783;15444.92277538605;15864.747726715996;15869.761953570705;15983.600409989844;16219.432319868747;527742.7225727771;585631;589966;590097.0495147583;592981;58282.4171758277;58310;58381;58381.41618583845;58465;1248.4007676315418;1361.8093466914443;1378.7311474262624;1401.2993503248376;1410.5768269519308;30432.239194020327;31484;31488.685113148575;31531.84234078815;32391.76082391742;49415.50584494253;49952.500474994784;50000.74999624979;50068.749656251486;50093.99812003812;432756.8362158169;434768;435665;436560;437352;26103;26799.330016749736;26881.596776048766;27002.32494187661;27304.72695273022;44126.55873441225;44486;47761;48048.759756201;48522;32074.26661715559;39697.61058363059;39716.602833971294;40262.597374025885;40555.98610034773;39175.6184611084;39854.2029159421;40498.59501404948;40566.18867622572;40823.97940051522;38171.23657526889;38360.616393835706;38478.845634631005;38500.037499061626;38690.80654596822;33356;33609.15977100592;34751.13122171966;34865;34908.65091349054;20816.89591552093;22203.333899983027;25353.492930141663;25963.480730385665;26591.33521661974 09:02:25 INFO - PROCESS | 1645 | |2;jquery.html;347.66797708188676;356.8679588552236;381.1139101588807;387.8778184871766;409.93645984872336;15532;16233.83766162338;20417.183312667512;20920.686189707165;21036.789632103693;18851.340203092896;20153.29463468779;20749.273775417863;21446.03492842822;21467.355979320644;5277.498637629438;5895.410458954113;6031.758729650809;6140.631562106266;6144.723487443076;5285.207218917174;6011.819645410645;6111.419415155553;6124.969375153118;6134.969325153368;5882.705864706769;6067.120267561201;6091.878162436748;6184.907226391608;6224.315325314212;6082.270127584674;6191.659458729768;6230.688465576703;6234.906476402835;6244.687765611747;5816.156657284712;5856.4143585641095;5879.970600146972;5890.234269544974;5892.410758924073;13655.112417692819;13710.040297179201;13742.381592828247;13871.237081960488;13938.233397163152;2096.9056392462376;2220.1452440810244;2223.9110435582556;2824.449232399683;2969.792114551982;9695.90304096957;10096.747581310527;10577;10616.256862019662;10650.520726567323;1846.8984205868671;1868.8037756035692;1936.651402747519;1985.0967809646577;2044.2640649366217;92129.6180557299;113948;118888;120770;121502.39248803788;11344.659660210207;11432.142589305835;11482.885171148182;11504.194706370641;11734.295942243492;14668.779968300483;14674.41302347894;14684.853151468562;14687.485937992118;14699.338529766186;7094.467914906402;8262.339012878956;8515.105913879077;8637.179467950535;8668.78328041804;8325.167483251747;8334.66661333552;8341.206640572305;8511.659533618586;8542.44474109181;4415.66882483815;4509.098180363875;4513.323001549794;4596.195665758479;4609.677322587454;1610.3236640610971;1855.2949879045875;1900.8194221548931;1906.256559941624;1908.4083933980232;892.906244844282;926.2404828041076;935.219092058139;974.5322245322285;1059.0944742245424;20616.350691944608;22023.66964495534;22077.558448830932;22170;22439.32682019542;912.5756523216603;919.8758167647281;994.6568433890441;995.8705368302219;1006.9899301007042;8773.780655483664;11127.554897804159;11531.827022594667;11599.362035088152;12490.625281241577;577528;579427;590038;592677;598255;90034.54982725176;122721;136453;139764.13184220204;144803;1009.7273736091361;1013.8124446977333;1042.1923009667569;1044.5717255925035;1045.3936716704293;15426.691466170389;17341.393051243147;18884.716729248797;19218.807811921703;20267.250558646698;54819.17771233514;54878.725606371714;55818;56550;57023.71488142533;77889;79873;80436.7934480995;80584.79122813045;82892.58553707192;7089.929100708927;7666.69333226687;8110.675572977015;8254.669813207405;8513.148685131437;10393.9480302598;12812;13088.541901033419;13499.460021599027;13574.660633484242;10562.260641755003;10929.398883061507;10933.289336193122;11793.233439826385;11945.283283003047;11246;11452;11620.88379116198;11657.825132622847;11959.521619135485;10856.620018299322;11553.884461155281;11617.244879082833;11790.705232368917;11843.940780296043;8224.547649879314;8258.793530161794;8291.709790157316;8314.420260150551;8403.991521017517;9366;9789.31474796786;11198.04816590599;11206.607768728056;11462.140339474407 09:02:25 INFO - PROCESS | 1645 | |3;mootools.html;1336.5522549945767;1486.814148231471;1518.802555667763;1687.299770595203;1851.1577232359277;7075.4339652827775;7679.308862202401;8182.386321025917;8187.222213889679;8193.016837979443;8315.75052748417;8614.655413783457;8620.827583448327;8742.868856967134;8748.256398206144;2040.5918816236774;2387.140629373424;2425.1996841042414;2429.149797570845;2432.8783560821976;2375.7743014413604;2408.5664580375524;2420.257414661441;2421.225207933462;2425.0542288507495;2387.057112440585;2405.927822165338;2415.372003279151;2425.8301918865686;2430.2830664953794;2374.0622454130694;2398.0767404549288;2400.7239167495786;2403.771641694054;2406.2420337593603;2354.7527509611414;2368.561816064034;2406.3863714752624;2411;2415.685960825099;8766.649334026632;8838.779030524223;8864.556772161366;8870.778230544223;8874.778130546723;5192.584593232532;5194.27280180775;5346.358436987586;5358.115910874701;5366.248725178478;3608.0438683748566;3680;3695.131644063643;3698.42674385468;3719.256148770257;1085.159001773624;1095.7453715495712;1117.5585643670704;1118.8937050980226;1124.162498938288;11506.194566380356;11593.304401735923;11613.593524226611;11614.767704645945;11652.126090543243;3778.4899038629983;4721.386219791474;4915.459299477055;4972.303877457159;5025.195968644999;8997.01032886382;9059.81880362389;9074.092590740873;9075.228605568469;9075.682351117679;5283.947160528345;5289.021531016774;5295.4969277918535;5299.867503312448;5316.548093412103;4115.8765237042935;4404.647628189737;4927.926081108714;4940.407151141886;4978.5270399312;3777.414500752388;3800.733948623625;3863.5750067492554;3908.1988192420486;3912.139329347539;1175.1949914308725;1177.5172179406402;1182.568362547681;1185.7865584194756;1196.6589521986193;822.2599660305773;883.9160279773412;913.2693844924034;930.7812664023949;945.5177859291811;5568.192612071227;5604.859878502988;5830.463748269516;6010.699465026732;6081.239845019372;434.1945690743688;444.64206313917606;449.86504048785235;933.8552524358736;1004.6232662751469;2864.914052578426;3759.642833930772;3918.747439625104;4013.4180543821567;4016.859409920639;10457.372557646566;10673.25287229902;10769.569217231223;10776.461176941122;10801.081908037904;10719.19606029551;10725.83911241332;10774.838377424345;10795.676129715965;10807;6702.932970670231;7678.731244406419;8205.95897020511;8253.13342099095;8254.092049874509;5856.560757943256;5899.705014749159;5983.491403230774;6025.758969641165;6099.420555047263;10011.048950349705;10051;10106.595736170471;10106.797864042825;10120.493975301353;9213.585388657393;9481.715548533555;9609.183219426428;9646.517674116407;10273.229507786797;8023.438359314792;8094.31198348147;8097.838043238984;8302.70940517079;8320.916790832256;8252.711155109542;8317.293030092509;8323.250907418362;8325.542095184823;8325.583720814057;7318.96340518294;7480.177180510031;7496;7593.240675932362;7638.885416718865;7199.2440793715905;7293.854122917618;7368.078990126234;7447.218042105717;7546.547207167588;6942.722291108501;7126.608036558039;7130.42956563484;7134.464915131282;7154.427645788429;4847.9063825134135;4958.45456999737;4988.4762099980435;5088.898222035613;5151.871203219949;4923.409190897115;4967.40391153064;5144.254083157846;5190.922136167885;5251.159814429673 09:02:25 INFO - PROCESS | 1645 | |4;prototype.html;296.3702132967443;300.77592193815605;309.2269326683292;309.23464425546774;313.9105354973833;4378.387025816388;4569.725816451007;5089.312942752727;5104.821331253403;5130.769115389807;4877.463479017305;5031.421386540549;5040.773165207565;5089.414717307511;5099.603186502338;3889.4943657324507;3945;3961.5936342598325;3964.1368556778266;3973.443717879499;3101.875924962999;3700.888973330791;3883.339832228528;3942.940855887164;3965.3060714375015;3327.617324007746;3867.1298957734566;3878.4764056852387;3898.493195884531;3914.725969182158;3066.4020515999277;3334.5324459899743;3863.7102217333813;3869.8258578364043;3882.5729169791443;3133.85897634607;3353.9496907546404;3386.0519054664487;3386.779859309137;3387.4072037393357;4036.7174297799165;4634.837780677693;4926.679765815246;5036.194208926554;5070.6957582545165;3882.3982282745937;4426.919347906479;4429.822807087713;4458.175237581057;4507.256302710049;4415.381846541487;4456.977715111404;4882.194437917739;5232.895342093175;5428.83713488596;3169.683031696835;3673.7876500754687;3714.405695088773;3718.4608231806506;3740.981295093507;4434;4836.975815120937;5233.383308345827;5629;5711.086226203787;2967.5993740845142;3698;3709.7588656737225;3738.663520283133;3741.4948981887096;3780.640839120279;3811.9618803812164;3817.522809648794;3839.865604703822;3876.554196267465;4193.454850869429;4400.251957167289;4418.536053714378;4466.508684044752;4499.55004499554;4688.695234809795;5406.324209473816;5417.101850510037;5432.29380180574;5488.56091512686;1072.9409882456384;1076.482160154179;1228.4778968938165;1247.8440194975628;1263.4567136131368;683.3849535418163;880.5993273060742;881.5724373678761;887.8934527856699;888.9288856891562;4230.894227644272;4249.3413520904305;4655.86032419028;4898.779554920037;5101.336826212569;4846.975765121152;5239.056969745407;5285.418603953562;5518.724063796874;5535.308086489189;4303;5166.689998600096;5179.507946745053;5182.274481572583;5207.817726379559;2645.54495027734;2900.622204452868;2915.8104723192923;2933.8386388748395;2972.464956307885;4749.738764367924;4981.252812078144;5208.5051920067535;5477.370102438191;5814.534837213013;4799.880002999883;4848.490908454632;5368.302120724359;5403.729813509309;5656.511135100624;4476.753778542146;5383.196629129286;5756.71216439168;5784.843031393738;5794.333651630032;4606.637154876892;5186.688798672092;5261.868453288698;5538.390777014444;5589.329280486368;4719.418994636698;5407.972010496064;5437.265969094122;5497.862553436196;5510.311211098613;4774.522547745198;5472.644278121909;5751.626144300607;5765.827025189251;5779.682117483579;3423.9828800855835;3751.793651349202;3766;3777.527809023872;3793.867214647474;5043.470435604356;5537.197106419627;5542.307211598551;5549.861253468695;5563.499285064364;4597.655175861891;4633.83781567657;4788.305695674038;5002.924956125588;5507.972460137674;4485.573870482299;4975.651704380659;5243.632945693765;5399.9460005401015;5480.479354461319;4722.740249286185;4768.880777980578;5178.352705911761;5313.734313284398;5347.609621498361;4795;5214.878801057791;5434.266374039454;5441.183822426668;5446.782128714807;4376.431063961792;4826.613870890391;5280.046382838221;5288.471152884681;5343.599230057839 09:02:25 INFO - PROCESS | 1645 | |5;yui.html;703.2615753458869;739.9556026638398;759.1876691939627;776.3866545429112;838.488522001579;14869.702605947874;14956.177410242432;15584.688306233882;15645.217739113055;15660.216989150525;14121.717565648682;14366.784498232535;14387.1367717937;14395.78406323906;14837.629059273497;670.4033410264868;689.1248114894081;839.86562150056;865.3639574912465;868.6047848229041;837.995810020952;851.6508231625035;853.8206976534935;857.7512521368795;864.3171894203572;911.4804561400005;912.393258483108;914.2092090341839;923.4320892651039;926.4904302633558;723.2586598736285;890.3100097424483;917.101240784032;923.2567782934735;924.4499522783933;459.6943032883129;571.3857603076709;674.1371045062328;676.0670275020441;678.0608856733411;15408.22958852064;15470.381184752596;15470.61323466911;15471.149086800222;15474.5357639271;14036.649083772987;14152;14295.928520357436;14304.642383940381;14529.200893950829;13013.958883289313;13233.602991910258;13329.866701333056;13384.665383365495;13673.179609223478;4526.977365113186;4571.108633816407;4591.288350305708;4596.517365676623;4770.522947705236;118337;118819;118977.40511297475;119064.21403678952;119985;10886.945565272123;10902.182336324808;11234.3259404436;11284.097272218203;11308.604198853001;22287.55424891493;22315.776842231695;22328.21851235199;22334.55330893373;22337.553248935255;7343.926560734431;7446.367058800062;7463.216362281991;7964.681412743425;8162.836743265101;4317.136572685476;4376.6498680105515;4382.2112019836095;4417.646588272931;4459.910801783946;3388.3223355329033;3499.440089585705;3500.8774692886136;3502.6322236164838;3510.052285882797;626.3579830673505;635.3455940381415;644.4844124700221;657.8388294867788;658.4107224034462;560.3164139749499;612.9264488261348;625.974961001564;630.8170630517099;645.1677336236203;7111.573305601681;7861.2531809478005;8774.298056155492;8806.427582207136;8818.060026794223;567.9545636349083;598.4314900844129;613.7637009751188;631.5253882185149;639.3510586754448;3785.5268091488565;4143.937840932388;4314.201872653569;4369.148016136823;4390.824367025347;128502;131799;133127;145747.271263643;162704;126958.36520817337;129167.99999999812;129558;130861;131648.65337094115;98382.50808745767;99385.50307248418;101727;103858;110184.44907775571;8449.23956843887;8994.55027248648;9643.119591679555;9827.213822894295;9835.754106147404;57929.99339560343;59322;60165.89419370301;64683.67658161679;65677.34322656713;92060.16613035942;92742.53628731813;93566.06433935846;94713;95081;13134.211947283193;13224.603261902159;13226.338683066;13397.888642092314;13798.067550747988;14910.79774856321;15085.160566512228;15107.244637768288;15205;15316.693666126392;10526;11137.944310278397;13383.866161338263;13804.309784510533;14334.569962900712;13312.667183320495;13393.976790939325;13707.794383084061;13846.376913038735;13967.371468283749;13197.868021319664;13216.692045104228;13687.04190706641;13697.452101915811;13744.312784360942;11060.889391105986;11069.889301107209;11339.773204536028;11340.262882912584;11711.707207319545;10348.327358721659;10511.789764204826;10639.202059845387;10722.463876805972;11293.153013524183 09:02:25 INFO - PROCESS | 1645 | __end_tp_report 09:02:25 INFO - PROCESS | 1645 | __start_cc_report 09:02:25 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1240 09:02:25 INFO - PROCESS | 1645 | __end_cc_report 09:02:25 INFO - PROCESS | 1645 | __startTimestamp1472227345948__endTimestamp 09:02:25 INFO - PROCESS | 1645 | 09:02:25 INFO - PROCESS | 1645 | ------- Summary: start ------- 09:02:25 INFO - PROCESS | 1645 | Number of tests: 6 09:02:25 INFO - PROCESS | 1645 | 09:02:25 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5703.64 Median:6015.46 stddev:663.81 (11.0%) stddev-sans-first:639.73 09:02:25 INFO - PROCESS | 1645 | Values: 3353.2 3354.4 3380.6 3410.8 3446.8 5414.4 5474.1 5510.1 5529.9 5561.1 5411.1 5490.3 5550.2 5552.3 5587.5 4993.1 5006.3 5076.0 5082.1 5199.9 5141.6 5162.8 5186.6 5196.6 5203.7 5023.7 5074.9 5099.9 5120.4 5136.4 4978.5 5030.9 5050.6 5067.5 5113.1 4971.1 5006.5 5007.3 5078.3 5118.8 5543.7 5555.5 5564.5 5574.8 5584.5 6037.8 6057.3 6109.3 6173.6 6233.2 6229.5 6275.9 6285.0 6301.8 6312.9 5774.0 5780.0 5784.7 5795.6 5796.6 6191.6 6198.6 6257.1 6271.3 6283.7 6154.0 6160.8 6166.3 6178.8 6186.2 6200.7 6266.2 6284.0 6291.0 6292.2 6086.1 6102.2 6123.6 6188.5 6211.6 6219.3 6240.8 6252.7 6258.7 6277.6 4962.7 4965.8 4978.5 4981.7 4995.1 4388.1 4414.4 4415.6 4470.6 4474.3 4725.3 5757.1 5757.9 5771.2 5811.2 6227.2 6277.1 6277.7 6285.5 6289.5 5573.0 5618.6 5684.2 5762.7 5782.2 5382.5 5436.7 5457.0 5467.0 5509.1 6364.3 6375.7 6376.6 6391.8 6407.2 6099.5 6245.8 6258.7 6299.2 6311.3 6196.7 6217.2 6270.5 6273.0 6285.2 6133.6 6215.1 6218.5 6227.7 6229.6 6262.0 6275.3 6275.4 6285.8 6286.0 6057.9 6110.8 6123.0 6127.4 6146.6 5936.5 6038.3 6086.6 6118.9 6160.5 4958.2 6015.5 6018.9 6027.0 6076.7 6084.2 6153.3 6160.8 6164.4 6167.6 4951.9 5781.8 5885.4 5888.6 5911.8 09:02:25 INFO - PROCESS | 1645 | 09:02:25 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:62858.49 Median:35348.73 stddev:130576.73 (369.4%) stddev-sans-first:130859.11 09:02:25 INFO - PROCESS | 1645 | Values: 502.9 518.8 534.2 541.8 557.6 24869.0 32090.4 32134.0 32217.5 32221.7 41365.6 42848.8 42917.4 44817.3 45020.1 9762.7 9923.0 9924.1 9952.5 9953.5 8846.2 9723.8 9738.2 9826.3 9836.3 8920.2 9064.0 9068.4 9071.5 12097.3 10485.5 12119.2 12383.5 12402.2 12453.0 12067.5 12108.6 12161.5 12179.0 12211.1 35040.1 35313.5 35339.0 35358.5 35361.8 3187.8 3317.6 3417.9 3597.5 3805.9 31222.8 31539.7 32021.7 32107.5 32327.2 3082.2 3179.3 3218.2 3251.6 3313.1 442676.0 442678.0 444167.0 445017.5 455078.7 46426.8 56095.4 56121.0 56287.7 56422.9 60800.0 60877.4 60914.0 61009.1 61148.1 16135.7 16344.0 16351.8 16363.3 16385.5 12695.0 12792.4 12796.4 12813.5 12820.2 12907.7 14588.1 16159.0 16291.2 16341.7 5098.2 5883.5 5996.2 6087.4 6175.7 858.8 954.3 1006.7 1014.9 1023.7 26686.1 28287.0 29469.7 30323.1 30815.2 1039.3 1136.8 1139.7 1159.9 1164.3 15444.9 15864.7 15869.8 15983.6 16219.4 527742.7 585631.0 589966.0 590097.0 592981.0 58282.4 58310.0 58381.0 58381.4 58465.0 1248.4 1361.8 1378.7 1401.3 1410.6 30432.2 31484.0 31488.7 31531.8 32391.8 49415.5 49952.5 50000.7 50068.7 50094.0 432756.8 434768.0 435665.0 436560.0 437352.0 26103.0 26799.3 26881.6 27002.3 27304.7 44126.6 44486.0 47761.0 48048.8 48522.0 32074.3 39697.6 39716.6 40262.6 40556.0 39175.6 39854.2 40498.6 40566.2 40824.0 38171.2 38360.6 38478.8 38500.0 38690.8 33356.0 33609.2 34751.1 34865.0 34908.7 20816.9 22203.3 25353.5 25963.5 26591.3 09:02:25 INFO - PROCESS | 1645 | 09:02:25 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:34427.28 Median:12195.07 stddev:98195.25 (805.2%) stddev-sans-first:98437.37 09:02:25 INFO - PROCESS | 1645 | Values: 347.7 356.9 381.1 387.9 409.9 15532.0 16233.8 20417.2 20920.7 21036.8 18851.3 20153.3 20749.3 21446.0 21467.4 5277.5 5895.4 6031.8 6140.6 6144.7 5285.2 6011.8 6111.4 6125.0 6135.0 5882.7 6067.1 6091.9 6184.9 6224.3 6082.3 6191.7 6230.7 6234.9 6244.7 5816.2 5856.4 5880.0 5890.2 5892.4 13655.1 13710.0 13742.4 13871.2 13938.2 2096.9 2220.1 2223.9 2824.4 2969.8 9695.9 10096.7 10577.0 10616.3 10650.5 1846.9 1868.8 1936.7 1985.1 2044.3 92129.6 113948.0 118888.0 120770.0 121502.4 11344.7 11432.1 11482.9 11504.2 11734.3 14668.8 14674.4 14684.9 14687.5 14699.3 7094.5 8262.3 8515.1 8637.2 8668.8 8325.2 8334.7 8341.2 8511.7 8542.4 4415.7 4509.1 4513.3 4596.2 4609.7 1610.3 1855.3 1900.8 1906.3 1908.4 892.9 926.2 935.2 974.5 1059.1 20616.4 22023.7 22077.6 22170.0 22439.3 912.6 919.9 994.7 995.9 1007.0 8773.8 11127.6 11531.8 11599.4 12490.6 577528.0 579427.0 590038.0 592677.0 598255.0 90034.5 122721.0 136453.0 139764.1 144803.0 1009.7 1013.8 1042.2 1044.6 1045.4 15426.7 17341.4 18884.7 19218.8 20267.3 54819.2 54878.7 55818.0 56550.0 57023.7 77889.0 79873.0 80436.8 80584.8 82892.6 7089.9 7666.7 8110.7 8254.7 8513.1 10393.9 12812.0 13088.5 13499.5 13574.7 10562.3 10929.4 10933.3 11793.2 11945.3 11246.0 11452.0 11620.9 11657.8 11959.5 10856.6 11553.9 11617.2 11790.7 11843.9 8224.5 8258.8 8291.7 8314.4 8404.0 9366.0 9789.3 11198.0 11206.6 11462.1 09:02:25 INFO - PROCESS | 1645 | 09:02:25 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5663.40 Median:4969.85 stddev:3161.37 (63.6%) stddev-sans-first:3153.52 09:02:25 INFO - PROCESS | 1645 | Values: 1336.6 1486.8 1518.8 1687.3 1851.2 7075.4 7679.3 8182.4 8187.2 8193.0 8315.8 8614.7 8620.8 8742.9 8748.3 2040.6 2387.1 2425.2 2429.1 2432.9 2375.8 2408.6 2420.3 2421.2 2425.1 2387.1 2405.9 2415.4 2425.8 2430.3 2374.1 2398.1 2400.7 2403.8 2406.2 2354.8 2368.6 2406.4 2411.0 2415.7 8766.6 8838.8 8864.6 8870.8 8874.8 5192.6 5194.3 5346.4 5358.1 5366.2 3608.0 3680.0 3695.1 3698.4 3719.3 1085.2 1095.7 1117.6 1118.9 1124.2 11506.2 11593.3 11613.6 11614.8 11652.1 3778.5 4721.4 4915.5 4972.3 5025.2 8997.0 9059.8 9074.1 9075.2 9075.7 5283.9 5289.0 5295.5 5299.9 5316.5 4115.9 4404.6 4927.9 4940.4 4978.5 3777.4 3800.7 3863.6 3908.2 3912.1 1175.2 1177.5 1182.6 1185.8 1196.7 822.3 883.9 913.3 930.8 945.5 5568.2 5604.9 5830.5 6010.7 6081.2 434.2 444.6 449.9 933.9 1004.6 2864.9 3759.6 3918.7 4013.4 4016.9 10457.4 10673.3 10769.6 10776.5 10801.1 10719.2 10725.8 10774.8 10795.7 10807.0 6702.9 7678.7 8206.0 8253.1 8254.1 5856.6 5899.7 5983.5 6025.8 6099.4 10011.0 10051.0 10106.6 10106.8 10120.5 9213.6 9481.7 9609.2 9646.5 10273.2 8023.4 8094.3 8097.8 8302.7 8320.9 8252.7 8317.3 8323.3 8325.5 8325.6 7319.0 7480.2 7496.0 7593.2 7638.9 7199.2 7293.9 7368.1 7447.2 7546.5 6942.7 7126.6 7130.4 7134.5 7154.4 4847.9 4958.5 4988.5 5088.9 5151.9 4923.4 4967.4 5144.3 5190.9 5251.2 09:02:25 INFO - PROCESS | 1645 | 09:02:25 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4296.84 Median:4759.31 stddev:1315.17 (27.6%) stddev-sans-first:1284.13 09:02:25 INFO - PROCESS | 1645 | Values: 296.4 300.8 309.2 309.2 313.9 4378.4 4569.7 5089.3 5104.8 5130.8 4877.5 5031.4 5040.8 5089.4 5099.6 3889.5 3945.0 3961.6 3964.1 3973.4 3101.9 3700.9 3883.3 3942.9 3965.3 3327.6 3867.1 3878.5 3898.5 3914.7 3066.4 3334.5 3863.7 3869.8 3882.6 3133.9 3353.9 3386.1 3386.8 3387.4 4036.7 4634.8 4926.7 5036.2 5070.7 3882.4 4426.9 4429.8 4458.2 4507.3 4415.4 4457.0 4882.2 5232.9 5428.8 3169.7 3673.8 3714.4 3718.5 3741.0 4434.0 4837.0 5233.4 5629.0 5711.1 2967.6 3698.0 3709.8 3738.7 3741.5 3780.6 3812.0 3817.5 3839.9 3876.6 4193.5 4400.3 4418.5 4466.5 4499.6 4688.7 5406.3 5417.1 5432.3 5488.6 1072.9 1076.5 1228.5 1247.8 1263.5 683.4 880.6 881.6 887.9 888.9 4230.9 4249.3 4655.9 4898.8 5101.3 4847.0 5239.1 5285.4 5518.7 5535.3 4303.0 5166.7 5179.5 5182.3 5207.8 2645.5 2900.6 2915.8 2933.8 2972.5 4749.7 4981.3 5208.5 5477.4 5814.5 4799.9 4848.5 5368.3 5403.7 5656.5 4476.8 5383.2 5756.7 5784.8 5794.3 4606.6 5186.7 5261.9 5538.4 5589.3 4719.4 5408.0 5437.3 5497.9 5510.3 4774.5 5472.6 5751.6 5765.8 5779.7 3424.0 3751.8 3766.0 3777.5 3793.9 5043.5 5537.2 5542.3 5549.9 5563.5 4597.7 4633.8 4788.3 5002.9 5508.0 4485.6 4975.7 5243.6 5399.9 5480.5 4722.7 4768.9 5178.4 5313.7 5347.6 4795.0 5214.9 5434.3 5441.2 5446.8 4376.4 4826.6 5280.0 5288.5 5343.6 09:02:25 INFO - PROCESS | 1645 | 09:02:25 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:24781.88 Median:3501.75 stddev:39204.59 (1119.6%) stddev-sans-first:39272.89 09:02:25 INFO - PROCESS | 1645 | Values: 703.3 740.0 759.2 776.4 838.5 14869.7 14956.2 15584.7 15645.2 15660.2 14121.7 14366.8 14387.1 14395.8 14837.6 670.4 689.1 839.9 865.4 868.6 838.0 851.7 853.8 857.8 864.3 911.5 912.4 914.2 923.4 926.5 723.3 890.3 917.1 923.3 924.4 459.7 571.4 674.1 676.1 678.1 15408.2 15470.4 15470.6 15471.1 15474.5 14036.6 14152.0 14295.9 14304.6 14529.2 13014.0 13233.6 13329.9 13384.7 13673.2 4527.0 4571.1 4591.3 4596.5 4770.5 118337.0 118819.0 118977.4 119064.2 119985.0 10886.9 10902.2 11234.3 11284.1 11308.6 22287.6 22315.8 22328.2 22334.6 22337.6 7343.9 7446.4 7463.2 7964.7 8162.8 4317.1 4376.6 4382.2 4417.6 4459.9 3388.3 3499.4 3500.9 3502.6 3510.1 626.4 635.3 644.5 657.8 658.4 560.3 612.9 626.0 630.8 645.2 7111.6 7861.3 8774.3 8806.4 8818.1 568.0 598.4 613.8 631.5 639.4 3785.5 4143.9 4314.2 4369.1 4390.8 128502.0 131799.0 133127.0 145747.3 162704.0 126958.4 129168.0 129558.0 130861.0 131648.7 98382.5 99385.5 101727.0 103858.0 110184.4 8449.2 8994.6 9643.1 9827.2 9835.8 57930.0 59322.0 60165.9 64683.7 65677.3 92060.2 92742.5 93566.1 94713.0 95081.0 13134.2 13224.6 13226.3 13397.9 13798.1 14910.8 15085.2 15107.2 15205.0 15316.7 10526.0 11137.9 13383.9 13804.3 14334.6 13312.7 13394.0 13707.8 13846.4 13967.4 13197.9 13216.7 13687.0 13697.5 13744.3 11060.9 11069.9 11339.8 11340.3 11711.7 10348.3 10511.8 10639.2 10722.5 11293.2 09:02:25 INFO - PROCESS | 1645 | -------- Summary: end -------- 09:02:25 INFO - PROCESS | 1645 | 09:02:26 INFO - TEST-INFO | 1645: exit 0 09:02:26 INFO - TEST-OK | dromaeo_css | took 1117315ms 09:02:26 INFO - TEST-START | kraken 09:02:26 INFO - operating with platform_type : mac_ 09:02:26 INFO - Initialising browser for kraken test... 09:02:26 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpWTdNWp/profile http://localhost:49269/getInfo.html) 09:02:29 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:02:29 INFO - PROCESS | 1654 | colorDepth:24 09:02:29 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:02:29 INFO - PROCESS | 1654 | __metrics 09:02:29 INFO - PROCESS | 1654 | 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:02:29 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:02:30 INFO - TEST-INFO | 1654: exit 0 09:02:30 INFO - Browser initialized. 09:02:30 INFO - Running cycle 1/1 for kraken test... 09:02:30 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpWTdNWp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:42 INFO - PROCESS | 1655 | RSS: Main: 233697280 09:02:42 INFO - PROCESS | 1655 | 09:02:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 09:02:45 INFO - PROCESS | 1655 | RSS: Main: 292085760 09:02:45 INFO - PROCESS | 1655 | 09:02:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 09:02:48 INFO - PROCESS | 1655 | RSS: Main: 288096256 09:02:48 INFO - PROCESS | 1655 | 09:02:52 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 09:02:53 INFO - PROCESS | 1655 | RSS: Main: 379600896 09:02:53 INFO - PROCESS | 1655 | 09:02:55 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 09:02:55 INFO - PROCESS | 1655 | RSS: Main: 283279360 09:02:55 INFO - PROCESS | 1655 | 09:02:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:02:58 INFO - PROCESS | 1655 | RSS: Main: 285564928 09:02:58 INFO - PROCESS | 1655 | 09:03:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 09:03:01 INFO - PROCESS | 1655 | RSS: Main: 324288512 09:03:01 INFO - PROCESS | 1655 | 09:03:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 09:03:05 INFO - PROCESS | 1655 | RSS: Main: 324923392 09:03:05 INFO - PROCESS | 1655 | 09:03:08 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 09:03:08 INFO - PROCESS | 1655 | RSS: Main: 322777088 09:03:08 INFO - PROCESS | 1655 | 09:03:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:03:10 INFO - PROCESS | 1655 | RSS: Main: 296923136 09:03:10 INFO - PROCESS | 1655 | 09:03:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:03:13 INFO - PROCESS | 1655 | RSS: Main: 303067136 09:03:13 INFO - PROCESS | 1655 | 09:03:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:03:16 INFO - PROCESS | 1655 | RSS: Main: 308822016 09:03:16 INFO - PROCESS | 1655 | 09:03:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:03:20 INFO - PROCESS | 1655 | RSS: Main: 323317760 09:03:20 INFO - PROCESS | 1655 | 09:03:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:03:23 INFO - PROCESS | 1655 | RSS: Main: 319311872 09:03:23 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 09:03:25 INFO - PROCESS | 1655 | RSS: Main: 362196992 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | __start_tp_report 09:03:25 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 09:03:25 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 09:03:25 INFO - PROCESS | 1655 | |i|pagename|runs| 09:03:25 INFO - PROCESS | 1655 | |0;ai-astar;112;110;129;111;113;112;113;112;112;112 09:03:25 INFO - PROCESS | 1655 | |1;audio-beat-detection;143;113;127;162;109;157;158;108;163;134 09:03:25 INFO - PROCESS | 1655 | |2;audio-dft;186;184;185;182;185;183;185;184;180;181 09:03:25 INFO - PROCESS | 1655 | |3;audio-fft;75;77;75;152;57;57;72;92;163;67 09:03:25 INFO - PROCESS | 1655 | |4;audio-oscillator;79;84;130;70;76;88;120;68;70;118 09:03:25 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;87;85;87;85;87;84;90;85;87;84 09:03:25 INFO - PROCESS | 1655 | |6;imaging-darkroom;93;93;102;149;131;93;93;94;94;94 09:03:25 INFO - PROCESS | 1655 | |7;imaging-desaturate;84;87;84;85;85;83;86;83;85;84 09:03:25 INFO - PROCESS | 1655 | |8;json-parse-financial;56;55;56;47;46;53;53;54;52;52 09:03:25 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;67;72;69;78;72;72;69;68;69;69 09:03:25 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;89;84;88;87;87;85;88;84;89;90 09:03:25 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;144;139;144;150;160;138;160;151;193;149 09:03:25 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;171;168;170;168;170;165;166;171;170;169 09:03:25 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;69;70;84;85;85;70;79;85;80;86 09:03:25 INFO - PROCESS | 1655 | __end_tp_report 09:03:25 INFO - PROCESS | 1655 | __start_cc_report 09:03:25 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2074 09:03:25 INFO - PROCESS | 1655 | __end_cc_report 09:03:25 INFO - PROCESS | 1655 | __startTimestamp1472227405657__endTimestamp 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | ------- Summary: start ------- 09:03:25 INFO - PROCESS | 1655 | Number of tests: 14 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:113.60 Median:112.00 stddev:5.48 (4.9%) stddev-sans-first:5.78 09:03:25 INFO - PROCESS | 1655 | Values: 112.0 110.0 129.0 111.0 113.0 112.0 113.0 112.0 112.0 112.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:150.00 stddev:22.33 (14.9%) stddev-sans-first:23.59 09:03:25 INFO - PROCESS | 1655 | Values: 143.0 113.0 127.0 162.0 109.0 157.0 158.0 108.0 163.0 134.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.50 stddev:1.96 (1.1%) stddev-sans-first:1.86 09:03:25 INFO - PROCESS | 1655 | Values: 186.0 184.0 185.0 182.0 185.0 183.0 185.0 184.0 180.0 181.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:88.70 Median:73.50 stddev:37.73 (51.3%) stddev-sans-first:39.69 09:03:25 INFO - PROCESS | 1655 | Values: 75.0 77.0 75.0 152.0 57.0 57.0 72.0 92.0 163.0 67.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:73.00 stddev:23.39 (32.0%) stddev-sans-first:24.45 09:03:25 INFO - PROCESS | 1655 | Values: 79.0 84.0 130.0 70.0 76.0 88.0 120.0 68.0 70.0 118.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:1.85 (2.1%) stddev-sans-first:1.94 09:03:25 INFO - PROCESS | 1655 | Values: 87.0 85.0 87.0 85.0 87.0 84.0 90.0 85.0 87.0 84.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:103.60 Median:93.00 stddev:19.83 (21.3%) stddev-sans-first:20.66 09:03:25 INFO - PROCESS | 1655 | Values: 93.0 93.0 102.0 149.0 131.0 93.0 93.0 94.0 94.0 94.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.26 (1.5%) stddev-sans-first:1.32 09:03:25 INFO - PROCESS | 1655 | Values: 84.0 87.0 84.0 85.0 85.0 83.0 86.0 83.0 85.0 84.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:53.50 stddev:3.44 (6.4%) stddev-sans-first:3.39 09:03:25 INFO - PROCESS | 1655 | Values: 56.0 55.0 56.0 47.0 46.0 53.0 53.0 54.0 52.0 52.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.50 stddev:3.17 (4.5%) stddev-sans-first:3.10 09:03:25 INFO - PROCESS | 1655 | Values: 67.0 72.0 69.0 78.0 72.0 72.0 69.0 68.0 69.0 69.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:88.00 stddev:2.13 (2.4%) stddev-sans-first:2.15 09:03:25 INFO - PROCESS | 1655 | Values: 89.0 84.0 88.0 87.0 87.0 85.0 88.0 84.0 89.0 90.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:152.80 Median:150.50 stddev:16.02 (10.6%) stddev-sans-first:16.67 09:03:25 INFO - PROCESS | 1655 | Values: 144.0 139.0 144.0 150.0 160.0 138.0 160.0 151.0 193.0 149.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:170.00 stddev:2.04 (1.2%) stddev-sans-first:2.01 09:03:25 INFO - PROCESS | 1655 | Values: 171.0 168.0 170.0 168.0 170.0 165.0 166.0 171.0 170.0 169.0 09:03:25 INFO - PROCESS | 1655 | 09:03:25 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.30 Median:84.50 stddev:7.02 (8.3%) stddev-sans-first:6.39 09:03:25 INFO - PROCESS | 1655 | Values: 69.0 70.0 84.0 85.0 85.0 70.0 79.0 85.0 80.0 86.0 09:03:25 INFO - PROCESS | 1655 | -------- Summary: end -------- 09:03:25 INFO - PROCESS | 1655 | 09:03:26 INFO - TEST-INFO | 1655: exit 0 09:03:26 INFO - TEST-OK | kraken | took 59936ms 09:03:26 INFO - SUITE-END | took 1177s 09:03:26 INFO - Completed test suite (00:19:37) 09:03:26 INFO - javascript benchmark 09:03:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3353.161709572607, 3354.3794398036366, 3380.577427821522, 3410.7782994105396, 3446.8104254266023, 5414.377346605142, 5474.069408200606, 5510.118381059031, 5529.944700552998, 5561.0546207144735, 5411.053065713494, 5490.286262785842, 5550.222968784364, 5552.305961754791, 5587.469190426903, 4993.051320249159, 5006.27409025691, 5075.974620126913, 5082.059818933509, 5199.948000519984, 5141.588672906178, 5162.845114646567, 5186.636935414523, 5196.636235463519, 5203.739813009334, 5023.673461225045, 5074.923876141861, 5099.872503187413, 5120.411152717448, 5136.409312929024, 4978.5270399312, 5030.899382012376, 5050.570701490377, 5067.467915868854, 5113.130767769489, 4971.130052240844, 5006.4743201964, 5007.32401125847, 5078.26365177051, 5118.820841270538, 5543.667379957215, 5555.500004999529, 5564.4713752193475, 5574.804881829157, 5584.46947539983, 6037.773261289834, 6057.30341010785, 6109.283207920386, 6173.598716083485, 6233.158523599302, 6229.4704950079295, 6275.937240627627, 6284.968575157095, 6301.779437719658, 6312.936870631281, 5773.971130144364, 5780.0, 5784.71076446176, 5795.565332600072, 5796.594238403356, 6191.628502289877, 6198.597091189058, 6257.0614407838275, 6271.310155882849, 6283.6858157091965, 6154.015357542772, 6160.784372546939, 6166.290876549165, 6178.845528861814, 6186.226721659793, 6200.658963756947, 6266.154069200691, 6284.0, 6290.968545157245, 6292.244930608356, 6086.147939288503, 6102.246889800143, 6123.648682309606, 6188.4739797116845, 6211.565190436626, 6219.346968568235, 6240.781572644935, 6252.749890004349, 6258.687065646699, 6277.623342599459, 4962.652614317035, 4965.82619608312, 4978.5270399312, 4981.676191047571, 4995.0509403214, 4388.078503514298, 4414.359917811964, 4415.602595766396, 4470.575295346936, 4474.328850672425, 4725.267583524559, 5757.050086735725, 5757.8560535986935, 5771.249737534094, 5811.244538210006, 6227.221597300338, 6277.05844123376, 6277.748890044439, 6285.528585356025, 6289.465395441439, 5573.0, 5618.634788738719, 5684.204211410406, 5762.711864406847, 5782.161586569924, 5382.4886635769535, 5436.700981446058, 5456.972715136399, 5467.0, 5509.090999984957, 6364.299927008025, 6375.71309291083, 6376.585521941059, 6391.808245752635, 6407.199100112486, 6099.461111717566, 6245.781397651061, 6258.749650013949, 6299.244090709144, 6311.274203466476, 6196.721147548281, 6217.160683307696, 6270.529710271839, 6272.968635156795, 6285.214348206474, 6133.570650054453, 6215.098810672517, 6218.471429928507, 6227.719752611234, 6229.626222426669, 6262.0, 6275.278342990615, 6275.3724627536885, 6285.779997700059, 6285.96857015712, 6057.909131363122, 6110.816675499742, 6123.0, 6127.448529632355, 6146.600470969552, 5936.525077993664, 6038.305594856472, 6086.573939824169, 6118.877622447615, 6160.537959652955, 4958.231474121372, 6015.4586087252355, 6018.8796224074395, 6027.0, 6076.726547305293, 6084.209052823105, 6153.323134455296, 6160.845978850564, 6164.352742961924, 6167.629942203482, 4951.900961980812, 5781.82654520365, 5885.411458854081, 5888.558358123244, 5911.793087241926], "value": 5660.6384940528205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.87428142964256, 518.7924830067973, 534.1640332879044, 541.7535021565188, 557.5595279729014, 24869.0, 32090.35819283613, 32134.0, 32217.51673724896, 32221.67778322216, 41365.58634413655, 42848.78575607125, 42917.35623965643, 44817.32774008392, 45020.0995980081, 9762.707118786448, 9923.007699230091, 9924.05721456464, 9952.502374881227, 9953.502324883762, 8846.20384165425, 9723.805523889554, 9738.220942324597, 9826.263030272723, 9836.262280328969, 8920.241779448756, 9063.957644870858, 9068.367693815111, 9071.50106744132, 12097.274163550172, 10485.475726213659, 12119.212251203688, 12383.504659813596, 12402.19385739924, 12453.0, 12067.456964436622, 12108.636740897788, 12161.51353945841, 12178.96478799303, 12211.145219834616, 35040.12399690002, 35313.470297945554, 35339.0, 35358.46962295568, 35361.82319088414, 3187.756774857807, 3317.6018877734828, 3417.8803741869165, 3597.4963505109304, 3805.923881522354, 31222.843885780425, 31539.684603153903, 32021.679783202337, 32107.51838722421, 32327.191820204447, 3082.1985689084336, 3179.300553878143, 3218.1793642621215, 3251.561039259693, 3313.088900552343, 442676.0, 442677.9999999968, 444167.0, 445017.54982450407, 455078.7246063782, 46426.76786616045, 56095.43904560984, 56121.0, 56287.71856140693, 56422.871542568915, 60800.0, 60877.39122608806, 60914.0, 61009.08486372708, 61148.082778758355, 16135.677286454205, 16344.019358838508, 16351.83648163527, 16363.263653135638, 16385.50843474674, 12695.0, 12792.424340904681, 12796.36018199105, 12813.487460501661, 12820.16668916536, 12907.677308067186, 14588.051776634486, 16159.0, 16291.185440727917, 16341.67316653684, 5098.235264710249, 5883.470487656132, 5996.190514280604, 6087.391260873877, 6175.6912154393, 858.7552547523926, 954.346272803132, 1006.6879267427121, 1014.9289549731448, 1023.744063984004, 26686.065987690727, 28287.0099546519, 29469.705302947124, 30323.090307290815, 30815.2296192597, 1039.3133749258102, 1136.8408422820812, 1139.7093741096132, 1159.8724140344555, 1164.3305099567783, 15444.92277538605, 15864.747726715996, 15869.761953570705, 15983.600409989844, 16219.432319868747, 527742.7225727771, 585631.0, 589966.0, 590097.0495147583, 592981.0, 58282.4171758277, 58310.0, 58381.0, 58381.41618583845, 58465.0, 1248.4007676315418, 1361.8093466914443, 1378.7311474262624, 1401.2993503248376, 1410.5768269519308, 30432.239194020327, 31484.0, 31488.685113148575, 31531.84234078815, 32391.76082391742, 49415.50584494253, 49952.500474994784, 50000.74999624979, 50068.749656251486, 50093.99812003812, 432756.8362158169, 434768.0, 435665.0, 436560.0, 437352.0, 26103.0, 26799.330016749736, 26881.596776048766, 27002.32494187661, 27304.72695273022, 44126.55873441225, 44486.0, 47761.0, 48048.759756201, 48522.0, 32074.26661715559, 39697.61058363059, 39716.602833971294, 40262.597374025885, 40555.98610034773, 39175.6184611084, 39854.2029159421, 40498.59501404948, 40566.18867622572, 40823.97940051522, 38171.23657526889, 38360.616393835706, 38478.845634631005, 38500.037499061626, 38690.80654596822, 33356.0, 33609.15977100592, 34751.13122171966, 34865.0, 34908.65091349054, 20816.89591552093, 22203.333899983027, 25353.492930141663, 25963.480730385665, 26591.33521661974], "value": 19575.581697232294, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.66797708188676, 356.8679588552236, 381.1139101588807, 387.8778184871766, 409.93645984872336, 15532.0, 16233.83766162338, 20417.183312667512, 20920.686189707165, 21036.789632103693, 18851.340203092896, 20153.29463468779, 20749.273775417863, 21446.03492842822, 21467.355979320644, 5277.498637629438, 5895.410458954113, 6031.758729650809, 6140.631562106266, 6144.723487443076, 5285.207218917174, 6011.819645410645, 6111.419415155553, 6124.969375153118, 6134.969325153368, 5882.705864706769, 6067.120267561201, 6091.878162436748, 6184.907226391608, 6224.315325314212, 6082.270127584674, 6191.659458729768, 6230.688465576703, 6234.906476402835, 6244.687765611747, 5816.156657284712, 5856.4143585641095, 5879.970600146972, 5890.234269544974, 5892.410758924073, 13655.112417692819, 13710.040297179201, 13742.381592828247, 13871.237081960488, 13938.233397163152, 2096.9056392462376, 2220.1452440810244, 2223.9110435582556, 2824.449232399683, 2969.792114551982, 9695.90304096957, 10096.747581310527, 10577.0, 10616.256862019662, 10650.520726567323, 1846.8984205868671, 1868.8037756035692, 1936.651402747519, 1985.0967809646577, 2044.2640649366217, 92129.6180557299, 113948.0, 118888.0, 120770.0, 121502.39248803788, 11344.659660210207, 11432.142589305835, 11482.885171148182, 11504.194706370641, 11734.295942243492, 14668.779968300483, 14674.41302347894, 14684.853151468562, 14687.485937992118, 14699.338529766186, 7094.467914906402, 8262.339012878956, 8515.105913879077, 8637.179467950535, 8668.78328041804, 8325.167483251747, 8334.66661333552, 8341.206640572305, 8511.659533618586, 8542.44474109181, 4415.66882483815, 4509.098180363875, 4513.323001549794, 4596.195665758479, 4609.677322587454, 1610.3236640610971, 1855.2949879045875, 1900.8194221548931, 1906.256559941624, 1908.4083933980232, 892.906244844282, 926.2404828041076, 935.219092058139, 974.5322245322285, 1059.0944742245424, 20616.350691944608, 22023.66964495534, 22077.558448830932, 22170.0, 22439.32682019542, 912.5756523216603, 919.8758167647281, 994.6568433890441, 995.8705368302219, 1006.9899301007042, 8773.780655483664, 11127.554897804159, 11531.827022594667, 11599.362035088152, 12490.625281241577, 577528.0, 579427.0, 590038.0, 592677.0, 598255.0, 90034.54982725176, 122721.0, 136453.0, 139764.13184220204, 144803.0, 1009.7273736091361, 1013.8124446977333, 1042.1923009667569, 1044.5717255925035, 1045.3936716704293, 15426.691466170389, 17341.393051243147, 18884.716729248797, 19218.807811921703, 20267.250558646698, 54819.17771233514, 54878.725606371714, 55818.0, 56550.0, 57023.71488142533, 77889.0, 79873.0, 80436.7934480995, 80584.79122813045, 82892.58553707192, 7089.929100708927, 7666.69333226687, 8110.675572977015, 8254.669813207405, 8513.148685131437, 10393.9480302598, 12812.0, 13088.541901033419, 13499.460021599027, 13574.660633484242, 10562.260641755003, 10929.398883061507, 10933.289336193122, 11793.233439826385, 11945.283283003047, 11246.0, 11452.0, 11620.88379116198, 11657.825132622847, 11959.521619135485, 10856.620018299322, 11553.884461155281, 11617.244879082833, 11790.705232368917, 11843.940780296043, 8224.547649879314, 8258.793530161794, 8291.709790157316, 8314.420260150551, 8403.991521017517, 9366.0, 9789.31474796786, 11198.04816590599, 11206.607768728056, 11462.140339474407], "value": 9502.672619118037, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.5522549945767, 1486.814148231471, 1518.802555667763, 1687.299770595203, 1851.1577232359277, 7075.4339652827775, 7679.308862202401, 8182.386321025917, 8187.222213889679, 8193.016837979443, 8315.75052748417, 8614.655413783457, 8620.827583448327, 8742.868856967134, 8748.256398206144, 2040.5918816236774, 2387.140629373424, 2425.1996841042414, 2429.149797570845, 2432.8783560821976, 2375.7743014413604, 2408.5664580375524, 2420.257414661441, 2421.225207933462, 2425.0542288507495, 2387.057112440585, 2405.927822165338, 2415.372003279151, 2425.8301918865686, 2430.2830664953794, 2374.0622454130694, 2398.0767404549288, 2400.7239167495786, 2403.771641694054, 2406.2420337593603, 2354.7527509611414, 2368.561816064034, 2406.3863714752624, 2411.0, 2415.685960825099, 8766.649334026632, 8838.779030524223, 8864.556772161366, 8870.778230544223, 8874.778130546723, 5192.584593232532, 5194.27280180775, 5346.358436987586, 5358.115910874701, 5366.248725178478, 3608.0438683748566, 3680.0, 3695.131644063643, 3698.42674385468, 3719.256148770257, 1085.159001773624, 1095.7453715495712, 1117.5585643670704, 1118.8937050980226, 1124.162498938288, 11506.194566380356, 11593.304401735923, 11613.593524226611, 11614.767704645945, 11652.126090543243, 3778.4899038629983, 4721.386219791474, 4915.459299477055, 4972.303877457159, 5025.195968644999, 8997.01032886382, 9059.81880362389, 9074.092590740873, 9075.228605568469, 9075.682351117679, 5283.947160528345, 5289.021531016774, 5295.4969277918535, 5299.867503312448, 5316.548093412103, 4115.8765237042935, 4404.647628189737, 4927.926081108714, 4940.407151141886, 4978.5270399312, 3777.414500752388, 3800.733948623625, 3863.5750067492554, 3908.1988192420486, 3912.139329347539, 1175.1949914308725, 1177.5172179406402, 1182.568362547681, 1185.7865584194756, 1196.6589521986193, 822.2599660305773, 883.9160279773412, 913.2693844924034, 930.7812664023949, 945.5177859291811, 5568.192612071227, 5604.859878502988, 5830.463748269516, 6010.699465026732, 6081.239845019372, 434.1945690743688, 444.64206313917606, 449.86504048785235, 933.8552524358736, 1004.6232662751469, 2864.914052578426, 3759.642833930772, 3918.747439625104, 4013.4180543821567, 4016.859409920639, 10457.372557646566, 10673.25287229902, 10769.569217231223, 10776.461176941122, 10801.081908037904, 10719.19606029551, 10725.83911241332, 10774.838377424345, 10795.676129715965, 10807.0, 6702.932970670231, 7678.731244406419, 8205.95897020511, 8253.13342099095, 8254.092049874509, 5856.560757943256, 5899.705014749159, 5983.491403230774, 6025.758969641165, 6099.420555047263, 10011.048950349705, 10051.0, 10106.595736170471, 10106.797864042825, 10120.493975301353, 9213.585388657393, 9481.715548533555, 9609.183219426428, 9646.517674116407, 10273.229507786797, 8023.438359314792, 8094.31198348147, 8097.838043238984, 8302.70940517079, 8320.916790832256, 8252.711155109542, 8317.293030092509, 8323.250907418362, 8325.542095184823, 8325.583720814057, 7318.96340518294, 7480.177180510031, 7496.0, 7593.240675932362, 7638.885416718865, 7199.2440793715905, 7293.854122917618, 7368.078990126234, 7447.218042105717, 7546.547207167588, 6942.722291108501, 7126.608036558039, 7130.42956563484, 7134.464915131282, 7154.427645788429, 4847.9063825134135, 4958.45456999737, 4988.4762099980435, 5088.898222035613, 5151.871203219949, 4923.409190897115, 4967.40391153064, 5144.254083157846, 5190.922136167885, 5251.159814429673], "value": 4524.8261070047765, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.3702132967443, 300.77592193815605, 309.2269326683292, 309.23464425546774, 313.9105354973833, 4378.387025816388, 4569.725816451007, 5089.312942752727, 5104.821331253403, 5130.769115389807, 4877.463479017305, 5031.421386540549, 5040.773165207565, 5089.414717307511, 5099.603186502338, 3889.4943657324507, 3945.0, 3961.5936342598325, 3964.1368556778266, 3973.443717879499, 3101.875924962999, 3700.888973330791, 3883.339832228528, 3942.940855887164, 3965.3060714375015, 3327.617324007746, 3867.1298957734566, 3878.4764056852387, 3898.493195884531, 3914.725969182158, 3066.4020515999277, 3334.5324459899743, 3863.7102217333813, 3869.8258578364043, 3882.5729169791443, 3133.85897634607, 3353.9496907546404, 3386.0519054664487, 3386.779859309137, 3387.4072037393357, 4036.7174297799165, 4634.837780677693, 4926.679765815246, 5036.194208926554, 5070.6957582545165, 3882.3982282745937, 4426.919347906479, 4429.822807087713, 4458.175237581057, 4507.256302710049, 4415.381846541487, 4456.977715111404, 4882.194437917739, 5232.895342093175, 5428.83713488596, 3169.683031696835, 3673.7876500754687, 3714.405695088773, 3718.4608231806506, 3740.981295093507, 4434.0, 4836.975815120937, 5233.383308345827, 5629.0, 5711.086226203787, 2967.5993740845142, 3698.0, 3709.7588656737225, 3738.663520283133, 3741.4948981887096, 3780.640839120279, 3811.9618803812164, 3817.522809648794, 3839.865604703822, 3876.554196267465, 4193.454850869429, 4400.251957167289, 4418.536053714378, 4466.508684044752, 4499.55004499554, 4688.695234809795, 5406.324209473816, 5417.101850510037, 5432.29380180574, 5488.56091512686, 1072.9409882456384, 1076.482160154179, 1228.4778968938165, 1247.8440194975628, 1263.4567136131368, 683.3849535418163, 880.5993273060742, 881.5724373678761, 887.8934527856699, 888.9288856891562, 4230.894227644272, 4249.3413520904305, 4655.86032419028, 4898.779554920037, 5101.336826212569, 4846.975765121152, 5239.056969745407, 5285.418603953562, 5518.724063796874, 5535.308086489189, 4303.0, 5166.689998600096, 5179.507946745053, 5182.274481572583, 5207.817726379559, 2645.54495027734, 2900.622204452868, 2915.8104723192923, 2933.8386388748395, 2972.464956307885, 4749.738764367924, 4981.252812078144, 5208.5051920067535, 5477.370102438191, 5814.534837213013, 4799.880002999883, 4848.490908454632, 5368.302120724359, 5403.729813509309, 5656.511135100624, 4476.753778542146, 5383.196629129286, 5756.71216439168, 5784.843031393738, 5794.333651630032, 4606.637154876892, 5186.688798672092, 5261.868453288698, 5538.390777014444, 5589.329280486368, 4719.418994636698, 5407.972010496064, 5437.265969094122, 5497.862553436196, 5510.311211098613, 4774.522547745198, 5472.644278121909, 5751.626144300607, 5765.827025189251, 5779.682117483579, 3423.9828800855835, 3751.793651349202, 3766.0, 3777.527809023872, 3793.867214647474, 5043.470435604356, 5537.197106419627, 5542.307211598551, 5549.861253468695, 5563.499285064364, 4597.655175861891, 4633.83781567657, 4788.305695674038, 5002.924956125588, 5507.972460137674, 4485.573870482299, 4975.651704380659, 5243.632945693765, 5399.9460005401015, 5480.479354461319, 4722.740249286185, 4768.880777980578, 5178.352705911761, 5313.734313284398, 5347.609621498361, 4795.0, 5214.878801057791, 5434.266374039454, 5441.183822426668, 5446.782128714807, 4376.431063961792, 4826.613870890391, 5280.046382838221, 5288.471152884681, 5343.599230057839], "value": 3881.8684354004345, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.2615753458869, 739.9556026638398, 759.1876691939627, 776.3866545429112, 838.488522001579, 14869.702605947874, 14956.177410242432, 15584.688306233882, 15645.217739113055, 15660.216989150525, 14121.717565648682, 14366.784498232535, 14387.1367717937, 14395.78406323906, 14837.629059273497, 670.4033410264868, 689.1248114894081, 839.86562150056, 865.3639574912465, 868.6047848229041, 837.995810020952, 851.6508231625035, 853.8206976534935, 857.7512521368795, 864.3171894203572, 911.4804561400005, 912.393258483108, 914.2092090341839, 923.4320892651039, 926.4904302633558, 723.2586598736285, 890.3100097424483, 917.101240784032, 923.2567782934735, 924.4499522783933, 459.6943032883129, 571.3857603076709, 674.1371045062328, 676.0670275020441, 678.0608856733411, 15408.22958852064, 15470.381184752596, 15470.61323466911, 15471.149086800222, 15474.5357639271, 14036.649083772987, 14152.0, 14295.928520357436, 14304.642383940381, 14529.200893950829, 13013.958883289313, 13233.602991910258, 13329.866701333056, 13384.665383365495, 13673.179609223478, 4526.977365113186, 4571.108633816407, 4591.288350305708, 4596.517365676623, 4770.522947705236, 118337.0, 118819.0, 118977.40511297475, 119064.21403678952, 119985.0, 10886.945565272123, 10902.182336324808, 11234.3259404436, 11284.097272218203, 11308.604198853001, 22287.55424891493, 22315.776842231695, 22328.21851235199, 22334.55330893373, 22337.553248935255, 7343.926560734431, 7446.367058800062, 7463.216362281991, 7964.681412743425, 8162.836743265101, 4317.136572685476, 4376.6498680105515, 4382.2112019836095, 4417.646588272931, 4459.910801783946, 3388.3223355329033, 3499.440089585705, 3500.8774692886136, 3502.6322236164838, 3510.052285882797, 626.3579830673505, 635.3455940381415, 644.4844124700221, 657.8388294867788, 658.4107224034462, 560.3164139749499, 612.9264488261348, 625.974961001564, 630.8170630517099, 645.1677336236203, 7111.573305601681, 7861.2531809478005, 8774.298056155492, 8806.427582207136, 8818.060026794223, 567.9545636349083, 598.4314900844129, 613.7637009751188, 631.5253882185149, 639.3510586754448, 3785.5268091488565, 4143.937840932388, 4314.201872653569, 4369.148016136823, 4390.824367025347, 128502.0, 131799.0, 133127.0, 145747.271263643, 162704.0, 126958.36520817337, 129167.99999999812, 129558.0, 130861.0, 131648.65337094115, 98382.50808745767, 99385.50307248418, 101727.0, 103858.0, 110184.44907775571, 8449.23956843887, 8994.55027248648, 9643.119591679555, 9827.213822894295, 9835.754106147404, 57929.99339560343, 59322.0, 60165.89419370301, 64683.67658161679, 65677.34322656713, 92060.16613035942, 92742.53628731813, 93566.06433935846, 94713.0, 95081.0, 13134.211947283193, 13224.603261902159, 13226.338683066, 13397.888642092314, 13798.067550747988, 14910.79774856321, 15085.160566512228, 15107.244637768288, 15205.0, 15316.693666126392, 10526.0, 11137.944310278397, 13383.866161338263, 13804.309784510533, 14334.569962900712, 13312.667183320495, 13393.976790939325, 13707.794383084061, 13846.376913038735, 13967.371468283749, 13197.868021319664, 13216.692045104228, 13687.04190706641, 13697.452101915811, 13744.312784360942, 11060.889391105986, 11069.889301107209, 11339.773204536028, 11340.262882912584, 11711.707207319545, 10348.327358721659, 10511.789764204826, 10639.202059845387, 10722.463876805972, 11293.153013524183], "value": 7795.14911117148, "unit": "score"}], "name": "dromaeo_css", "value": 7241.4169416361, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 129.0, 111.0, 113.0, 112.0, 113.0, 112.0, 112.0, 112.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 113.0, 127.0, 162.0, 109.0, 157.0, 158.0, 108.0, 163.0, 134.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 185.0, 182.0, 185.0, 183.0, 185.0, 184.0, 180.0, 181.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 152.0, 57.0, 57.0, 72.0, 92.0, 163.0, 67.0], "value": 88.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 130.0, 70.0, 76.0, 88.0, 120.0, 68.0, 70.0, 118.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 87.0, 84.0, 90.0, 85.0, 87.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 102.0, 149.0, 131.0, 93.0, 93.0, 94.0, 94.0, 94.0], "value": 103.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 84.0, 85.0, 85.0, 83.0, 86.0, 83.0, 85.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 47.0, 46.0, 53.0, 53.0, 54.0, 52.0, 52.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 69.0, 78.0, 72.0, 72.0, 69.0, 68.0, 69.0, 69.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 88.0, 87.0, 87.0, 85.0, 88.0, 84.0, 89.0, 90.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 139.0, 144.0, 150.0, 160.0, 138.0, 160.0, 151.0, 193.0, 149.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 170.0, 168.0, 170.0, 165.0, 166.0, 171.0, 170.0, 169.0], "value": 168.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 84.0, 85.0, 85.0, 70.0, 79.0, 85.0, 80.0, 86.0], "value": 79.3, "unit": "score"}], "name": "kraken", "value": 1498.6999999999998, "alertThreshold": 2.0}]} 09:03:26 INFO - Return code: 0 09:03:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:03:26 INFO - # TBPL SUCCESS # 09:03:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:03:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:03:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:03:26 INFO - 2016-08-26 09:03:26.945 screenresolution[1659:9845] starting screenresolution argv=screenresolution get 09:03:26 INFO - 2016-08-26 09:03:26.948 screenresolution[1659:9845] Display 0: 1600x1200x32@60 09:03:26 INFO - 2016-08-26 09:03:26.954 screenresolution[1660:9848] starting screenresolution argv=screenresolution list 09:03:26 INFO - Available Modes on Display 0 09:03:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:03:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:03:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:03:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:03:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:03:27 INFO - Intel Iris: 09:03:27 INFO - Chipset Model: Intel Iris 09:03:27 INFO - Type: GPU 09:03:27 INFO - Bus: Built-In 09:03:27 INFO - VRAM (Dynamic, Max): 1536 MB 09:03:27 INFO - Vendor: Intel (0x8086) 09:03:27 INFO - Device ID: 0x0a2e 09:03:27 INFO - Revision ID: 0x0009 09:03:27 INFO - Displays: 09:03:27 INFO - Display: 09:03:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:03:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:03:27 INFO - Display Serial Number: 1600x1200 60 09:03:27 INFO - Main Display: Yes 09:03:27 INFO - Mirror: Off 09:03:27 INFO - Online: Yes 09:03:27 INFO - Rotation: Supported 09:03:27 INFO - Return code: 0 09:03:27 INFO - Running post-action listener: _resource_record_post_action 09:03:27 INFO - [mozharness: 2016-08-26 16:03:27.188354Z] Finished run-tests step (success) 09:03:27 INFO - Running post-run listener: _resource_record_post_run 09:03:27 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 38265856; Write bytes: 703606784; Read time: 376; Write time: 1882 09:03:27 INFO - TinderboxPrint: CPU usage
53.4% 09:03:27 INFO - TinderboxPrint: I/O read bytes / time
38,265,856 / 376 09:03:27 INFO - TinderboxPrint: I/O write bytes / time
703,606,784 / 1,882 09:03:27 INFO - TinderboxPrint: CPU idle
2,259.3 (46.6%) 09:03:27 INFO - TinderboxPrint: CPU system
965.1 (19.9%) 09:03:27 INFO - TinderboxPrint: CPU user
1,625.2 (33.5%) 09:03:27 INFO - TinderboxPrint: Swap in / out
444,993,536 / 0 09:03:27 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391324160; Write bytes: 385333248; Read time: 29813; Write time: 699 09:03:27 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 37311488; Write bytes: 294156288; Read time: 360; Write time: 1138 09:03:27 INFO - Running post-run listener: _upload_blobber_files 09:03:27 INFO - Blob upload gear active. 09:03:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:03:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:03:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:03:28 INFO - (blobuploader) - INFO - Open directory for files ... 09:03:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:03:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:03:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:29 INFO - (blobuploader) - INFO - Done attempting. 09:03:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:03:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:03:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:30 INFO - (blobuploader) - INFO - Done attempting. 09:03:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:03:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:03:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:34 INFO - (blobuploader) - INFO - Done attempting. 09:03:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:03:34 INFO - Return code: 0 09:03:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:03:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:03:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bb5218a2cf967927c3fdbc42722f9064fdc30e80ba50e00d4689cba02709c04aabe6a03bcca48d67244094375382b8250f81e0a76ef7e87b549bab4e94cabe3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68dba199ef980e4a32043e49b2ab041b01b70f352669b2c3d10760e1136ebd6d2ecbcfb99951a40a7934bb68a151f277a2c4fb4959a47f6dfb0dc6299ab32fb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866954fb806464f15afee656bbff472f0ab24d0e3e1dbaea4e3d51e99a345b2058d6a889c8277c6d6359114fd9fb8288565fb667a1f62b4e374aaee6da1763a7"} 09:03:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:03:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:03:34 INFO - Contents: 09:03:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bb5218a2cf967927c3fdbc42722f9064fdc30e80ba50e00d4689cba02709c04aabe6a03bcca48d67244094375382b8250f81e0a76ef7e87b549bab4e94cabe3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68dba199ef980e4a32043e49b2ab041b01b70f352669b2c3d10760e1136ebd6d2ecbcfb99951a40a7934bb68a151f277a2c4fb4959a47f6dfb0dc6299ab32fb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866954fb806464f15afee656bbff472f0ab24d0e3e1dbaea4e3d51e99a345b2058d6a889c8277c6d6359114fd9fb8288565fb667a1f62b4e374aaee6da1763a7"} 09:03:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:03:34 INFO - Copying logs to upload dir... 09:03:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:03:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.403635 ========= master_lag: 83.45 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 39 secs) (at 2016-08-26 09:04:57.742999) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-26 09:04:57.746408) ========= 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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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/Firefox/sha512/9bb5218a2cf967927c3fdbc42722f9064fdc30e80ba50e00d4689cba02709c04aabe6a03bcca48d67244094375382b8250f81e0a76ef7e87b549bab4e94cabe3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68dba199ef980e4a32043e49b2ab041b01b70f352669b2c3d10760e1136ebd6d2ecbcfb99951a40a7934bb68a151f277a2c4fb4959a47f6dfb0dc6299ab32fb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866954fb806464f15afee656bbff472f0ab24d0e3e1dbaea4e3d51e99a345b2058d6a889c8277c6d6359114fd9fb8288565fb667a1f62b4e374aaee6da1763a7"} build_url:https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011394 build_url: 'https://queue.taskcluster.net/v1/task/BwerARjgTfOqYK6utmpglA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bb5218a2cf967927c3fdbc42722f9064fdc30e80ba50e00d4689cba02709c04aabe6a03bcca48d67244094375382b8250f81e0a76ef7e87b549bab4e94cabe3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68dba199ef980e4a32043e49b2ab041b01b70f352669b2c3d10760e1136ebd6d2ecbcfb99951a40a7934bb68a151f277a2c4fb4959a47f6dfb0dc6299ab32fb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866954fb806464f15afee656bbff472f0ab24d0e3e1dbaea4e3d51e99a345b2058d6a889c8277c6d6359114fd9fb8288565fb667a1f62b4e374aaee6da1763a7"}' ========= master_lag: 1.50 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-26 09:04:59.255300) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-08-26 09:04:59.255675) ========= 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.oRSbnkWM1C/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.8Okv1JJ9ic/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.005128 ========= master_lag: 3.50 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-08-26 09:05:02.762510) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:05:02.762830) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:05:02.776808) ========= ========= Total master_lag: 88.52 =========