builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0158 starttime: 1472314967.8 results: success (0) buildid: 20160827055712 builduid: 65c8787e2cd94a6b8a6faedfa5414b90 revision: 1a5b53a831e5a6c20de1b081c774feb3ff76756c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:47.803026) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:47.804012) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:47.804675) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:47.830414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:47.831060) ========= 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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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-27 09:22:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.31M=0.001s 2016-08-27 09:22:48 (8.31 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.624625 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:48.482311) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:48.483019) ========= 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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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.021037 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:48.533205) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:48.533604) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1a5b53a831e5a6c20de1b081c774feb3ff76756c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1a5b53a831e5a6c20de1b081c774feb3ff76756c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jiyGQY8kWY/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.rPrRBTmKk5/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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-27 09:22:48,611 truncating revision to first 12 chars 2016-08-27 09:22:48,611 Setting DEBUG logging. 2016-08-27 09:22:48,611 attempt 1/10 2016-08-27 09:22:48,611 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1a5b53a831e5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-27 09:22:48,893 unpacking tar archive at: mozilla-central-1a5b53a831e5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.531625 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:49.088105) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:49.088499) ========= script_repo_revision: 1a5b53a831e5a6c20de1b081c774feb3ff76756c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:49.088952) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:49.089261) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-27 09:22:49.107973) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-27 09:22:49.108306) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jiyGQY8kWY/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.rPrRBTmKk5/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:22:49 INFO - MultiFileLogger online at 20160827 09:22:49 in /builds/slave/test 09:22:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:22:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:22:49 INFO - {'append_to_log': False, 09:22:49 INFO - 'base_work_dir': '/builds/slave/test', 09:22:49 INFO - 'blob_upload_branch': 'Firefox', 09:22:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:22:49 INFO - 'branch': 'Firefox', 09:22:49 INFO - 'buildbot_json_path': 'buildprops.json', 09:22:49 INFO - 'config_files': ('talos/mac_config.py',), 09:22:49 INFO - 'default_actions': ('clobber', 09:22:49 INFO - 'read-buildbot-config', 09:22:49 INFO - 'download-and-extract', 09:22:49 INFO - 'populate-webroot', 09:22:49 INFO - 'create-virtualenv', 09:22:49 INFO - 'install', 09:22:49 INFO - 'run-tests'), 09:22:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:22:49 INFO - 'download_minidump_stackwalk': True, 09:22:49 INFO - 'download_symbols': 'ondemand', 09:22:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:22:49 INFO - 'tooltool.py': '/tools/tooltool.py', 09:22:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:22:49 INFO - '/tools/misc-python/virtualenv.py')}, 09:22:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:22:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:22:49 INFO - 'installer_path': 'installer.exe', 09:22:49 INFO - 'log_level': 'info', 09:22:49 INFO - 'log_name': 'talos', 09:22:49 INFO - 'log_to_console': True, 09:22:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:22:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:22:49 INFO - 'opt_config_files': (), 09:22:49 INFO - 'pip_index': False, 09:22:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:22:49 INFO - 'cmd': ('bash', 09:22:49 INFO - '-c', 09:22:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:22:49 INFO - 'enabled': True, 09:22:49 INFO - 'halt_on_failure': False, 09:22:49 INFO - 'name': 'check_screen_resolution'},), 09:22:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:22:49 INFO - 'cmd': ('bash', 09:22:49 INFO - '-c', 09:22:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:22:49 INFO - 'enabled': True, 09:22:49 INFO - 'halt_on_failure': False, 09:22:49 INFO - 'name': 'check_screen_resolution'},), 09:22:49 INFO - 'run_cmd_checks_enabled': True, 09:22:49 INFO - 'sps_profile': False, 09:22:49 INFO - 'sps_profile_interval': 0, 09:22:49 INFO - 'suite': 'dromaeojs', 09:22:49 INFO - 'system_bits': '32', 09:22:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:22:49 INFO - 'title': 't-yosemite-r7-0158', 09:22:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:22:49 INFO - 'use_talos_json': True, 09:22:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:22:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:22:49 INFO - 'work_dir': 'build'} 09:22:49 INFO - [mozharness: 2016-08-27 16:22:49.303770Z] Running clobber step. 09:22:49 INFO - Running pre-action listener: _resource_record_pre_action 09:22:49 INFO - Running main action method: clobber 09:22:49 INFO - rmtree: /builds/slave/test/build 09:22:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:22:50 INFO - Running post-action listener: _resource_record_post_action 09:22:50 INFO - [mozharness: 2016-08-27 16:22:50.216664Z] Finished clobber step (success) 09:22:50 INFO - [mozharness: 2016-08-27 16:22:50.216776Z] Running read-buildbot-config step. 09:22:50 INFO - Running pre-action listener: _resource_record_pre_action 09:22:50 INFO - Running main action method: read_buildbot_config 09:22:50 INFO - Using buildbot properties: 09:22:50 INFO - { 09:22:50 INFO - "project": "", 09:22:50 INFO - "product": "firefox", 09:22:50 INFO - "script_repo_revision": "production", 09:22:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:22:50 INFO - "repository": "", 09:22:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:22:50 INFO - "buildid": "20160827055712", 09:22:50 INFO - "pgo_build": "False", 09:22:50 INFO - "basedir": "/builds/slave/test", 09:22:50 INFO - "buildnumber": 18, 09:22:50 INFO - "slavename": "t-yosemite-r7-0158", 09:22:50 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:22:50 INFO - "platform": "yosemite_r7", 09:22:50 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:22:50 INFO - "branch": "mozilla-central", 09:22:50 INFO - "repo_path": "mozilla-central", 09:22:50 INFO - "revision": "1a5b53a831e5a6c20de1b081c774feb3ff76756c", 09:22:50 INFO - "stage_platform": "macosx64", 09:22:50 INFO - "builduid": "65c8787e2cd94a6b8a6faedfa5414b90", 09:22:50 INFO - "slavebuilddir": "test" 09:22:50 INFO - } 09:22:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:22:50 INFO - Running post-action listener: _resource_record_post_action 09:22:50 INFO - [mozharness: 2016-08-27 16:22:50.219604Z] Finished read-buildbot-config step (success) 09:22:50 INFO - [mozharness: 2016-08-27 16:22:50.219708Z] Running download-and-extract step. 09:22:50 INFO - Running pre-action listener: _resource_record_pre_action 09:22:50 INFO - Running main action method: download_and_extract 09:22:50 INFO - mkdir: /builds/slave/test/build/tests 09:22: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')]} 09:22:50 INFO - trying https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:22:50 INFO - Downloading https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 09:22:51 INFO - Downloaded 1199 bytes. 09:22:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:22:51 INFO - Using the following test package requirements: 09:22:51 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:22:51 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:22:51 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:22:51 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:22:51 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:22:51 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:22:51 INFO - u'jsshell-mac.zip'], 09:22:51 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:22:51 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:22:51 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:22:51 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:22:51 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:22:51 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:22:51 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:22:51 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:22:51 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:22:51 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:22:51 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:22:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:51 INFO - trying https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:22:51 INFO - Downloading https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:22:53 INFO - Downloaded 16369283 bytes. 09:22:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:23:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:00 INFO - trying https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:23:00 INFO - Downloading https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:23:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:23:01 INFO - Downloaded 16369283 bytes. 09:23:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:07 INFO - trying https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:23:07 INFO - Downloading https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:23:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:23:09 INFO - Downloaded 14413998 bytes. 09:23:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:23:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:10 INFO - trying https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:23:10 INFO - Downloading https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:23:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 09:23:12 INFO - Downloaded 111541368 bytes. 09:23:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:23:12 INFO - mkdir: /builds/slave/test/properties 09:23:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:23:12 INFO - Writing to file /builds/slave/test/properties/build_url 09:23:12 INFO - Contents: 09:23:12 INFO - build_url:https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:23:13 INFO - Running post-action listener: _resource_record_post_action 09:23:13 INFO - Running post-action listener: set_extra_try_arguments 09:23:13 INFO - [mozharness: 2016-08-27 16:23:13.715063Z] Finished download-and-extract step (success) 09:23:13 INFO - [mozharness: 2016-08-27 16:23:13.715201Z] Running populate-webroot step. 09:23:13 INFO - Running pre-action listener: _resource_record_pre_action 09:23:13 INFO - Running main action method: populate_webroot 09:23:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:23:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:23:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:23:13 INFO - '--tppagecycles', 09:23:13 INFO - '7'], 09:23:13 INFO - 'tests': ['tsvgm']}}, 09:23:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:23:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:23:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:23:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:23:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:23:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:23:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:13 INFO - 'talos_options': ['--disable-e10s'], 09:23:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:13 INFO - 'talos_options': ['--disable-e10s'], 09:23:13 INFO - 'tests': ['damp', 'tps']}, 09:23:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:13 INFO - 'tests': ['damp', 'tps']}, 09:23:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:23:13 INFO - 'tests': ['dromaeo_dom']}, 09:23:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:23:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:23:13 INFO - 'tests': ['basic_compositor_video']}, 09:23:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:23:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:23:13 INFO - 'tests': ['a11yr', 09:23:13 INFO - 'ts_paint', 09:23:13 INFO - 'tpaint', 09:23:13 INFO - 'sessionrestore', 09:23:13 INFO - 'sessionrestore_no_auto_restore', 09:23:13 INFO - 'tabpaint']}, 09:23:13 INFO - 'other-e10s': {'tests': ['a11yr', 09:23:13 INFO - 'ts_paint', 09:23:13 INFO - 'tpaint', 09:23:13 INFO - 'sessionrestore', 09:23:13 INFO - 'sessionrestore_no_auto_restore', 09:23:13 INFO - 'tabpaint']}, 09:23:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:23:13 INFO - 'ts_paint', 09:23:13 INFO - 'tpaint', 09:23:13 INFO - 'sessionrestore', 09:23:13 INFO - 'sessionrestore_no_auto_restore', 09:23:13 INFO - 'tabpaint']}, 09:23:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:23:13 INFO - 'ts_paint', 09:23:13 INFO - 'tpaint', 09:23:13 INFO - 'sessionrestore', 09:23:13 INFO - 'sessionrestore_no_auto_restore', 09:23:13 INFO - 'tabpaint']}, 09:23:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:23:13 INFO - 'tests': ['a11yr', 09:23:13 INFO - 'ts_paint', 09:23:13 INFO - 'tpaint', 09:23:13 INFO - 'sessionrestore', 09:23:13 INFO - 'sessionrestore_no_auto_restore', 09:23:13 INFO - 'tabpaint']}, 09:23:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:23:13 INFO - 'tests': ['a11yr', 09:23:13 INFO - 'ts_paint', 09:23:13 INFO - 'tpaint', 09:23:13 INFO - 'sessionrestore', 09:23:13 INFO - 'sessionrestore_no_auto_restore', 09:23:13 INFO - 'tabpaint']}, 09:23:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:23:13 INFO - 'tests': ['tsvgx', 09:23:13 INFO - 'tsvgr_opacity', 09:23:13 INFO - 'tart', 09:23:13 INFO - 'tscrollx', 09:23:13 INFO - 'cart']}, 09:23:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:23:13 INFO - 'tsvgr_opacity', 09:23:13 INFO - 'tart', 09:23:13 INFO - 'tscrollx', 09:23:13 INFO - 'cart']}, 09:23:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:13 INFO - 'talos_options': ['--disable-e10s'], 09:23:13 INFO - 'tests': ['tp5o']}, 09:23:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:13 INFO - 'tests': ['tp5o']}, 09:23:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:13 INFO - 'talos_options': ['--disable-e10s', 09:23:13 INFO - '--xperf_path', 09:23:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:13 INFO - 'tests': ['tp5n']}, 09:23:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:23:13 INFO - 'talos_options': ['--xperf_path', 09:23:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:13 INFO - 'tests': ['tp5n']}}, 09:23:13 INFO - 'talos.zip': {'path': '', 09:23:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:23:13 INFO - Running post-action listener: _resource_record_post_action 09:23:13 INFO - [mozharness: 2016-08-27 16:23:13.734009Z] Finished populate-webroot step (success) 09:23:13 INFO - [mozharness: 2016-08-27 16:23:13.734140Z] Running create-virtualenv step. 09:23:13 INFO - Running pre-action listener: _resource_record_pre_action 09:23:13 INFO - Running main action method: create_virtualenv 09:23:13 INFO - Creating virtualenv /builds/slave/test/build/venv 09:23:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:23:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:23:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:23:13 INFO - Using real prefix '/tools/python27' 09:23:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:23:14 INFO - Installing distribute.............................................................................................................................................................................................done. 09:23:17 INFO - Installing pip.................done. 09:23:17 INFO - Return code: 0 09:23:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:17 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 0x102157a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa92d8a6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020bbd30>, '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 0x101e20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1020b3b20>, '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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiyGQY8kWY/Render', 09:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:17 INFO - 'HOME': '/Users/cltbld', 09:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:17 INFO - 'LOGNAME': 'cltbld', 09:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:17 INFO - 'MOZ_NO_REMOTE': '1', 09:23:17 INFO - 'NO_EM_RESTART': '1', 09:23:17 INFO - 'PAGER': '/bin/cat', 09:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:17 INFO - 'PWD': '/builds/slave/test', 09:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:17 INFO - 'SHELL': '/bin/bash', 09:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPrRBTmKk5/Listeners', 09:23:17 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 09:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:17 INFO - 'USER': 'cltbld', 09:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:17 INFO - 'XPC_FLAGS': '0x0', 09:23:17 INFO - 'XPC_SERVICE_NAME': '0', 09:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:23:17 INFO - Cleaning up... 09:23:17 INFO - Return code: 0 09:23:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:17 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 0x102157a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa92d8a6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020bbd30>, '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 0x101e20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1020b3b20>, '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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiyGQY8kWY/Render', 09:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:17 INFO - 'HOME': '/Users/cltbld', 09:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:17 INFO - 'LOGNAME': 'cltbld', 09:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:17 INFO - 'MOZ_NO_REMOTE': '1', 09:23:17 INFO - 'NO_EM_RESTART': '1', 09:23:17 INFO - 'PAGER': '/bin/cat', 09:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:17 INFO - 'PWD': '/builds/slave/test', 09:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:17 INFO - 'SHELL': '/bin/bash', 09:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPrRBTmKk5/Listeners', 09:23:17 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 09:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:17 INFO - 'USER': 'cltbld', 09:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:17 INFO - 'XPC_FLAGS': '0x0', 09:23:17 INFO - 'XPC_SERVICE_NAME': '0', 09:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:18 INFO - Downloading/unpacking psutil>=3.1.1 09:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:23:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:23:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:23:21 INFO - Installing collected packages: psutil 09:23:21 INFO - Running setup.py install for psutil 09:23:21 INFO - building 'psutil._psutil_osx' extension 09:23:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:23:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:23:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:23:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:23:22 INFO - building 'psutil._psutil_posix' extension 09:23:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:23:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:23:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:23:22 INFO - ^ 09:23:22 INFO - 1 warning generated. 09:23:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:23:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:23:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:23:22 INFO - Successfully installed psutil 09:23:22 INFO - Cleaning up... 09:23:22 INFO - Return code: 0 09:23:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:22 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 0x102157a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa92d8a6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020bbd30>, '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 0x101e20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1020b3b20>, '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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:23:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:23:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiyGQY8kWY/Render', 09:23:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:22 INFO - 'HOME': '/Users/cltbld', 09:23:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:22 INFO - 'LOGNAME': 'cltbld', 09:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:22 INFO - 'MOZ_NO_REMOTE': '1', 09:23:22 INFO - 'NO_EM_RESTART': '1', 09:23:22 INFO - 'PAGER': '/bin/cat', 09:23:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:22 INFO - 'PWD': '/builds/slave/test', 09:23:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:22 INFO - 'SHELL': '/bin/bash', 09:23:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPrRBTmKk5/Listeners', 09:23:22 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 09:23:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:22 INFO - 'USER': 'cltbld', 09:23:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:22 INFO - 'XPC_FLAGS': '0x0', 09:23:22 INFO - 'XPC_SERVICE_NAME': '0', 09:23:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:23:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:23:25 INFO - Installing collected packages: mozsystemmonitor 09:23:25 INFO - Running setup.py install for mozsystemmonitor 09:23:25 INFO - Successfully installed mozsystemmonitor 09:23:25 INFO - Cleaning up... 09:23:25 INFO - Return code: 0 09:23:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:23: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')]} 09:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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')]} 09:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:25 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 0x102157a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa92d8a6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020bbd30>, '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 0x101e20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1020b3b20>, '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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiyGQY8kWY/Render', 09:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:25 INFO - 'HOME': '/Users/cltbld', 09:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:25 INFO - 'LOGNAME': 'cltbld', 09:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:25 INFO - 'MOZ_NO_REMOTE': '1', 09:23:25 INFO - 'NO_EM_RESTART': '1', 09:23:25 INFO - 'PAGER': '/bin/cat', 09:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:25 INFO - 'PWD': '/builds/slave/test', 09:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:25 INFO - 'SHELL': '/bin/bash', 09:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPrRBTmKk5/Listeners', 09:23:25 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 09:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:25 INFO - 'USER': 'cltbld', 09:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:25 INFO - 'XPC_FLAGS': '0x0', 09:23:25 INFO - 'XPC_SERVICE_NAME': '0', 09:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:25 INFO - Downloading/unpacking jsonschema==2.5.1 09:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:23:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:23: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 09:23: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 09:23:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23: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 09:23: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 09:23:28 INFO - Downloading functools32-3.2.3-2.tar.gz 09:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:23:28 INFO - warning: no files found matching '*.txt' 09:23:28 INFO - no previously-included directories found matching 'build' 09:23:28 INFO - no previously-included directories found matching 'dist' 09:23:28 INFO - no previously-included directories found matching '.git*' 09:23:28 INFO - Installing collected packages: jsonschema, functools32 09:23:28 INFO - Running setup.py install for functools32 09:23:28 INFO - warning: no files found matching '*.txt' 09:23:28 INFO - no previously-included directories found matching 'build' 09:23:28 INFO - no previously-included directories found matching 'dist' 09:23:28 INFO - no previously-included directories found matching '.git*' 09:23:29 INFO - Successfully installed jsonschema functools32 09:23:29 INFO - Cleaning up... 09:23:29 INFO - Return code: 0 09:23:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:29 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 0x102157a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa92d8a6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020bbd30>, '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 0x101e20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1020b3b20>, '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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiyGQY8kWY/Render', 09:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:29 INFO - 'HOME': '/Users/cltbld', 09:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:29 INFO - 'LOGNAME': 'cltbld', 09:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:29 INFO - 'MOZ_NO_REMOTE': '1', 09:23:29 INFO - 'NO_EM_RESTART': '1', 09:23:29 INFO - 'PAGER': '/bin/cat', 09:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:29 INFO - 'PWD': '/builds/slave/test', 09:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:29 INFO - 'SHELL': '/bin/bash', 09:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPrRBTmKk5/Listeners', 09:23:29 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 09:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:29 INFO - 'USER': 'cltbld', 09:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:29 INFO - 'XPC_FLAGS': '0x0', 09:23:29 INFO - 'XPC_SERVICE_NAME': '0', 09:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:23:29 INFO - Cleaning up... 09:23:29 INFO - Return code: 0 09:23:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:29 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 0x102157a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa92d8a6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020bbd30>, '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 0x101e20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1020b3b20>, '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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiyGQY8kWY/Render', 09:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:29 INFO - 'HOME': '/Users/cltbld', 09:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:29 INFO - 'LOGNAME': 'cltbld', 09:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:29 INFO - 'MOZ_NO_REMOTE': '1', 09:23:29 INFO - 'NO_EM_RESTART': '1', 09:23:29 INFO - 'PAGER': '/bin/cat', 09:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:29 INFO - 'PWD': '/builds/slave/test', 09:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:29 INFO - 'SHELL': '/bin/bash', 09:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPrRBTmKk5/Listeners', 09:23:29 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 09:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:29 INFO - 'USER': 'cltbld', 09:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:29 INFO - 'XPC_FLAGS': '0x0', 09:23:29 INFO - 'XPC_SERVICE_NAME': '0', 09:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:29 INFO - Downloading/unpacking blobuploader==1.2.4 09:23: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 09:23: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 09:23: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 09:23: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 09:23: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 09:23: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 09:23:32 INFO - Downloading blobuploader-1.2.4.tar.gz 09:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:23:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:23: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 09:23: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 09:23: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 09:23: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 09:23: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 09:23: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 09:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:23:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:23: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 09:23: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 09:23: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 09:23: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 09:23: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 09:23: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 09:23:33 INFO - Downloading docopt-0.6.1.tar.gz 09:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:23:33 INFO - Installing collected packages: blobuploader, requests, docopt 09:23:33 INFO - Running setup.py install for blobuploader 09:23:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:23:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:23:33 INFO - Running setup.py install for requests 09:23:34 INFO - Running setup.py install for docopt 09:23:34 INFO - Successfully installed blobuploader requests docopt 09:23:34 INFO - Cleaning up... 09:23:34 INFO - Return code: 0 09:23:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:34 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 0x102157a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa92d8a6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020bbd30>, '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 0x101e20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1020b3b20>, '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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:23:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiyGQY8kWY/Render', 09:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:34 INFO - 'HOME': '/Users/cltbld', 09:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:34 INFO - 'LOGNAME': 'cltbld', 09:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:34 INFO - 'MOZ_NO_REMOTE': '1', 09:23:34 INFO - 'NO_EM_RESTART': '1', 09:23:34 INFO - 'PAGER': '/bin/cat', 09:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:34 INFO - 'PWD': '/builds/slave/test', 09:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:34 INFO - 'SHELL': '/bin/bash', 09:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPrRBTmKk5/Listeners', 09:23:34 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 09:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:34 INFO - 'USER': 'cltbld', 09:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:34 INFO - 'XPC_FLAGS': '0x0', 09:23:34 INFO - 'XPC_SERVICE_NAME': '0', 09:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:23:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-oELeaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:23:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-kaD6GS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:23:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-aEFHmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:23:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-FslgFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:23:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-XMNgEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:23:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-CCobGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:23:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-vwX8k0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:23:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-UoNggO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:23:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-k3YAFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:23:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-4UCcX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:23:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-ZnLKPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:23:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-hT0C5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:23:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-iNsmNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:23:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-WRgFyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:36 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-6W04dB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:23:36 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-nif7_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:23:36 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-_qSfOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:23:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:23:36 INFO - Running setup.py install for manifestparser 09:23:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:23:36 INFO - Running setup.py install for mozcrash 09:23:36 INFO - Running setup.py install for mozdebug 09:23:36 INFO - Running setup.py install for mozdevice 09:23:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:23:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:23:36 INFO - Running setup.py install for mozfile 09:23:37 INFO - Running setup.py install for mozhttpd 09:23:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Running setup.py install for mozinfo 09:23:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Running setup.py install for mozInstall 09:23:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Running setup.py install for mozleak 09:23:37 INFO - Running setup.py install for mozlog 09:23:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:23:37 INFO - Running setup.py install for moznetwork 09:23:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Running setup.py install for mozprocess 09:23:38 INFO - Running setup.py install for mozprofile 09:23:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Running setup.py install for mozrunner 09:23:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Running setup.py install for mozscreenshot 09:23:38 INFO - Running setup.py install for moztest 09:23:38 INFO - Running setup.py install for mozversion 09:23:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:23:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:23:38 INFO - Cleaning up... 09:23:38 INFO - Return code: 0 09:23:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:38 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 0x102157a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa92d8a6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020bbd30>, '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 0x101e20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1020b3b20>, '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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:23:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiyGQY8kWY/Render', 09:23:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:38 INFO - 'HOME': '/Users/cltbld', 09:23:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:38 INFO - 'LOGNAME': 'cltbld', 09:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:38 INFO - 'MOZ_NO_REMOTE': '1', 09:23:38 INFO - 'NO_EM_RESTART': '1', 09:23:38 INFO - 'PAGER': '/bin/cat', 09:23:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:38 INFO - 'PWD': '/builds/slave/test', 09:23:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:38 INFO - 'SHELL': '/bin/bash', 09:23:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPrRBTmKk5/Listeners', 09:23:38 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 09:23:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:38 INFO - 'USER': 'cltbld', 09:23:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:38 INFO - 'XPC_FLAGS': '0x0', 09:23:38 INFO - 'XPC_SERVICE_NAME': '0', 09:23:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:23:39 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-aQ0eVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:23:39 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-icP6Nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:23:39 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-sAhMGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:23:39 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-hnCGAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:23:39 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-O7gCdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:23:39 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-f5IMid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:23:39 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-8DgjzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:23:40 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-Fv0hHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:23:40 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-z8S8fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:23:40 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-tJoiwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:23:40 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-KqO7QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:23:40 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-3RXO5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:23:40 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-9Q3bT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:23:40 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-zK8qHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:40 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-3djbSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:23:40 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-FWEBty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:23:40 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-QVgTLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:23:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:23:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:23:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:23:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:23:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:23:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:23:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:43 INFO - Downloading blessings-1.6.tar.gz 09:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:23:43 INFO - Installing collected packages: blessings 09:23:43 INFO - Running setup.py install for blessings 09:23:43 INFO - Successfully installed blessings 09:23:43 INFO - Cleaning up... 09:23:43 INFO - Return code: 0 09:23:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:23:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:23:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:23:44 INFO - Reading from file tmpfile_stdout 09:23:44 INFO - Current package versions: 09:23:44 INFO - blessings == 1.6 09:23:44 INFO - blobuploader == 1.2.4 09:23:44 INFO - docopt == 0.6.1 09:23:44 INFO - functools32 == 3.2.3-2 09:23:44 INFO - jsonschema == 2.5.1 09:23:44 INFO - manifestparser == 1.1 09:23:44 INFO - mozInstall == 1.12 09:23:44 INFO - mozcrash == 0.17 09:23:44 INFO - mozdebug == 0.1 09:23:44 INFO - mozdevice == 0.48 09:23:44 INFO - mozfile == 1.2 09:23:44 INFO - mozhttpd == 0.7 09:23:44 INFO - mozinfo == 0.9 09:23:44 INFO - mozleak == 0.1 09:23:44 INFO - mozlog == 3.3 09:23:44 INFO - moznetwork == 0.27 09:23:44 INFO - mozprocess == 0.23 09:23:44 INFO - mozprofile == 0.28 09:23:44 INFO - mozrunner == 6.12 09:23:44 INFO - mozscreenshot == 0.1 09:23:44 INFO - mozsystemmonitor == 0.3 09:23:44 INFO - moztest == 0.7 09:23:44 INFO - mozversion == 1.4 09:23:44 INFO - psutil == 3.1.1 09:23:44 INFO - requests == 1.2.3 09:23:44 INFO - wsgiref == 0.1.2 09:23:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23: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')]} 09:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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')]} 09:23:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:44 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 0x102157a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa92d8a6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020bbd30>, '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 0x101e20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1020b3b20>, '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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiyGQY8kWY/Render', 09:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:44 INFO - 'HOME': '/Users/cltbld', 09:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:44 INFO - 'LOGNAME': 'cltbld', 09:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:44 INFO - 'MOZ_NO_REMOTE': '1', 09:23:44 INFO - 'NO_EM_RESTART': '1', 09:23:44 INFO - 'PAGER': '/bin/cat', 09:23:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:44 INFO - 'PWD': '/builds/slave/test', 09:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:44 INFO - 'SHELL': '/bin/bash', 09:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPrRBTmKk5/Listeners', 09:23:44 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 09:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:44 INFO - 'USER': 'cltbld', 09:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:44 INFO - 'XPC_FLAGS': '0x0', 09:23:44 INFO - 'XPC_SERVICE_NAME': '0', 09:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:23:44 INFO - Cleaning up... 09:23:44 INFO - Return code: 0 09:23:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:23: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')]} 09:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23: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')]} 09:23:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:44 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 0x102157a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa92d8a6b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020bbd30>, '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 0x101e20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1020b3b20>, '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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiyGQY8kWY/Render', 09:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:44 INFO - 'HOME': '/Users/cltbld', 09:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:44 INFO - 'LOGNAME': 'cltbld', 09:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:44 INFO - 'MOZ_NO_REMOTE': '1', 09:23:44 INFO - 'NO_EM_RESTART': '1', 09:23:44 INFO - 'PAGER': '/bin/cat', 09:23:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:44 INFO - 'PWD': '/builds/slave/test', 09:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:44 INFO - 'SHELL': '/bin/bash', 09:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPrRBTmKk5/Listeners', 09:23:44 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 09:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:44 INFO - 'USER': 'cltbld', 09:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:44 INFO - 'XPC_FLAGS': '0x0', 09:23:44 INFO - 'XPC_SERVICE_NAME': '0', 09:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:23:44 INFO - Cleaning up... 09:23:44 INFO - Return code: 0 09:23:44 INFO - Running post-action listener: _resource_record_post_action 09:23:44 INFO - Running post-action listener: _start_resource_monitoring 09:23:44 INFO - Starting resource monitoring. 09:23:44 INFO - [mozharness: 2016-08-27 16:23:44.776883Z] Finished create-virtualenv step (success) 09:23:44 INFO - [mozharness: 2016-08-27 16:23:44.778002Z] Running install step. 09:23:44 INFO - Running pre-action listener: _resource_record_pre_action 09:23:44 INFO - Running main action method: install 09:23:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:23:45 INFO - Reading from file tmpfile_stdout 09:23:45 INFO - Detecting whether we're running mozinstall >=1.0... 09:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:23:45 INFO - Reading from file tmpfile_stdout 09:23:45 INFO - Output received: 09:23:45 INFO - Usage: mozinstall [options] installer 09:23:45 INFO - Options: 09:23:45 INFO - -h, --help show this help message and exit 09:23:45 INFO - -d DEST, --destination=DEST 09:23:45 INFO - Directory to install application into. [default: 09:23:45 INFO - "/builds/slave/test"] 09:23:45 INFO - --app=APP Application being installed. [default: firefox] 09:23:45 INFO - mkdir: /builds/slave/test/build/application 09:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:24:19 INFO - Reading from file tmpfile_stdout 09:24:19 INFO - Output received: 09:24:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:24:19 INFO - Running post-action listener: _resource_record_post_action 09:24:19 INFO - [mozharness: 2016-08-27 16:24:19.668575Z] Finished install step (success) 09:24:19 INFO - [mozharness: 2016-08-27 16:24:19.668728Z] Running run-tests step. 09:24:19 INFO - Running pre-action listener: _resource_record_pre_action 09:24:19 INFO - Running main action method: run_tests 09:24:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:24:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:24:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:24:19 INFO - 2016-08-27 09:24:19.683 screenresolution[2580:36470] starting screenresolution argv=screenresolution get 09:24:19 INFO - 2016-08-27 09:24:19.686 screenresolution[2580:36470] Display 0: 1600x1200x32@60 09:24:19 INFO - 2016-08-27 09:24:19.692 screenresolution[2581:36472] starting screenresolution argv=screenresolution list 09:24:19 INFO - Available Modes on Display 0 09:24:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:24:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:24:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:24:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:24:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:24:19 INFO - Intel Iris: 09:24:19 INFO - Chipset Model: Intel Iris 09:24:19 INFO - Type: GPU 09:24:19 INFO - Bus: Built-In 09:24:19 INFO - VRAM (Dynamic, Max): 1536 MB 09:24:19 INFO - Vendor: Intel (0x8086) 09:24:19 INFO - Device ID: 0x0a2e 09:24:19 INFO - Revision ID: 0x0009 09:24:19 INFO - Displays: 09:24:19 INFO - Display: 09:24:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:24:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:24:19 INFO - Display Serial Number: 1600x1200 60 09:24:19 INFO - Main Display: Yes 09:24:19 INFO - Mirror: Off 09:24:19 INFO - Online: Yes 09:24:19 INFO - Rotation: Supported 09:24:19 INFO - Return code: 0 09:24:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:24:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:24:19 INFO - Python 2.7.3 09:24:19 INFO - Return code: 0 09:24:19 INFO - grabbing minidump binary from tooltool 09:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:19 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 0x1020bbd30>, '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 0x101e20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1020b3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:24:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:24:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:24:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:24:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:24:20 INFO - Return code: 0 09:24:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:24:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:24:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:24:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:24:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:24:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0158', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:24:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0158 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:24:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiyGQY8kWY/Render', 09:24:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:20 INFO - 'HOME': '/Users/cltbld', 09:24:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:20 INFO - 'LOGNAME': 'cltbld', 09:24:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:24:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:20 INFO - 'MOZ_NO_REMOTE': '1', 09:24:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:24:20 INFO - 'NO_EM_RESTART': '1', 09:24:20 INFO - 'PAGER': '/bin/cat', 09:24:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:20 INFO - 'PWD': '/builds/slave/test', 09:24:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:24:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:20 INFO - 'SHELL': '/bin/bash', 09:24:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPrRBTmKk5/Listeners', 09:24:20 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 09:24:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:20 INFO - 'USER': 'cltbld', 09:24:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:20 INFO - 'XPC_FLAGS': '0x0', 09:24:20 INFO - 'XPC_SERVICE_NAME': '0', 09:24:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0158', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:24:20 INFO - mozversion application_buildid: 20160827055712 09:24:20 INFO - mozversion application_changeset: 1a5b53a831e5a6c20de1b081c774feb3ff76756c 09:24:20 INFO - mozversion application_display_name: Nightly 09:24:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:24:20 INFO - mozversion application_name: Firefox 09:24:20 INFO - mozversion application_remotingname: firefox 09:24:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:24:20 INFO - mozversion application_vendor: Mozilla 09:24:20 INFO - mozversion application_version: 51.0a1 09:24:20 INFO - mozversion platform_buildid: 20160827055712 09:24:20 INFO - mozversion platform_changeset: 1a5b53a831e5a6c20de1b081c774feb3ff76756c 09:24:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:24:20 INFO - mozversion platform_version: 51.0a1 09:24:20 INFO - using testdate: 1472315060 09:24:20 INFO - actual date: 1472315060 09:24:20 INFO - starting webserver on 'localhost:55266' 09:24:20 INFO - SUITE-START | Running 2 tests 09:24:20 INFO - TEST-START | dromaeo_css 09:24:20 INFO - operating with platform_type : mac_ 09:24:20 INFO - Initialising browser for dromaeo_css test... 09:24:20 INFO - TEST-INFO | started process 2591 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpLC5MgF/profile http://localhost:55266/getInfo.html) 09:24:22 INFO - PROCESS | 2591 | __metrics Screen width/height:1600/1200 09:24:22 INFO - PROCESS | 2591 | colorDepth:24 09:24:22 INFO - PROCESS | 2591 | Browser inner width/height: 1024/689 09:24:22 INFO - PROCESS | 2591 | __metrics 09:24:22 INFO - PROCESS | 2591 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:24:22 INFO - PROCESS | 2591 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:24:23 INFO - TEST-INFO | 2591: exit 0 09:24:23 INFO - Browser initialized. 09:24:23 INFO - Running cycle 1/1 for dromaeo_css test... 09:24:23 INFO - TEST-INFO | started process 2593 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpLC5MgF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:24:35 INFO - PROCESS | 2593 | RSS: Main: 232984576 09:24:35 INFO - PROCESS | 2593 | 09:25:26 INFO - PROCESS | 2593 | 1472315126842 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:27:38 INFO - PROCESS | 2593 | Cycle 1(1): loaded http://localhost:55266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55266/tests/dromaeo/cssquery-ext.html) 09:27:39 INFO - PROCESS | 2593 | RSS: Main: 360148992 09:27:39 INFO - PROCESS | 2593 | 09:30:42 INFO - PROCESS | 2593 | Cycle 1(1): loaded http://localhost:55266/tests/dromaeo/cssquery-ext.html (next: http://localhost:55266/tests/dromaeo/cssquery-jquery.html) 09:30:43 INFO - PROCESS | 2593 | RSS: Main: 368680960 09:30:43 INFO - PROCESS | 2593 | 09:33:45 INFO - PROCESS | 2593 | Cycle 1(1): loaded http://localhost:55266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55266/tests/dromaeo/cssquery-mootools.html) 09:33:46 INFO - PROCESS | 2593 | RSS: Main: 337731584 09:33:46 INFO - PROCESS | 2593 | 09:36:48 INFO - PROCESS | 2593 | Cycle 1(1): loaded http://localhost:55266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55266/tests/dromaeo/cssquery-prototype.html) 09:36:49 INFO - PROCESS | 2593 | RSS: Main: 334065664 09:36:49 INFO - PROCESS | 2593 | 09:39:53 INFO - PROCESS | 2593 | Cycle 1(1): loaded http://localhost:55266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55266/tests/dromaeo/cssquery-yui.html) 09:39:53 INFO - PROCESS | 2593 | RSS: Main: 325591040 09:39:53 INFO - PROCESS | 2593 | 09:42:57 INFO - PROCESS | 2593 | Cycle 1(1): loaded http://localhost:55266/tests/dromaeo/cssquery-yui.html (next: http://localhost:55266/tests/dromaeo/cssquery-dojo.html) 09:42:57 INFO - PROCESS | 2593 | RSS: Main: 532344832 09:42:57 INFO - PROCESS | 2593 | 09:42:57 INFO - PROCESS | 2593 | __start_tp_report 09:42:57 INFO - PROCESS | 2593 | _x_x_mozilla_page_load 09:42:57 INFO - PROCESS | 2593 | _x_x_mozilla_page_load_details 09:42:57 INFO - PROCESS | 2593 | |i|pagename|runs| 09:42:57 INFO - PROCESS | 2593 | |0;dojo.html;3301.7688761786685;3351.7988920664775;3352.9161770955725;3364.6971772540464;3407.914802129948;5620.859478513029;5681.715914204294;5712.257406537155;5780.450857168573;5785.4503822136885;5708.1437784332375;5720.998825205593;5817.301923769153;5817.418258174181;5850.853728656775;5170.146925757245;5175.456577059411;5243.3970093439175;5248.0553500369915;5327.573794096483;5364.1149210380245;5367.221752845835;5370.624056316059;5380.488853558925;5415.64798288112;5207.218917162456;5238.73806309683;5259.605529585297;5270.894582108374;5292.259083728281;5289.18017707256;5295.232191332235;5298.36419629643;5300.575953923677;5305.602079844027;5181.404138524081;5182.352205974254;5209.765560549784;5210.140326846104;5294.947050529522;5680.233168522279;5689.402612725646;5749.59752817303;5766.798162064308;5780.566457515703;6256.374362563753;6289.811305660837;6296.370362963713;6300.905486417708;6309.337519560426;5856;6158.599691020114;6222.5644204905675;6297.464715499143;6327.5570710050315;5855.882882342372;5856.15085812559;5860.120981852671;5862.355140934494;5864.266966629171;6174.722137503823;6264.498840092781;6272.937270627326;6282.748690052438;6301.243850737941;4884.267359896044;6115.938840611626;6128.724207410677;6141.056449558059;6181.81454556364;6269.405004559999;6306.274778400542;6309.053641953652;6310.242770867525;6320.051992201114;6038.698065096728;6254.186955695731;6264.310925798159;6270.184875966095;6270.216222972129;6134.222293197073;6188.133661287423;6246.75012999484;6248.625082495065;6249.875002500015;4997.5002499749735;5009.5741861941415;5011.373578302712;5025.246213067996;5046.974765126224;4479.216137175981;4482.058767658763;4490.438695163105;4506.1663592235545;4532.410786597788;5317.41508434072;5395.785948161632;5727.8568035799435;5744.913826292609;5791.797287094932;6276.964300890393;6297.590656607306;6316;6332.240131184195;6333.20834895638;5729.656220626693;5755.3381361143165;5764.827055188351;5795.043817770021;5822.214001109881;5367.35591728995;5396.4333744957;5432.239486471897;5475.698836563947;5491.917621235684;6206.317305096345;6242.750289988349;6251.71867265965;6264.404881536247;6277.529185311211;6318.905216421849;6328.398802113792;6329.651869147241;6337.556371054166;6353.8729225416155;6206.317305096526;6215.906761398493;6261;6262.780802672067;6270.059491076375;6103.549811064127;6116.510679145568;6226.813195604139;6229.750809967551;6260.561760676709;6208.161898143873;6238.282597501346;6263.937360626518;6335.619862808247;6339;6071.241094863142;6104.542159338156;6132.816015519542;6139.355367686508;6151.661658608819;5947.6431414115295;5952.315483719254;6133.969330153321;6142.692865356625;6158.969205153946;5834.299884013945;5928.288605367384;5949.791757288474;5959.404059594006;6025.66858822769;6146.754129834757;6149.323574406902;6154.78458253959;6162.106494558174;6172.506199503939;5903.675297858659;5958.702064896651;5959.463648271676;5983.461488466059;5990.880182396415 09:42:57 INFO - PROCESS | 2593 | |1;ext.html;500.34205020398196;516.9379674439067;522.3183745212498;522.3835873669071;528.936527616686;30224.84887575562;30831;39911.002224944394;41171.764847054634;41230.7938460308;42093.57906420935;42572.935676608104;42586.14827703444;42616.78691606545;42632.36051459231;8198.590070496466;8569.914300856974;9643.794525684289;9794.383926652094;9831.410115393099;8665.17680820321;8910.910890891073;11856.703582410422;12100.334481603508;12139.57511487098;12039.916407523322;12058.457369418353;12141.93929030358;12145.39273036349;12159;12078.275303481776;12157.209781364272;12158.088143389192;12160.270383776957;12169.878301216962;11784.646460606196;11882.28706277626;11939.880601193963;11964.820527692005;11965.760684786343;39807.8009609953;40248.19503609911;40263.19473610511;40307.395389069185;40436.79781601103;3405.2338223899674;3407.539982102434;3594.4967704521387;3637.4725664778725;3858.82635281413;30481.542776858365;30622;30855.0743477696;31050.378992420025;31626.051218463483;3167.619885613741;3169.89942528734;3200.647928727838;3210.7270881974828;3298.719608833252;477363;481629;483556;484299.99999999645;488995;58106;58416.53958651068;58457.70771146202;58464.83070338569;58502;33422.164445889044;60288.09567856486;60362.39637603656;60398.79202415927;60465.69767151136;15620.984635998624;16325.183740812912;16329.510114696579;16335.346586136659;16422.1788910554;11280.661580152608;11555.306681599131;12539.749205015849;12636.684082898;12660.87339126597;18405.907970460063;18562.814371856377;18762.718559221623;19111.61776764457;19175.94532300709;6491.415772580491;7616.771496855104;7621.733239336597;7763.844723105506;7816.335611472975;878.6529320918202;906.9434109262635;999.5352161245056;1015.7511409704522;1080.7082087836397;24272;28633;28702.71297287042;29489.820407183473;30084.77965288315;1029.3206483720708;1041.5521325829966;1075.913926885863;1091.0398849012818;1115.11905594581;13003.993462516999;14168.291585420893;14884.925575372054;15381.769273460906;15460.149691766836;585982;640836;682628;683299;684375;56040.71979640157;56662.15006774902;56671.86656266852;56680.71659641675;56815;1212.5877201751448;1331.6670832291734;1361.7412691588565;1372.5813626843708;1389.0623828915643;29588.852055739582;30542;30612.540811887393;31294.748210071342;31389.372212556078;48271;48684.513154868;48689.026219474705;48788;48814;543339;544051;544607;544750;545140;21487.78512214858;26779;26876.059337923078;27417;27573.86213069002;45679;45761.77119114383;46637.53362466468;47081;47252.81867953191;31727.52408713825;34777.65222347744;37795.62204377921;38785.80607096946;39134.412983804694;29302.70697293;37927;38801.02997425087;38830.2233955325;39476.80261598674;36656.0835979092;37126.70056548008;37771.43342849804;37783.43324850075;37869.81065094657;32700;32761.508577371835;33231;33338;33756.32487350288;23943;24702.382440439134;24718.629220561346;25097.121600743885;25633.359166021 09:42:57 INFO - PROCESS | 2593 | |2;jquery.html;307.5325505232047;346.5667915106117;370.2391585292225;372.39857629927667;398.93218152914;15725.685486290282;17654.47036588903;20380.388588342335;20515.487112822186;20610.072546735413;20654.69017964728;21047.842368669757;21278.787212127874;21395;21507.67738483924;5667.801626943058;5748.109043098327;5820.243368362107;6014.398560143973;6017.247844019497;4079.1433798902117;5784.710764461781;5877;5890.705464726747;5894.086416605412;6163.352847950968;6454.160959075337;6480.9675951620175;6497.122888410051;6536.346365363449;5707.743151558148;6004.5796794224425;6059.303180134297;6458.709358078874;6476.158099447065;5907.881842363128;5911.433470130419;5911.527077833763;5912.320083190445;5920.319163296233;13135.802962955564;13290.3354832258;13444.260565668885;13482.19106853592;13576.45694172232;2220.4781876259067;2224.73303203615;2228.765979572138;2393.676853624756;2874.8993785217417;9461.290403219786;9745.31782775206;10033.046860548237;10341.586336546528;13693.520726774515;1709.7008023595945;1728.2395745871902;1782.6123535729043;1824.3888297420247;1939.0983192815268;106119.4694026525;122207;123421.38289308586;124833;125458;9231.35380523357;10695.240617925105;11027.944860275808;11041.212601545536;11216.55133794639;14173.929130354281;14204.147751134966;14204.289785510684;14209.005369624234;14210.786838197435;7967.2431119043595;8311.875321870177;8313.293370063491;8389.06382766027;8419.621117049748;6640.800775976729;8174.713885013996;8184.6726130955285;8328.459819755033;8342.207490288412;4406.228909940747;4423.800928958204;4435.911281774346;4444.2222611042935;4554.931676024862;1795.6678014567347;1849.836779107714;1855.8886466812035;1871.6724573199908;1887.9339223127124;668.8528523724773;824.789678631945;853.1340689200466;888.7822483491457;927.188709878856;16572.917135414245;18307.72538411925;19672.147468924537;20360.083796229206;20747.896260518603;787.5117427195175;879.7624641346929;887.1439061305742;915.7802127489355;920.7836158502746;9211.631534738535;10335.638252661121;10909.727256818644;10939.398333091756;10948.671539853816;564936.7012659723;573135;574291;586044;588432;77271;122551;123578;135753.32123339517;137776;954.4559601027348;992.5384696116196;1011.812814629296;1018.6027449294781;1018.7962407518378;17902.10208414302;18341.991190484652;18736.437906862815;18844;19143.85136891791;54594;54631.72684136554;55489;55824.72087639536;55978.72010639921;74411.62794186211;79039.60480197563;79956;82710.58644706738;85038.57480712557;7586.658600362886;7863;7863.488873223222;7994.161342891051;8000.95999520022;10065.194784417376;12150.270983741004;12196.024318054357;12508.124431289722;12759.87240127624;9620;10638.73403164927;10753.924607539184;11007;11152.996230339308;10823;10832.67501974942;11055.281406708538;11152;11219.382933938712;11226.775464490827;11258.437078146224;11485.942570287096;11531.308121512737;11577.305361678325;7743.7676869694;7769.7669069928;7962.6814927404575;8007.91992080095;8167.754967350989;8168.346532277523;9793;10918.836217456903;11106.444677766242;11196.104311655212 09:42:57 INFO - PROCESS | 2593 | |3;mootools.html;1413.6324555615538;1532.6857994111208;1718.3040868448275;1840.7330937014126;1897.5256185953504;7927.762167134995;8222.301104406133;8411;8981.236594889435;9031.503267320302;8083.595820208995;8194.221548952855;8515.276201522862;8730.432521886072;8743.606537705802;2360.70491188601;2399.9760002400017;2401.6157414813633;2402.039184326266;2403.987980060106;2118.639831228687;2356.3402247370764;2357.0571771291534;2406.5307265083316;2409.204962362416;2161.643328850738;2308.595995700754;2378.203301893859;2382.3924899150693;2390.175389490623;2341.262502311766;2350.4241460842204;2350.7884290413945;2352.5530149271754;2356.0811283599323;2337.4506990857135;2380.297812145404;2383.332666853284;2385.797207237387;2387.9164229252065;8853.645854165825;8942.418742781698;8945.01604823469;8951.641934322619;8961.238294744955;4369.71596846204;5294.311739473882;5314.813981510629;5367.570594352482;5381.0583147948955;3227.9677203227902;3327.3844338797157;3464.9480257796154;3465.636108208627;3478.043538027063;1073.7798751256053;1076.4563895232936;1077.6874706335154;1088.4612117002134;1090.5092708281304;11460.94269528647;11613.303201807834;11636.12729045325;11676.182667213214;11687;4523.932141017887;4527.683062185682;4561.361409402686;4770.713757174581;4772.475027746945;9879.407235565888;9883.30816842814;9890.950545247371;9897.960714124913;9902.752431189278;5334.093204155303;5345.251664766935;5348.224507446398;5353.946460535422;5355.0896347621;4751;4862.124817532807;4886.047220791911;4895.951040489549;4906.8773280668265;3857.8842634721004;3889.2221555688975;3941.0935484838647;3942.8817135485406;3969.7816620086173;1099.5217080569978;1252.1798222164498;1260.4832018872219;1261.1802328486374;1276.9808452873215;614.6066517428849;760.7082797507215;888.8933328000682;923.1460898668705;944.636028576229;5188.962207558503;5595.916061259084;5702.885942281131;5741.312356865462;5964.194833697482;849.8895143631288;894.7584152278979;936.1621348892703;942.9104235097655;943.0804965158915;3795.35478968576;3916.981584787976;4007;4055.3916912463364;4079.3880917862557;10154.492275386201;10671;10688.358698478116;10698.62554810578;10720.195985300978;10650.733731656615;10672.466376681135;10679.305845120043;10684.465776711133;10689.518971646294;6837.62393068386;8131.425375313699;8141.674333026731;8226.876596851052;8241.8351632967;4955.876103097307;5659.434056594308;5745.9425405745405;5797.971010144923;5872.55955803325;9876.40741555509;9886.901130988597;9934.354266972623;9950.203983681144;9992;9632.518374081128;10062.39625622465;10069.748256293651;10253.74365640865;10264.692059238234;7435.479516434014;7622.2758837910305;7638.045244344457;7639.159692433939;7665.348445382205;8064.153263907206;8158.7144449946345;8161.673533058611;8190.180981901762;8217.95891020541;7377.372923301579;7468.3278504934815;7474.14047384536;7483.88774168377;7526.774196774106;7223.674934627849;7310.195878453473;7342.816429589089;7356.117265928123;7367.226441223809;6005.189299444519;6879.965600171967;6912.101426814482;6970.79087627372;6974.37230649244;4860.343853579863;4869.537393947569;4925.581325587222;4999.77501012448;5122.871928201825;4310.245707001325;4963.354763880672;5012.248162775612;5015.84952451427;5189.584833213258 09:42:57 INFO - PROCESS | 2593 | |4;prototype.html;293.6945576600336;299.21903831001083;307.58783230471164;307.7968540763096;311.2172885193738;4437.5784300491405;5334.7996700742315;5340.893182136355;5350.438203988583;5369.040300290395;4716.009637976029;4992.750362481879;5165.096108181072;5166.870828229296;5314.76083576239;3198.4882418812995;3865.9806700966456;3989.5412027616762;3994.2211268802666;4037.878863634081;3735.439684047401;3752.84988600457;4040.272750904836;4046.5953404659444;4057.736247143926;3899.337112690835;4007.9739796453637;4024;4037.6675697019914;4079.4084857695616;4039.192161567669;4047.5952404759582;4061.4720086388875;4069.511658600972;4128.2156390285745;2951.38021015587;3539.80531070791;3543.5924868640177;3556.324298383299;3566.732495062881;4171.561985991458;4956.801727930879;5076.898462030738;5105.183170692709;5163.967206558703;4564.931526027112;4590.553975497707;4606.930896036562;4612.377329060568;4661.230896902007;5483.8080667176855;5491.917621235684;5492.940147444709;5510.8071217507195;5520.66875987442;3494.5107684924133;3795.0891785971444;3868.9226215475533;3879.476270703447;3910.7513179921375;5475.453913930041;5750.88498230033;5768.596198266123;5807.548112971799;5843.766249350021;3302.735781137503;3941.5664276929515;3966.6033396660105;3970.2456533259165;3973.602639736061;3550.833991265326;3583.4087375582735;3867.9806600966817;3997.340438827619;4064.8170832312617;3807.7144214184045;4263.2752432086;4558.726476411426;4666.32338310943;4669.252919532926;5597.636153650081;5603.943960560424;5607.271054762937;5610.353270374208;5655.434456554394;1081.9513121909533;1147.9610952088374;1322.451182759144;1347.1109068015062;1357.1314358810369;834.1950018232435;860.5654144657034;870.4907629037084;894.1952242981368;912.6440688131634;4179.551869707503;4355.978220108879;5166.896662066738;5205.6616319939085;5292.708901010481;4929.580985616191;5123.849681757788;5247.344081989751;5730.541556675456;5741.45456181662;4998.5751211146735;5148.742562871917;5196.298499702491;5214.661047031931;5262.55268302191;2538.90826944412;2684.9126103927942;2986.716261955111;3004.911586447398;3009.4870782896087;5583.246261754693;5701.144828275709;5779.739911703899;5879.2944846618675;5884.735186916599;4779.713217206979;5094.77073531692;5774.82675519735;5825.242718446575;5836.9493491172;5261.947610477843;5294.391145018218;5347.41178470368;5793.681347525927;5813.709314534341;5342.6260161788305;5477.3427188737605;5491.4026251146925;5609.411011843861;5651.350094738993;4855.344528488608;5569.832905012856;5580;5596.664200148004;5646.181303710857;5591.086239632355;5747.195392645117;5761.827145185651;5779.133130030529;5804.506616937608;3121.3757248550833;3697.393337696971;3794.5256842894637;3884.495015648061;3932.233214523255;5201.323827902348;5374.865628359166;5673.432656734293;5707.229524014205;5756.798512052058;4279.7218180818145;4913.77887995034;5427.4301198373605;5647.435256474319;5751.654900705971;4262.893427664209;4638.5593368629925;4939.468280179689;5271.89456210866;5703.287089113861;5226.465164395341;5476.371341756059;5500.119980803052;5506.834794956158;5508.889822203613;5346.572274217975;5504.697241651587;5593.161025846156;5605.495505404533;5692.800751973661;5476.946145195551;5479.369872464717;5492.148716948879;5502.889942201214;5569.415211402745 09:42:57 INFO - PROCESS | 2593 | |5;yui.html;672.7813460625295;698.5180225644307;725.1189804387672;779.9181085985967;802.0255389701514;15146.924265378671;15774.0535567866;15777.92111039446;15806.68386632268;15832.208389580519;14074.577762667112;14150.292485375716;14181.149131052143;14191.92904035481;14196.574102776933;652.1652285075112;672.0658284983851;687.7077242172082;830.6552780596076;858.0561382479254;835.9874601880977;848.995755021224;850.1328644782318;851.2381418630322;853.4879072556479;897.2956229359954;900.3517467423445;900.8423525882944;901.9323550733722;903.6837107012526;685.8662560800645;702.6170736948376;705.8870580707113;899.5007770687266;902.6118768929391;525.5401523666792;650.1223348479562;669.6082791566939;670.7719375412335;673.0005941177347;14368.425262989467;14368.71262574753;14388.784168237486;14390.568282951528;14410.567682969528;13090.934545327213;13324.52777722602;13564.93217533916;13635.863641363658;13966.86033139676;12161.756764864742;12378.319192444413;12413.689657758538;12422.81365779514;12832.294223817686;4427.291633338683;4564.406627138451;4592.47186573545;4612.930806037879;4722.268048452495;107446;108816.36775448374;109232;115300;115952.84047159589;10342.37945723259;10816.729581760519;10918.290311129751;11075.889241107487;11152.219344645959;20183.293584724463;20709.68935465969;20729.378118656463;20734.58530829375;20795.68806467904;7659.961700191463;7708.344790692742;7713.691452341844;7833.195517946476;7847.803804904809;4323.135372925428;4338.956610433918;4385.736855788663;4395.230834603932;4508.819647214074;2856.428714257157;3057.373238486105;3373.645767194458;3446.1556918555116;3449.8275086245585;600.0069884341422;632.705791806812;633.3033662970695;646.6540400885503;665.351282499559;485.97813098410654;502.7209898506364;505.5500604462032;595.3778301674761;613.2610204703321;7605.809854753565;8347.248747612744;8599.95700021496;8834.602442890086;9091.181793638605;554.5425024354923;581.9010768169337;614.6158650843223;622.1600838867583;623.5915475363644;4150.667946564267;4195.454590903224;4279.465066866642;4292.7424354538825;4327.653787696977;85333.57333213418;85405;86135.569322153;125785;157892;94621.58067628992;132996;135486;136472;138539.30730346285;110721.44639276912;111371;114988.27517587243;115905;115907;7408.555486670817;8140.308073813792;8254.0507841599;8275.83448331042;8514.361422893184;49676.54204953333;62391;62711.6864415675;63572.68213658902;64706.522491863114;91852;94261.3670222261;97388;97743;99645.50531742175;9442.339036267394;11019.724506887393;11147.83278250843;11164;11459.19785614999;11704.765904682028;11716.648500544998;12112.515499379926;12146.271223726604;12300.50797968107;10750.73123171927;11115.777684446104;11280.09759219244;11445.885541144482;12035.398230088636;10955.287906286067;11028.393438360647;11355;11497.310161390344;11537.365444900612;10711.464426778786;10881.78236435251;11182.105431565293;11306.717332066764;11419.657410277705;9244.214241789523;9409.811803764023;9570.721350833843;9600.367248975721;9695.563699633674;8140.511569305861;8311.584420779058;9573.425594464354;9668.371555848847;9671.129598336183 09:42:57 INFO - PROCESS | 2593 | __end_tp_report 09:42:57 INFO - PROCESS | 2593 | __start_cc_report 09:42:57 INFO - PROCESS | 2593 | _x_x_mozilla_cycle_collect,1265 09:42:57 INFO - PROCESS | 2593 | __end_cc_report 09:42:57 INFO - PROCESS | 2593 | __startTimestamp1472316177262__endTimestamp 09:42:57 INFO - PROCESS | 2593 | 09:42:57 INFO - PROCESS | 2593 | ------- Summary: start ------- 09:42:57 INFO - PROCESS | 2593 | Number of tests: 6 09:42:57 INFO - PROCESS | 2593 | 09:42:57 INFO - PROCESS | 2593 | [#0] dojo.html Cycles:165 Average:5767.83 Median:5952.32 stddev:631.32 (10.6%) stddev-sans-first:602.89 09:42:57 INFO - PROCESS | 2593 | Values: 3301.8 3351.8 3352.9 3364.7 3407.9 5620.9 5681.7 5712.3 5780.5 5785.5 5708.1 5721.0 5817.3 5817.4 5850.9 5170.1 5175.5 5243.4 5248.1 5327.6 5364.1 5367.2 5370.6 5380.5 5415.6 5207.2 5238.7 5259.6 5270.9 5292.3 5289.2 5295.2 5298.4 5300.6 5305.6 5181.4 5182.4 5209.8 5210.1 5294.9 5680.2 5689.4 5749.6 5766.8 5780.6 6256.4 6289.8 6296.4 6300.9 6309.3 5856.0 6158.6 6222.6 6297.5 6327.6 5855.9 5856.2 5860.1 5862.4 5864.3 6174.7 6264.5 6272.9 6282.7 6301.2 4884.3 6115.9 6128.7 6141.1 6181.8 6269.4 6306.3 6309.1 6310.2 6320.1 6038.7 6254.2 6264.3 6270.2 6270.2 6134.2 6188.1 6246.8 6248.6 6249.9 4997.5 5009.6 5011.4 5025.2 5047.0 4479.2 4482.1 4490.4 4506.2 4532.4 5317.4 5395.8 5727.9 5744.9 5791.8 6277.0 6297.6 6316.0 6332.2 6333.2 5729.7 5755.3 5764.8 5795.0 5822.2 5367.4 5396.4 5432.2 5475.7 5491.9 6206.3 6242.8 6251.7 6264.4 6277.5 6318.9 6328.4 6329.7 6337.6 6353.9 6206.3 6215.9 6261.0 6262.8 6270.1 6103.5 6116.5 6226.8 6229.8 6260.6 6208.2 6238.3 6263.9 6335.6 6339.0 6071.2 6104.5 6132.8 6139.4 6151.7 5947.6 5952.3 6134.0 6142.7 6159.0 5834.3 5928.3 5949.8 5959.4 6025.7 6146.8 6149.3 6154.8 6162.1 6172.5 5903.7 5958.7 5959.5 5983.5 5990.9 09:42:57 INFO - PROCESS | 2593 | 09:42:57 INFO - PROCESS | 2593 | [#1] ext.html Cycles:180 Average:69111.78 Median:3406.39 stddev:151242.72 (4440.0%) stddev-sans-first:151579.26 09:42:57 INFO - PROCESS | 2593 | Values: 500.3 516.9 522.3 522.4 528.9 30224.8 30831.0 39911.0 41171.8 41230.8 42093.6 42572.9 42586.1 42616.8 42632.4 8198.6 8569.9 9643.8 9794.4 9831.4 8665.2 8910.9 11856.7 12100.3 12139.6 12039.9 12058.5 12141.9 12145.4 12159.0 12078.3 12157.2 12158.1 12160.3 12169.9 11784.6 11882.3 11939.9 11964.8 11965.8 39807.8 40248.2 40263.2 40307.4 40436.8 3405.2 3407.5 3594.5 3637.5 3858.8 30481.5 30622.0 30855.1 31050.4 31626.1 3167.6 3169.9 3200.6 3210.7 3298.7 477363.0 481629.0 483556.0 484300.0 488995.0 58106.0 58416.5 58457.7 58464.8 58502.0 33422.2 60288.1 60362.4 60398.8 60465.7 15621.0 16325.2 16329.5 16335.3 16422.2 11280.7 11555.3 12539.7 12636.7 12660.9 18405.9 18562.8 18762.7 19111.6 19175.9 6491.4 7616.8 7621.7 7763.8 7816.3 878.7 906.9 999.5 1015.8 1080.7 24272.0 28633.0 28702.7 29489.8 30084.8 1029.3 1041.6 1075.9 1091.0 1115.1 13004.0 14168.3 14884.9 15381.8 15460.1 585982.0 640836.0 682628.0 683299.0 684375.0 56040.7 56662.2 56671.9 56680.7 56815.0 1212.6 1331.7 1361.7 1372.6 1389.1 29588.9 30542.0 30612.5 31294.7 31389.4 48271.0 48684.5 48689.0 48788.0 48814.0 543339.0 544051.0 544607.0 544750.0 545140.0 21487.8 26779.0 26876.1 27417.0 27573.9 45679.0 45761.8 46637.5 47081.0 47252.8 31727.5 34777.7 37795.6 38785.8 39134.4 29302.7 37927.0 38801.0 38830.2 39476.8 36656.1 37126.7 37771.4 37783.4 37869.8 32700.0 32761.5 33231.0 33338.0 33756.3 23943.0 24702.4 24718.6 25097.1 25633.4 09:42:57 INFO - PROCESS | 2593 | 09:42:57 INFO - PROCESS | 2593 | [#2] jquery.html Cycles:180 Average:33904.82 Median:327.05 stddev:96614.85 (29541.3%) stddev-sans-first:96852.94 09:42:57 INFO - PROCESS | 2593 | Values: 307.5 346.6 370.2 372.4 398.9 15725.7 17654.5 20380.4 20515.5 20610.1 20654.7 21047.8 21278.8 21395.0 21507.7 5667.8 5748.1 5820.2 6014.4 6017.2 4079.1 5784.7 5877.0 5890.7 5894.1 6163.4 6454.2 6481.0 6497.1 6536.3 5707.7 6004.6 6059.3 6458.7 6476.2 5907.9 5911.4 5911.5 5912.3 5920.3 13135.8 13290.3 13444.3 13482.2 13576.5 2220.5 2224.7 2228.8 2393.7 2874.9 9461.3 9745.3 10033.0 10341.6 13693.5 1709.7 1728.2 1782.6 1824.4 1939.1 106119.5 122207.0 123421.4 124833.0 125458.0 9231.4 10695.2 11027.9 11041.2 11216.6 14173.9 14204.1 14204.3 14209.0 14210.8 7967.2 8311.9 8313.3 8389.1 8419.6 6640.8 8174.7 8184.7 8328.5 8342.2 4406.2 4423.8 4435.9 4444.2 4554.9 1795.7 1849.8 1855.9 1871.7 1887.9 668.9 824.8 853.1 888.8 927.2 16572.9 18307.7 19672.1 20360.1 20747.9 787.5 879.8 887.1 915.8 920.8 9211.6 10335.6 10909.7 10939.4 10948.7 564936.7 573135.0 574291.0 586044.0 588432.0 77271.0 122551.0 123578.0 135753.3 137776.0 954.5 992.5 1011.8 1018.6 1018.8 17902.1 18342.0 18736.4 18844.0 19143.9 54594.0 54631.7 55489.0 55824.7 55978.7 74411.6 79039.6 79956.0 82710.6 85038.6 7586.7 7863.0 7863.5 7994.2 8001.0 10065.2 12150.3 12196.0 12508.1 12759.9 9620.0 10638.7 10753.9 11007.0 11153.0 10823.0 10832.7 11055.3 11152.0 11219.4 11226.8 11258.4 11485.9 11531.3 11577.3 7743.8 7769.8 7962.7 8007.9 8167.8 8168.3 9793.0 10918.8 11106.4 11196.1 09:42:57 INFO - PROCESS | 2593 | 09:42:57 INFO - PROCESS | 2593 | [#3] mootools.html Cycles:180 Average:5659.21 Median:4981.56 stddev:3178.79 (63.8%) stddev-sans-first:3171.69 09:42:57 INFO - PROCESS | 2593 | Values: 1413.6 1532.7 1718.3 1840.7 1897.5 7927.8 8222.3 8411.0 8981.2 9031.5 8083.6 8194.2 8515.3 8730.4 8743.6 2360.7 2400.0 2401.6 2402.0 2404.0 2118.6 2356.3 2357.1 2406.5 2409.2 2161.6 2308.6 2378.2 2382.4 2390.2 2341.3 2350.4 2350.8 2352.6 2356.1 2337.5 2380.3 2383.3 2385.8 2387.9 8853.6 8942.4 8945.0 8951.6 8961.2 4369.7 5294.3 5314.8 5367.6 5381.1 3228.0 3327.4 3464.9 3465.6 3478.0 1073.8 1076.5 1077.7 1088.5 1090.5 11460.9 11613.3 11636.1 11676.2 11687.0 4523.9 4527.7 4561.4 4770.7 4772.5 9879.4 9883.3 9891.0 9898.0 9902.8 5334.1 5345.3 5348.2 5353.9 5355.1 4751.0 4862.1 4886.0 4896.0 4906.9 3857.9 3889.2 3941.1 3942.9 3969.8 1099.5 1252.2 1260.5 1261.2 1277.0 614.6 760.7 888.9 923.1 944.6 5189.0 5595.9 5702.9 5741.3 5964.2 849.9 894.8 936.2 942.9 943.1 3795.4 3917.0 4007.0 4055.4 4079.4 10154.5 10671.0 10688.4 10698.6 10720.2 10650.7 10672.5 10679.3 10684.5 10689.5 6837.6 8131.4 8141.7 8226.9 8241.8 4955.9 5659.4 5745.9 5798.0 5872.6 9876.4 9886.9 9934.4 9950.2 9992.0 9632.5 10062.4 10069.7 10253.7 10264.7 7435.5 7622.3 7638.0 7639.2 7665.3 8064.2 8158.7 8161.7 8190.2 8218.0 7377.4 7468.3 7474.1 7483.9 7526.8 7223.7 7310.2 7342.8 7356.1 7367.2 6005.2 6880.0 6912.1 6970.8 6974.4 4860.3 4869.5 4925.6 4999.8 5122.9 4310.2 4963.4 5012.2 5015.8 5189.6 09:42:57 INFO - PROCESS | 2593 | 09:42:57 INFO - PROCESS | 2593 | [#4] prototype.html Cycles:180 Average:4489.49 Median:5156.35 stddev:1383.24 (26.8%) stddev-sans-first:1350.79 09:42:57 INFO - PROCESS | 2593 | Values: 293.7 299.2 307.6 307.8 311.2 4437.6 5334.8 5340.9 5350.4 5369.0 4716.0 4992.8 5165.1 5166.9 5314.8 3198.5 3866.0 3989.5 3994.2 4037.9 3735.4 3752.8 4040.3 4046.6 4057.7 3899.3 4008.0 4024.0 4037.7 4079.4 4039.2 4047.6 4061.5 4069.5 4128.2 2951.4 3539.8 3543.6 3556.3 3566.7 4171.6 4956.8 5076.9 5105.2 5164.0 4564.9 4590.6 4606.9 4612.4 4661.2 5483.8 5491.9 5492.9 5510.8 5520.7 3494.5 3795.1 3868.9 3879.5 3910.8 5475.5 5750.9 5768.6 5807.5 5843.8 3302.7 3941.6 3966.6 3970.2 3973.6 3550.8 3583.4 3868.0 3997.3 4064.8 3807.7 4263.3 4558.7 4666.3 4669.3 5597.6 5603.9 5607.3 5610.4 5655.4 1082.0 1148.0 1322.5 1347.1 1357.1 834.2 860.6 870.5 894.2 912.6 4179.6 4356.0 5166.9 5205.7 5292.7 4929.6 5123.8 5247.3 5730.5 5741.5 4998.6 5148.7 5196.3 5214.7 5262.6 2538.9 2684.9 2986.7 3004.9 3009.5 5583.2 5701.1 5779.7 5879.3 5884.7 4779.7 5094.8 5774.8 5825.2 5836.9 5261.9 5294.4 5347.4 5793.7 5813.7 5342.6 5477.3 5491.4 5609.4 5651.4 4855.3 5569.8 5580.0 5596.7 5646.2 5591.1 5747.2 5761.8 5779.1 5804.5 3121.4 3697.4 3794.5 3884.5 3932.2 5201.3 5374.9 5673.4 5707.2 5756.8 4279.7 4913.8 5427.4 5647.4 5751.7 4262.9 4638.6 4939.5 5271.9 5703.3 5226.5 5476.4 5500.1 5506.8 5508.9 5346.6 5504.7 5593.2 5605.5 5692.8 5476.9 5479.4 5492.1 5502.9 5569.4 09:42:57 INFO - PROCESS | 2593 | 09:42:57 INFO - PROCESS | 2593 | [#5] yui.html Cycles:180 Average:23379.93 Median:4536.61 stddev:37513.11 (826.9%) stddev-sans-first:37579.60 09:42:57 INFO - PROCESS | 2593 | Values: 672.8 698.5 725.1 779.9 802.0 15146.9 15774.1 15777.9 15806.7 15832.2 14074.6 14150.3 14181.1 14191.9 14196.6 652.2 672.1 687.7 830.7 858.1 836.0 849.0 850.1 851.2 853.5 897.3 900.4 900.8 901.9 903.7 685.9 702.6 705.9 899.5 902.6 525.5 650.1 669.6 670.8 673.0 14368.4 14368.7 14388.8 14390.6 14410.6 13090.9 13324.5 13564.9 13635.9 13966.9 12161.8 12378.3 12413.7 12422.8 12832.3 4427.3 4564.4 4592.5 4612.9 4722.3 107446.0 108816.4 109232.0 115300.0 115952.8 10342.4 10816.7 10918.3 11075.9 11152.2 20183.3 20709.7 20729.4 20734.6 20795.7 7660.0 7708.3 7713.7 7833.2 7847.8 4323.1 4339.0 4385.7 4395.2 4508.8 2856.4 3057.4 3373.6 3446.2 3449.8 600.0 632.7 633.3 646.7 665.4 486.0 502.7 505.6 595.4 613.3 7605.8 8347.2 8600.0 8834.6 9091.2 554.5 581.9 614.6 622.2 623.6 4150.7 4195.5 4279.5 4292.7 4327.7 85333.6 85405.0 86135.6 125785.0 157892.0 94621.6 132996.0 135486.0 136472.0 138539.3 110721.4 111371.0 114988.3 115905.0 115907.0 7408.6 8140.3 8254.1 8275.8 8514.4 49676.5 62391.0 62711.7 63572.7 64706.5 91852.0 94261.4 97388.0 97743.0 99645.5 9442.3 11019.7 11147.8 11164.0 11459.2 11704.8 11716.6 12112.5 12146.3 12300.5 10750.7 11115.8 11280.1 11445.9 12035.4 10955.3 11028.4 11355.0 11497.3 11537.4 10711.5 10881.8 11182.1 11306.7 11419.7 9244.2 9409.8 9570.7 9600.4 9695.6 8140.5 8311.6 9573.4 9668.4 9671.1 09:42:57 INFO - PROCESS | 2593 | -------- Summary: end -------- 09:42:57 INFO - PROCESS | 2593 | 09:42:57 INFO - TEST-INFO | 2593: exit 0 09:42:57 INFO - TEST-OK | dromaeo_css | took 1117665ms 09:42:57 INFO - TEST-START | kraken 09:42:57 INFO - operating with platform_type : mac_ 09:42:57 INFO - Initialising browser for kraken test... 09:42:57 INFO - TEST-INFO | started process 2596 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpj9H2Ec/profile http://localhost:55266/getInfo.html) 09:43:00 INFO - PROCESS | 2596 | __metrics Screen width/height:1600/1200 09:43:00 INFO - PROCESS | 2596 | colorDepth:24 09:43:00 INFO - PROCESS | 2596 | Browser inner width/height: 1024/689 09:43:00 INFO - PROCESS | 2596 | __metrics 09:43:00 INFO - PROCESS | 2596 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:43:00 INFO - PROCESS | 2596 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:43:00 INFO - TEST-INFO | 2596: exit 0 09:43:00 INFO - Browser initialized. 09:43:00 INFO - Running cycle 1/1 for kraken test... 09:43:00 INFO - TEST-INFO | started process 2597 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpj9H2Ec/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:43:12 INFO - PROCESS | 2597 | RSS: Main: 233193472 09:43:12 INFO - PROCESS | 2597 | 09:43:15 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55266/tests/kraken/driver.html?testName=audio-beat-detection) 09:43:16 INFO - PROCESS | 2597 | RSS: Main: 290967552 09:43:16 INFO - PROCESS | 2597 | 09:43:19 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55266/tests/kraken/driver.html?testName=audio-dft) 09:43:19 INFO - PROCESS | 2597 | RSS: Main: 286507008 09:43:19 INFO - PROCESS | 2597 | 09:43:23 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55266/tests/kraken/driver.html?testName=audio-fft) 09:43:23 INFO - PROCESS | 2597 | RSS: Main: 378372096 09:43:23 INFO - PROCESS | 2597 | 09:43:25 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55266/tests/kraken/driver.html?testName=audio-oscillator) 09:43:26 INFO - PROCESS | 2597 | RSS: Main: 279580672 09:43:26 INFO - PROCESS | 2597 | 09:43:28 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:43:29 INFO - PROCESS | 2597 | RSS: Main: 283017216 09:43:29 INFO - PROCESS | 2597 | 09:43:31 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55266/tests/kraken/driver.html?testName=imaging-darkroom) 09:43:32 INFO - PROCESS | 2597 | RSS: Main: 321708032 09:43:32 INFO - PROCESS | 2597 | 09:43:35 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55266/tests/kraken/driver.html?testName=imaging-desaturate) 09:43:36 INFO - PROCESS | 2597 | RSS: Main: 323387392 09:43:36 INFO - PROCESS | 2597 | 09:43:39 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55266/tests/kraken/driver.html?testName=json-parse-financial) 09:43:39 INFO - PROCESS | 2597 | RSS: Main: 320454656 09:43:39 INFO - PROCESS | 2597 | 09:43:41 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:43:41 INFO - PROCESS | 2597 | RSS: Main: 296288256 09:43:41 INFO - PROCESS | 2597 | 09:43:43 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55266/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:43:44 INFO - PROCESS | 2597 | RSS: Main: 300826624 09:43:44 INFO - PROCESS | 2597 | 09:43:46 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:43:47 INFO - PROCESS | 2597 | RSS: Main: 305778688 09:43:47 INFO - PROCESS | 2597 | 09:43:50 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:43:51 INFO - PROCESS | 2597 | RSS: Main: 320622592 09:43:51 INFO - PROCESS | 2597 | 09:43:53 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:43:54 INFO - PROCESS | 2597 | RSS: Main: 317079552 09:43:54 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:55266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55266/tests/kraken/driver.html?testName=ai-astar) 09:43:56 INFO - PROCESS | 2597 | RSS: Main: 361029632 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | __start_tp_report 09:43:56 INFO - PROCESS | 2597 | _x_x_mozilla_page_load 09:43:56 INFO - PROCESS | 2597 | _x_x_mozilla_page_load_details 09:43:56 INFO - PROCESS | 2597 | |i|pagename|runs| 09:43:56 INFO - PROCESS | 2597 | |0;ai-astar;112;114;134;114;114;113;114;113;117;161 09:43:56 INFO - PROCESS | 2597 | |1;audio-beat-detection;150;113;140;199;139;132;130;106;163;107 09:43:56 INFO - PROCESS | 2597 | |2;audio-dft;186;186;185;185;187;184;183;184;182;185 09:43:56 INFO - PROCESS | 2597 | |3;audio-fft;79;77;79;160;59;58;57;70;142;58 09:43:56 INFO - PROCESS | 2597 | |4;audio-oscillator;84;84;139;71;73;90;115;68;70;114 09:43:56 INFO - PROCESS | 2597 | |5;imaging-gaussian-blur;89;87;89;86;88;86;90;86;89;86 09:43:56 INFO - PROCESS | 2597 | |6;imaging-darkroom;98;108;161;131;98;99;98;99;98;98 09:43:56 INFO - PROCESS | 2597 | |7;imaging-desaturate;85;86;84;87;90;84;84;85;86;87 09:43:56 INFO - PROCESS | 2597 | |8;json-parse-financial;54;55;54;55;54;55;54;54;55;54 09:43:56 INFO - PROCESS | 2597 | |9;json-stringify-tinderbox;67;73;73;76;73;73;72;71;73;72 09:43:56 INFO - PROCESS | 2597 | |10;stanford-crypto-aes;85;85;88;87;88;85;103;105;107;77 09:43:56 INFO - PROCESS | 2597 | |11;stanford-crypto-ccm;141;137;140;152;153;141;138;141;183;138 09:43:56 INFO - PROCESS | 2597 | |12;stanford-crypto-pbkdf2;172;170;169;171;171;171;169;171;169;179 09:43:56 INFO - PROCESS | 2597 | |13;stanford-crypto-sha256-iterative;68;67;71;68;68;67;68;68;68;68 09:43:56 INFO - PROCESS | 2597 | __end_tp_report 09:43:56 INFO - PROCESS | 2597 | __start_cc_report 09:43:56 INFO - PROCESS | 2597 | _x_x_mozilla_cycle_collect,2186 09:43:56 INFO - PROCESS | 2597 | __end_cc_report 09:43:56 INFO - PROCESS | 2597 | __startTimestamp1472316236616__endTimestamp 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | ------- Summary: start ------- 09:43:56 INFO - PROCESS | 2597 | Number of tests: 14 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#0] ai-astar Cycles:10 Average:120.60 Median:114.00 stddev:15.59 (13.7%) stddev-sans-first:16.23 09:43:56 INFO - PROCESS | 2597 | Values: 112.0 114.0 134.0 114.0 114.0 113.0 114.0 113.0 117.0 161.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:139.50 stddev:28.29 (20.3%) stddev-sans-first:29.67 09:43:56 INFO - PROCESS | 2597 | Values: 150.0 113.0 140.0 199.0 139.0 132.0 130.0 106.0 163.0 107.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:1.49 (0.8%) stddev-sans-first:1.51 09:43:56 INFO - PROCESS | 2597 | Values: 186.0 186.0 185.0 185.0 187.0 184.0 183.0 184.0 182.0 185.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#3] audio-fft Cycles:10 Average:83.90 Median:64.50 stddev:36.73 (56.9%) stddev-sans-first:38.91 09:43:56 INFO - PROCESS | 2597 | Values: 79.0 77.0 79.0 160.0 59.0 58.0 57.0 70.0 142.0 58.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:72.00 stddev:24.04 (33.4%) stddev-sans-first:25.37 09:43:56 INFO - PROCESS | 2597 | Values: 84.0 84.0 139.0 71.0 73.0 90.0 115.0 68.0 70.0 114.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.50 stddev:1.58 (1.8%) stddev-sans-first:1.59 09:43:56 INFO - PROCESS | 2597 | Values: 89.0 87.0 89.0 86.0 88.0 86.0 90.0 86.0 89.0 86.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#6] imaging-darkroom Cycles:10 Average:108.80 Median:98.00 stddev:21.06 (21.5%) stddev-sans-first:21.98 09:43:56 INFO - PROCESS | 2597 | Values: 98.0 108.0 161.0 131.0 98.0 99.0 98.0 99.0 98.0 98.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.00 stddev:1.87 (2.2%) stddev-sans-first:1.96 09:43:56 INFO - PROCESS | 2597 | Values: 85.0 86.0 84.0 87.0 90.0 84.0 84.0 85.0 86.0 87.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 09:43:56 INFO - PROCESS | 2597 | Values: 54.0 55.0 54.0 55.0 54.0 55.0 54.0 54.0 55.0 54.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:73.00 stddev:2.26 (3.1%) stddev-sans-first:1.36 09:43:56 INFO - PROCESS | 2597 | Values: 67.0 73.0 73.0 76.0 73.0 73.0 72.0 71.0 73.0 72.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:85.00 stddev:10.19 (12.0%) stddev-sans-first:10.57 09:43:56 INFO - PROCESS | 2597 | Values: 85.0 85.0 88.0 87.0 88.0 85.0 103.0 105.0 107.0 77.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:141.00 stddev:14.03 (10.0%) stddev-sans-first:14.75 09:43:56 INFO - PROCESS | 2597 | Values: 141.0 137.0 140.0 152.0 153.0 141.0 138.0 141.0 183.0 138.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:171.00 stddev:2.94 (1.7%) stddev-sans-first:3.10 09:43:56 INFO - PROCESS | 2597 | Values: 172.0 170.0 169.0 171.0 171.0 171.0 169.0 171.0 169.0 179.0 09:43:56 INFO - PROCESS | 2597 | 09:43:56 INFO - PROCESS | 2597 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 09:43:56 INFO - PROCESS | 2597 | Values: 68.0 67.0 71.0 68.0 68.0 67.0 68.0 68.0 68.0 68.0 09:43:56 INFO - PROCESS | 2597 | -------- Summary: end -------- 09:43:56 INFO - PROCESS | 2597 | 09:43:57 INFO - TEST-INFO | 2597: exit 0 09:43:57 INFO - TEST-OK | kraken | took 59652ms 09:43:57 INFO - SUITE-END | took 1177s 09:43:57 INFO - Completed test suite (00:19:37) 09:43:57 INFO - javascript benchmark 09:43:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.7688761786685, 3351.7988920664775, 3352.9161770955725, 3364.6971772540464, 3407.914802129948, 5620.859478513029, 5681.715914204294, 5712.257406537155, 5780.450857168573, 5785.4503822136885, 5708.1437784332375, 5720.998825205593, 5817.301923769153, 5817.418258174181, 5850.853728656775, 5170.146925757245, 5175.456577059411, 5243.3970093439175, 5248.0553500369915, 5327.573794096483, 5364.1149210380245, 5367.221752845835, 5370.624056316059, 5380.488853558925, 5415.64798288112, 5207.218917162456, 5238.73806309683, 5259.605529585297, 5270.894582108374, 5292.259083728281, 5289.18017707256, 5295.232191332235, 5298.36419629643, 5300.575953923677, 5305.602079844027, 5181.404138524081, 5182.352205974254, 5209.765560549784, 5210.140326846104, 5294.947050529522, 5680.233168522279, 5689.402612725646, 5749.59752817303, 5766.798162064308, 5780.566457515703, 6256.374362563753, 6289.811305660837, 6296.370362963713, 6300.905486417708, 6309.337519560426, 5856.0, 6158.599691020114, 6222.5644204905675, 6297.464715499143, 6327.5570710050315, 5855.882882342372, 5856.15085812559, 5860.120981852671, 5862.355140934494, 5864.266966629171, 6174.722137503823, 6264.498840092781, 6272.937270627326, 6282.748690052438, 6301.243850737941, 4884.267359896044, 6115.938840611626, 6128.724207410677, 6141.056449558059, 6181.81454556364, 6269.405004559999, 6306.274778400542, 6309.053641953652, 6310.242770867525, 6320.051992201114, 6038.698065096728, 6254.186955695731, 6264.310925798159, 6270.184875966095, 6270.216222972129, 6134.222293197073, 6188.133661287423, 6246.75012999484, 6248.625082495065, 6249.875002500015, 4997.5002499749735, 5009.5741861941415, 5011.373578302712, 5025.246213067996, 5046.974765126224, 4479.216137175981, 4482.058767658763, 4490.438695163105, 4506.1663592235545, 4532.410786597788, 5317.41508434072, 5395.785948161632, 5727.8568035799435, 5744.913826292609, 5791.797287094932, 6276.964300890393, 6297.590656607306, 6316.0, 6332.240131184195, 6333.20834895638, 5729.656220626693, 5755.3381361143165, 5764.827055188351, 5795.043817770021, 5822.214001109881, 5367.35591728995, 5396.4333744957, 5432.239486471897, 5475.698836563947, 5491.917621235684, 6206.317305096345, 6242.750289988349, 6251.71867265965, 6264.404881536247, 6277.529185311211, 6318.905216421849, 6328.398802113792, 6329.651869147241, 6337.556371054166, 6353.8729225416155, 6206.317305096526, 6215.906761398493, 6261.0, 6262.780802672067, 6270.059491076375, 6103.549811064127, 6116.510679145568, 6226.813195604139, 6229.750809967551, 6260.561760676709, 6208.161898143873, 6238.282597501346, 6263.937360626518, 6335.619862808247, 6339.0, 6071.241094863142, 6104.542159338156, 6132.816015519542, 6139.355367686508, 6151.661658608819, 5947.6431414115295, 5952.315483719254, 6133.969330153321, 6142.692865356625, 6158.969205153946, 5834.299884013945, 5928.288605367384, 5949.791757288474, 5959.404059594006, 6025.66858822769, 6146.754129834757, 6149.323574406902, 6154.78458253959, 6162.106494558174, 6172.506199503939, 5903.675297858659, 5958.702064896651, 5959.463648271676, 5983.461488466059, 5990.880182396415], "value": 5727.683226545137, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.34205020398196, 516.9379674439067, 522.3183745212498, 522.3835873669071, 528.936527616686, 30224.84887575562, 30831.0, 39911.002224944394, 41171.764847054634, 41230.7938460308, 42093.57906420935, 42572.935676608104, 42586.14827703444, 42616.78691606545, 42632.36051459231, 8198.590070496466, 8569.914300856974, 9643.794525684289, 9794.383926652094, 9831.410115393099, 8665.17680820321, 8910.910890891073, 11856.703582410422, 12100.334481603508, 12139.57511487098, 12039.916407523322, 12058.457369418353, 12141.93929030358, 12145.39273036349, 12159.0, 12078.275303481776, 12157.209781364272, 12158.088143389192, 12160.270383776957, 12169.878301216962, 11784.646460606196, 11882.28706277626, 11939.880601193963, 11964.820527692005, 11965.760684786343, 39807.8009609953, 40248.19503609911, 40263.19473610511, 40307.395389069185, 40436.79781601103, 3405.2338223899674, 3407.539982102434, 3594.4967704521387, 3637.4725664778725, 3858.82635281413, 30481.542776858365, 30622.0, 30855.0743477696, 31050.378992420025, 31626.051218463483, 3167.619885613741, 3169.89942528734, 3200.647928727838, 3210.7270881974828, 3298.719608833252, 477363.0, 481629.0, 483556.0, 484299.99999999645, 488995.0, 58106.0, 58416.53958651068, 58457.70771146202, 58464.83070338569, 58502.0, 33422.164445889044, 60288.09567856486, 60362.39637603656, 60398.79202415927, 60465.69767151136, 15620.984635998624, 16325.183740812912, 16329.510114696579, 16335.346586136659, 16422.1788910554, 11280.661580152608, 11555.306681599131, 12539.749205015849, 12636.684082898, 12660.87339126597, 18405.907970460063, 18562.814371856377, 18762.718559221623, 19111.61776764457, 19175.94532300709, 6491.415772580491, 7616.771496855104, 7621.733239336597, 7763.844723105506, 7816.335611472975, 878.6529320918202, 906.9434109262635, 999.5352161245056, 1015.7511409704522, 1080.7082087836397, 24272.0, 28633.0, 28702.71297287042, 29489.820407183473, 30084.77965288315, 1029.3206483720708, 1041.5521325829966, 1075.913926885863, 1091.0398849012818, 1115.11905594581, 13003.993462516999, 14168.291585420893, 14884.925575372054, 15381.769273460906, 15460.149691766836, 585982.0, 640836.0, 682628.0, 683299.0, 684375.0, 56040.71979640157, 56662.15006774902, 56671.86656266852, 56680.71659641675, 56815.0, 1212.5877201751448, 1331.6670832291734, 1361.7412691588565, 1372.5813626843708, 1389.0623828915643, 29588.852055739582, 30542.0, 30612.540811887393, 31294.748210071342, 31389.372212556078, 48271.0, 48684.513154868, 48689.026219474705, 48788.0, 48814.0, 543339.0, 544051.0, 544607.0, 544750.0, 545140.0, 21487.78512214858, 26779.0, 26876.059337923078, 27417.0, 27573.86213069002, 45679.0, 45761.77119114383, 46637.53362466468, 47081.0, 47252.81867953191, 31727.52408713825, 34777.65222347744, 37795.62204377921, 38785.80607096946, 39134.412983804694, 29302.70697293, 37927.0, 38801.02997425087, 38830.2233955325, 39476.80261598674, 36656.0835979092, 37126.70056548008, 37771.43342849804, 37783.43324850075, 37869.81065094657, 32700.0, 32761.508577371835, 33231.0, 33338.0, 33756.32487350288, 23943.0, 24702.382440439134, 24718.629220561346, 25097.121600743885, 25633.359166021], "value": 20044.32999666728, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.5325505232047, 346.5667915106117, 370.2391585292225, 372.39857629927667, 398.93218152914, 15725.685486290282, 17654.47036588903, 20380.388588342335, 20515.487112822186, 20610.072546735413, 20654.69017964728, 21047.842368669757, 21278.787212127874, 21395.0, 21507.67738483924, 5667.801626943058, 5748.109043098327, 5820.243368362107, 6014.398560143973, 6017.247844019497, 4079.1433798902117, 5784.710764461781, 5877.0, 5890.705464726747, 5894.086416605412, 6163.352847950968, 6454.160959075337, 6480.9675951620175, 6497.122888410051, 6536.346365363449, 5707.743151558148, 6004.5796794224425, 6059.303180134297, 6458.709358078874, 6476.158099447065, 5907.881842363128, 5911.433470130419, 5911.527077833763, 5912.320083190445, 5920.319163296233, 13135.802962955564, 13290.3354832258, 13444.260565668885, 13482.19106853592, 13576.45694172232, 2220.4781876259067, 2224.73303203615, 2228.765979572138, 2393.676853624756, 2874.8993785217417, 9461.290403219786, 9745.31782775206, 10033.046860548237, 10341.586336546528, 13693.520726774515, 1709.7008023595945, 1728.2395745871902, 1782.6123535729043, 1824.3888297420247, 1939.0983192815268, 106119.4694026525, 122207.0, 123421.38289308586, 124833.0, 125458.0, 9231.35380523357, 10695.240617925105, 11027.944860275808, 11041.212601545536, 11216.55133794639, 14173.929130354281, 14204.147751134966, 14204.289785510684, 14209.005369624234, 14210.786838197435, 7967.2431119043595, 8311.875321870177, 8313.293370063491, 8389.06382766027, 8419.621117049748, 6640.800775976729, 8174.713885013996, 8184.6726130955285, 8328.459819755033, 8342.207490288412, 4406.228909940747, 4423.800928958204, 4435.911281774346, 4444.2222611042935, 4554.931676024862, 1795.6678014567347, 1849.836779107714, 1855.8886466812035, 1871.6724573199908, 1887.9339223127124, 668.8528523724773, 824.789678631945, 853.1340689200466, 888.7822483491457, 927.188709878856, 16572.917135414245, 18307.72538411925, 19672.147468924537, 20360.083796229206, 20747.896260518603, 787.5117427195175, 879.7624641346929, 887.1439061305742, 915.7802127489355, 920.7836158502746, 9211.631534738535, 10335.638252661121, 10909.727256818644, 10939.398333091756, 10948.671539853816, 564936.7012659723, 573135.0, 574291.0, 586044.0, 588432.0, 77271.0, 122551.0, 123578.0, 135753.32123339517, 137776.0, 954.4559601027348, 992.5384696116196, 1011.812814629296, 1018.6027449294781, 1018.7962407518378, 17902.10208414302, 18341.991190484652, 18736.437906862815, 18844.0, 19143.85136891791, 54594.0, 54631.72684136554, 55489.0, 55824.72087639536, 55978.72010639921, 74411.62794186211, 79039.60480197563, 79956.0, 82710.58644706738, 85038.57480712557, 7586.658600362886, 7863.0, 7863.488873223222, 7994.161342891051, 8000.95999520022, 10065.194784417376, 12150.270983741004, 12196.024318054357, 12508.124431289722, 12759.87240127624, 9620.0, 10638.73403164927, 10753.924607539184, 11007.0, 11152.996230339308, 10823.0, 10832.67501974942, 11055.281406708538, 11152.0, 11219.382933938712, 11226.775464490827, 11258.437078146224, 11485.942570287096, 11531.308121512737, 11577.305361678325, 7743.7676869694, 7769.7669069928, 7962.6814927404575, 8007.91992080095, 8167.754967350989, 8168.346532277523, 9793.0, 10918.836217456903, 11106.444677766242, 11196.104311655212], "value": 9230.05401369351, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.6324555615538, 1532.6857994111208, 1718.3040868448275, 1840.7330937014126, 1897.5256185953504, 7927.762167134995, 8222.301104406133, 8411.0, 8981.236594889435, 9031.503267320302, 8083.595820208995, 8194.221548952855, 8515.276201522862, 8730.432521886072, 8743.606537705802, 2360.70491188601, 2399.9760002400017, 2401.6157414813633, 2402.039184326266, 2403.987980060106, 2118.639831228687, 2356.3402247370764, 2357.0571771291534, 2406.5307265083316, 2409.204962362416, 2161.643328850738, 2308.595995700754, 2378.203301893859, 2382.3924899150693, 2390.175389490623, 2341.262502311766, 2350.4241460842204, 2350.7884290413945, 2352.5530149271754, 2356.0811283599323, 2337.4506990857135, 2380.297812145404, 2383.332666853284, 2385.797207237387, 2387.9164229252065, 8853.645854165825, 8942.418742781698, 8945.01604823469, 8951.641934322619, 8961.238294744955, 4369.71596846204, 5294.311739473882, 5314.813981510629, 5367.570594352482, 5381.0583147948955, 3227.9677203227902, 3327.3844338797157, 3464.9480257796154, 3465.636108208627, 3478.043538027063, 1073.7798751256053, 1076.4563895232936, 1077.6874706335154, 1088.4612117002134, 1090.5092708281304, 11460.94269528647, 11613.303201807834, 11636.12729045325, 11676.182667213214, 11687.0, 4523.932141017887, 4527.683062185682, 4561.361409402686, 4770.713757174581, 4772.475027746945, 9879.407235565888, 9883.30816842814, 9890.950545247371, 9897.960714124913, 9902.752431189278, 5334.093204155303, 5345.251664766935, 5348.224507446398, 5353.946460535422, 5355.0896347621, 4751.0, 4862.124817532807, 4886.047220791911, 4895.951040489549, 4906.8773280668265, 3857.8842634721004, 3889.2221555688975, 3941.0935484838647, 3942.8817135485406, 3969.7816620086173, 1099.5217080569978, 1252.1798222164498, 1260.4832018872219, 1261.1802328486374, 1276.9808452873215, 614.6066517428849, 760.7082797507215, 888.8933328000682, 923.1460898668705, 944.636028576229, 5188.962207558503, 5595.916061259084, 5702.885942281131, 5741.312356865462, 5964.194833697482, 849.8895143631288, 894.7584152278979, 936.1621348892703, 942.9104235097655, 943.0804965158915, 3795.35478968576, 3916.981584787976, 4007.0, 4055.3916912463364, 4079.3880917862557, 10154.492275386201, 10671.0, 10688.358698478116, 10698.62554810578, 10720.195985300978, 10650.733731656615, 10672.466376681135, 10679.305845120043, 10684.465776711133, 10689.518971646294, 6837.62393068386, 8131.425375313699, 8141.674333026731, 8226.876596851052, 8241.8351632967, 4955.876103097307, 5659.434056594308, 5745.9425405745405, 5797.971010144923, 5872.55955803325, 9876.40741555509, 9886.901130988597, 9934.354266972623, 9950.203983681144, 9992.0, 9632.518374081128, 10062.39625622465, 10069.748256293651, 10253.74365640865, 10264.692059238234, 7435.479516434014, 7622.2758837910305, 7638.045244344457, 7639.159692433939, 7665.348445382205, 8064.153263907206, 8158.7144449946345, 8161.673533058611, 8190.180981901762, 8217.95891020541, 7377.372923301579, 7468.3278504934815, 7474.14047384536, 7483.88774168377, 7526.774196774106, 7223.674934627849, 7310.195878453473, 7342.816429589089, 7356.117265928123, 7367.226441223809, 6005.189299444519, 6879.965600171967, 6912.101426814482, 6970.79087627372, 6974.37230649244, 4860.343853579863, 4869.537393947569, 4925.581325587222, 4999.77501012448, 5122.871928201825, 4310.245707001325, 4963.354763880672, 5012.248162775612, 5015.84952451427, 5189.584833213258], "value": 4543.4941143985125, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.6945576600336, 299.21903831001083, 307.58783230471164, 307.7968540763096, 311.2172885193738, 4437.5784300491405, 5334.7996700742315, 5340.893182136355, 5350.438203988583, 5369.040300290395, 4716.009637976029, 4992.750362481879, 5165.096108181072, 5166.870828229296, 5314.76083576239, 3198.4882418812995, 3865.9806700966456, 3989.5412027616762, 3994.2211268802666, 4037.878863634081, 3735.439684047401, 3752.84988600457, 4040.272750904836, 4046.5953404659444, 4057.736247143926, 3899.337112690835, 4007.9739796453637, 4024.0, 4037.6675697019914, 4079.4084857695616, 4039.192161567669, 4047.5952404759582, 4061.4720086388875, 4069.511658600972, 4128.2156390285745, 2951.38021015587, 3539.80531070791, 3543.5924868640177, 3556.324298383299, 3566.732495062881, 4171.561985991458, 4956.801727930879, 5076.898462030738, 5105.183170692709, 5163.967206558703, 4564.931526027112, 4590.553975497707, 4606.930896036562, 4612.377329060568, 4661.230896902007, 5483.8080667176855, 5491.917621235684, 5492.940147444709, 5510.8071217507195, 5520.66875987442, 3494.5107684924133, 3795.0891785971444, 3868.9226215475533, 3879.476270703447, 3910.7513179921375, 5475.453913930041, 5750.88498230033, 5768.596198266123, 5807.548112971799, 5843.766249350021, 3302.735781137503, 3941.5664276929515, 3966.6033396660105, 3970.2456533259165, 3973.602639736061, 3550.833991265326, 3583.4087375582735, 3867.9806600966817, 3997.340438827619, 4064.8170832312617, 3807.7144214184045, 4263.2752432086, 4558.726476411426, 4666.32338310943, 4669.252919532926, 5597.636153650081, 5603.943960560424, 5607.271054762937, 5610.353270374208, 5655.434456554394, 1081.9513121909533, 1147.9610952088374, 1322.451182759144, 1347.1109068015062, 1357.1314358810369, 834.1950018232435, 860.5654144657034, 870.4907629037084, 894.1952242981368, 912.6440688131634, 4179.551869707503, 4355.978220108879, 5166.896662066738, 5205.6616319939085, 5292.708901010481, 4929.580985616191, 5123.849681757788, 5247.344081989751, 5730.541556675456, 5741.45456181662, 4998.5751211146735, 5148.742562871917, 5196.298499702491, 5214.661047031931, 5262.55268302191, 2538.90826944412, 2684.9126103927942, 2986.716261955111, 3004.911586447398, 3009.4870782896087, 5583.246261754693, 5701.144828275709, 5779.739911703899, 5879.2944846618675, 5884.735186916599, 4779.713217206979, 5094.77073531692, 5774.82675519735, 5825.242718446575, 5836.9493491172, 5261.947610477843, 5294.391145018218, 5347.41178470368, 5793.681347525927, 5813.709314534341, 5342.6260161788305, 5477.3427188737605, 5491.4026251146925, 5609.411011843861, 5651.350094738993, 4855.344528488608, 5569.832905012856, 5580.0, 5596.664200148004, 5646.181303710857, 5591.086239632355, 5747.195392645117, 5761.827145185651, 5779.133130030529, 5804.506616937608, 3121.3757248550833, 3697.393337696971, 3794.5256842894637, 3884.495015648061, 3932.233214523255, 5201.323827902348, 5374.865628359166, 5673.432656734293, 5707.229524014205, 5756.798512052058, 4279.7218180818145, 4913.77887995034, 5427.4301198373605, 5647.435256474319, 5751.654900705971, 4262.893427664209, 4638.5593368629925, 4939.468280179689, 5271.89456210866, 5703.287089113861, 5226.465164395341, 5476.371341756059, 5500.119980803052, 5506.834794956158, 5508.889822203613, 5346.572274217975, 5504.697241651587, 5593.161025846156, 5605.495505404533, 5692.800751973661, 5476.946145195551, 5479.369872464717, 5492.148716948879, 5502.889942201214, 5569.415211402745], "value": 4045.62997745129, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.7813460625295, 698.5180225644307, 725.1189804387672, 779.9181085985967, 802.0255389701514, 15146.924265378671, 15774.0535567866, 15777.92111039446, 15806.68386632268, 15832.208389580519, 14074.577762667112, 14150.292485375716, 14181.149131052143, 14191.92904035481, 14196.574102776933, 652.1652285075112, 672.0658284983851, 687.7077242172082, 830.6552780596076, 858.0561382479254, 835.9874601880977, 848.995755021224, 850.1328644782318, 851.2381418630322, 853.4879072556479, 897.2956229359954, 900.3517467423445, 900.8423525882944, 901.9323550733722, 903.6837107012526, 685.8662560800645, 702.6170736948376, 705.8870580707113, 899.5007770687266, 902.6118768929391, 525.5401523666792, 650.1223348479562, 669.6082791566939, 670.7719375412335, 673.0005941177347, 14368.425262989467, 14368.71262574753, 14388.784168237486, 14390.568282951528, 14410.567682969528, 13090.934545327213, 13324.52777722602, 13564.93217533916, 13635.863641363658, 13966.86033139676, 12161.756764864742, 12378.319192444413, 12413.689657758538, 12422.81365779514, 12832.294223817686, 4427.291633338683, 4564.406627138451, 4592.47186573545, 4612.930806037879, 4722.268048452495, 107446.0, 108816.36775448374, 109232.0, 115300.0, 115952.84047159589, 10342.37945723259, 10816.729581760519, 10918.290311129751, 11075.889241107487, 11152.219344645959, 20183.293584724463, 20709.68935465969, 20729.378118656463, 20734.58530829375, 20795.68806467904, 7659.961700191463, 7708.344790692742, 7713.691452341844, 7833.195517946476, 7847.803804904809, 4323.135372925428, 4338.956610433918, 4385.736855788663, 4395.230834603932, 4508.819647214074, 2856.428714257157, 3057.373238486105, 3373.645767194458, 3446.1556918555116, 3449.8275086245585, 600.0069884341422, 632.705791806812, 633.3033662970695, 646.6540400885503, 665.351282499559, 485.97813098410654, 502.7209898506364, 505.5500604462032, 595.3778301674761, 613.2610204703321, 7605.809854753565, 8347.248747612744, 8599.95700021496, 8834.602442890086, 9091.181793638605, 554.5425024354923, 581.9010768169337, 614.6158650843223, 622.1600838867583, 623.5915475363644, 4150.667946564267, 4195.454590903224, 4279.465066866642, 4292.7424354538825, 4327.653787696977, 85333.57333213418, 85405.0, 86135.569322153, 125785.0, 157892.0, 94621.58067628992, 132996.0, 135486.0, 136472.0, 138539.30730346285, 110721.44639276912, 111371.0, 114988.27517587243, 115905.0, 115907.0, 7408.555486670817, 8140.308073813792, 8254.0507841599, 8275.83448331042, 8514.361422893184, 49676.54204953333, 62391.0, 62711.6864415675, 63572.68213658902, 64706.522491863114, 91852.0, 94261.3670222261, 97388.0, 97743.0, 99645.50531742175, 9442.339036267394, 11019.724506887393, 11147.83278250843, 11164.0, 11459.19785614999, 11704.765904682028, 11716.648500544998, 12112.515499379926, 12146.271223726604, 12300.50797968107, 10750.73123171927, 11115.777684446104, 11280.09759219244, 11445.885541144482, 12035.398230088636, 10955.287906286067, 11028.393438360647, 11355.0, 11497.310161390344, 11537.365444900612, 10711.464426778786, 10881.78236435251, 11182.105431565293, 11306.717332066764, 11419.657410277705, 9244.214241789523, 9409.811803764023, 9570.721350833843, 9600.367248975721, 9695.563699633674, 8140.511569305861, 8311.584420779058, 9573.425594464354, 9668.371555848847, 9671.129598336183], "value": 7306.610009846765, "unit": "score"}], "name": "dromaeo_css", "value": 7225.793058212665, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 134.0, 114.0, 114.0, 113.0, 114.0, 113.0, 117.0, 161.0], "value": 120.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 113.0, 140.0, 199.0, 139.0, 132.0, 130.0, 106.0, 163.0, 107.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 185.0, 185.0, 187.0, 184.0, 183.0, 184.0, 182.0, 185.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 79.0, 160.0, 59.0, 58.0, 57.0, 70.0, 142.0, 58.0], "value": 83.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 139.0, 71.0, 73.0, 90.0, 115.0, 68.0, 70.0, 114.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 88.0, 86.0, 90.0, 86.0, 89.0, 86.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 161.0, 131.0, 98.0, 99.0, 98.0, 99.0, 98.0, 98.0], "value": 108.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 87.0, 90.0, 84.0, 84.0, 85.0, 86.0, 87.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 55.0, 54.0, 55.0, 54.0, 54.0, 55.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 73.0, 76.0, 73.0, 73.0, 72.0, 71.0, 73.0, 72.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 88.0, 87.0, 88.0, 85.0, 103.0, 105.0, 107.0, 77.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 137.0, 140.0, 152.0, 153.0, 141.0, 138.0, 141.0, 183.0, 138.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 170.0, 169.0, 171.0, 171.0, 171.0, 169.0, 171.0, 169.0, 179.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 71.0, 68.0, 68.0, 67.0, 68.0, 68.0, 68.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1503.5, "alertThreshold": 2.0}]} 09:43:57 INFO - Return code: 0 09:43:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:43:57 INFO - # TBPL SUCCESS # 09:43:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:43:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:43:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:43:57 INFO - 2016-08-27 09:43:57.949 screenresolution[2601:38440] starting screenresolution argv=screenresolution get 09:43:57 INFO - 2016-08-27 09:43:57.952 screenresolution[2601:38440] Display 0: 1600x1200x32@60 09:43:57 INFO - 2016-08-27 09:43:57.958 screenresolution[2602:38443] starting screenresolution argv=screenresolution list 09:43:57 INFO - Available Modes on Display 0 09:43:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:43:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:43:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:43:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:43:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:43:58 INFO - Intel Iris: 09:43:58 INFO - Chipset Model: Intel Iris 09:43:58 INFO - Type: GPU 09:43:58 INFO - Bus: Built-In 09:43:58 INFO - VRAM (Dynamic, Max): 1536 MB 09:43:58 INFO - Vendor: Intel (0x8086) 09:43:58 INFO - Device ID: 0x0a2e 09:43:58 INFO - Revision ID: 0x0009 09:43:58 INFO - Displays: 09:43:58 INFO - Display: 09:43:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:43:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:43:58 INFO - Display Serial Number: 1600x1200 60 09:43:58 INFO - Main Display: Yes 09:43:58 INFO - Mirror: Off 09:43:58 INFO - Online: Yes 09:43:58 INFO - Rotation: Supported 09:43:58 INFO - Return code: 0 09:43:58 INFO - Running post-action listener: _resource_record_post_action 09:43:58 INFO - [mozharness: 2016-08-27 16:43:58.198370Z] Finished run-tests step (success) 09:43:58 INFO - Running post-run listener: _resource_record_post_run 09:43:58 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 602112; Write bytes: 704870400; Read time: 5; Write time: 2180 09:43:58 INFO - TinderboxPrint: CPU usage
53.5% 09:43:58 INFO - TinderboxPrint: I/O read bytes / time
602,112 / 5 09:43:58 INFO - TinderboxPrint: I/O write bytes / time
704,870,400 / 2,180 09:43:58 INFO - TinderboxPrint: CPU idle
2,255.3 (46.5%) 09:43:58 INFO - TinderboxPrint: CPU system
971.7 (20.0%) 09:43:58 INFO - TinderboxPrint: CPU user
1,622.7 (33.5%) 09:43:58 INFO - TinderboxPrint: Swap in / out
406,016,000 / 0 09:43:58 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 388063744; Write bytes: 384537600; Read time: 29276; Write time: 737 09:43:58 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 602112; Write bytes: 294114304; Read time: 5; Write time: 1392 09:43:58 INFO - Running post-run listener: _upload_blobber_files 09:43:58 INFO - Blob upload gear active. 09:43:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:43:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:43:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:43:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:43:59 INFO - Traceback (most recent call last): 09:43:59 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 09:43:59 INFO - main() 09:43:59 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 09:43:59 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 09:43:59 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 09:43:59 INFO - return set(response.json().get('whitelist', [])) 09:43:59 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/models.py", line 651, in json 09:43:59 INFO - return json.loads(self.text or self.content, **kwargs) 09:43:59 INFO - File "/tools/python27/lib/python2.7/json/__init__.py", line 326, in loads 09:43:59 INFO - return _default_decoder.decode(s) 09:43:59 INFO - File "/tools/python27/lib/python2.7/json/decoder.py", line 366, in decode 09:43:59 INFO - obj, end = self.raw_decode(s, idx=_w(s, 0).end()) 09:43:59 INFO - File "/tools/python27/lib/python2.7/json/decoder.py", line 384, in raw_decode 09:43:59 INFO - raise ValueError("No JSON object could be decoded") 09:43:59 INFO - ValueError: No JSON object could be decoded 09:43:59 ERROR - Return code: 1 09:43:59 INFO - Setting buildbot property blobber_files to {} 09:43:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:43:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:43:59 INFO - Contents: 09:43:59 INFO - blobber_files:{} 09:43:59 INFO - Running post-run listener: copy_logs_to_upload_dir 09:43:59 INFO - Copying logs to upload dir... 09:43:59 INFO - mkdir: /builds/slave/test/build/upload/logs 09:43:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.022086 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-27 09:43:59.170275) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-27 09:43:59.173367) ========= 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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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:{} build_url:https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011801 build_url: 'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-27 09:43:59.207541) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 09:43:59.207851) ========= 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.jiyGQY8kWY/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.rPrRBTmKk5/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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.005530 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 09:43:59.256534) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-27 09:43:59.256830) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-27 09:43:59.257150) ========= ========= Total master_lag: 0.18 =========