builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0145 starttime: 1472231148.61 results: success (0) buildid: 20160826075817 builduid: 746cf2f2435f455a83d4a285997c7562 revision: 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:48.614793) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:48.615310) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:48.615598) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:48.672283) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:48.672629) ========= 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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:05:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.6M=0.001s 2016-08-26 10:05:48 (14.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.152378 ========= master_lag: 0.64 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:49.464651) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:49.464969) ========= 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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.032108 ========= master_lag: 0.88 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:50.376861) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-08-26 10:05:50.377208) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ydFqIlVmIB/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.WAOgRKjYvt/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:05:50,466 truncating revision to first 12 chars 2016-08-26 10:05:50,467 Setting DEBUG logging. 2016-08-26 10:05:50,467 attempt 1/10 2016-08-26 10:05:50,467 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4eda5a74d949?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 10:05:51,530 unpacking tar archive at: autoland-4eda5a74d949/testing/mozharness/ program finished with exit code 0 elapsedTime=1.325163 ========= master_lag: 2.25 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-08-26 10:05:53.956398) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:53.956909) ========= script_repo_revision: 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:53.957320) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:53.957623) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 10:05:54.737519) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-26 10:05:54.737886) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ydFqIlVmIB/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.WAOgRKjYvt/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:05:55 INFO - MultiFileLogger online at 20160826 10:05:55 in /builds/slave/test 10:05:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 10:05:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:05:55 INFO - {'append_to_log': False, 10:05:55 INFO - 'base_work_dir': '/builds/slave/test', 10:05:55 INFO - 'blob_upload_branch': 'Autoland', 10:05:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:05:55 INFO - 'branch': 'Autoland', 10:05:55 INFO - 'buildbot_json_path': 'buildprops.json', 10:05:55 INFO - 'config_files': ('talos/mac_config.py',), 10:05:55 INFO - 'default_actions': ('clobber', 10:05:55 INFO - 'read-buildbot-config', 10:05:55 INFO - 'download-and-extract', 10:05:55 INFO - 'populate-webroot', 10:05:55 INFO - 'create-virtualenv', 10:05:55 INFO - 'install', 10:05:55 INFO - 'run-tests'), 10:05:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:05:55 INFO - 'download_minidump_stackwalk': True, 10:05:55 INFO - 'download_symbols': 'ondemand', 10:05:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:05:55 INFO - 'tooltool.py': '/tools/tooltool.py', 10:05:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:05:55 INFO - '/tools/misc-python/virtualenv.py')}, 10:05:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:05:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:05:55 INFO - 'installer_path': 'installer.exe', 10:05:55 INFO - 'log_level': 'info', 10:05:55 INFO - 'log_name': 'talos', 10:05:55 INFO - 'log_to_console': True, 10:05:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:05:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:05:55 INFO - 'opt_config_files': (), 10:05:55 INFO - 'pip_index': False, 10:05:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:05:55 INFO - 'cmd': ('bash', 10:05:55 INFO - '-c', 10:05:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:05:55 INFO - 'enabled': True, 10:05:55 INFO - 'halt_on_failure': False, 10:05:55 INFO - 'name': 'check_screen_resolution'},), 10:05:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:05:55 INFO - 'cmd': ('bash', 10:05:55 INFO - '-c', 10:05:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:05:55 INFO - 'enabled': True, 10:05:55 INFO - 'halt_on_failure': False, 10:05:55 INFO - 'name': 'check_screen_resolution'},), 10:05:55 INFO - 'run_cmd_checks_enabled': True, 10:05:55 INFO - 'sps_profile': False, 10:05:55 INFO - 'sps_profile_interval': 0, 10:05:55 INFO - 'suite': 'dromaeojs', 10:05:55 INFO - 'system_bits': '32', 10:05:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:05:55 INFO - 'title': 't-yosemite-r7-0145', 10:05:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:05:55 INFO - 'use_talos_json': True, 10:05:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:05:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:05:55 INFO - 'work_dir': 'build'} 10:05:55 INFO - [mozharness: 2016-08-26 17:05:55.101564Z] Running clobber step. 10:05:55 INFO - Running pre-action listener: _resource_record_pre_action 10:05:55 INFO - Running main action method: clobber 10:05:55 INFO - rmtree: /builds/slave/test/build 10:05:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:05:57 INFO - Running post-action listener: _resource_record_post_action 10:05:57 INFO - [mozharness: 2016-08-26 17:05:57.358180Z] Finished clobber step (success) 10:05:57 INFO - [mozharness: 2016-08-26 17:05:57.358301Z] Running read-buildbot-config step. 10:05:57 INFO - Running pre-action listener: _resource_record_pre_action 10:05:57 INFO - Running main action method: read_buildbot_config 10:05:57 INFO - Using buildbot properties: 10:05:57 INFO - { 10:05:57 INFO - "project": "", 10:05:57 INFO - "product": "firefox", 10:05:57 INFO - "script_repo_revision": "production", 10:05:57 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 10:05:57 INFO - "repository": "", 10:05:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 10:05:57 INFO - "buildid": "20160826075817", 10:05:57 INFO - "pgo_build": "False", 10:05:57 INFO - "basedir": "/builds/slave/test", 10:05:57 INFO - "buildnumber": 187, 10:05:57 INFO - "slavename": "t-yosemite-r7-0145", 10:05:57 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:05:57 INFO - "platform": "yosemite_r7", 10:05:57 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:05:57 INFO - "branch": "autoland", 10:05:57 INFO - "repo_path": "integration/autoland", 10:05:57 INFO - "revision": "4eda5a74d949ad4c8bc587898fb420a8ef8bd28d", 10:05:57 INFO - "stage_platform": "macosx64", 10:05:57 INFO - "builduid": "746cf2f2435f455a83d4a285997c7562", 10:05:57 INFO - "slavebuilddir": "test" 10:05:57 INFO - } 10:05:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:05:57 INFO - Running post-action listener: _resource_record_post_action 10:05:57 INFO - [mozharness: 2016-08-26 17:05:57.361609Z] Finished read-buildbot-config step (success) 10:05:57 INFO - [mozharness: 2016-08-26 17:05:57.361693Z] Running download-and-extract step. 10:05:57 INFO - Running pre-action listener: _resource_record_pre_action 10:05:57 INFO - Running main action method: download_and_extract 10:05:57 INFO - mkdir: /builds/slave/test/build/tests 10:05: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')]} 10:05:57 INFO - trying https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:05:57 INFO - Downloading https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/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 10:05:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/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 10:05:58 INFO - Downloaded 1199 bytes. 10:05:58 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:05:58 INFO - Using the following test package requirements: 10:05:58 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:05:58 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:05:58 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:05:58 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:05:58 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:05:58 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:05:58 INFO - u'jsshell-mac.zip'], 10:05:58 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:05:58 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:05:58 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:05:58 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:05:58 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:05:58 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:05:58 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:05:58 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:05:58 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:05:58 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:05:58 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:05:58 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:05:58 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')]} 10:05:58 INFO - trying https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:05:58 INFO - Downloading https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/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 10:05:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/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 10:05:59 INFO - Downloaded 16368887 bytes. 10:05:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:06:06 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 10:06:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:06 INFO - trying https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:06:06 INFO - Downloading https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/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 10:06:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/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 10:06:07 INFO - Downloaded 16368887 bytes. 10:06:07 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:06:14 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')]} 10:06:14 INFO - trying https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:06:14 INFO - Downloading https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/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 10:06:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/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 10:06:15 INFO - Downloaded 14413998 bytes. 10:06:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 10:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:15 INFO - trying https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:06:15 INFO - Downloading https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/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 10:06:18 INFO - Downloaded 111615286 bytes. 10:06:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:06:18 INFO - mkdir: /builds/slave/test/properties 10:06:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:06:18 INFO - Writing to file /builds/slave/test/properties/build_url 10:06:18 INFO - Contents: 10:06:18 INFO - build_url:https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:06:19 INFO - Running post-action listener: _resource_record_post_action 10:06:19 INFO - Running post-action listener: set_extra_try_arguments 10:06:19 INFO - [mozharness: 2016-08-26 17:06:19.598796Z] Finished download-and-extract step (success) 10:06:19 INFO - [mozharness: 2016-08-26 17:06:19.598924Z] Running populate-webroot step. 10:06:19 INFO - Running pre-action listener: _resource_record_pre_action 10:06:19 INFO - Running main action method: populate_webroot 10:06:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:06:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:06:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:06:19 INFO - '--tppagecycles', 10:06:19 INFO - '7'], 10:06:19 INFO - 'tests': ['tsvgm']}}, 10:06:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:06:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:06:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:06:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:06:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:06:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:06:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:19 INFO - 'talos_options': ['--disable-e10s'], 10:06:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:06:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:06:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:19 INFO - 'talos_options': ['--disable-e10s'], 10:06:19 INFO - 'tests': ['damp', 'tps']}, 10:06:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:19 INFO - 'tests': ['damp', 'tps']}, 10:06:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:06:19 INFO - 'tests': ['dromaeo_dom']}, 10:06:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:06:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:06:19 INFO - 'tests': ['basic_compositor_video']}, 10:06:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:06:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:06:19 INFO - 'tests': ['a11yr', 10:06:19 INFO - 'ts_paint', 10:06:19 INFO - 'tpaint', 10:06:19 INFO - 'sessionrestore', 10:06:19 INFO - 'sessionrestore_no_auto_restore', 10:06:19 INFO - 'tabpaint']}, 10:06:19 INFO - 'other-e10s': {'tests': ['a11yr', 10:06:19 INFO - 'ts_paint', 10:06:19 INFO - 'tpaint', 10:06:19 INFO - 'sessionrestore', 10:06:19 INFO - 'sessionrestore_no_auto_restore', 10:06:19 INFO - 'tabpaint']}, 10:06:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:06:19 INFO - 'ts_paint', 10:06:19 INFO - 'tpaint', 10:06:19 INFO - 'sessionrestore', 10:06:19 INFO - 'sessionrestore_no_auto_restore', 10:06:19 INFO - 'tabpaint']}, 10:06:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:06:19 INFO - 'ts_paint', 10:06:19 INFO - 'tpaint', 10:06:19 INFO - 'sessionrestore', 10:06:19 INFO - 'sessionrestore_no_auto_restore', 10:06:19 INFO - 'tabpaint']}, 10:06:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:06:19 INFO - 'tests': ['a11yr', 10:06:19 INFO - 'ts_paint', 10:06:19 INFO - 'tpaint', 10:06:19 INFO - 'sessionrestore', 10:06:19 INFO - 'sessionrestore_no_auto_restore', 10:06:19 INFO - 'tabpaint']}, 10:06:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:06:19 INFO - 'tests': ['a11yr', 10:06:19 INFO - 'ts_paint', 10:06:19 INFO - 'tpaint', 10:06:19 INFO - 'sessionrestore', 10:06:19 INFO - 'sessionrestore_no_auto_restore', 10:06:19 INFO - 'tabpaint']}, 10:06:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:06:19 INFO - 'tests': ['tsvgx', 10:06:19 INFO - 'tsvgr_opacity', 10:06:19 INFO - 'tart', 10:06:19 INFO - 'tscrollx', 10:06:19 INFO - 'cart']}, 10:06:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:06:19 INFO - 'tsvgr_opacity', 10:06:19 INFO - 'tart', 10:06:19 INFO - 'tscrollx', 10:06:19 INFO - 'cart']}, 10:06:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:19 INFO - 'talos_options': ['--disable-e10s'], 10:06:19 INFO - 'tests': ['tp5o']}, 10:06:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:19 INFO - 'tests': ['tp5o']}, 10:06:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:19 INFO - 'talos_options': ['--disable-e10s', 10:06:19 INFO - '--xperf_path', 10:06:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:06:19 INFO - 'tests': ['tp5n']}, 10:06:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:06:19 INFO - 'talos_options': ['--xperf_path', 10:06:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:06:19 INFO - 'tests': ['tp5n']}}, 10:06:19 INFO - 'talos.zip': {'path': '', 10:06:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:06:19 INFO - Running post-action listener: _resource_record_post_action 10:06:19 INFO - [mozharness: 2016-08-26 17:06:19.617792Z] Finished populate-webroot step (success) 10:06:19 INFO - [mozharness: 2016-08-26 17:06:19.617920Z] Running create-virtualenv step. 10:06:19 INFO - Running pre-action listener: _resource_record_pre_action 10:06:19 INFO - Running main action method: create_virtualenv 10:06:19 INFO - Creating virtualenv /builds/slave/test/build/venv 10:06:19 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 10:06:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:06:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:06:19 INFO - Using real prefix '/tools/python27' 10:06:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:06:20 INFO - Installing distribute.............................................................................................................................................................................................done. 10:06:23 INFO - Installing pip.................done. 10:06:23 INFO - Return code: 0 10:06:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:06:23 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')]} 10:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:23 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')]} 10:06:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:23 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 0x10fe11a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a1c987f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd75d30>, '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 0x10fadadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6db20>, '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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:06:23 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 10:06:23 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 10:06:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ydFqIlVmIB/Render', 10:06:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:23 INFO - 'HOME': '/Users/cltbld', 10:06:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:23 INFO - 'LOGNAME': 'cltbld', 10:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:23 INFO - 'MOZ_NO_REMOTE': '1', 10:06:23 INFO - 'NO_EM_RESTART': '1', 10:06:23 INFO - 'PAGER': '/bin/cat', 10:06:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:23 INFO - 'PWD': '/builds/slave/test', 10:06:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:23 INFO - 'SHELL': '/bin/bash', 10:06:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WAOgRKjYvt/Listeners', 10:06:23 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 10:06:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:23 INFO - 'USER': 'cltbld', 10:06:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:23 INFO - 'XPC_FLAGS': '0x0', 10:06:23 INFO - 'XPC_SERVICE_NAME': '0', 10:06:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:23 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 10:06:23 INFO - Cleaning up... 10:06:23 INFO - Return code: 0 10:06:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:06:23 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')]} 10:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:23 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')]} 10:06:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:23 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 0x10fe11a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a1c987f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd75d30>, '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 0x10fadadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6db20>, '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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:06:23 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 10:06:23 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 10:06:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ydFqIlVmIB/Render', 10:06:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:23 INFO - 'HOME': '/Users/cltbld', 10:06:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:23 INFO - 'LOGNAME': 'cltbld', 10:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:23 INFO - 'MOZ_NO_REMOTE': '1', 10:06:23 INFO - 'NO_EM_RESTART': '1', 10:06:23 INFO - 'PAGER': '/bin/cat', 10:06:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:23 INFO - 'PWD': '/builds/slave/test', 10:06:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:23 INFO - 'SHELL': '/bin/bash', 10:06:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WAOgRKjYvt/Listeners', 10:06:23 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 10:06:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:23 INFO - 'USER': 'cltbld', 10:06:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:23 INFO - 'XPC_FLAGS': '0x0', 10:06:23 INFO - 'XPC_SERVICE_NAME': '0', 10:06:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:24 INFO - Downloading/unpacking psutil>=3.1.1 10:06:24 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 10:06:24 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 10:06:24 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 10:06:24 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 10:06:24 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 10:06:24 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 10:06:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:06:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:06:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:06:27 INFO - Installing collected packages: psutil 10:06:27 INFO - Running setup.py install for psutil 10:06:27 INFO - building 'psutil._psutil_osx' extension 10:06:27 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 10:06:28 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 10:06:28 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 10:06:28 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 10:06:28 INFO - building 'psutil._psutil_posix' extension 10:06:28 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 10:06:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:06:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:06:28 INFO - ^ 10:06:28 INFO - 1 warning generated. 10:06:28 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 10:06:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:06:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:06:28 INFO - Successfully installed psutil 10:06:28 INFO - Cleaning up... 10:06:28 INFO - Return code: 0 10:06:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:28 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 0x10fe11a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a1c987f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd75d30>, '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 0x10fadadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6db20>, '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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:06:28 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 10:06:28 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 10:06:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ydFqIlVmIB/Render', 10:06:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:28 INFO - 'HOME': '/Users/cltbld', 10:06:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:28 INFO - 'LOGNAME': 'cltbld', 10:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:28 INFO - 'MOZ_NO_REMOTE': '1', 10:06:28 INFO - 'NO_EM_RESTART': '1', 10:06:28 INFO - 'PAGER': '/bin/cat', 10:06:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:28 INFO - 'PWD': '/builds/slave/test', 10:06:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:28 INFO - 'SHELL': '/bin/bash', 10:06:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WAOgRKjYvt/Listeners', 10:06:28 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 10:06:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:28 INFO - 'USER': 'cltbld', 10:06:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:28 INFO - 'XPC_FLAGS': '0x0', 10:06:28 INFO - 'XPC_SERVICE_NAME': '0', 10:06:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:06:28 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 10:06:28 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 10:06:29 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 10:06:29 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 10:06:29 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 10:06:29 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 10:06:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:06:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:06:31 INFO - Installing collected packages: mozsystemmonitor 10:06:31 INFO - Running setup.py install for mozsystemmonitor 10:06:31 INFO - Successfully installed mozsystemmonitor 10:06:31 INFO - Cleaning up... 10:06:31 INFO - Return code: 0 10:06:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:31 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 0x10fe11a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a1c987f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd75d30>, '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 0x10fadadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6db20>, '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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:06:31 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 10:06:31 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 10:06:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ydFqIlVmIB/Render', 10:06:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:31 INFO - 'HOME': '/Users/cltbld', 10:06:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:31 INFO - 'LOGNAME': 'cltbld', 10:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:31 INFO - 'MOZ_NO_REMOTE': '1', 10:06:31 INFO - 'NO_EM_RESTART': '1', 10:06:31 INFO - 'PAGER': '/bin/cat', 10:06:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:31 INFO - 'PWD': '/builds/slave/test', 10:06:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:31 INFO - 'SHELL': '/bin/bash', 10:06:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WAOgRKjYvt/Listeners', 10:06:31 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 10:06:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:31 INFO - 'USER': 'cltbld', 10:06:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:31 INFO - 'XPC_FLAGS': '0x0', 10:06:31 INFO - 'XPC_SERVICE_NAME': '0', 10:06:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:32 INFO - Downloading/unpacking jsonschema==2.5.1 10:06:32 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 10:06:32 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 10:06:32 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 10:06:32 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 10:06:32 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 10:06:32 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 10:06:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:06:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:06:34 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 10:06:34 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 10:06:34 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 10:06:34 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 10:06:34 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 10:06:34 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 10:06:34 INFO - Downloading functools32-3.2.3-2.tar.gz 10:06:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:06:34 INFO - warning: no files found matching '*.txt' 10:06:34 INFO - no previously-included directories found matching 'build' 10:06:34 INFO - no previously-included directories found matching 'dist' 10:06:34 INFO - no previously-included directories found matching '.git*' 10:06:34 INFO - Installing collected packages: jsonschema, functools32 10:06:34 INFO - Running setup.py install for functools32 10:06:35 INFO - warning: no files found matching '*.txt' 10:06:35 INFO - no previously-included directories found matching 'build' 10:06:35 INFO - no previously-included directories found matching 'dist' 10:06:35 INFO - no previously-included directories found matching '.git*' 10:06:35 INFO - Successfully installed jsonschema functools32 10:06:35 INFO - Cleaning up... 10:06:35 INFO - Return code: 0 10:06:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fe11a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a1c987f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd75d30>, '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 0x10fadadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6db20>, '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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:06:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:06:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ydFqIlVmIB/Render', 10:06:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:35 INFO - 'HOME': '/Users/cltbld', 10:06:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:35 INFO - 'LOGNAME': 'cltbld', 10:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:35 INFO - 'MOZ_NO_REMOTE': '1', 10:06:35 INFO - 'NO_EM_RESTART': '1', 10:06:35 INFO - 'PAGER': '/bin/cat', 10:06:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:35 INFO - 'PWD': '/builds/slave/test', 10:06:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:35 INFO - 'SHELL': '/bin/bash', 10:06:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WAOgRKjYvt/Listeners', 10:06:35 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 10:06:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:35 INFO - 'USER': 'cltbld', 10:06:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:35 INFO - 'XPC_FLAGS': '0x0', 10:06:35 INFO - 'XPC_SERVICE_NAME': '0', 10:06:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:06:35 INFO - Cleaning up... 10:06:35 INFO - Return code: 0 10:06:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fe11a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a1c987f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd75d30>, '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 0x10fadadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6db20>, '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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:06:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:06:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ydFqIlVmIB/Render', 10:06:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:35 INFO - 'HOME': '/Users/cltbld', 10:06:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:35 INFO - 'LOGNAME': 'cltbld', 10:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:35 INFO - 'MOZ_NO_REMOTE': '1', 10:06:35 INFO - 'NO_EM_RESTART': '1', 10:06:35 INFO - 'PAGER': '/bin/cat', 10:06:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:35 INFO - 'PWD': '/builds/slave/test', 10:06:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:35 INFO - 'SHELL': '/bin/bash', 10:06:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WAOgRKjYvt/Listeners', 10:06:35 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 10:06:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:35 INFO - 'USER': 'cltbld', 10:06:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:35 INFO - 'XPC_FLAGS': '0x0', 10:06:35 INFO - 'XPC_SERVICE_NAME': '0', 10:06:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:35 INFO - Downloading/unpacking blobuploader==1.2.4 10:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:06:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:38 INFO - Downloading blobuploader-1.2.4.tar.gz 10:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:06:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:06:38 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 10:06:38 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 10:06:38 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 10:06:38 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 10:06:38 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 10:06:38 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 10:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:06:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:06:38 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 10:06:38 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 10:06:38 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 10:06:38 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 10:06:38 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 10:06:38 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 10:06:39 INFO - Downloading docopt-0.6.1.tar.gz 10:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:06:39 INFO - Installing collected packages: blobuploader, requests, docopt 10:06:39 INFO - Running setup.py install for blobuploader 10:06:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:06:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:06:39 INFO - Running setup.py install for requests 10:06:40 INFO - Running setup.py install for docopt 10:06:40 INFO - Successfully installed blobuploader requests docopt 10:06:40 INFO - Cleaning up... 10:06:40 INFO - Return code: 0 10:06:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:06:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:40 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 0x10fe11a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a1c987f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd75d30>, '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 0x10fadadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6db20>, '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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:06:40 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 10:06:40 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 10:06:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ydFqIlVmIB/Render', 10:06:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:40 INFO - 'HOME': '/Users/cltbld', 10:06:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:40 INFO - 'LOGNAME': 'cltbld', 10:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:40 INFO - 'MOZ_NO_REMOTE': '1', 10:06:40 INFO - 'NO_EM_RESTART': '1', 10:06:40 INFO - 'PAGER': '/bin/cat', 10:06:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:40 INFO - 'PWD': '/builds/slave/test', 10:06:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:40 INFO - 'SHELL': '/bin/bash', 10:06:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WAOgRKjYvt/Listeners', 10:06:40 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 10:06:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:40 INFO - 'USER': 'cltbld', 10:06:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:40 INFO - 'XPC_FLAGS': '0x0', 10:06:40 INFO - 'XPC_SERVICE_NAME': '0', 10:06:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:06:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-CxXbvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:06:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-W2dHXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:06:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-YimuU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:06:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Q5q_c7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:06:40 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-csjofd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:06:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-g8jgiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:06:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-5DP8tk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:06:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-MRl2_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:06:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-P9cyVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:06:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-epTK3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:06:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-5F8QLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:06:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-A6NSgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:06:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-pBtEaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:06:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-JoM_Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:41 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-J3cmGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:06:42 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Zy0ipz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:06:42 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-gUxpMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:06:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:06:42 INFO - Running setup.py install for manifestparser 10:06:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:06:42 INFO - Running setup.py install for mozcrash 10:06:42 INFO - Running setup.py install for mozdebug 10:06:42 INFO - Running setup.py install for mozdevice 10:06:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:06:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:06:42 INFO - Running setup.py install for mozfile 10:06:43 INFO - Running setup.py install for mozhttpd 10:06:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:06:43 INFO - Running setup.py install for mozinfo 10:06:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:06:43 INFO - Running setup.py install for mozInstall 10:06:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:06:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:06:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:06:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:06:43 INFO - Running setup.py install for mozleak 10:06:43 INFO - Running setup.py install for mozlog 10:06:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:06:43 INFO - Running setup.py install for moznetwork 10:06:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:06:43 INFO - Running setup.py install for mozprocess 10:06:44 INFO - Running setup.py install for mozprofile 10:06:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:06:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:06:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:06:44 INFO - Running setup.py install for mozrunner 10:06:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:06:44 INFO - Running setup.py install for mozscreenshot 10:06:44 INFO - Running setup.py install for moztest 10:06:44 INFO - Running setup.py install for mozversion 10:06:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:06:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:06:44 INFO - Cleaning up... 10:06:44 INFO - Return code: 0 10:06:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:06:44 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')]} 10:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:44 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')]} 10:06:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:44 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 0x10fe11a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a1c987f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd75d30>, '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 0x10fadadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6db20>, '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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:06:44 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 10:06:44 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 10:06:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ydFqIlVmIB/Render', 10:06:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:44 INFO - 'HOME': '/Users/cltbld', 10:06:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:44 INFO - 'LOGNAME': 'cltbld', 10:06:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:44 INFO - 'MOZ_NO_REMOTE': '1', 10:06:44 INFO - 'NO_EM_RESTART': '1', 10:06:44 INFO - 'PAGER': '/bin/cat', 10:06:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:44 INFO - 'PWD': '/builds/slave/test', 10:06:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:44 INFO - 'SHELL': '/bin/bash', 10:06:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WAOgRKjYvt/Listeners', 10:06:44 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 10:06:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:44 INFO - 'USER': 'cltbld', 10:06:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:44 INFO - 'XPC_FLAGS': '0x0', 10:06:44 INFO - 'XPC_SERVICE_NAME': '0', 10:06:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:06:45 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-W0GaVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:06:45 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)) 10:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:06:45 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-D9WGkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:06:45 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)) 10:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:06:45 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-7HPGqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:06:45 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)) 10:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:06:45 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-2esBet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:06:45 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)) 10:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:06:45 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Wbcrg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:06:45 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)) 10:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:06:45 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-k9kyUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:06:45 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)) 10:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:06:45 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-qhLW0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:06:45 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)) 10:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:06:45 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-GtVRb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:06:45 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)) 10:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:06:45 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-mg37AC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:06:46 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)) 10:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:06:46 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-gchTsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:06:46 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)) 10:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:06:46 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-R6X82_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:06:46 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)) 10:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:06:46 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-BoMsur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:06:46 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)) 10:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:06:46 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-aQ2i1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:06:46 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)) 10:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:06:46 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-zXtpbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:06:46 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)) 10:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:46 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-YhlVkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:46 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)) 10:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:06:46 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-3l7_Tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:06:46 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)) 10:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:06:46 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-k_VlXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:06:46 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)) 10:06:46 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)) 10:06:46 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)) 10:06:46 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)) 10:06:46 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)) 10:06:46 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)) 10:06:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:06: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 10:06: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 10:06: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 10:06: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 10:06: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 10:06: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 10:06:49 INFO - Downloading blessings-1.6.tar.gz 10:06:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:06:49 INFO - Installing collected packages: blessings 10:06:49 INFO - Running setup.py install for blessings 10:06:49 INFO - Successfully installed blessings 10:06:49 INFO - Cleaning up... 10:06:49 INFO - Return code: 0 10:06:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:06:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:06:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:06:50 INFO - Reading from file tmpfile_stdout 10:06:50 INFO - Current package versions: 10:06:50 INFO - blessings == 1.6 10:06:50 INFO - blobuploader == 1.2.4 10:06:50 INFO - docopt == 0.6.1 10:06:50 INFO - functools32 == 3.2.3-2 10:06:50 INFO - jsonschema == 2.5.1 10:06:50 INFO - manifestparser == 1.1 10:06:50 INFO - mozInstall == 1.12 10:06:50 INFO - mozcrash == 0.17 10:06:50 INFO - mozdebug == 0.1 10:06:50 INFO - mozdevice == 0.48 10:06:50 INFO - mozfile == 1.2 10:06:50 INFO - mozhttpd == 0.7 10:06:50 INFO - mozinfo == 0.9 10:06:50 INFO - mozleak == 0.1 10:06:50 INFO - mozlog == 3.3 10:06:50 INFO - moznetwork == 0.27 10:06:50 INFO - mozprocess == 0.23 10:06:50 INFO - mozprofile == 0.28 10:06:50 INFO - mozrunner == 6.12 10:06:50 INFO - mozscreenshot == 0.1 10:06:50 INFO - mozsystemmonitor == 0.3 10:06:50 INFO - moztest == 0.7 10:06:50 INFO - mozversion == 1.4 10:06:50 INFO - psutil == 3.1.1 10:06:50 INFO - requests == 1.2.3 10:06:50 INFO - wsgiref == 0.1.2 10:06:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:06: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')]} 10:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06: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')]} 10:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:50 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 0x10fe11a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a1c987f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd75d30>, '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 0x10fadadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6db20>, '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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:06:50 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 10:06:50 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 10:06:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ydFqIlVmIB/Render', 10:06:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:50 INFO - 'HOME': '/Users/cltbld', 10:06:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:50 INFO - 'LOGNAME': 'cltbld', 10:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:50 INFO - 'MOZ_NO_REMOTE': '1', 10:06:50 INFO - 'NO_EM_RESTART': '1', 10:06:50 INFO - 'PAGER': '/bin/cat', 10:06:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:50 INFO - 'PWD': '/builds/slave/test', 10:06:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:50 INFO - 'SHELL': '/bin/bash', 10:06:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WAOgRKjYvt/Listeners', 10:06:50 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 10:06:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:50 INFO - 'USER': 'cltbld', 10:06:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:50 INFO - 'XPC_FLAGS': '0x0', 10:06:50 INFO - 'XPC_SERVICE_NAME': '0', 10:06:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:50 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)) 10:06:50 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)) 10:06:50 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)) 10:06:50 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)) 10:06:50 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)) 10:06:50 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)) 10:06:50 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)) 10:06:50 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)) 10:06:50 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)) 10:06:50 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)) 10:06:50 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)) 10:06:50 INFO - Cleaning up... 10:06:50 INFO - Return code: 0 10:06:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:06: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')]} 10:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06: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')]} 10:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe11a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fea6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a1c987f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd75d30>, '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 0x10fadadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6db20>, '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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 10:06: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', 'jsonschema'] in /builds/slave/test/build 10:06: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 jsonschema 10:06:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ydFqIlVmIB/Render', 10:06:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:50 INFO - 'HOME': '/Users/cltbld', 10:06:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:50 INFO - 'LOGNAME': 'cltbld', 10:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:50 INFO - 'MOZ_NO_REMOTE': '1', 10:06:50 INFO - 'NO_EM_RESTART': '1', 10:06:50 INFO - 'PAGER': '/bin/cat', 10:06:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:50 INFO - 'PWD': '/builds/slave/test', 10:06:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:50 INFO - 'SHELL': '/bin/bash', 10:06:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WAOgRKjYvt/Listeners', 10:06:50 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 10:06:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:50 INFO - 'USER': 'cltbld', 10:06:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:50 INFO - 'XPC_FLAGS': '0x0', 10:06:50 INFO - 'XPC_SERVICE_NAME': '0', 10:06:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:06:50 INFO - Cleaning up... 10:06:50 INFO - Return code: 0 10:06:50 INFO - Running post-action listener: _resource_record_post_action 10:06:50 INFO - Running post-action listener: _start_resource_monitoring 10:06:50 INFO - Starting resource monitoring. 10:06:50 INFO - [mozharness: 2016-08-26 17:06:50.688011Z] Finished create-virtualenv step (success) 10:06:50 INFO - [mozharness: 2016-08-26 17:06:50.688985Z] Running install step. 10:06:50 INFO - Running pre-action listener: _resource_record_pre_action 10:06:50 INFO - Running main action method: install 10:06:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:06:50 INFO - Reading from file tmpfile_stdout 10:06:50 INFO - Detecting whether we're running mozinstall >=1.0... 10:06:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:06:51 INFO - Reading from file tmpfile_stdout 10:06:51 INFO - Output received: 10:06:51 INFO - Usage: mozinstall [options] installer 10:06:51 INFO - Options: 10:06:51 INFO - -h, --help show this help message and exit 10:06:51 INFO - -d DEST, --destination=DEST 10:06:51 INFO - Directory to install application into. [default: 10:06:51 INFO - "/builds/slave/test"] 10:06:51 INFO - --app=APP Application being installed. [default: firefox] 10:06:51 INFO - mkdir: /builds/slave/test/build/application 10:06:51 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'] 10:06:51 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 10:07:25 INFO - Reading from file tmpfile_stdout 10:07:25 INFO - Output received: 10:07:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:07:25 INFO - Running post-action listener: _resource_record_post_action 10:07:25 INFO - [mozharness: 2016-08-26 17:07:25.269143Z] Finished install step (success) 10:07:25 INFO - [mozharness: 2016-08-26 17:07:25.269276Z] Running run-tests step. 10:07:25 INFO - Running pre-action listener: _resource_record_pre_action 10:07:25 INFO - Running main action method: run_tests 10:07:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:07:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:07:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:07:25 INFO - 2016-08-26 10:07:25.283 screenresolution[1636:7921] starting screenresolution argv=screenresolution get 10:07:25 INFO - 2016-08-26 10:07:25.286 screenresolution[1636:7921] Display 0: 1600x1200x32@60 10:07:25 INFO - 2016-08-26 10:07:25.292 screenresolution[1637:7923] starting screenresolution argv=screenresolution list 10:07:25 INFO - Available Modes on Display 0 10:07:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:07:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:07:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:07:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:07:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:07:25 INFO - Intel Iris: 10:07:25 INFO - Chipset Model: Intel Iris 10:07:25 INFO - Type: GPU 10:07:25 INFO - Bus: Built-In 10:07:25 INFO - VRAM (Dynamic, Max): 1536 MB 10:07:25 INFO - Vendor: Intel (0x8086) 10:07:25 INFO - Device ID: 0x0a2e 10:07:25 INFO - Revision ID: 0x0009 10:07:25 INFO - Displays: 10:07:25 INFO - Display: 10:07:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:07:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:07:25 INFO - Display Serial Number: 1600x1200 60 10:07:25 INFO - Main Display: Yes 10:07:25 INFO - Mirror: Off 10:07:25 INFO - Online: Yes 10:07:25 INFO - Rotation: Supported 10:07:25 INFO - Return code: 0 10:07:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:07:25 INFO - Python 2.7.3 10:07:25 INFO - Return code: 0 10:07:25 INFO - grabbing minidump binary from tooltool 10:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:07:25 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 0x10fd75d30>, '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 0x10fadadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd6db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:07:25 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 10:07:25 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 10:07:25 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 10:07:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:07:25 INFO - Return code: 0 10:07:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:07:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:07:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:07:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:07:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:07:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--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 10:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0145 --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 10:07:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ydFqIlVmIB/Render', 10:07:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:25 INFO - 'HOME': '/Users/cltbld', 10:07:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:25 INFO - 'LOGNAME': 'cltbld', 10:07:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:07:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:25 INFO - 'MOZ_NO_REMOTE': '1', 10:07:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:07:25 INFO - 'NO_EM_RESTART': '1', 10:07:25 INFO - 'PAGER': '/bin/cat', 10:07:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:25 INFO - 'PWD': '/builds/slave/test', 10:07:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:07:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:25 INFO - 'SHELL': '/bin/bash', 10:07:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WAOgRKjYvt/Listeners', 10:07:25 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 10:07:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:25 INFO - 'USER': 'cltbld', 10:07:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:25 INFO - 'XPC_FLAGS': '0x0', 10:07:25 INFO - 'XPC_SERVICE_NAME': '0', 10:07:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--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 10:07:25 INFO - mozversion application_buildid: 20160826075817 10:07:25 INFO - mozversion application_changeset: 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d 10:07:25 INFO - mozversion application_display_name: Nightly 10:07:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:07:25 INFO - mozversion application_name: Firefox 10:07:25 INFO - mozversion application_remotingname: firefox 10:07:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:07:25 INFO - mozversion application_vendor: Mozilla 10:07:25 INFO - mozversion application_version: 51.0a1 10:07:25 INFO - mozversion platform_buildid: 20160826075817 10:07:25 INFO - mozversion platform_changeset: 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d 10:07:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:07:25 INFO - mozversion platform_version: 51.0a1 10:07:25 INFO - using testdate: 1472231245 10:07:25 INFO - actual date: 1472231245 10:07:25 INFO - starting webserver on 'localhost:49262' 10:07:25 INFO - SUITE-START | Running 2 tests 10:07:25 INFO - TEST-START | dromaeo_css 10:07:25 INFO - operating with platform_type : mac_ 10:07:25 INFO - Initialising browser for dromaeo_css test... 10:07:25 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpvYKenH/profile http://localhost:49262/getInfo.html) 10:07:28 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 10:07:28 INFO - PROCESS | 1647 | colorDepth:24 10:07:28 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 10:07:28 INFO - PROCESS | 1647 | __metrics 10:07:28 INFO - PROCESS | 1647 | 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" 10:07:28 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:07:29 INFO - TEST-INFO | 1647: exit 0 10:07:29 INFO - Browser initialized. 10:07:29 INFO - Running cycle 1/1 for dromaeo_css test... 10:07:29 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpvYKenH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:07:41 INFO - PROCESS | 1650 | RSS: Main: 233443328 10:07:41 INFO - PROCESS | 1650 | 10:08:32 INFO - PROCESS | 1650 | 1472231312329 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] 10:10:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49262/tests/dromaeo/cssquery-ext.html) 10:10:45 INFO - PROCESS | 1650 | RSS: Main: 388210688 10:10:45 INFO - PROCESS | 1650 | 10:13:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-ext.html (next: http://localhost:49262/tests/dromaeo/cssquery-jquery.html) 10:13:49 INFO - PROCESS | 1650 | RSS: Main: 369676288 10:13:49 INFO - PROCESS | 1650 | 10:16:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49262/tests/dromaeo/cssquery-mootools.html) 10:16:52 INFO - PROCESS | 1650 | RSS: Main: 341626880 10:16:52 INFO - PROCESS | 1650 | 10:19:54 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49262/tests/dromaeo/cssquery-prototype.html) 10:19:55 INFO - PROCESS | 1650 | RSS: Main: 331915264 10:19:55 INFO - PROCESS | 1650 | 10:22:58 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49262/tests/dromaeo/cssquery-yui.html) 10:22:59 INFO - PROCESS | 1650 | RSS: Main: 323158016 10:22:59 INFO - PROCESS | 1650 | 10:26:02 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-yui.html (next: http://localhost:49262/tests/dromaeo/cssquery-dojo.html) 10:26:03 INFO - PROCESS | 1650 | RSS: Main: 625545216 10:26:03 INFO - PROCESS | 1650 | 10:26:03 INFO - PROCESS | 1650 | __start_tp_report 10:26:03 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 10:26:03 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 10:26:03 INFO - PROCESS | 1650 | |i|pagename|runs| 10:26:03 INFO - PROCESS | 1650 | |0;dojo.html;3339.5992480902287;3359.093044877883;3371.6965473107416;3388.728901687862;3442.7590068695185;5603.075492543736;5657.915131273029;5699.658020518772;5737.225474560933;5748.080307150852;5659.490645841884;5706.77172913084;5739.454751798583;5768.884622307552;5787.189793428924;5150.356205474316;5178.119719647669;5232.6075544334135;5250.842474725764;5359.732013399314;5301.390340110898;5356.892862142755;5367.704776237305;5374.543163831079;5405.486478784529;5150.61370397218;5194.870128246824;5209.739513024334;5232.110541208004;5242.6330156889035;5223;5228.633995620308;5244.685318880879;5270.578353731692;5340.305760251143;5208.140656791625;5213.244079608436;5223.921641175385;5256.894862102775;5314.92027619586;5649.604527683064;5681.176229446707;5751.338596061465;5754.942450575482;5769.509591684712;6156.8768624627255;6200.596961197552;6229.813105606839;6293.370662933716;6300.054991751228;6263.749450021994;6299.96050651642;6313.558050936436;6324.146240257553;6349.682515874234;4983.451820299764;5671.716414179275;5724.914126288109;5828.766849326022;5835.79574714883;5170.534651881274;6273.592216505913;6279.497640188774;6287.528435367366;6324.588901721466;6150.630962142286;6153.784617538365;6167.475764559973;6170.228721409824;6181.629102253789;6094.380596348918;6148.938510614926;6207.317195108625;6221.3156552779155;6259.123722678829;6109.981658125326;6124.655575320006;6143.846403839851;6179.62922224668;6186.288576813634;6173.84565385869;6174.38256174388;6205.131281620576;6228.56400051992;6229.221347331583;4960.429550601655;4961.82633607822;4967.279744437036;4975.104481193348;4998.825041123543;4406.515283318832;4410.647148228134;4420.491643460979;4481.305397663367;4488.259437192892;5696.572757043155;5745;5750.769969201268;5788.27646544182;5841.474267315962;6124.693765311806;6164;6181.938180618226;6193.071039344044;6193.411625895533;5571.331440227199;5718.456746609066;5760.193572899798;5785;5789.478946894799;5360.597955153301;5426.158945363475;5453.263809385682;5457.945420545823;5513.5037846593195;6295.622262664255;6335.1447554579545;6344.777932772331;6371.267304259976;6378.362163783585;5259.21111833228;6265.467435267871;6293.307736148929;6321.715522801577;6321.96839015802;6230.813075607739;6267.780627678009;6287.402696743802;6295;6337.841553961188;6167.568270221221;6236.407541283571;6238.251409830849;6242.282137554058;6247.562670613195;6246.656433895998;6270.905936411049;6278.623282602875;6296.464800492009;6304.211973503312;5797.188393624981;6019.067044608093;6063.878722425438;6075.787347442818;6124;6104.816855494342;6105.908411373922;6107.54193435503;6122.877542449215;6135.263768347827;5938.346781854079;5944.791932282349;5956.702164891824;5963.970180149072;5974.671393073423;5898.616589921641;6063.636181829104;6082.847928801639;6105.603135796159;6127.724252408563;5883.794067207627;5929.0513517836935;5938.435848594377;5949.405059494016;5966.284045914518 10:26:03 INFO - PROCESS | 1650 | |1;ext.html;462.9491055304458;468.4589299359241;496.20359323286124;502.46738457235335;530.3450238954888;31119.377612447737;39090.60909390905;40048.39927401084;40196.79901600495;42050.369244461355;40990.7950460248;41451.963700907494;41472.37791433131;41509.79245103778;41595.37606935899;9621.807563848719;9794.9510252449;9802.411855288705;9812.607495700164;9814.165795907356;8771.692990745325;8972.596233169523;8984.955075224647;9000.369974101815;12077.698057548543;11920.880791192063;12256.571020014302;12257.44841482131;12272.263664180133;12273.815892761571;12275.079369047313;12319.199252048587;12339.75320493585;12341.568045118378;12374.443150058269;12068.698282542919;12092.032637388987;12132.211406258564;12132.45403956824;12161.331126788024;40361.79819100915;40581.797091014654;40667;40709.389359159635;40728.38907416391;3533.081398836295;3548.254866478043;3578.481120237577;3810.5617853946874;4109.465769449982;30374.54438183429;31450.842745786125;31552;31555.526667100014;31651.84174079138;3228.370467758788;3270.1497610621172;3293.5389045533643;3321.5183798349344;3323.302106557626;460302;461206;461854;462373.37626623973;467242;55279;55325;55652;55764.72117639467;55770.44229557733;57891;58057.70971145117;58126.4187358121;58202.126968095516;58264.12603810946;15714.607134821721;15938.043717376762;15944.963577367434;15946.9202653986;15956.361745530048;12519.3740312984;12581.37093145339;12585.056120790978;12589.622311330675;12589.622311330675;12787.488500459878;13094.738105238033;13101.541446049534;13376.46494140243;14533.200673962821;4495.656668694591;5639.603168653306;6433.1636887205295;6617.8014659560295;6643.10318107059;866.9003064647646;907.4827348411342;910.9043550427242;989.4557993103764;996.3473924579412;25108.74891251064;27038.14380367632;27786.444271114466;28410.85794571014;30165.104420345353;1057.6456886942806;1072.0458791675414;1092.093562343253;1116.1740312168936;1138.1918837625378;15246.313915873812;15562.688746225012;15760.92119539418;15922.681546369007;15966.680666386606;620622;625130;626120;626550;629308;47935.76032119817;55354;55404.72297638567;57730;58428.707856460445;1351.0542620165922;1373.6977864869912;1396.134396674089;1397.0918902713317;1397.1617029782253;26440;28582.14178582125;30256.243593910327;31003.379932401676;31121.533177002817;51677.48322516727;52177.7391113042;52184;52292.21561676654;52305;547424;788445;814642;815791;819524;24565.017399303826;24774;24842.378940526633;24933.875330623232;24948.750512495368;43740.56259437365;44298.55701442944;44435;44848.32727509025;45276.6830911174;39029.414558782206;39115.804420977714;39314.21371572495;40185.79907100446;40597.79701101476;37400.438993415664;38058.809705951295;38258.61741382662;39335.803320984356;39870.60129398669;36599.81700091482;36635;37255.81372093122;37450.81274593718;37707.2458550822;29111.417771644872;32402.83798580992;33671.27588309944;33784;33822.49266260959;25026.499470010862;25413.87293063523;25682.486350272517;25696.614550781378;26163.084292050447 10:26:03 INFO - PROCESS | 1650 | |2;jquery.html;344.17741597581784;353.44509120680544;377.48096367494696;384.2718049296583;405.94925634295714;15633.3746650134;16539.503814885542;20360.796392036074;20779.27272545461;20911.581768364624;20979.37061888142;21187.788122118774;21385.144594216214;21607.351779446602;21647.891760541213;5814.622049566775;6064.878702425949;6103.206583144185;7651.426143039266;8040.879386809173;7752.844943101135;8005.199480052006;8100.270975612223;8111.269985701286;8113.350931925432;7980.241877021703;8044.8391032179325;8055.798605034892;8066.556339401331;8073.071596766388;6920.858058420355;7910.920890791076;8018.959905200495;8062.113167551564;8075.192480751966;7618.276263754934;7679.654415551257;7768.7669369918995;7860.489068210605;7865.292123708837;29198.41603167946;29724;29847.552286715716;29851;30037.39925201484;2658.2025392382207;2670.612761149622;2771.390294135287;2857.2999615094227;2974.3902499987666;15802.209889505479;17103.31586736529;17475;17651.646967060587;17826.93038417699;1994.112619884152;2006.6388050150968;2054.6404379233722;2091.874487530738;2096.779838116991;78984.60507697424;81225;107976.46011769891;127892.721072789;145061;16304.836951630568;16863.591835102583;20902.89548552278;20911.144053884636;21170.36488905335;33195.66804331926;33262.83580074734;33401.3319733604;33414.331713365595;33452;10480.947595261974;10731.892681073245;10922.399268040175;10930.01629853317;11117.221794474472;8913.24237439812;8940.392439920388;8951.239144672776;9083.81832363349;9131.178193962442;8664.696735614223;9156.946951100575;9595.90404095964;9793.363431376938;9818.354446193467;3618.5476815398074;3733.533308336458;3739.9626003739613;3740.1023754299317;3748.7938163401277;834.9749507514784;892.8258989497116;897.5871099294267;984.8522721591676;1024.8052869954686;20481.692774608393;21222.575548488945;21292.57414851725;21303.68044479334;21356.660834791302;880.5993273060742;900.1808354397467;931.6226928094133;941.8493041113389;965.6909788867636;10902.291351062315;11217.943910280397;11263.155263355282;11284.774304513863;11317.886821131848;481645;556135;559048;567153;571664;91704.08295917089;99736;124746;131392;132507.337463314;950.933434659581;953.1945506047379;983.2429029646988;984.9409035457895;988.2736188901152;15823.920880395524;16478.94013248344;19467.61064778725;19655.803441965396;19749.605007899474;46138.538614613426;50572;51577.48422515877;54097.18854217111;54394.28028598475;61334;81787.18212817796;82655.586722066;83288;83942;7922.009748781403;8031.27718505314;8052.718154864786;8186.426950113673;8260.545669987969;10258.948705256425;12659.683507912376;12824.679383015498;13154.144980576231;13207.339633018502;11031.503582338975;11100.333979961228;11250.60622878228;11315.20793544444;11360.772784544097;8757.817694611145;9349.158575727944;10844.620438284623;11077.889221107685;11109.388983605979;9345.720105320574;11119.888801111885;11185.384803835866;11198.496067676811;11430.085593152695;7876.921230787619;8130.308923741548;8172.223638754546;8328.958355208186;8341.332693384637;10290.331128476626;11083.667489975314;11400.657980260605;11412.543498259976;11493 10:26:03 INFO - PROCESS | 1650 | |3;mootools.html;1375.9105658132223;1518.8101487314086;1648.2994727240919;1687.2829047654752;1869.2522990803668;7912.683492660301;8184;8363.372747043974;8910.46537207768;8914.598843052063;8020.478668886532;8112.067112282075;8295.294899933513;8593.39846210764;8633.654653813855;1871.4105056907065;2302.6776251324827;2303.884805759714;2303.9193628223015;2309.595820731374;1940;2078;2310.6482707615983;2365.515069410775;2365.6924599802;2125.075592117426;2144.4746037220903;2153.1387445022037;2271.46620544172;2343.8593684378907;2000.959980800376;2308.4113551044543;2327.662488939103;2327.7206735191717;2337.368910394201;2300.5513924784673;2316.6177580699164;2341.6487526870937;2344.859308441499;2351.564960482316;7571.772846814604;8569.971603407632;8608.268297194745;8665.43674661145;8696.347773916981;5143.074246635604;5297;5304.575633949275;5305.15117581189;5321.600879933982;3132.859021344045;3240.708336249725;3276.5249038889465;3276.754243431752;3289.111939776271;996.3573495095695;1036.3056751976194;1052.105710146377;1054.2620165883836;1059.8622179116742;11273.09815214789;11456.4844581994;11465.140114491447;11475.885241147565;11476.655700329004;4450.933236001462;4643.95356046442;4807.18277892759;4829.613630909519;4846.854594362175;9957.0540798624;9959.601615935426;9973.401595904268;9973.401595904268;9977.900220997843;4543.818247270072;5360.410354860962;5365.463453654604;5366.980273747976;5369.731513424314;4708.599769019672;4846.830360937351;4893.559579637778;4896.730679812645;4897.975510122426;3782.167923056923;3786.3563194257044;3847.7691338519217;3866.709996750255;3887.8056097195026;995.875515560555;1256.8114782782473;1257.1577043380958;1276.6553030681664;1282.602393258093;741.8034220931457;853.4367317570374;907.4464576608264;915.0986278515657;916.7295647783919;4640.744759038285;5687.43125687428;5892.1161825726485;5985.191999080069;6020.337762846083;425.14121474621095;436.25182811477833;438.69510690070734;443.79807187729506;445.58560538698674;3878.1855810279594;3889.4360317753767;3959.8812035638975;3991.960080399159;4024.215278020758;10181.33821301613;10689.037986581245;10696.358218506915;10704;10757.784844303227;10646.627368042082;10658.307210031324;10658.78682426347;10661.73345666349;10662.306950048378;7324.278794483293;8025.438219324708;8052.355811535063;8153.8776918346275;8171.918280817115;5615.8876822464135;5689.914651280317;5694.829155125353;5766.097895130153;5866.911996319973;9586.712398628053;9678.467684277432;9847.359921605073;9853.556589953327;9981.45102019396;9710.660126895526;9962.452065136487;9987.25095617817;10061.748456288651;10146.137578305927;7164.068671072727;7225.24134965821;7333;7334.266573342623;7346.889796652948;8041;8051;8152.755417337489;8153.55155466455;8153.6738530458115;7178.964105179441;7221.350078492961;7344.081989751281;7385.224551422239;7392.815179620553;7098.893516597152;7158.319959603829;7217.783466496014;7224.674889629874;7306.744263950736;6722.193336799615;6779.457643388419;6880.277570855188;6914.688839002157;6939.063226464363;4816.373871396836;4900.828471003498;4916.803327866988;4948.975255123702;5068.6451948363265;4899.971006088761;5012.248162775612;5028.924566131438;5077.949220507748;5173.767180476812 10:26:03 INFO - PROCESS | 1650 | |4;prototype.html;294.96026506564357;307.04968123657284;309.6500953922068;312.70449425293106;315.26700421519973;5379.38137114232;5400.838819653779;5420.159875219338;5436.075867102592;5439;5283.154695248761;5315.893682126355;5355.196720491928;5370.7046112463795;5379.601303661047;3210;3904.414337849324;3907.8241479133367;4058.3506638937774;4111.609397107285;3721.181340105186;3917.4319723640056;4071.755694658315;4089.1003979124544;4108.383742438628;3624.6194149614307;3741.6258374162503;3846.7114966377635;4115.300398932174;4118.929078439613;3619.49327094207;3805.46723458716;4099.672026237894;4111.60939710727;4114.876553703379;3009.5554134015797;3468.236987862666;3567.965290065878;3568.3933731265747;3589.3898037333715;5169.638125331228;5189.351331083614;5208.895822083575;5221.728548081596;5244.763985620656;3867.8839634811;3895.668868146211;4120.402541631476;4666.53334666534;4705.647076469244;5570.521118102446;5577.2191893135;5582.86042848928;5586.776528938837;5622.971885140548;3470.6182319944787;3714.758540694846;3935.3506671399186;3955.7626542407547;3960.6237407446247;5069.493050694938;5114.616403769695;5601.579881508903;5759.1937128802;5958.433948774859;3522.5949015862993;4004.9199016019516;4014.8193331300163;4015.257177422128;4018.678505719594;3399.609044959812;3939.6605154247695;4069.8604390770633;4081.285774989365;4082.591740825894;4673.322368256584;4678.953210467852;4700.247960326331;4705.435347758223;4723.905521889543;4350.976795789973;4541.592955733467;4748.905021899543;5664.997551297819;5678.432156784289;1319.33373646308;1335.485837952376;1338.8259526261718;1343.173948021979;1344.9529266475627;713.6895450479058;728.8797348437448;743.3644234179699;816.008549612224;820.2084987986622;4591.517890621503;5000.3249561309485;5180.715060671624;5191.377034755854;5220.084936297777;4860.246661767432;5070.543651071422;5624.384661132593;5850.3564607893095;5893.88016276906;4468.352088947085;5029.214628806721;5317.511096892877;5318.627696061239;5327.813526526553;2884.8172249377653;2910.0978696603697;2994.757175772074;3026.0028381268257;3077.7691673124605;5004.248513020487;5278.987969143479;5376.946230537723;5860.2381690380835;5993.700314984233;5557.805476808373;5712.429406766972;5926.658969707028;5975.43233392827;6012.458878700938;5271.68207948013;5979.79070732531;5986.132010858314;6012.759489620366;6018.488428483628;5458.344998600193;5801.767929282782;5819.94180058194;5835.912461313169;5891.410858914246;5000.874628282892;5298.629095963245;5722.513586345207;5742.196092546963;5792.248681876075;5512.035393805988;5885.676287804212;6054.969725151346;6062.120742569818;6062.90905636407;2716;3176.761742869248;3222.113918672384;3283.6223834258412;3421.6920477157178;4117.547069822377;4277.443932288783;4634.513376095461;4838.443578988461;4872.123017856819;4154.50145982484;4483.439570053743;4601.952131301538;4676.339899335687;4693.286950263061;3930.941035884407;4380.3717769338145;4741.075490279362;4758.405199350082;4775.379200703886;3679.466477360821;4076.980754811297;4502.617277531446;4581.839635612738;4677.742724150205;4275.87172384829;5088.949110508996;5339.679619222859;5450.863728406822;5566.359868614999;5506.614536982373;5534.501894829485;5540.390557038642;5545.639533430314;5568.470995255444 10:26:03 INFO - PROCESS | 1650 | |5;yui.html;635.5932203389829;735.4888352594945;768.4582369429553;775.7827808213702;813.6094674556211;14654.340554675038;16316.184190790473;16328.346866125356;16347.509574712747;16362.918185409071;11913.344766037864;14312.069715468488;14372.49696260631;14426;14586.562403127897;664.3854434647956;676.425038717089;826.5908375354193;828.883956246123;851.259404318244;849.1169184048582;855.0722466124231;858.6222062292571;859.6647307550061;862.784303924019;907.9863802042975;910.4582773249906;910.9772255693595;915.4232833315002;916.2395211974045;706.9964650176717;910.5128756115513;910.995445022774;911.3848152497038;912.8539433690578;653.751574401729;678.3826717686882;682.5870348439222;683.0130461483159;685.3283781893772;14989.175595342253;15008.174550399724;15020.549383518512;15043.398264069425;15050.247487625575;13595.864041359657;13909.304534773322;13913.721725565432;13972.93013534936;14132.434702611883;10588.735281617945;12566.685832854251;12965.53654805584;13059.020573456934;13142.671433214246;4151.958480415187;4647.465541462741;4659.510751371091;4672.766361681903;4874.829380971684;81523.5923820383;81570.18429815685;109695.45152274267;110600;115552.42223788996;11010.614628488125;11112.777744445066;11329.206955513035;11358.829617555743;11531.192816502931;22271.44321391978;22554;22555.98498067591;22600.434989125402;22606.547869042526;7521.586312752851;7701.92298077012;7782.727604533814;7913.406494512819;7927.128015918244;3992.061865461614;4331.45856767904;4566.95433045672;4588.632909367309;4723.197056500403;3039.361734035833;3158.494640857452;3427.8114703691535;3575.8927232183078;3607.855685772592;616.1127975714957;617.3918690090258;628.943256660271;633.1484153813205;634.1914059573987;569.5870493891978;653.1019847709399;660.920689517261;668.3176881744283;679.4123083532709;8171.836563268701;8558.272546833465;9034.774130646787;9358.485283309483;9596.712098637052;617.8980468222693;655.0316299520007;681.9761308354283;688.6487891175536;691.9793304875269;3459.8270086495572;4422;4448.799804008828;4460.799264033127;4665.824102297241;134140;141266.58733412533;144524;145180;155601.22199389158;114814;121435.39282303532;122305.38847305707;123371.3831430855;126258;82971.7554236699;91885;102445;103191;103794.48102759589;8646.78383040429;8893.288536916902;9741.58746981698;9747.561359738971;9955.900440995787;54487.72756136194;57275.42724572701;57394;57723.711381444504;57926.420735792104;95950;96132;98455.50772246093;100341.49829250807;100363.49818250861;12723;12899.93550032244;13000.544980925622;13324.267165305613;13429.865701342862;14604.634884127981;14800.925995369955;14913.701725965202;15431.228438577802;15466.226688665296;10029;13443;13781.242031688353;13944.860551394357;14166.78749818733;12696.238225706487;13522.797158042835;13826.446942122604;13970.301484925916;14021.369038393093;12809.231446113263;12915.70842915701;13189.102963535383;13635.590932272047;13943.511977080754;10077.546510407195;10469.528871200966;10741.785164296827;10974.890251097388;11075.778484430428;9190.40262382943;9343.252539796937;10231;10275.794484110425;11634.592789252336 10:26:03 INFO - PROCESS | 1650 | __end_tp_report 10:26:03 INFO - PROCESS | 1650 | __start_cc_report 10:26:03 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1547 10:26:03 INFO - PROCESS | 1650 | __end_cc_report 10:26:03 INFO - PROCESS | 1650 | __startTimestamp1472232363090__endTimestamp 10:26:03 INFO - PROCESS | 1650 | 10:26:03 INFO - PROCESS | 1650 | ------- Summary: start ------- 10:26:03 INFO - PROCESS | 1650 | Number of tests: 6 10:26:03 INFO - PROCESS | 1650 | 10:26:03 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5744.68 Median:5938.44 stddev:629.39 (10.6%) stddev-sans-first:602.38 10:26:03 INFO - PROCESS | 1650 | Values: 3339.6 3359.1 3371.7 3388.7 3442.8 5603.1 5657.9 5699.7 5737.2 5748.1 5659.5 5706.8 5739.5 5768.9 5787.2 5150.4 5178.1 5232.6 5250.8 5359.7 5301.4 5356.9 5367.7 5374.5 5405.5 5150.6 5194.9 5209.7 5232.1 5242.6 5223.0 5228.6 5244.7 5270.6 5340.3 5208.1 5213.2 5223.9 5256.9 5314.9 5649.6 5681.2 5751.3 5754.9 5769.5 6156.9 6200.6 6229.8 6293.4 6300.1 6263.7 6300.0 6313.6 6324.1 6349.7 4983.5 5671.7 5724.9 5828.8 5835.8 5170.5 6273.6 6279.5 6287.5 6324.6 6150.6 6153.8 6167.5 6170.2 6181.6 6094.4 6148.9 6207.3 6221.3 6259.1 6110.0 6124.7 6143.8 6179.6 6186.3 6173.8 6174.4 6205.1 6228.6 6229.2 4960.4 4961.8 4967.3 4975.1 4998.8 4406.5 4410.6 4420.5 4481.3 4488.3 5696.6 5745.0 5750.8 5788.3 5841.5 6124.7 6164.0 6181.9 6193.1 6193.4 5571.3 5718.5 5760.2 5785.0 5789.5 5360.6 5426.2 5453.3 5457.9 5513.5 6295.6 6335.1 6344.8 6371.3 6378.4 5259.2 6265.5 6293.3 6321.7 6322.0 6230.8 6267.8 6287.4 6295.0 6337.8 6167.6 6236.4 6238.3 6242.3 6247.6 6246.7 6270.9 6278.6 6296.5 6304.2 5797.2 6019.1 6063.9 6075.8 6124.0 6104.8 6105.9 6107.5 6122.9 6135.3 5938.3 5944.8 5956.7 5964.0 5974.7 5898.6 6063.6 6082.8 6105.6 6127.7 5883.8 5929.1 5938.4 5949.4 5966.3 10:26:03 INFO - PROCESS | 1650 | 10:26:03 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:73564.66 Median:37883.03 stddev:169200.15 (446.6%) stddev-sans-first:169585.78 10:26:03 INFO - PROCESS | 1650 | Values: 462.9 468.5 496.2 502.5 530.3 31119.4 39090.6 40048.4 40196.8 42050.4 40990.8 41452.0 41472.4 41509.8 41595.4 9621.8 9795.0 9802.4 9812.6 9814.2 8771.7 8972.6 8985.0 9000.4 12077.7 11920.9 12256.6 12257.4 12272.3 12273.8 12275.1 12319.2 12339.8 12341.6 12374.4 12068.7 12092.0 12132.2 12132.5 12161.3 40361.8 40581.8 40667.0 40709.4 40728.4 3533.1 3548.3 3578.5 3810.6 4109.5 30374.5 31450.8 31552.0 31555.5 31651.8 3228.4 3270.1 3293.5 3321.5 3323.3 460302.0 461206.0 461854.0 462373.4 467242.0 55279.0 55325.0 55652.0 55764.7 55770.4 57891.0 58057.7 58126.4 58202.1 58264.1 15714.6 15938.0 15945.0 15946.9 15956.4 12519.4 12581.4 12585.1 12589.6 12589.6 12787.5 13094.7 13101.5 13376.5 14533.2 4495.7 5639.6 6433.2 6617.8 6643.1 866.9 907.5 910.9 989.5 996.3 25108.7 27038.1 27786.4 28410.9 30165.1 1057.6 1072.0 1092.1 1116.2 1138.2 15246.3 15562.7 15760.9 15922.7 15966.7 620622.0 625130.0 626120.0 626550.0 629308.0 47935.8 55354.0 55404.7 57730.0 58428.7 1351.1 1373.7 1396.1 1397.1 1397.2 26440.0 28582.1 30256.2 31003.4 31121.5 51677.5 52177.7 52184.0 52292.2 52305.0 547424.0 788445.0 814642.0 815791.0 819524.0 24565.0 24774.0 24842.4 24933.9 24948.8 43740.6 44298.6 44435.0 44848.3 45276.7 39029.4 39115.8 39314.2 40185.8 40597.8 37400.4 38058.8 38258.6 39335.8 39870.6 36599.8 36635.0 37255.8 37450.8 37707.2 29111.4 32402.8 33671.3 33784.0 33822.5 25026.5 25413.9 25682.5 25696.6 26163.1 10:26:03 INFO - PROCESS | 1650 | 10:26:03 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:34528.87 Median:1986.00 stddev:91285.47 (4596.5%) stddev-sans-first:91505.47 10:26:03 INFO - PROCESS | 1650 | Values: 344.2 353.4 377.5 384.3 405.9 15633.4 16539.5 20360.8 20779.3 20911.6 20979.4 21187.8 21385.1 21607.4 21647.9 5814.6 6064.9 6103.2 7651.4 8040.9 7752.8 8005.2 8100.3 8111.3 8113.4 7980.2 8044.8 8055.8 8066.6 8073.1 6920.9 7910.9 8019.0 8062.1 8075.2 7618.3 7679.7 7768.8 7860.5 7865.3 29198.4 29724.0 29847.6 29851.0 30037.4 2658.2 2670.6 2771.4 2857.3 2974.4 15802.2 17103.3 17475.0 17651.6 17826.9 1994.1 2006.6 2054.6 2091.9 2096.8 78984.6 81225.0 107976.5 127892.7 145061.0 16304.8 16863.6 20902.9 20911.1 21170.4 33195.7 33262.8 33401.3 33414.3 33452.0 10480.9 10731.9 10922.4 10930.0 11117.2 8913.2 8940.4 8951.2 9083.8 9131.2 8664.7 9156.9 9595.9 9793.4 9818.4 3618.5 3733.5 3740.0 3740.1 3748.8 835.0 892.8 897.6 984.9 1024.8 20481.7 21222.6 21292.6 21303.7 21356.7 880.6 900.2 931.6 941.8 965.7 10902.3 11217.9 11263.2 11284.8 11317.9 481645.0 556135.0 559048.0 567153.0 571664.0 91704.1 99736.0 124746.0 131392.0 132507.3 950.9 953.2 983.2 984.9 988.3 15823.9 16478.9 19467.6 19655.8 19749.6 46138.5 50572.0 51577.5 54097.2 54394.3 61334.0 81787.2 82655.6 83288.0 83942.0 7922.0 8031.3 8052.7 8186.4 8260.5 10258.9 12659.7 12824.7 13154.1 13207.3 11031.5 11100.3 11250.6 11315.2 11360.8 8757.8 9349.2 10844.6 11077.9 11109.4 9345.7 11119.9 11185.4 11198.5 11430.1 7876.9 8130.3 8172.2 8329.0 8341.3 10290.3 11083.7 11400.7 11412.5 11493.0 10:26:03 INFO - PROCESS | 1650 | 10:26:03 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5587.18 Median:4932.89 stddev:3178.94 (64.4%) stddev-sans-first:3172.10 10:26:03 INFO - PROCESS | 1650 | Values: 1375.9 1518.8 1648.3 1687.3 1869.3 7912.7 8184.0 8363.4 8910.5 8914.6 8020.5 8112.1 8295.3 8593.4 8633.7 1871.4 2302.7 2303.9 2303.9 2309.6 1940.0 2078.0 2310.6 2365.5 2365.7 2125.1 2144.5 2153.1 2271.5 2343.9 2001.0 2308.4 2327.7 2327.7 2337.4 2300.6 2316.6 2341.6 2344.9 2351.6 7571.8 8570.0 8608.3 8665.4 8696.3 5143.1 5297.0 5304.6 5305.2 5321.6 3132.9 3240.7 3276.5 3276.8 3289.1 996.4 1036.3 1052.1 1054.3 1059.9 11273.1 11456.5 11465.1 11475.9 11476.7 4450.9 4644.0 4807.2 4829.6 4846.9 9957.1 9959.6 9973.4 9973.4 9977.9 4543.8 5360.4 5365.5 5367.0 5369.7 4708.6 4846.8 4893.6 4896.7 4898.0 3782.2 3786.4 3847.8 3866.7 3887.8 995.9 1256.8 1257.2 1276.7 1282.6 741.8 853.4 907.4 915.1 916.7 4640.7 5687.4 5892.1 5985.2 6020.3 425.1 436.3 438.7 443.8 445.6 3878.2 3889.4 3959.9 3992.0 4024.2 10181.3 10689.0 10696.4 10704.0 10757.8 10646.6 10658.3 10658.8 10661.7 10662.3 7324.3 8025.4 8052.4 8153.9 8171.9 5615.9 5689.9 5694.8 5766.1 5866.9 9586.7 9678.5 9847.4 9853.6 9981.5 9710.7 9962.5 9987.3 10061.7 10146.1 7164.1 7225.2 7333.0 7334.3 7346.9 8041.0 8051.0 8152.8 8153.6 8153.7 7179.0 7221.4 7344.1 7385.2 7392.8 7098.9 7158.3 7217.8 7224.7 7306.7 6722.2 6779.5 6880.3 6914.7 6939.1 4816.4 4900.8 4916.8 4949.0 5068.6 4900.0 5012.2 5028.9 5077.9 5173.8 10:26:03 INFO - PROCESS | 1650 | 10:26:03 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4411.96 Median:4744.99 stddev:1381.62 (29.1%) stddev-sans-first:1350.50 10:26:03 INFO - PROCESS | 1650 | Values: 295.0 307.0 309.7 312.7 315.3 5379.4 5400.8 5420.2 5436.1 5439.0 5283.2 5315.9 5355.2 5370.7 5379.6 3210.0 3904.4 3907.8 4058.4 4111.6 3721.2 3917.4 4071.8 4089.1 4108.4 3624.6 3741.6 3846.7 4115.3 4118.9 3619.5 3805.5 4099.7 4111.6 4114.9 3009.6 3468.2 3568.0 3568.4 3589.4 5169.6 5189.4 5208.9 5221.7 5244.8 3867.9 3895.7 4120.4 4666.5 4705.6 5570.5 5577.2 5582.9 5586.8 5623.0 3470.6 3714.8 3935.4 3955.8 3960.6 5069.5 5114.6 5601.6 5759.2 5958.4 3522.6 4004.9 4014.8 4015.3 4018.7 3399.6 3939.7 4069.9 4081.3 4082.6 4673.3 4679.0 4700.2 4705.4 4723.9 4351.0 4541.6 4748.9 5665.0 5678.4 1319.3 1335.5 1338.8 1343.2 1345.0 713.7 728.9 743.4 816.0 820.2 4591.5 5000.3 5180.7 5191.4 5220.1 4860.2 5070.5 5624.4 5850.4 5893.9 4468.4 5029.2 5317.5 5318.6 5327.8 2884.8 2910.1 2994.8 3026.0 3077.8 5004.2 5279.0 5376.9 5860.2 5993.7 5557.8 5712.4 5926.7 5975.4 6012.5 5271.7 5979.8 5986.1 6012.8 6018.5 5458.3 5801.8 5819.9 5835.9 5891.4 5000.9 5298.6 5722.5 5742.2 5792.2 5512.0 5885.7 6055.0 6062.1 6062.9 2716.0 3176.8 3222.1 3283.6 3421.7 4117.5 4277.4 4634.5 4838.4 4872.1 4154.5 4483.4 4602.0 4676.3 4693.3 3930.9 4380.4 4741.1 4758.4 4775.4 3679.5 4077.0 4502.6 4581.8 4677.7 4275.9 5088.9 5339.7 5450.9 5566.4 5506.6 5534.5 5540.4 5545.6 5568.5 10:26:03 INFO - PROCESS | 1650 | 10:26:03 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:23922.62 Median:3591.87 stddev:37626.59 (1047.5%) stddev-sans-first:37691.52 10:26:03 INFO - PROCESS | 1650 | Values: 635.6 735.5 768.5 775.8 813.6 14654.3 16316.2 16328.3 16347.5 16362.9 11913.3 14312.1 14372.5 14426.0 14586.6 664.4 676.4 826.6 828.9 851.3 849.1 855.1 858.6 859.7 862.8 908.0 910.5 911.0 915.4 916.2 707.0 910.5 911.0 911.4 912.9 653.8 678.4 682.6 683.0 685.3 14989.2 15008.2 15020.5 15043.4 15050.2 13595.9 13909.3 13913.7 13972.9 14132.4 10588.7 12566.7 12965.5 13059.0 13142.7 4152.0 4647.5 4659.5 4672.8 4874.8 81523.6 81570.2 109695.5 110600.0 115552.4 11010.6 11112.8 11329.2 11358.8 11531.2 22271.4 22554.0 22556.0 22600.4 22606.5 7521.6 7701.9 7782.7 7913.4 7927.1 3992.1 4331.5 4567.0 4588.6 4723.2 3039.4 3158.5 3427.8 3575.9 3607.9 616.1 617.4 628.9 633.1 634.2 569.6 653.1 660.9 668.3 679.4 8171.8 8558.3 9034.8 9358.5 9596.7 617.9 655.0 682.0 688.6 692.0 3459.8 4422.0 4448.8 4460.8 4665.8 134140.0 141266.6 144524.0 145180.0 155601.2 114814.0 121435.4 122305.4 123371.4 126258.0 82971.8 91885.0 102445.0 103191.0 103794.5 8646.8 8893.3 9741.6 9747.6 9955.9 54487.7 57275.4 57394.0 57723.7 57926.4 95950.0 96132.0 98455.5 100341.5 100363.5 12723.0 12899.9 13000.5 13324.3 13429.9 14604.6 14800.9 14913.7 15431.2 15466.2 10029.0 13443.0 13781.2 13944.9 14166.8 12696.2 13522.8 13826.4 13970.3 14021.4 12809.2 12915.7 13189.1 13635.6 13943.5 10077.5 10469.5 10741.8 10974.9 11075.8 9190.4 9343.3 10231.0 10275.8 11634.6 10:26:03 INFO - PROCESS | 1650 | -------- Summary: end -------- 10:26:03 INFO - PROCESS | 1650 | 10:26:03 INFO - TEST-INFO | 1650: exit 0 10:26:03 INFO - TEST-OK | dromaeo_css | took 1117831ms 10:26:03 INFO - TEST-START | kraken 10:26:03 INFO - operating with platform_type : mac_ 10:26:03 INFO - Initialising browser for kraken test... 10:26:03 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpv9MIxG/profile http://localhost:49262/getInfo.html) 10:26:05 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 10:26:05 INFO - PROCESS | 1655 | colorDepth:24 10:26:05 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 10:26:05 INFO - PROCESS | 1655 | __metrics 10:26:06 INFO - PROCESS | 1655 | 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" 10:26:06 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:26:06 INFO - TEST-INFO | 1655: exit 0 10:26:06 INFO - Browser initialized. 10:26:06 INFO - Running cycle 1/1 for kraken test... 10:26:06 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpv9MIxG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:26:18 INFO - PROCESS | 1656 | RSS: Main: 233398272 10:26:18 INFO - PROCESS | 1656 | 10:26:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection) 10:26:22 INFO - PROCESS | 1656 | RSS: Main: 290455552 10:26:22 INFO - PROCESS | 1656 | 10:26:24 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-dft) 10:26:25 INFO - PROCESS | 1656 | RSS: Main: 288407552 10:26:25 INFO - PROCESS | 1656 | 10:26:28 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-fft) 10:26:29 INFO - PROCESS | 1656 | RSS: Main: 379076608 10:26:29 INFO - PROCESS | 1656 | 10:26:31 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator) 10:26:32 INFO - PROCESS | 1656 | RSS: Main: 279384064 10:26:32 INFO - PROCESS | 1656 | 10:26:34 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:26:34 INFO - PROCESS | 1656 | RSS: Main: 282660864 10:26:34 INFO - PROCESS | 1656 | 10:26:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom) 10:26:38 INFO - PROCESS | 1656 | RSS: Main: 321994752 10:26:38 INFO - PROCESS | 1656 | 10:26:41 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate) 10:26:41 INFO - PROCESS | 1656 | RSS: Main: 323624960 10:26:41 INFO - PROCESS | 1656 | 10:26:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial) 10:26:45 INFO - PROCESS | 1656 | RSS: Main: 322486272 10:26:45 INFO - PROCESS | 1656 | 10:26:46 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:26:47 INFO - PROCESS | 1656 | RSS: Main: 295985152 10:26:47 INFO - PROCESS | 1656 | 10:26:49 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:26:49 INFO - PROCESS | 1656 | RSS: Main: 303435776 10:26:49 INFO - PROCESS | 1656 | 10:26:52 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:26:52 INFO - PROCESS | 1656 | RSS: Main: 300179456 10:26:52 INFO - PROCESS | 1656 | 10:26:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:26:56 INFO - PROCESS | 1656 | RSS: Main: 319090688 10:26:56 INFO - PROCESS | 1656 | 10:26:59 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:26:59 INFO - PROCESS | 1656 | RSS: Main: 315187200 10:26:59 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49262/tests/kraken/driver.html?testName=ai-astar) 10:27:01 INFO - PROCESS | 1656 | RSS: Main: 358268928 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | __start_tp_report 10:27:01 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 10:27:01 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 10:27:01 INFO - PROCESS | 1656 | |i|pagename|runs| 10:27:01 INFO - PROCESS | 1656 | |0;ai-astar;104;112;128;115;111;113;113;114;113;113 10:27:01 INFO - PROCESS | 1656 | |1;audio-beat-detection;145;110;130;163;110;129;130;113;194;109 10:27:01 INFO - PROCESS | 1656 | |2;audio-dft;184;211;186;183;185;185;180;183;186;185 10:27:01 INFO - PROCESS | 1656 | |3;audio-fft;77;75;75;148;57;57;58;71;138;56 10:27:01 INFO - PROCESS | 1656 | |4;audio-oscillator;79;82;130;70;86;80;119;69;93;137 10:27:01 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;118;131;86;85;86;83;88;86;85;84 10:27:01 INFO - PROCESS | 1656 | |6;imaging-darkroom;92;93;93;94;92;94;93;94;93;92 10:27:01 INFO - PROCESS | 1656 | |7;imaging-desaturate;87;86;86;85;86;83;84;84;85;85 10:27:01 INFO - PROCESS | 1656 | |8;json-parse-financial;52;55;54;54;53;54;54;54;53;53 10:27:01 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;66;64;68;79;77;71;74;68;68;70 10:27:01 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;88;85;87;85;82;88;94;107;94;85 10:27:01 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;140;138;136;140;139;159;160;138;180;141 10:27:01 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;171;171;170;171;172;172;170;171;171;170 10:27:01 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;58;87;87;70;68;68;68;69;67;67 10:27:01 INFO - PROCESS | 1656 | __end_tp_report 10:27:01 INFO - PROCESS | 1656 | __start_cc_report 10:27:01 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2042 10:27:01 INFO - PROCESS | 1656 | __end_cc_report 10:27:01 INFO - PROCESS | 1656 | __startTimestamp1472232421820__endTimestamp 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | ------- Summary: start ------- 10:27:01 INFO - PROCESS | 1656 | Number of tests: 14 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:113.60 Median:113.00 stddev:5.89 (5.2%) stddev-sans-first:5.12 10:27:01 INFO - PROCESS | 1656 | Values: 104.0 112.0 128.0 115.0 111.0 113.0 113.0 114.0 113.0 113.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:130.00 stddev:27.55 (21.2%) stddev-sans-first:28.90 10:27:01 INFO - PROCESS | 1656 | Values: 145.0 110.0 130.0 163.0 110.0 129.0 130.0 113.0 194.0 109.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:186.80 Median:185.00 stddev:8.69 (4.7%) stddev-sans-first:9.16 10:27:01 INFO - PROCESS | 1656 | Values: 184.0 211.0 186.0 183.0 185.0 185.0 180.0 183.0 186.0 185.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:81.20 Median:64.50 stddev:33.72 (52.3%) stddev-sans-first:35.73 10:27:01 INFO - PROCESS | 1656 | Values: 77.0 75.0 75.0 148.0 57.0 57.0 58.0 71.0 138.0 56.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:79.50 stddev:24.94 (31.4%) stddev-sans-first:25.82 10:27:01 INFO - PROCESS | 1656 | Values: 79.0 82.0 130.0 70.0 86.0 80.0 119.0 69.0 93.0 137.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:85.50 stddev:16.83 (19.7%) stddev-sans-first:15.27 10:27:01 INFO - PROCESS | 1656 | Values: 118.0 131.0 86.0 85.0 86.0 83.0 88.0 86.0 85.0 84.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 10:27:01 INFO - PROCESS | 1656 | Values: 92.0 93.0 93.0 94.0 92.0 94.0 93.0 94.0 93.0 92.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.20 (1.4%) stddev-sans-first:1.05 10:27:01 INFO - PROCESS | 1656 | Values: 87.0 86.0 86.0 85.0 86.0 83.0 84.0 84.0 85.0 85.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.84 (1.6%) stddev-sans-first:0.67 10:27:01 INFO - PROCESS | 1656 | Values: 52.0 55.0 54.0 54.0 53.0 54.0 54.0 54.0 53.0 53.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.50 stddev:4.81 (6.8%) stddev-sans-first:4.82 10:27:01 INFO - PROCESS | 1656 | Values: 66.0 64.0 68.0 79.0 77.0 71.0 74.0 68.0 68.0 70.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:87.50 stddev:7.26 (8.3%) stddev-sans-first:7.68 10:27:01 INFO - PROCESS | 1656 | Values: 88.0 85.0 87.0 85.0 82.0 88.0 94.0 107.0 94.0 85.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:140.50 stddev:14.46 (10.3%) stddev-sans-first:15.11 10:27:01 INFO - PROCESS | 1656 | Values: 140.0 138.0 136.0 140.0 139.0 159.0 160.0 138.0 180.0 141.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.90 Median:171.00 stddev:0.74 (0.4%) stddev-sans-first:0.78 10:27:01 INFO - PROCESS | 1656 | Values: 171.0 171.0 170.0 171.0 172.0 172.0 170.0 171.0 171.0 170.0 10:27:01 INFO - PROCESS | 1656 | 10:27:01 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:68.50 stddev:9.10 (13.3%) stddev-sans-first:8.37 10:27:01 INFO - PROCESS | 1656 | Values: 58.0 87.0 87.0 70.0 68.0 68.0 68.0 69.0 67.0 67.0 10:27:01 INFO - PROCESS | 1656 | -------- Summary: end -------- 10:27:01 INFO - PROCESS | 1656 | 10:27:02 INFO - TEST-INFO | 1656: exit 0 10:27:02 INFO - TEST-OK | kraken | took 58920ms 10:27:02 INFO - SUITE-END | took 1176s 10:27:02 INFO - Completed test suite (00:19:37) 10:27:02 INFO - javascript benchmark 10:27:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3339.5992480902287, 3359.093044877883, 3371.6965473107416, 3388.728901687862, 3442.7590068695185, 5603.075492543736, 5657.915131273029, 5699.658020518772, 5737.225474560933, 5748.080307150852, 5659.490645841884, 5706.77172913084, 5739.454751798583, 5768.884622307552, 5787.189793428924, 5150.356205474316, 5178.119719647669, 5232.6075544334135, 5250.842474725764, 5359.732013399314, 5301.390340110898, 5356.892862142755, 5367.704776237305, 5374.543163831079, 5405.486478784529, 5150.61370397218, 5194.870128246824, 5209.739513024334, 5232.110541208004, 5242.6330156889035, 5223.0, 5228.633995620308, 5244.685318880879, 5270.578353731692, 5340.305760251143, 5208.140656791625, 5213.244079608436, 5223.921641175385, 5256.894862102775, 5314.92027619586, 5649.604527683064, 5681.176229446707, 5751.338596061465, 5754.942450575482, 5769.509591684712, 6156.8768624627255, 6200.596961197552, 6229.813105606839, 6293.370662933716, 6300.054991751228, 6263.749450021994, 6299.96050651642, 6313.558050936436, 6324.146240257553, 6349.682515874234, 4983.451820299764, 5671.716414179275, 5724.914126288109, 5828.766849326022, 5835.79574714883, 5170.534651881274, 6273.592216505913, 6279.497640188774, 6287.528435367366, 6324.588901721466, 6150.630962142286, 6153.784617538365, 6167.475764559973, 6170.228721409824, 6181.629102253789, 6094.380596348918, 6148.938510614926, 6207.317195108625, 6221.3156552779155, 6259.123722678829, 6109.981658125326, 6124.655575320006, 6143.846403839851, 6179.62922224668, 6186.288576813634, 6173.84565385869, 6174.38256174388, 6205.131281620576, 6228.56400051992, 6229.221347331583, 4960.429550601655, 4961.82633607822, 4967.279744437036, 4975.104481193348, 4998.825041123543, 4406.515283318832, 4410.647148228134, 4420.491643460979, 4481.305397663367, 4488.259437192892, 5696.572757043155, 5745.0, 5750.769969201268, 5788.27646544182, 5841.474267315962, 6124.693765311806, 6164.0, 6181.938180618226, 6193.071039344044, 6193.411625895533, 5571.331440227199, 5718.456746609066, 5760.193572899798, 5785.0, 5789.478946894799, 5360.597955153301, 5426.158945363475, 5453.263809385682, 5457.945420545823, 5513.5037846593195, 6295.622262664255, 6335.1447554579545, 6344.777932772331, 6371.267304259976, 6378.362163783585, 5259.21111833228, 6265.467435267871, 6293.307736148929, 6321.715522801577, 6321.96839015802, 6230.813075607739, 6267.780627678009, 6287.402696743802, 6295.0, 6337.841553961188, 6167.568270221221, 6236.407541283571, 6238.251409830849, 6242.282137554058, 6247.562670613195, 6246.656433895998, 6270.905936411049, 6278.623282602875, 6296.464800492009, 6304.211973503312, 5797.188393624981, 6019.067044608093, 6063.878722425438, 6075.787347442818, 6124.0, 6104.816855494342, 6105.908411373922, 6107.54193435503, 6122.877542449215, 6135.263768347827, 5938.346781854079, 5944.791932282349, 5956.702164891824, 5963.970180149072, 5974.671393073423, 5898.616589921641, 6063.636181829104, 6082.847928801639, 6105.603135796159, 6127.724252408563, 5883.794067207627, 5929.0513517836935, 5938.435848594377, 5949.405059494016, 5966.284045914518], "value": 5705.320828680822, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.9491055304458, 468.4589299359241, 496.20359323286124, 502.46738457235335, 530.3450238954888, 31119.377612447737, 39090.60909390905, 40048.39927401084, 40196.79901600495, 42050.369244461355, 40990.7950460248, 41451.963700907494, 41472.37791433131, 41509.79245103778, 41595.37606935899, 9621.807563848719, 9794.9510252449, 9802.411855288705, 9812.607495700164, 9814.165795907356, 8771.692990745325, 8972.596233169523, 8984.955075224647, 9000.369974101815, 12077.698057548543, 11920.880791192063, 12256.571020014302, 12257.44841482131, 12272.263664180133, 12273.815892761571, 12275.079369047313, 12319.199252048587, 12339.75320493585, 12341.568045118378, 12374.443150058269, 12068.698282542919, 12092.032637388987, 12132.211406258564, 12132.45403956824, 12161.331126788024, 40361.79819100915, 40581.797091014654, 40667.0, 40709.389359159635, 40728.38907416391, 3533.081398836295, 3548.254866478043, 3578.481120237577, 3810.5617853946874, 4109.465769449982, 30374.54438183429, 31450.842745786125, 31552.0, 31555.526667100014, 31651.84174079138, 3228.370467758788, 3270.1497610621172, 3293.5389045533643, 3321.5183798349344, 3323.302106557626, 460302.0, 461206.0, 461854.0, 462373.37626623973, 467242.0, 55279.0, 55325.0, 55652.0, 55764.72117639467, 55770.44229557733, 57891.0, 58057.70971145117, 58126.4187358121, 58202.126968095516, 58264.12603810946, 15714.607134821721, 15938.043717376762, 15944.963577367434, 15946.9202653986, 15956.361745530048, 12519.3740312984, 12581.37093145339, 12585.056120790978, 12589.622311330675, 12589.622311330675, 12787.488500459878, 13094.738105238033, 13101.541446049534, 13376.46494140243, 14533.200673962821, 4495.656668694591, 5639.603168653306, 6433.1636887205295, 6617.8014659560295, 6643.10318107059, 866.9003064647646, 907.4827348411342, 910.9043550427242, 989.4557993103764, 996.3473924579412, 25108.74891251064, 27038.14380367632, 27786.444271114466, 28410.85794571014, 30165.104420345353, 1057.6456886942806, 1072.0458791675414, 1092.093562343253, 1116.1740312168936, 1138.1918837625378, 15246.313915873812, 15562.688746225012, 15760.92119539418, 15922.681546369007, 15966.680666386606, 620622.0, 625130.0, 626120.0, 626550.0, 629308.0, 47935.76032119817, 55354.0, 55404.72297638567, 57730.0, 58428.707856460445, 1351.0542620165922, 1373.6977864869912, 1396.134396674089, 1397.0918902713317, 1397.1617029782253, 26440.0, 28582.14178582125, 30256.243593910327, 31003.379932401676, 31121.533177002817, 51677.48322516727, 52177.7391113042, 52184.0, 52292.21561676654, 52305.0, 547424.0, 788445.0, 814642.0, 815791.0, 819524.0, 24565.017399303826, 24774.0, 24842.378940526633, 24933.875330623232, 24948.750512495368, 43740.56259437365, 44298.55701442944, 44435.0, 44848.32727509025, 45276.6830911174, 39029.414558782206, 39115.804420977714, 39314.21371572495, 40185.79907100446, 40597.79701101476, 37400.438993415664, 38058.809705951295, 38258.61741382662, 39335.803320984356, 39870.60129398669, 36599.81700091482, 36635.0, 37255.81372093122, 37450.81274593718, 37707.2458550822, 29111.417771644872, 32402.83798580992, 33671.27588309944, 33784.0, 33822.49266260959, 25026.499470010862, 25413.87293063523, 25682.486350272517, 25696.614550781378, 26163.084292050447], "value": 19959.882099644594, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.17741597581784, 353.44509120680544, 377.48096367494696, 384.2718049296583, 405.94925634295714, 15633.3746650134, 16539.503814885542, 20360.796392036074, 20779.27272545461, 20911.581768364624, 20979.37061888142, 21187.788122118774, 21385.144594216214, 21607.351779446602, 21647.891760541213, 5814.622049566775, 6064.878702425949, 6103.206583144185, 7651.426143039266, 8040.879386809173, 7752.844943101135, 8005.199480052006, 8100.270975612223, 8111.269985701286, 8113.350931925432, 7980.241877021703, 8044.8391032179325, 8055.798605034892, 8066.556339401331, 8073.071596766388, 6920.858058420355, 7910.920890791076, 8018.959905200495, 8062.113167551564, 8075.192480751966, 7618.276263754934, 7679.654415551257, 7768.7669369918995, 7860.489068210605, 7865.292123708837, 29198.41603167946, 29724.0, 29847.552286715716, 29851.0, 30037.39925201484, 2658.2025392382207, 2670.612761149622, 2771.390294135287, 2857.2999615094227, 2974.3902499987666, 15802.209889505479, 17103.31586736529, 17475.0, 17651.646967060587, 17826.93038417699, 1994.112619884152, 2006.6388050150968, 2054.6404379233722, 2091.874487530738, 2096.779838116991, 78984.60507697424, 81225.0, 107976.46011769891, 127892.721072789, 145061.0, 16304.836951630568, 16863.591835102583, 20902.89548552278, 20911.144053884636, 21170.36488905335, 33195.66804331926, 33262.83580074734, 33401.3319733604, 33414.331713365595, 33452.0, 10480.947595261974, 10731.892681073245, 10922.399268040175, 10930.01629853317, 11117.221794474472, 8913.24237439812, 8940.392439920388, 8951.239144672776, 9083.81832363349, 9131.178193962442, 8664.696735614223, 9156.946951100575, 9595.90404095964, 9793.363431376938, 9818.354446193467, 3618.5476815398074, 3733.533308336458, 3739.9626003739613, 3740.1023754299317, 3748.7938163401277, 834.9749507514784, 892.8258989497116, 897.5871099294267, 984.8522721591676, 1024.8052869954686, 20481.692774608393, 21222.575548488945, 21292.57414851725, 21303.68044479334, 21356.660834791302, 880.5993273060742, 900.1808354397467, 931.6226928094133, 941.8493041113389, 965.6909788867636, 10902.291351062315, 11217.943910280397, 11263.155263355282, 11284.774304513863, 11317.886821131848, 481645.0, 556135.0, 559048.0, 567153.0, 571664.0, 91704.08295917089, 99736.0, 124746.0, 131392.0, 132507.337463314, 950.933434659581, 953.1945506047379, 983.2429029646988, 984.9409035457895, 988.2736188901152, 15823.920880395524, 16478.94013248344, 19467.61064778725, 19655.803441965396, 19749.605007899474, 46138.538614613426, 50572.0, 51577.48422515877, 54097.18854217111, 54394.28028598475, 61334.0, 81787.18212817796, 82655.586722066, 83288.0, 83942.0, 7922.009748781403, 8031.27718505314, 8052.718154864786, 8186.426950113673, 8260.545669987969, 10258.948705256425, 12659.683507912376, 12824.679383015498, 13154.144980576231, 13207.339633018502, 11031.503582338975, 11100.333979961228, 11250.60622878228, 11315.20793544444, 11360.772784544097, 8757.817694611145, 9349.158575727944, 10844.620438284623, 11077.889221107685, 11109.388983605979, 9345.720105320574, 11119.888801111885, 11185.384803835866, 11198.496067676811, 11430.085593152695, 7876.921230787619, 8130.308923741548, 8172.223638754546, 8328.958355208186, 8341.332693384637, 10290.331128476626, 11083.667489975314, 11400.657980260605, 11412.543498259976, 11493.0], "value": 10977.218367303314, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.9105658132223, 1518.8101487314086, 1648.2994727240919, 1687.2829047654752, 1869.2522990803668, 7912.683492660301, 8184.0, 8363.372747043974, 8910.46537207768, 8914.598843052063, 8020.478668886532, 8112.067112282075, 8295.294899933513, 8593.39846210764, 8633.654653813855, 1871.4105056907065, 2302.6776251324827, 2303.884805759714, 2303.9193628223015, 2309.595820731374, 1940.0, 2078.0, 2310.6482707615983, 2365.515069410775, 2365.6924599802, 2125.075592117426, 2144.4746037220903, 2153.1387445022037, 2271.46620544172, 2343.8593684378907, 2000.959980800376, 2308.4113551044543, 2327.662488939103, 2327.7206735191717, 2337.368910394201, 2300.5513924784673, 2316.6177580699164, 2341.6487526870937, 2344.859308441499, 2351.564960482316, 7571.772846814604, 8569.971603407632, 8608.268297194745, 8665.43674661145, 8696.347773916981, 5143.074246635604, 5297.0, 5304.575633949275, 5305.15117581189, 5321.600879933982, 3132.859021344045, 3240.708336249725, 3276.5249038889465, 3276.754243431752, 3289.111939776271, 996.3573495095695, 1036.3056751976194, 1052.105710146377, 1054.2620165883836, 1059.8622179116742, 11273.09815214789, 11456.4844581994, 11465.140114491447, 11475.885241147565, 11476.655700329004, 4450.933236001462, 4643.95356046442, 4807.18277892759, 4829.613630909519, 4846.854594362175, 9957.0540798624, 9959.601615935426, 9973.401595904268, 9973.401595904268, 9977.900220997843, 4543.818247270072, 5360.410354860962, 5365.463453654604, 5366.980273747976, 5369.731513424314, 4708.599769019672, 4846.830360937351, 4893.559579637778, 4896.730679812645, 4897.975510122426, 3782.167923056923, 3786.3563194257044, 3847.7691338519217, 3866.709996750255, 3887.8056097195026, 995.875515560555, 1256.8114782782473, 1257.1577043380958, 1276.6553030681664, 1282.602393258093, 741.8034220931457, 853.4367317570374, 907.4464576608264, 915.0986278515657, 916.7295647783919, 4640.744759038285, 5687.43125687428, 5892.1161825726485, 5985.191999080069, 6020.337762846083, 425.14121474621095, 436.25182811477833, 438.69510690070734, 443.79807187729506, 445.58560538698674, 3878.1855810279594, 3889.4360317753767, 3959.8812035638975, 3991.960080399159, 4024.215278020758, 10181.33821301613, 10689.037986581245, 10696.358218506915, 10704.0, 10757.784844303227, 10646.627368042082, 10658.307210031324, 10658.78682426347, 10661.73345666349, 10662.306950048378, 7324.278794483293, 8025.438219324708, 8052.355811535063, 8153.8776918346275, 8171.918280817115, 5615.8876822464135, 5689.914651280317, 5694.829155125353, 5766.097895130153, 5866.911996319973, 9586.712398628053, 9678.467684277432, 9847.359921605073, 9853.556589953327, 9981.45102019396, 9710.660126895526, 9962.452065136487, 9987.25095617817, 10061.748456288651, 10146.137578305927, 7164.068671072727, 7225.24134965821, 7333.0, 7334.266573342623, 7346.889796652948, 8041.0, 8051.0, 8152.755417337489, 8153.55155466455, 8153.6738530458115, 7178.964105179441, 7221.350078492961, 7344.081989751281, 7385.224551422239, 7392.815179620553, 7098.893516597152, 7158.319959603829, 7217.783466496014, 7224.674889629874, 7306.744263950736, 6722.193336799615, 6779.457643388419, 6880.277570855188, 6914.688839002157, 6939.063226464363, 4816.373871396836, 4900.828471003498, 4916.803327866988, 4948.975255123702, 5068.6451948363265, 4899.971006088761, 5012.248162775612, 5028.924566131438, 5077.949220507748, 5173.767180476812], "value": 4393.755019318081, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.96026506564357, 307.04968123657284, 309.6500953922068, 312.70449425293106, 315.26700421519973, 5379.38137114232, 5400.838819653779, 5420.159875219338, 5436.075867102592, 5439.0, 5283.154695248761, 5315.893682126355, 5355.196720491928, 5370.7046112463795, 5379.601303661047, 3210.0, 3904.414337849324, 3907.8241479133367, 4058.3506638937774, 4111.609397107285, 3721.181340105186, 3917.4319723640056, 4071.755694658315, 4089.1003979124544, 4108.383742438628, 3624.6194149614307, 3741.6258374162503, 3846.7114966377635, 4115.300398932174, 4118.929078439613, 3619.49327094207, 3805.46723458716, 4099.672026237894, 4111.60939710727, 4114.876553703379, 3009.5554134015797, 3468.236987862666, 3567.965290065878, 3568.3933731265747, 3589.3898037333715, 5169.638125331228, 5189.351331083614, 5208.895822083575, 5221.728548081596, 5244.763985620656, 3867.8839634811, 3895.668868146211, 4120.402541631476, 4666.53334666534, 4705.647076469244, 5570.521118102446, 5577.2191893135, 5582.86042848928, 5586.776528938837, 5622.971885140548, 3470.6182319944787, 3714.758540694846, 3935.3506671399186, 3955.7626542407547, 3960.6237407446247, 5069.493050694938, 5114.616403769695, 5601.579881508903, 5759.1937128802, 5958.433948774859, 3522.5949015862993, 4004.9199016019516, 4014.8193331300163, 4015.257177422128, 4018.678505719594, 3399.609044959812, 3939.6605154247695, 4069.8604390770633, 4081.285774989365, 4082.591740825894, 4673.322368256584, 4678.953210467852, 4700.247960326331, 4705.435347758223, 4723.905521889543, 4350.976795789973, 4541.592955733467, 4748.905021899543, 5664.997551297819, 5678.432156784289, 1319.33373646308, 1335.485837952376, 1338.8259526261718, 1343.173948021979, 1344.9529266475627, 713.6895450479058, 728.8797348437448, 743.3644234179699, 816.008549612224, 820.2084987986622, 4591.517890621503, 5000.3249561309485, 5180.715060671624, 5191.377034755854, 5220.084936297777, 4860.246661767432, 5070.543651071422, 5624.384661132593, 5850.3564607893095, 5893.88016276906, 4468.352088947085, 5029.214628806721, 5317.511096892877, 5318.627696061239, 5327.813526526553, 2884.8172249377653, 2910.0978696603697, 2994.757175772074, 3026.0028381268257, 3077.7691673124605, 5004.248513020487, 5278.987969143479, 5376.946230537723, 5860.2381690380835, 5993.700314984233, 5557.805476808373, 5712.429406766972, 5926.658969707028, 5975.43233392827, 6012.458878700938, 5271.68207948013, 5979.79070732531, 5986.132010858314, 6012.759489620366, 6018.488428483628, 5458.344998600193, 5801.767929282782, 5819.94180058194, 5835.912461313169, 5891.410858914246, 5000.874628282892, 5298.629095963245, 5722.513586345207, 5742.196092546963, 5792.248681876075, 5512.035393805988, 5885.676287804212, 6054.969725151346, 6062.120742569818, 6062.90905636407, 2716.0, 3176.761742869248, 3222.113918672384, 3283.6223834258412, 3421.6920477157178, 4117.547069822377, 4277.443932288783, 4634.513376095461, 4838.443578988461, 4872.123017856819, 4154.50145982484, 4483.439570053743, 4601.952131301538, 4676.339899335687, 4693.286950263061, 3930.941035884407, 4380.3717769338145, 4741.075490279362, 4758.405199350082, 4775.379200703886, 3679.466477360821, 4076.980754811297, 4502.617277531446, 4581.839635612738, 4677.742724150205, 4275.87172384829, 5088.949110508996, 5339.679619222859, 5450.863728406822, 5566.359868614999, 5506.614536982373, 5534.501894829485, 5540.390557038642, 5545.639533430314, 5568.470995255444], "value": 3971.469623801184, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.5932203389829, 735.4888352594945, 768.4582369429553, 775.7827808213702, 813.6094674556211, 14654.340554675038, 16316.184190790473, 16328.346866125356, 16347.509574712747, 16362.918185409071, 11913.344766037864, 14312.069715468488, 14372.49696260631, 14426.0, 14586.562403127897, 664.3854434647956, 676.425038717089, 826.5908375354193, 828.883956246123, 851.259404318244, 849.1169184048582, 855.0722466124231, 858.6222062292571, 859.6647307550061, 862.784303924019, 907.9863802042975, 910.4582773249906, 910.9772255693595, 915.4232833315002, 916.2395211974045, 706.9964650176717, 910.5128756115513, 910.995445022774, 911.3848152497038, 912.8539433690578, 653.751574401729, 678.3826717686882, 682.5870348439222, 683.0130461483159, 685.3283781893772, 14989.175595342253, 15008.174550399724, 15020.549383518512, 15043.398264069425, 15050.247487625575, 13595.864041359657, 13909.304534773322, 13913.721725565432, 13972.93013534936, 14132.434702611883, 10588.735281617945, 12566.685832854251, 12965.53654805584, 13059.020573456934, 13142.671433214246, 4151.958480415187, 4647.465541462741, 4659.510751371091, 4672.766361681903, 4874.829380971684, 81523.5923820383, 81570.18429815685, 109695.45152274267, 110600.0, 115552.42223788996, 11010.614628488125, 11112.777744445066, 11329.206955513035, 11358.829617555743, 11531.192816502931, 22271.44321391978, 22554.0, 22555.98498067591, 22600.434989125402, 22606.547869042526, 7521.586312752851, 7701.92298077012, 7782.727604533814, 7913.406494512819, 7927.128015918244, 3992.061865461614, 4331.45856767904, 4566.95433045672, 4588.632909367309, 4723.197056500403, 3039.361734035833, 3158.494640857452, 3427.8114703691535, 3575.8927232183078, 3607.855685772592, 616.1127975714957, 617.3918690090258, 628.943256660271, 633.1484153813205, 634.1914059573987, 569.5870493891978, 653.1019847709399, 660.920689517261, 668.3176881744283, 679.4123083532709, 8171.836563268701, 8558.272546833465, 9034.774130646787, 9358.485283309483, 9596.712098637052, 617.8980468222693, 655.0316299520007, 681.9761308354283, 688.6487891175536, 691.9793304875269, 3459.8270086495572, 4422.0, 4448.799804008828, 4460.799264033127, 4665.824102297241, 134140.0, 141266.58733412533, 144524.0, 145180.0, 155601.22199389158, 114814.0, 121435.39282303532, 122305.38847305707, 123371.3831430855, 126258.0, 82971.7554236699, 91885.0, 102445.0, 103191.0, 103794.48102759589, 8646.78383040429, 8893.288536916902, 9741.58746981698, 9747.561359738971, 9955.900440995787, 54487.72756136194, 57275.42724572701, 57394.0, 57723.711381444504, 57926.420735792104, 95950.0, 96132.0, 98455.50772246093, 100341.49829250807, 100363.49818250861, 12723.0, 12899.93550032244, 13000.544980925622, 13324.267165305613, 13429.865701342862, 14604.634884127981, 14800.925995369955, 14913.701725965202, 15431.228438577802, 15466.226688665296, 10029.0, 13443.0, 13781.242031688353, 13944.860551394357, 14166.78749818733, 12696.238225706487, 13522.797158042835, 13826.446942122604, 13970.301484925916, 14021.369038393093, 12809.231446113263, 12915.70842915701, 13189.102963535383, 13635.590932272047, 13943.511977080754, 10077.546510407195, 10469.528871200966, 10741.785164296827, 10974.890251097388, 11075.778484430428, 9190.40262382943, 9343.252539796937, 10231.0, 10275.794484110425, 11634.592789252336], "value": 7736.907596619827, "unit": "score"}], "name": "dromaeo_css", "value": 7434.002105518545, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 128.0, 115.0, 111.0, 113.0, 113.0, 114.0, 113.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 110.0, 130.0, 163.0, 110.0, 129.0, 130.0, 113.0, 194.0, 109.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 211.0, 186.0, 183.0, 185.0, 185.0, 180.0, 183.0, 186.0, 185.0], "value": 186.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 148.0, 57.0, 57.0, 58.0, 71.0, 138.0, 56.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 130.0, 70.0, 86.0, 80.0, 119.0, 69.0, 93.0, 137.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 131.0, 86.0, 85.0, 86.0, 83.0, 88.0, 86.0, 85.0, 84.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 92.0, 94.0, 93.0, 94.0, 93.0, 92.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 85.0, 86.0, 83.0, 84.0, 84.0, 85.0, 85.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 54.0, 54.0, 53.0, 54.0, 54.0, 54.0, 53.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 68.0, 79.0, 77.0, 71.0, 74.0, 68.0, 68.0, 70.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 82.0, 88.0, 94.0, 107.0, 94.0, 85.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 136.0, 140.0, 139.0, 159.0, 160.0, 138.0, 180.0, 141.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 170.0, 171.0, 172.0, 172.0, 170.0, 171.0, 171.0, 170.0], "value": 170.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 87.0, 87.0, 70.0, 68.0, 68.0, 68.0, 69.0, 67.0, 67.0], "value": 70.9, "unit": "score"}], "name": "kraken", "value": 1483.2000000000003, "alertThreshold": 2.0}]} 10:27:03 INFO - Return code: 0 10:27:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:27:03 INFO - # TBPL SUCCESS # 10:27:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:27:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:27:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:27:03 INFO - 2016-08-26 10:27:03.163 screenresolution[1660:10191] starting screenresolution argv=screenresolution get 10:27:03 INFO - 2016-08-26 10:27:03.165 screenresolution[1660:10191] Display 0: 1600x1200x32@60 10:27:03 INFO - 2016-08-26 10:27:03.171 screenresolution[1661:10194] starting screenresolution argv=screenresolution list 10:27:03 INFO - Available Modes on Display 0 10:27:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:27:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:27:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:27:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:27:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:27:03 INFO - Intel Iris: 10:27:03 INFO - Chipset Model: Intel Iris 10:27:03 INFO - Type: GPU 10:27:03 INFO - Bus: Built-In 10:27:03 INFO - VRAM (Dynamic, Max): 1536 MB 10:27:03 INFO - Vendor: Intel (0x8086) 10:27:03 INFO - Device ID: 0x0a2e 10:27:03 INFO - Revision ID: 0x0009 10:27:03 INFO - Displays: 10:27:03 INFO - Display: 10:27:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:27:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:27:03 INFO - Display Serial Number: 1600x1200 60 10:27:03 INFO - Main Display: Yes 10:27:03 INFO - Mirror: Off 10:27:03 INFO - Online: Yes 10:27:03 INFO - Rotation: Supported 10:27:03 INFO - Return code: 0 10:27:03 INFO - Running post-action listener: _resource_record_post_action 10:27:03 INFO - [mozharness: 2016-08-26 17:27:03.410147Z] Finished run-tests step (success) 10:27:03 INFO - Running post-run listener: _resource_record_post_run 10:27:03 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 32820224; Write bytes: 704753664; Read time: 307; Write time: 1786 10:27:03 INFO - TinderboxPrint: CPU usage
53.5% 10:27:03 INFO - TinderboxPrint: I/O read bytes / time
32,820,224 / 307 10:27:03 INFO - TinderboxPrint: I/O write bytes / time
704,753,664 / 1,786 10:27:03 INFO - TinderboxPrint: CPU idle
2,253.2 (46.5%) 10:27:03 INFO - TinderboxPrint: CPU system
969.0 (20.0%) 10:27:03 INFO - TinderboxPrint: CPU user
1,627.5 (33.6%) 10:27:03 INFO - TinderboxPrint: Swap in / out
434,491,392 / 0 10:27:03 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396569088; Write bytes: 390322176; Read time: 29727; Write time: 660 10:27:03 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31030272; Write bytes: 295766016; Read time: 287; Write time: 1080 10:27:03 INFO - Running post-run listener: _upload_blobber_files 10:27:03 INFO - Blob upload gear active. 10:27:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:27:03 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:27:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:27:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:27:04 INFO - (blobuploader) - INFO - Open directory for files ... 10:27:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:27:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:27:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:05 INFO - (blobuploader) - INFO - Done attempting. 10:27:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:27:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:27:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:06 INFO - (blobuploader) - INFO - Done attempting. 10:27:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:27:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:27:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:09 INFO - (blobuploader) - INFO - Done attempting. 10:27:09 INFO - (blobuploader) - INFO - Iteration through files over. 10:27:09 INFO - Return code: 0 10:27:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:27:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:27:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60aef8c97dd85ef874caa4107efaa9b72c035d351f32248b09f1c5ffd5cd6dc8712d577302f441ea53ca7b0b666f1fb3bd6bbecbb02dbe57338aaee3ebf04f12", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4c1a5cd7d26d520c79034980177073b8518cc86c23757c52de51a2693e0ca14c25983103a2b0a1ad3755ad7a095c809b06586273bddf43a6aa030dbf9618c2da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f18f601193f390dd9761903822bb26a485af377736e9ae2b9a897180c3b8c33d4a7eceddf9f913b2279bf19d3076d7b647ca8b20dbdfac990c5cb9cca216d245"} 10:27:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:27:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:27:09 INFO - Contents: 10:27:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60aef8c97dd85ef874caa4107efaa9b72c035d351f32248b09f1c5ffd5cd6dc8712d577302f441ea53ca7b0b666f1fb3bd6bbecbb02dbe57338aaee3ebf04f12", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4c1a5cd7d26d520c79034980177073b8518cc86c23757c52de51a2693e0ca14c25983103a2b0a1ad3755ad7a095c809b06586273bddf43a6aa030dbf9618c2da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f18f601193f390dd9761903822bb26a485af377736e9ae2b9a897180c3b8c33d4a7eceddf9f913b2279bf19d3076d7b647ca8b20dbdfac990c5cb9cca216d245"} 10:27:09 INFO - Running post-run listener: copy_logs_to_upload_dir 10:27:09 INFO - Copying logs to upload dir... 10:27:09 INFO - mkdir: /builds/slave/test/build/upload/logs 10:27:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.818706 ========= master_lag: 0.21 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-26 10:27:09.768243) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 10:27:09.771813) ========= 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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60aef8c97dd85ef874caa4107efaa9b72c035d351f32248b09f1c5ffd5cd6dc8712d577302f441ea53ca7b0b666f1fb3bd6bbecbb02dbe57338aaee3ebf04f12", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4c1a5cd7d26d520c79034980177073b8518cc86c23757c52de51a2693e0ca14c25983103a2b0a1ad3755ad7a095c809b06586273bddf43a6aa030dbf9618c2da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f18f601193f390dd9761903822bb26a485af377736e9ae2b9a897180c3b8c33d4a7eceddf9f913b2279bf19d3076d7b647ca8b20dbdfac990c5cb9cca216d245"} build_url:https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011134 build_url: 'https://queue.taskcluster.net/v1/task/UAFBPOP-QZipnhVY_jdP0w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60aef8c97dd85ef874caa4107efaa9b72c035d351f32248b09f1c5ffd5cd6dc8712d577302f441ea53ca7b0b666f1fb3bd6bbecbb02dbe57338aaee3ebf04f12", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4c1a5cd7d26d520c79034980177073b8518cc86c23757c52de51a2693e0ca14c25983103a2b0a1ad3755ad7a095c809b06586273bddf43a6aa030dbf9618c2da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f18f601193f390dd9761903822bb26a485af377736e9ae2b9a897180c3b8c33d4a7eceddf9f913b2279bf19d3076d7b647ca8b20dbdfac990c5cb9cca216d245"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 10:27:09.809989) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:27:09.810779) ========= 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.ydFqIlVmIB/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.WAOgRKjYvt/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.005135 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 10:27:09.864268) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 10:27:09.864999) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 10:27:09.865735) ========= ========= Total master_lag: 4.06 =========