builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0291 starttime: 1471519503.21 results: success (0) buildid: 20160817163516 builduid: d5c059133172493a96b3248b810652c9 revision: b25d09b7fab57ddb82f14916a443eb34fda31137 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.214905) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.215306) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.215583) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.253996) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.254306) ========= 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.dnsFqJ3oUh/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.pInG160nWk/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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-18 04:25:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.53M=0.001s 2016-08-18 04:25:03 (8.53 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151665 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.431780) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.432121) ========= 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.dnsFqJ3oUh/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.pInG160nWk/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.020052 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.481741) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 04:25:03.482080) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b25d09b7fab57ddb82f14916a443eb34fda31137 --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 b25d09b7fab57ddb82f14916a443eb34fda31137 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dnsFqJ3oUh/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.pInG160nWk/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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-18 04:25:03,564 truncating revision to first 12 chars 2016-08-18 04:25:03,564 Setting DEBUG logging. 2016-08-18 04:25:03,564 attempt 1/10 2016-08-18 04:25:03,564 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b25d09b7fab5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 04:25:04,796 unpacking tar archive at: mozilla-central-b25d09b7fab5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.513923 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 04:25:05.022371) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:05.023109) ========= script_repo_revision: b25d09b7fab57ddb82f14916a443eb34fda31137 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:05.024036) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:05.024391) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:05.043758) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-18 04:25:05.044182) ========= /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.dnsFqJ3oUh/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.pInG160nWk/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 04:25:05 INFO - MultiFileLogger online at 20160818 04:25:05 in /builds/slave/test 04:25:05 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 04:25:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:25:05 INFO - {'append_to_log': False, 04:25:05 INFO - 'base_work_dir': '/builds/slave/test', 04:25:05 INFO - 'blob_upload_branch': 'Firefox', 04:25:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:25:05 INFO - 'branch': 'Firefox', 04:25:05 INFO - 'buildbot_json_path': 'buildprops.json', 04:25:05 INFO - 'config_files': ('talos/mac_config.py',), 04:25:05 INFO - 'default_actions': ('clobber', 04:25:05 INFO - 'read-buildbot-config', 04:25:05 INFO - 'download-and-extract', 04:25:05 INFO - 'populate-webroot', 04:25:05 INFO - 'create-virtualenv', 04:25:05 INFO - 'install', 04:25:05 INFO - 'run-tests'), 04:25:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:25:05 INFO - 'download_minidump_stackwalk': True, 04:25:05 INFO - 'download_symbols': 'ondemand', 04:25:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:25:05 INFO - 'tooltool.py': '/tools/tooltool.py', 04:25:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:25:05 INFO - '/tools/misc-python/virtualenv.py')}, 04:25:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:25:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:25:05 INFO - 'installer_path': 'installer.exe', 04:25:05 INFO - 'log_level': 'info', 04:25:05 INFO - 'log_name': 'talos', 04:25:05 INFO - 'log_to_console': True, 04:25:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:25:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:25:05 INFO - 'opt_config_files': (), 04:25:05 INFO - 'pip_index': False, 04:25:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:25:05 INFO - 'cmd': ('bash', 04:25:05 INFO - '-c', 04:25:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:25:05 INFO - 'enabled': True, 04:25:05 INFO - 'halt_on_failure': False, 04:25:05 INFO - 'name': 'check_screen_resolution'},), 04:25:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:25:05 INFO - 'cmd': ('bash', 04:25:05 INFO - '-c', 04:25:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:25:05 INFO - 'enabled': True, 04:25:05 INFO - 'halt_on_failure': False, 04:25:05 INFO - 'name': 'check_screen_resolution'},), 04:25:05 INFO - 'run_cmd_checks_enabled': True, 04:25:05 INFO - 'sps_profile': False, 04:25:05 INFO - 'sps_profile_interval': 0, 04:25:05 INFO - 'suite': 'dromaeojs', 04:25:05 INFO - 'system_bits': '32', 04:25:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:25:05 INFO - 'title': 't-yosemite-r7-0291', 04:25:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:25:05 INFO - 'use_talos_json': True, 04:25:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:25:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:25:05 INFO - 'work_dir': 'build'} 04:25:05 INFO - [mozharness: 2016-08-18 11:25:05.252052Z] Running clobber step. 04:25:05 INFO - Running pre-action listener: _resource_record_pre_action 04:25:05 INFO - Running main action method: clobber 04:25:05 INFO - rmtree: /builds/slave/test/build 04:25:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:25:06 INFO - Running post-action listener: _resource_record_post_action 04:25:06 INFO - [mozharness: 2016-08-18 11:25:06.229664Z] Finished clobber step (success) 04:25:06 INFO - [mozharness: 2016-08-18 11:25:06.229822Z] Running read-buildbot-config step. 04:25:06 INFO - Running pre-action listener: _resource_record_pre_action 04:25:06 INFO - Running main action method: read_buildbot_config 04:25:06 INFO - Using buildbot properties: 04:25:06 INFO - { 04:25:06 INFO - "project": "", 04:25:06 INFO - "product": "firefox", 04:25:06 INFO - "script_repo_revision": "production", 04:25:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:25:06 INFO - "repository": "", 04:25:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:25:06 INFO - "buildid": "20160817163516", 04:25:06 INFO - "pgo_build": "False", 04:25:06 INFO - "basedir": "/builds/slave/test", 04:25:06 INFO - "buildnumber": 62, 04:25:06 INFO - "slavename": "t-yosemite-r7-0291", 04:25:06 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:25:06 INFO - "platform": "yosemite_r7", 04:25:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:25:06 INFO - "branch": "mozilla-central", 04:25:06 INFO - "repo_path": "mozilla-central", 04:25:06 INFO - "revision": "b25d09b7fab57ddb82f14916a443eb34fda31137", 04:25:06 INFO - "stage_platform": "macosx64", 04:25:06 INFO - "builduid": "d5c059133172493a96b3248b810652c9", 04:25:06 INFO - "slavebuilddir": "test" 04:25:06 INFO - } 04:25:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 04:25:06 INFO - Running post-action listener: _resource_record_post_action 04:25:06 INFO - [mozharness: 2016-08-18 11:25:06.233195Z] Finished read-buildbot-config step (success) 04:25:06 INFO - [mozharness: 2016-08-18 11:25:06.233305Z] Running download-and-extract step. 04:25:06 INFO - Running pre-action listener: _resource_record_pre_action 04:25:06 INFO - Running main action method: download_and_extract 04:25:06 INFO - mkdir: /builds/slave/test/build/tests 04:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:06 INFO - trying https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 04:25:06 INFO - Downloading https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/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 04:25:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/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 04:25:07 INFO - Downloaded 1199 bytes. 04:25:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:25:07 INFO - Using the following test package requirements: 04:25:07 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:25:07 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:07 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 04:25:07 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:07 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 04:25:07 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:07 INFO - u'jsshell-mac.zip'], 04:25:07 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:07 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 04:25:07 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:25:07 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:07 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 04:25:07 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:07 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 04:25:07 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:07 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 04:25:07 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:07 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 04:25:07 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:25: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')]} 04:25:07 INFO - trying https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:25:07 INFO - Downloading https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/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 04:25:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/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 04:25:08 INFO - Downloaded 16338716 bytes. 04:25:08 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 04:25:15 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 04:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:15 INFO - trying https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:25:15 INFO - Downloading https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/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 04:25:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/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 04:25:16 INFO - Downloaded 16338716 bytes. 04:25:16 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 04:25:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:23 INFO - trying https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:25:23 INFO - Downloading https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/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 04:25:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/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 04:25:24 INFO - Downloaded 14413719 bytes. 04:25:24 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 04:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:24 INFO - trying https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:25:24 INFO - Downloading https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 04:25:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/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 04:25:27 INFO - Downloaded 111292281 bytes. 04:25:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:25:27 INFO - mkdir: /builds/slave/test/properties 04:25:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:25:27 INFO - Writing to file /builds/slave/test/properties/build_url 04:25:27 INFO - Contents: 04:25:27 INFO - build_url:https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:25:27 INFO - Running post-action listener: _resource_record_post_action 04:25:27 INFO - Running post-action listener: set_extra_try_arguments 04:25:27 INFO - [mozharness: 2016-08-18 11:25:27.824941Z] Finished download-and-extract step (success) 04:25:27 INFO - [mozharness: 2016-08-18 11:25:27.825039Z] Running populate-webroot step. 04:25:27 INFO - Running pre-action listener: _resource_record_pre_action 04:25:27 INFO - Running main action method: populate_webroot 04:25:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:25:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:25:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:25:27 INFO - '--tppagecycles', 04:25:27 INFO - '7'], 04:25:27 INFO - 'tests': ['tsvgm']}}, 04:25:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:25:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:25:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:25:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:25:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:25:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:25:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:27 INFO - 'talos_options': ['--disable-e10s'], 04:25:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:25:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:25:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:27 INFO - 'talos_options': ['--disable-e10s'], 04:25:27 INFO - 'tests': ['damp', 'tps']}, 04:25:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:27 INFO - 'tests': ['damp', 'tps']}, 04:25:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:25:27 INFO - 'tests': ['dromaeo_dom']}, 04:25:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:25:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:25:27 INFO - 'tests': ['basic_compositor_video']}, 04:25:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:25:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:25:27 INFO - 'tests': ['a11yr', 04:25:27 INFO - 'ts_paint', 04:25:27 INFO - 'tpaint', 04:25:27 INFO - 'sessionrestore', 04:25:27 INFO - 'sessionrestore_no_auto_restore', 04:25:27 INFO - 'tabpaint']}, 04:25:27 INFO - 'other-e10s': {'tests': ['a11yr', 04:25:27 INFO - 'ts_paint', 04:25:27 INFO - 'tpaint', 04:25:27 INFO - 'sessionrestore', 04:25:27 INFO - 'sessionrestore_no_auto_restore', 04:25:27 INFO - 'tabpaint']}, 04:25:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:25:27 INFO - 'ts_paint', 04:25:27 INFO - 'tpaint', 04:25:27 INFO - 'sessionrestore', 04:25:27 INFO - 'sessionrestore_no_auto_restore', 04:25:27 INFO - 'tabpaint']}, 04:25:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:25:27 INFO - 'ts_paint', 04:25:27 INFO - 'tpaint', 04:25:27 INFO - 'sessionrestore', 04:25:27 INFO - 'sessionrestore_no_auto_restore', 04:25:27 INFO - 'tabpaint']}, 04:25:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:25:27 INFO - 'tests': ['a11yr', 04:25:27 INFO - 'ts_paint', 04:25:27 INFO - 'tpaint', 04:25:27 INFO - 'sessionrestore', 04:25:27 INFO - 'sessionrestore_no_auto_restore', 04:25:27 INFO - 'tabpaint']}, 04:25:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:25:27 INFO - 'tests': ['a11yr', 04:25:27 INFO - 'ts_paint', 04:25:27 INFO - 'tpaint', 04:25:27 INFO - 'sessionrestore', 04:25:27 INFO - 'sessionrestore_no_auto_restore', 04:25:27 INFO - 'tabpaint']}, 04:25:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:25:27 INFO - 'tests': ['tsvgx', 04:25:27 INFO - 'tsvgr_opacity', 04:25:27 INFO - 'tart', 04:25:27 INFO - 'tscrollx', 04:25:27 INFO - 'cart']}, 04:25:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:25:27 INFO - 'tsvgr_opacity', 04:25:27 INFO - 'tart', 04:25:27 INFO - 'tscrollx', 04:25:27 INFO - 'cart']}, 04:25:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:27 INFO - 'talos_options': ['--disable-e10s'], 04:25:27 INFO - 'tests': ['tp5o']}, 04:25:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:27 INFO - 'tests': ['tp5o']}, 04:25:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:27 INFO - 'talos_options': ['--disable-e10s', 04:25:27 INFO - '--xperf_path', 04:25:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:25:27 INFO - 'tests': ['tp5n']}, 04:25:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:27 INFO - 'talos_options': ['--xperf_path', 04:25:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:25:27 INFO - 'tests': ['tp5n']}}, 04:25:27 INFO - 'talos.zip': {'path': '', 04:25:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:25:27 INFO - Running post-action listener: _resource_record_post_action 04:25:27 INFO - [mozharness: 2016-08-18 11:25:27.843598Z] Finished populate-webroot step (success) 04:25:27 INFO - [mozharness: 2016-08-18 11:25:27.843736Z] Running create-virtualenv step. 04:25:27 INFO - Running pre-action listener: _resource_record_pre_action 04:25:27 INFO - Running main action method: create_virtualenv 04:25:27 INFO - Creating virtualenv /builds/slave/test/build/venv 04:25:27 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 04:25:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:25:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:25:27 INFO - Using real prefix '/tools/python27' 04:25:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:25:28 INFO - Installing distribute.............................................................................................................................................................................................done. 04:25:31 INFO - Installing pip.................done. 04:25:31 INFO - Return code: 0 04:25:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10875e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2eb691000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10862dd30>, '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 0x108392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108625b20>, '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.dnsFqJ3oUh/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.pInG160nWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 04:25:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:25:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnsFqJ3oUh/Render', 04:25:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:31 INFO - 'HOME': '/Users/cltbld', 04:25:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:31 INFO - 'LOGNAME': 'cltbld', 04:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:31 INFO - 'MOZ_NO_REMOTE': '1', 04:25:31 INFO - 'NO_EM_RESTART': '1', 04:25:31 INFO - 'PAGER': '/bin/cat', 04:25:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:31 INFO - 'PWD': '/builds/slave/test', 04:25:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:31 INFO - 'SHELL': '/bin/bash', 04:25:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pInG160nWk/Listeners', 04:25:31 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 04:25:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:31 INFO - 'USER': 'cltbld', 04:25:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:31 INFO - 'XPC_FLAGS': '0x0', 04:25:31 INFO - 'XPC_SERVICE_NAME': '0', 04:25:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:31 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 04:25:31 INFO - Cleaning up... 04:25:31 INFO - Return code: 0 04:25:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1086c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10875e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2eb691000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10862dd30>, '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 0x108392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108625b20>, '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.dnsFqJ3oUh/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.pInG160nWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 04:25:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:25:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnsFqJ3oUh/Render', 04:25:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:31 INFO - 'HOME': '/Users/cltbld', 04:25:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:31 INFO - 'LOGNAME': 'cltbld', 04:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:31 INFO - 'MOZ_NO_REMOTE': '1', 04:25:31 INFO - 'NO_EM_RESTART': '1', 04:25:31 INFO - 'PAGER': '/bin/cat', 04:25:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:31 INFO - 'PWD': '/builds/slave/test', 04:25:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:31 INFO - 'SHELL': '/bin/bash', 04:25:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pInG160nWk/Listeners', 04:25:31 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 04:25:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:31 INFO - 'USER': 'cltbld', 04:25:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:31 INFO - 'XPC_FLAGS': '0x0', 04:25:31 INFO - 'XPC_SERVICE_NAME': '0', 04:25:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:32 INFO - Downloading/unpacking psutil>=3.1.1 04:25: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 04:25: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 04:25: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 04:25: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 04:25: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 04:25: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 04:25:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:25:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:34 INFO - Installing collected packages: psutil 04:25:34 INFO - Running setup.py install for psutil 04:25:34 INFO - building 'psutil._psutil_osx' extension 04:25:34 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 04:25:35 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 04:25:35 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 04:25:35 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 04:25:35 INFO - building 'psutil._psutil_posix' extension 04:25:35 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 04:25:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:25:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:25:35 INFO - ^ 04:25:35 INFO - 1 warning generated. 04:25:35 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 04:25:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:35 INFO - Successfully installed psutil 04:25:35 INFO - Cleaning up... 04:25:35 INFO - Return code: 0 04:25:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10875e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2eb691000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10862dd30>, '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 0x108392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108625b20>, '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.dnsFqJ3oUh/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.pInG160nWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 04:25:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:25:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:25:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnsFqJ3oUh/Render', 04:25:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:35 INFO - 'HOME': '/Users/cltbld', 04:25:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:35 INFO - 'LOGNAME': 'cltbld', 04:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:35 INFO - 'MOZ_NO_REMOTE': '1', 04:25:35 INFO - 'NO_EM_RESTART': '1', 04:25:35 INFO - 'PAGER': '/bin/cat', 04:25:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:35 INFO - 'PWD': '/builds/slave/test', 04:25:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:35 INFO - 'SHELL': '/bin/bash', 04:25:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pInG160nWk/Listeners', 04:25:35 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 04:25:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:35 INFO - 'USER': 'cltbld', 04:25:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:35 INFO - 'XPC_FLAGS': '0x0', 04:25:35 INFO - 'XPC_SERVICE_NAME': '0', 04:25:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:25:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:25:38 INFO - Installing collected packages: mozsystemmonitor 04:25:38 INFO - Running setup.py install for mozsystemmonitor 04:25:38 INFO - Successfully installed mozsystemmonitor 04:25:38 INFO - Cleaning up... 04:25:38 INFO - Return code: 0 04:25:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:25: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')]} 04:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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')]} 04:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:38 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 0x1086c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10875e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2eb691000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10862dd30>, '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 0x108392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108625b20>, '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.dnsFqJ3oUh/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.pInG160nWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 04:25:38 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 04:25:38 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 04:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnsFqJ3oUh/Render', 04:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:38 INFO - 'HOME': '/Users/cltbld', 04:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:38 INFO - 'LOGNAME': 'cltbld', 04:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:38 INFO - 'MOZ_NO_REMOTE': '1', 04:25:38 INFO - 'NO_EM_RESTART': '1', 04:25:38 INFO - 'PAGER': '/bin/cat', 04:25:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:38 INFO - 'PWD': '/builds/slave/test', 04:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:38 INFO - 'SHELL': '/bin/bash', 04:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pInG160nWk/Listeners', 04:25:38 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 04:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:38 INFO - 'USER': 'cltbld', 04:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:38 INFO - 'XPC_FLAGS': '0x0', 04:25:38 INFO - 'XPC_SERVICE_NAME': '0', 04:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:38 INFO - Downloading/unpacking jsonschema==2.5.1 04:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:25:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:25: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 04:25: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 04:25: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 04:25: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 04:25: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 04:25: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 04:25:41 INFO - Downloading functools32-3.2.3-2.tar.gz 04:25:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:25:41 INFO - warning: no files found matching '*.txt' 04:25:41 INFO - no previously-included directories found matching 'build' 04:25:41 INFO - no previously-included directories found matching 'dist' 04:25:41 INFO - no previously-included directories found matching '.git*' 04:25:41 INFO - Installing collected packages: jsonschema, functools32 04:25:41 INFO - Running setup.py install for functools32 04:25:41 INFO - warning: no files found matching '*.txt' 04:25:41 INFO - no previously-included directories found matching 'build' 04:25:41 INFO - no previously-included directories found matching 'dist' 04:25:41 INFO - no previously-included directories found matching '.git*' 04:25:41 INFO - Successfully installed jsonschema functools32 04:25:41 INFO - Cleaning up... 04:25:41 INFO - Return code: 0 04:25:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:25:41 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')]} 04:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:41 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')]} 04:25:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:41 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 0x1086c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10875e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2eb691000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10862dd30>, '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 0x108392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108625b20>, '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.dnsFqJ3oUh/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.pInG160nWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 04:25:41 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 04:25:41 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 04:25:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnsFqJ3oUh/Render', 04:25:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:41 INFO - 'HOME': '/Users/cltbld', 04:25:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:41 INFO - 'LOGNAME': 'cltbld', 04:25:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:41 INFO - 'MOZ_NO_REMOTE': '1', 04:25:41 INFO - 'NO_EM_RESTART': '1', 04:25:41 INFO - 'PAGER': '/bin/cat', 04:25:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:41 INFO - 'PWD': '/builds/slave/test', 04:25:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:41 INFO - 'SHELL': '/bin/bash', 04:25:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pInG160nWk/Listeners', 04:25:41 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 04:25:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:41 INFO - 'USER': 'cltbld', 04:25:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:41 INFO - 'XPC_FLAGS': '0x0', 04:25:41 INFO - 'XPC_SERVICE_NAME': '0', 04:25:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:25:42 INFO - Cleaning up... 04:25:42 INFO - Return code: 0 04:25:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:42 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 0x1086c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10875e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2eb691000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10862dd30>, '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 0x108392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108625b20>, '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.dnsFqJ3oUh/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.pInG160nWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 04:25:42 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 04:25:42 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 04:25:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnsFqJ3oUh/Render', 04:25:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:42 INFO - 'HOME': '/Users/cltbld', 04:25:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:42 INFO - 'LOGNAME': 'cltbld', 04:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:42 INFO - 'MOZ_NO_REMOTE': '1', 04:25:42 INFO - 'NO_EM_RESTART': '1', 04:25:42 INFO - 'PAGER': '/bin/cat', 04:25:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:42 INFO - 'PWD': '/builds/slave/test', 04:25:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:42 INFO - 'SHELL': '/bin/bash', 04:25:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pInG160nWk/Listeners', 04:25:42 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 04:25:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:42 INFO - 'USER': 'cltbld', 04:25:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:42 INFO - 'XPC_FLAGS': '0x0', 04:25:42 INFO - 'XPC_SERVICE_NAME': '0', 04:25:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:42 INFO - Downloading/unpacking blobuploader==1.2.4 04:25:42 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 04:25:42 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 04:25:42 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 04:25:42 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 04:25:42 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 04:25:42 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 04:25:44 INFO - Downloading blobuploader-1.2.4.tar.gz 04:25:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:25:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:25:44 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 04:25:44 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 04:25:44 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 04:25:44 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 04:25:44 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 04:25:44 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 04:25:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:25:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:25:45 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 04:25:45 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 04:25:45 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 04:25:45 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 04:25:45 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 04:25:45 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 04:25:45 INFO - Downloading docopt-0.6.1.tar.gz 04:25:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:25:46 INFO - Installing collected packages: blobuploader, requests, docopt 04:25:46 INFO - Running setup.py install for blobuploader 04:25:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:25:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:25:46 INFO - Running setup.py install for requests 04:25:46 INFO - Running setup.py install for docopt 04:25:46 INFO - Successfully installed blobuploader requests docopt 04:25:46 INFO - Cleaning up... 04:25:46 INFO - Return code: 0 04:25:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:46 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 0x1086c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10875e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2eb691000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10862dd30>, '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 0x108392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108625b20>, '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.dnsFqJ3oUh/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.pInG160nWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 04:25:46 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 04:25:46 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 04:25:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnsFqJ3oUh/Render', 04:25:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:46 INFO - 'HOME': '/Users/cltbld', 04:25:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:46 INFO - 'LOGNAME': 'cltbld', 04:25:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:46 INFO - 'MOZ_NO_REMOTE': '1', 04:25:46 INFO - 'NO_EM_RESTART': '1', 04:25:46 INFO - 'PAGER': '/bin/cat', 04:25:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:46 INFO - 'PWD': '/builds/slave/test', 04:25:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:46 INFO - 'SHELL': '/bin/bash', 04:25:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pInG160nWk/Listeners', 04:25:46 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 04:25:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:46 INFO - 'USER': 'cltbld', 04:25:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:46 INFO - 'XPC_FLAGS': '0x0', 04:25:46 INFO - 'XPC_SERVICE_NAME': '0', 04:25:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:47 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-yKRqAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:47 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-KCKB05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:47 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-bFaIn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:47 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Wwdhfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:47 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-rRg6ox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:47 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-i1WC7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:47 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-NbmFLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:47 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-5BhRo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-IglLmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-CI6pTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Z2IqaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-wmqPHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-XdQIAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-3q_nCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-1lVTXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-JG1Xuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-VTa3Xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:25:48 INFO - Running setup.py install for manifestparser 04:25:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:25:49 INFO - Running setup.py install for mozcrash 04:25:49 INFO - Running setup.py install for mozdebug 04:25:49 INFO - Running setup.py install for mozdevice 04:25:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:25:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:25:49 INFO - Running setup.py install for mozfile 04:25:49 INFO - Running setup.py install for mozhttpd 04:25:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:25:49 INFO - Running setup.py install for mozinfo 04:25:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Running setup.py install for mozInstall 04:25:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Running setup.py install for mozleak 04:25:50 INFO - Running setup.py install for mozlog 04:25:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Running setup.py install for moznetwork 04:25:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Running setup.py install for mozprocess 04:25:50 INFO - Running setup.py install for mozprofile 04:25:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Running setup.py install for mozrunner 04:25:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:25:51 INFO - Running setup.py install for mozscreenshot 04:25:51 INFO - Running setup.py install for moztest 04:25:51 INFO - Running setup.py install for mozversion 04:25:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:25:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:25:51 INFO - Cleaning up... 04:25:51 INFO - Return code: 0 04:25:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25: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')]} 04:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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')]} 04:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:51 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 0x1086c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10875e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2eb691000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10862dd30>, '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 0x108392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108625b20>, '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.dnsFqJ3oUh/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.pInG160nWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 04:25:51 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 04:25:51 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 04:25:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnsFqJ3oUh/Render', 04:25:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:51 INFO - 'HOME': '/Users/cltbld', 04:25:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:51 INFO - 'LOGNAME': 'cltbld', 04:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:51 INFO - 'MOZ_NO_REMOTE': '1', 04:25:51 INFO - 'NO_EM_RESTART': '1', 04:25:51 INFO - 'PAGER': '/bin/cat', 04:25:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:51 INFO - 'PWD': '/builds/slave/test', 04:25:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:51 INFO - 'SHELL': '/bin/bash', 04:25:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pInG160nWk/Listeners', 04:25:51 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 04:25:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:51 INFO - 'USER': 'cltbld', 04:25:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:51 INFO - 'XPC_FLAGS': '0x0', 04:25:51 INFO - 'XPC_SERVICE_NAME': '0', 04:25:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:51 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-6vH_Pt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:51 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)) 04:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:51 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-x26OAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:52 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)) 04:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Q4SdZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:52 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)) 04:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-CbEn9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:52 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)) 04:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-IqE4li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:52 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)) 04:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Z1a2dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:52 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)) 04:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-4ppYFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:52 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)) 04:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-jqOBvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:52 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)) 04:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-wQP8bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:52 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)) 04:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-kAtIuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:52 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)) 04:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:52 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-paT656-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:53 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)) 04:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:53 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-GiAb5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:53 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)) 04:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:53 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-pIH3dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:53 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)) 04:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:53 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-hXFpmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:53 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)) 04:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:53 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-mTF7UC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:53 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)) 04:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:53 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-taryyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:53 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)) 04:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:53 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-pxRlz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:53 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)) 04:25:53 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)) 04:25:53 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)) 04:25:53 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)) 04:25:53 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)) 04:25:53 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)) 04:25:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:25:53 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 04:25:53 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 04:25:53 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 04:25:53 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 04:25:53 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 04:25:53 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 04:25:56 INFO - Downloading blessings-1.6.tar.gz 04:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:25:56 INFO - Installing collected packages: blessings 04:25:56 INFO - Running setup.py install for blessings 04:25:56 INFO - Successfully installed blessings 04:25:56 INFO - Cleaning up... 04:25:56 INFO - Return code: 0 04:25:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:25:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:56 INFO - Reading from file tmpfile_stdout 04:25:56 INFO - Current package versions: 04:25:56 INFO - blessings == 1.6 04:25:56 INFO - blobuploader == 1.2.4 04:25:56 INFO - docopt == 0.6.1 04:25:56 INFO - functools32 == 3.2.3-2 04:25:56 INFO - jsonschema == 2.5.1 04:25:56 INFO - manifestparser == 1.1 04:25:56 INFO - mozInstall == 1.12 04:25:56 INFO - mozcrash == 0.17 04:25:56 INFO - mozdebug == 0.1 04:25:56 INFO - mozdevice == 0.48 04:25:56 INFO - mozfile == 1.2 04:25:56 INFO - mozhttpd == 0.7 04:25:56 INFO - mozinfo == 0.9 04:25:56 INFO - mozleak == 0.1 04:25:56 INFO - mozlog == 3.3 04:25:56 INFO - moznetwork == 0.27 04:25:56 INFO - mozprocess == 0.23 04:25:56 INFO - mozprofile == 0.28 04:25:56 INFO - mozrunner == 6.12 04:25:56 INFO - mozscreenshot == 0.1 04:25:56 INFO - mozsystemmonitor == 0.3 04:25:56 INFO - moztest == 0.7 04:25:56 INFO - mozversion == 1.4 04:25:56 INFO - psutil == 3.1.1 04:25:56 INFO - requests == 1.2.3 04:25:56 INFO - wsgiref == 0.1.2 04:25:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:56 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 0x1086c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10875e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2eb691000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10862dd30>, '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 0x108392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108625b20>, '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.dnsFqJ3oUh/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.pInG160nWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 04:25:56 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 04:25:56 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 04:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnsFqJ3oUh/Render', 04:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:56 INFO - 'HOME': '/Users/cltbld', 04:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:56 INFO - 'LOGNAME': 'cltbld', 04:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:56 INFO - 'MOZ_NO_REMOTE': '1', 04:25:56 INFO - 'NO_EM_RESTART': '1', 04:25:56 INFO - 'PAGER': '/bin/cat', 04:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:56 INFO - 'PWD': '/builds/slave/test', 04:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:56 INFO - 'SHELL': '/bin/bash', 04:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pInG160nWk/Listeners', 04:25:56 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 04:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:56 INFO - 'USER': 'cltbld', 04:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:56 INFO - 'XPC_FLAGS': '0x0', 04:25:56 INFO - 'XPC_SERVICE_NAME': '0', 04:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:57 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)) 04:25:57 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)) 04:25:57 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)) 04:25:57 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)) 04:25:57 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)) 04:25:57 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)) 04:25:57 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)) 04:25:57 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)) 04:25:57 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)) 04:25:57 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)) 04:25:57 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)) 04:25:57 INFO - Cleaning up... 04:25:57 INFO - Return code: 0 04:25:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086c9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10875e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2eb691000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10862dd30>, '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 0x108392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108625b20>, '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.dnsFqJ3oUh/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.pInG160nWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 04:25:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:25:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:25:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnsFqJ3oUh/Render', 04:25:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:57 INFO - 'HOME': '/Users/cltbld', 04:25:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:57 INFO - 'LOGNAME': 'cltbld', 04:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:57 INFO - 'MOZ_NO_REMOTE': '1', 04:25:57 INFO - 'NO_EM_RESTART': '1', 04:25:57 INFO - 'PAGER': '/bin/cat', 04:25:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:57 INFO - 'PWD': '/builds/slave/test', 04:25:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:57 INFO - 'SHELL': '/bin/bash', 04:25:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pInG160nWk/Listeners', 04:25:57 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 04:25:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:57 INFO - 'USER': 'cltbld', 04:25:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:57 INFO - 'XPC_FLAGS': '0x0', 04:25:57 INFO - 'XPC_SERVICE_NAME': '0', 04:25:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:25:57 INFO - Cleaning up... 04:25:57 INFO - Return code: 0 04:25:57 INFO - Running post-action listener: _resource_record_post_action 04:25:57 INFO - Running post-action listener: _start_resource_monitoring 04:25:57 INFO - Starting resource monitoring. 04:25:57 INFO - [mozharness: 2016-08-18 11:25:57.385292Z] Finished create-virtualenv step (success) 04:25:57 INFO - [mozharness: 2016-08-18 11:25:57.386383Z] Running install step. 04:25:57 INFO - Running pre-action listener: _resource_record_pre_action 04:25:57 INFO - Running main action method: install 04:25:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:57 INFO - Reading from file tmpfile_stdout 04:25:57 INFO - Detecting whether we're running mozinstall >=1.0... 04:25:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:25:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:25:57 INFO - Reading from file tmpfile_stdout 04:25:57 INFO - Output received: 04:25:57 INFO - Usage: mozinstall [options] installer 04:25:57 INFO - Options: 04:25:57 INFO - -h, --help show this help message and exit 04:25:57 INFO - -d DEST, --destination=DEST 04:25:57 INFO - Directory to install application into. [default: 04:25:57 INFO - "/builds/slave/test"] 04:25:57 INFO - --app=APP Application being installed. [default: firefox] 04:25:57 INFO - mkdir: /builds/slave/test/build/application 04:25:57 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'] 04:25:57 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 04:26:31 INFO - Reading from file tmpfile_stdout 04:26:31 INFO - Output received: 04:26:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:26:31 INFO - Running post-action listener: _resource_record_post_action 04:26:31 INFO - [mozharness: 2016-08-18 11:26:31.735305Z] Finished install step (success) 04:26:31 INFO - [mozharness: 2016-08-18 11:26:31.735442Z] Running run-tests step. 04:26:31 INFO - Running pre-action listener: _resource_record_pre_action 04:26:31 INFO - Running main action method: run_tests 04:26:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:26:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:26:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:26:31 INFO - 2016-08-18 04:26:31.750 screenresolution[1964:14251] starting screenresolution argv=screenresolution get 04:26:31 INFO - 2016-08-18 04:26:31.753 screenresolution[1964:14251] Display 0: 1600x1200x32@60 04:26:31 INFO - 2016-08-18 04:26:31.759 screenresolution[1965:14253] starting screenresolution argv=screenresolution list 04:26:31 INFO - Available Modes on Display 0 04:26:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:26:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:26:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:26:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:26:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:26:31 INFO - Intel Iris: 04:26:31 INFO - Chipset Model: Intel Iris 04:26:31 INFO - Type: GPU 04:26:31 INFO - Bus: Built-In 04:26:31 INFO - VRAM (Dynamic, Max): 1536 MB 04:26:31 INFO - Vendor: Intel (0x8086) 04:26:31 INFO - Device ID: 0x0a2e 04:26:31 INFO - Revision ID: 0x0009 04:26:31 INFO - Displays: 04:26:31 INFO - Display: 04:26:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:26:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:26:31 INFO - Display Serial Number: 1600x1200 60 04:26:31 INFO - Main Display: Yes 04:26:31 INFO - Mirror: Off 04:26:31 INFO - Online: Yes 04:26:31 INFO - Rotation: Supported 04:26:31 INFO - Return code: 0 04:26:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:26:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:26:32 INFO - Python 2.7.3 04:26:32 INFO - Return code: 0 04:26:32 INFO - grabbing minidump binary from tooltool 04:26:32 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')]} 04:26:32 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 0x10862dd30>, '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 0x108392db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108625b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:26:32 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 04:26:32 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 04:26:32 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 04:26:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:26:32 INFO - Return code: 0 04:26:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:26:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:26:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:26:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:26:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:26:32 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/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--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 04:26:32 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/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0291 --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 04:26:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dnsFqJ3oUh/Render', 04:26:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:32 INFO - 'HOME': '/Users/cltbld', 04:26:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:32 INFO - 'LOGNAME': 'cltbld', 04:26:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:26:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:32 INFO - 'MOZ_NO_REMOTE': '1', 04:26:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:26:32 INFO - 'NO_EM_RESTART': '1', 04:26:32 INFO - 'PAGER': '/bin/cat', 04:26:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:32 INFO - 'PWD': '/builds/slave/test', 04:26:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:26:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:32 INFO - 'SHELL': '/bin/bash', 04:26:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pInG160nWk/Listeners', 04:26:32 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 04:26:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:32 INFO - 'USER': 'cltbld', 04:26:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:32 INFO - 'XPC_FLAGS': '0x0', 04:26:32 INFO - 'XPC_SERVICE_NAME': '0', 04:26:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:32 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/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--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 04:26:32 INFO - mozversion application_buildid: 20160817163516 04:26:32 INFO - mozversion application_changeset: b25d09b7fab57ddb82f14916a443eb34fda31137 04:26:32 INFO - mozversion application_display_name: Nightly 04:26:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:26:32 INFO - mozversion application_name: Firefox 04:26:32 INFO - mozversion application_remotingname: firefox 04:26:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:26:32 INFO - mozversion application_vendor: Mozilla 04:26:32 INFO - mozversion application_version: 51.0a1 04:26:32 INFO - mozversion platform_buildid: 20160817163516 04:26:32 INFO - mozversion platform_changeset: b25d09b7fab57ddb82f14916a443eb34fda31137 04:26:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:26:32 INFO - mozversion platform_version: 51.0a1 04:26:32 INFO - using testdate: 1471519592 04:26:32 INFO - actual date: 1471519592 04:26:32 INFO - starting webserver on 'localhost:49565' 04:26:32 INFO - SUITE-START | Running 2 tests 04:26:32 INFO - TEST-START | dromaeo_css 04:26:32 INFO - operating with platform_type : mac_ 04:26:32 INFO - Initialising browser for dromaeo_css test... 04:26:32 INFO - TEST-INFO | started process 1975 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpEGgycI/profile http://localhost:49565/getInfo.html) 04:26:34 INFO - PROCESS | 1975 | __metrics Screen width/height:1600/1200 04:26:34 INFO - PROCESS | 1975 | colorDepth:24 04:26:34 INFO - PROCESS | 1975 | Browser inner width/height: 1024/689 04:26:34 INFO - PROCESS | 1975 | __metrics 04:26:34 INFO - PROCESS | 1975 | 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" 04:26:34 INFO - PROCESS | 1975 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:26:35 INFO - TEST-INFO | 1975: exit 0 04:26:35 INFO - Browser initialized. 04:26:35 INFO - Running cycle 1/1 for dromaeo_css test... 04:26:35 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpEGgycI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:26:47 INFO - PROCESS | 1977 | RSS: Main: 228339712 04:26:47 INFO - PROCESS | 1977 | 04:27:38 INFO - PROCESS | 1977 | 1471519658404 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] 04:29:51 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49565/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49565/tests/dromaeo/cssquery-ext.html) 04:29:52 INFO - PROCESS | 1977 | RSS: Main: 379957248 04:29:52 INFO - PROCESS | 1977 | 04:32:56 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49565/tests/dromaeo/cssquery-ext.html (next: http://localhost:49565/tests/dromaeo/cssquery-jquery.html) 04:32:56 INFO - PROCESS | 1977 | RSS: Main: 384737280 04:32:56 INFO - PROCESS | 1977 | 04:35:59 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49565/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49565/tests/dromaeo/cssquery-mootools.html) 04:35:59 INFO - PROCESS | 1977 | RSS: Main: 335405056 04:35:59 INFO - PROCESS | 1977 | 04:39:02 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49565/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49565/tests/dromaeo/cssquery-prototype.html) 04:39:02 INFO - PROCESS | 1977 | RSS: Main: 337367040 04:39:02 INFO - PROCESS | 1977 | 04:42:06 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49565/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49565/tests/dromaeo/cssquery-yui.html) 04:42:06 INFO - PROCESS | 1977 | RSS: Main: 333082624 04:42:06 INFO - PROCESS | 1977 | 04:45:10 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49565/tests/dromaeo/cssquery-yui.html (next: http://localhost:49565/tests/dromaeo/cssquery-dojo.html) 04:45:10 INFO - PROCESS | 1977 | RSS: Main: 479518720 04:45:10 INFO - PROCESS | 1977 | 04:45:10 INFO - PROCESS | 1977 | __start_tp_report 04:45:10 INFO - PROCESS | 1977 | _x_x_mozilla_page_load 04:45:10 INFO - PROCESS | 1977 | _x_x_mozilla_page_load_details 04:45:10 INFO - PROCESS | 1977 | |i|pagename|runs| 04:45:10 INFO - PROCESS | 1977 | |0;dojo.html;2841.2896775806043;3325.5510506081673;3361.54619126418;3366.4613661814114;3378.0879162626084;5590.217369568264;5630.493255606994;5690.231818704474;5731.598788084836;5737.569682273831;5624.3813180550105;5689.829305120843;5725.370209276986;5764.654120752758;5802.47777700008;5136.922946155811;5197.038547868638;5202.725332293593;5233.869153271161;5281.86795330116;5299.311089558352;5313.681179129246;5320.228566857803;5325.733713314338;5355.143177091666;5153.845384638429;5200.6879587224885;5205.869853253661;5213.348331458567;5279.313689220415;5223.817166399158;5236.083685355085;5243.161094224944;5246.396664383607;5257.08001099806;5140.7172605506685;5191.5846732261325;5193.63644544882;5204.193350030751;5224.895502089975;5637.830865074055;5673.716314184274;5687.658740475544;5758.596898217126;5759.3376761672525;6206.689665516707;6236.064590311445;6251.343608921044;6274;6280.905786413207;6076.058210977261;6191.87616247677;6241.469475094623;6284.622922624657;6322.462590679797;5793.420657934173;5799.449052340021;5827.271591051119;5837.153612726216;5839.153322768175;6298.905516417258;6311.96844015777;6317.178766760292;6319.368063193644;6344.333844946306;5750.166225897222;5827.76688932438;5830.833833233371;5902.203202567598;6147.108669242934;5994.610350327215;6068.757249709962;6074.514038876968;6084.148219249309;6096;5932.58471906971;5943.078822782476;5946.791862284799;5959.28488581373;5959.4934430573885;5883.911741323884;5953.404659534012;5982.611130276518;6135.01831721223;6190.350013248634;4972.328735620717;4983.9782844516785;5014.674046186986;5028.170351891897;5033.874153146201;4324.329728892027;4445.599896009375;4448.844290449819;4454.265046267395;4497.955020449819;5618.6628802272;5697.401772813878;5755.539556835443;5757.539396848242;5761.9135712964335;6204.317525072329;6253.218347706536;6281.120643109968;6337.556371054075;6341.778037768656;5730.598858079894;5733.455321744427;5744.281964754406;5752.223449834302;5790.247267855236;5345.3852806926925;5396.703181325065;5423.945760542344;5439.3200849893765;5445.782168713286;6252.937470625236;6270.968645156745;6316.115743795965;6316.399942005502;6317.652529110943;6315.5894866833505;6333.240011198685;6335.65153916539;6342.49260059185;6373.521985850987;6253.155823963707;6312.968435157794;6319.988801791691;6332.366763323631;6344.904826427515;6112.480439162721;6115.057807494094;6129.847850912965;6159.876802464015;6206.224221972254;6279.874402512016;6299.874002520016;6307.148534947723;6307.990721484541;6319.652419116993;5980.8803823924145;6150.077488376779;6152.354002829818;6160.414760597736;6178;6045;6062.302835173835;6087.756489740361;6142.188054524016;6148.4466398022605;5941.494972927349;5961.910571341346;5981.551383646331;5998.4601385875485;6004.669743164168;5951.821445356646;5966.731497082728;6133.6319820810895;6135.07973803933;6138.416850399205;5791.652500849962;5857.560682948882;5894.528437724886;5905.645661260338;5936.8812623748145 04:45:10 INFO - PROCESS | 1977 | |1;ext.html;520.1365732883414;521.3379008659002;525.6189262784479;540.8242321245593;549.9780008799643;30701.84649076757;40269.99325016872;41225.76322710316;41475;41829.163416731644;42433.78783106088;42668.57331426685;42848.714538563814;42871;42978.78510607443;7316.378107860838;9699.612015519371;9884.406935583887;9887.209023278156;9895.851562226573;7609.923900761005;11922.344271065089;12186.878131218664;12188.268703877795;12462.003039756797;12230.449629766637;12282.631521054383;12295.877041229607;12300.507979680802;12304.38478076097;12088.093392995517;12177.208481448677;12179.025677945743;12203.02375809933;12206.389680516028;11930.508686414198;12007.93996030023;12013.819792703029;12036.759264814655;12052.337121458317;39655.8017209915;39905.40141897874;39981.60018399808;39989.200215995515;39999.600003999876;3350.698437140669;3409.9659003409897;3519.03226612681;3610.9277814443562;3748.306563285801;30684.53973190404;31103.84448077745;31786.682133178605;31821.84089079563;32199.51700724491;3279.8196099214533;3289.490129030004;3387.4072037393603;3388.8305584720665;3431.365197438482;414566.999999997;468775;474407;483937;484258;33193.502097468554;46949.76525117353;56865.71567142138;56997.43002570004;57111;33591.99224023283;34819.82590087033;34934.47598286028;35084.29831403358;35094.47358289628;14494.782578261334;14652.926735366467;14657.633559160893;14682.63293417673;14684.706305873822;11269.661910142544;11427.48576314068;12350.505979760888;12438.129330946927;12452.564160254347;7310.122785265802;7370.520916140434;7391.482596218213;7599.848003039909;7770.378369730408;3227.064151396069;3233.4826427771445;3234.450143475615;3239.8704051838135;3268.9836550817095;843.5908584336592;932.1424289653535;969.7236287657984;995.7510622344414;1064.2497039587072;29450.852745736134;29981.950631727785;30042;30639.540406893913;30693.386132277676;993.4734590666958;1033.7674023344837;1045.8431235314763;1056.1023130339304;1156.2773266708307;14813.006590975832;15125.697486050218;15585.766213506806;15685.215739212994;16035;646507;651568;655324;659366;659750;57831;58052;58129.70935145297;58171.70914145402;58269;1241.1932244041445;1274.3564499927663;1364.1542243808901;1386.9306534673428;1404.1434724817852;28418.28954276077;30649.31428771439;30724.846375768873;31436.528452073693;31535.36929261448;56120;56917;56962.430375695716;57027.42972570221;57208.71395642995;610915;618074;619090;625938;625944.9244060373;22942.770572294732;26910.32724181911;27386;28559.714402855705;42889.35665964951;33278.83360583182;40007.99980000523;46920.29619555641;47843;50891;33930.49104263487;39048.21903561856;39557.208855822144;40376.79811601041;41180;36150.8192459036;39257;39361.015974600865;39361.80319098386;40724.38913416242;38298.42552361773;38630.80684596559;38698;38711.806440967615;39003;30268.09195724132;32219.35561288808;33391.827776361206;34301.48547771735;35548.28903421969;22528.774712252667;22754.330348713804;25541.744582553936;25967.610485843106;26201.820918059115 04:45:10 INFO - PROCESS | 1977 | |2;jquery.html;324.88628979857043;372.5827073677482;374.0704349691018;394.1250424058589;403.96970227232964;20418.748750749543;21367.679484807722;21598.89200553995;21984.670229946565;22136.88931555344;20755.16979320829;21189.894050529765;21408.357749267507;21703.45741356467;21749;5892.469677729004;5923.378045305246;6056.969715151418;6132.356102609228;6136.9079463808075;4218.346156345771;4438.378626992224;6025.789097381593;6150;6499.480041596661;6674.032265321525;6761.459083273351;6779.76270830521;6787.592744435325;6815.216250131249;6471.352864713538;6641.966790166067;6715.227748808852;6724.226713927912;6739.494537909627;4748.667593268473;5461.9453805462235;5592.909382670381;5823.068309070529;5827.79602713903;12793.48826046957;12847.935760321232;13022.934885325607;13033.804492932613;13035;2106.29439137889;2131.3286314810784;2236.262033528948;2434.866082365469;2936.3540021195304;9624.95187524065;9641;9657.275704322205;9711.417314961125;9793.90206097937;1774.6361995790833;1782.9019403932778;1805.5847155154388;1815.700409432442;1852.7498787663628;128651.35674321569;130760.69239307675;131778.18661760553;132532;134994.17555332227;9462.492439503538;11317.773644527064;11329.37688427128;11447.771044579062;11663.533458661559;13892.86107138936;14393.78409323861;14402.423903043971;14421.92789036048;14423.278836058154;8521.914780852236;8541.103184165697;8550.700725474702;8669.653213871501;8708.433951793113;8511.74464766058;8518.403711740242;8524.872126917977;8700.869486957701;8718.346124040723;4388.692791504564;4471.28459446487;4474.597286244189;4550;4559.133764584718;1439.7624391975207;1761.339497688367;1845.0590198998607;1905.3045638341905;1979.237993372562;899.8335307967916;915.2403505090758;916.8028873792167;944.2729098594182;1048.2400259811575;20878.79121208799;21318.82746448929;21453.034613442433;21522.031508582153;21661.458463538536;814.5927036481759;894.7136916186757;894.7226359828474;935.9344845860725;948.4735971535864;10928.453577321103;11291.68520453305;11326.37704926237;11344.48949797261;11372.805855385232;524167;624051;633897;643418.7829060825;643854;96031.51984240035;129852;132756.0086598702;133453.32373410102;134885;925.0703043441298;934.56075644447;937.1425145991478;946.2950102173787;974.7075877236658;14200.786988195392;17427.21577528989;19503.414897553095;19799.60400792005;19870.90064549668;47171;48984;54076.45923540714;54855.45144548663;55255.72372138114;74322;77485.61257193904;78743;78960;79416.60291698505;7745.728899488491;7767.145613982344;7914.6834126636595;7942;7979.680812767424;10372.377657340583;12445;12651.86509710433;12824.871751282368;12946.287954162612;10437.68686939393;11003.504842282277;11036.061934735637;11101.666949991513;11102.333859968428;8418.831623367621;10691.465426728788;10798.244122911636;11127.443627818415;11351.091912646803;11058.778824423627;11130.332180069221;11264.324140551265;11269.549218031187;11311.151663625425;7910.655188618064;7935.563544005136;8094;8134.468561378083;8174.632141553763;10002.549885255326;10311;10820.18848586375;11091;11106.611673540807 04:45:10 INFO - PROCESS | 1977 | |3;mootools.html;1530.7550791873302;1557.9298931548078;1717.1156854220078;1809.2220345251537;1852.2313240005399;8172.795680107993;8726.1710137537;8733.737987860359;8735.082816304284;8744.256738177246;7656.96171519143;7854.607269636524;8258.83482330353;8427.578621068953;8501.234888860017;2349.0721165139757;2381.416552944522;2382.5711371953043;2385.0340612052146;2385.42749740062;2356.175338631474;2369.727481339642;2402.0872068613903;2403.8677872716994;2406.68713067301;1920.894350810705;2119.9364019079453;2198.1756841184556;2380.131252092991;2385.2605692235375;2375.857448553092;2381.0475809676095;2398.280515845257;2401.7358090610105;2406.554787364343;2366.810655147584;2381.38084098134;2382.845115067515;2384.9880750596135;2388.2835149455095;8693.869591956125;8747.868781968276;8750.562471876381;8756.168164024408;8763.868541971875;5055.165897626887;5255.080360936859;5279.996800607911;5292.338457692788;5293.258943747878;3426.640202778697;3480.443129099357;3546.131197856516;3616.276744651054;3621.8551257949653;1028.5775919125726;1063.1388575254068;1078.3529882070773;1083.496174278964;1086.473060565625;11103.666889993232;11248.887511124865;11266.985971262544;11278.323300601991;11304.660860174208;4483.955160448419;4638.95361046392;4752.453467851172;4762.166620841339;4765;9743.074407931235;9745.56144973478;9752.366096203725;9754.317197796086;9782.461964592016;5286.550643195372;5288.65623734456;5290.047791397508;5290.074237008508;5307.416184219733;3948.8815335539407;4812.759362031885;4874.171390863562;4875.390576177978;4878.780454879468;3737.626237376295;3767.9246415071543;3809.904752381213;3835.827387767557;3871.4386413969814;1118.904893084097;1196.5273716881784;1199.7000749812546;1209.2986068080493;1210.9939450302693;754.7169811320667;874.7200895713438;889.6530353162271;905.7328088213993;962.9374090684083;4844.854654360375;5328.307320048369;5835.67903765297;5923.259592550931;5969.343372229051;923.3121324613206;957.8658987741721;958.3674774648698;981.5632043740467;1013.4729940430934;3731.3097077040834;3888.922221555553;3990.620891015349;4038.273110840077;4063.065494936181;9997.300188986701;10341.638042668621;10388.324758890649;10543.947280263703;10592.841107383396;10535.104516116062;10549.525271362807;10550.841737373945;10561.683149505527;10573.894261057445;7902.17027212146;8124.918750812534;8128.065272493739;8128.431009829374;8135.593220338959;5643.633163844337;5804;5992.700364981646;6043.063325184604;6068.757249709962;9843.163331116935;9848.655297064568;9880.752981175241;9882.703518894445;9890.406575605199;9446.433214007182;9784.070513301225;9806.950965245414;10018.398896065968;10046.146077583488;7328.303811137934;7506.1743208248145;7507.849843003;7521.398288137046;7525.3227209551405;7546.547207167588;7694.384449243935;7799.766007019798;8155.469894456842;8293.377996650395;5902.763889444374;7314.268573142643;7365.558066516026;7407.333339999426;7422.925770742439;7199.568025918462;7213.6032518212005;7274.16347120088;7296.635168241673;7413.629318533944;6694.564853284521;6706.865862682622;6765.695543700447;6768.56004359715;6783;4799.424069111729;4833.903321933612;4900.95099049005;4911.165101932608;4931.013797240495;4471.977640111779;4801.18379875429;4847.878803029953;4957.454679985271;5065.885505188852 04:45:10 INFO - PROCESS | 1977 | |4;prototype.html;264.7855237257821;296.2312798288442;296.6277321960939;306.32608261823987;315.80262336039976;5025;5056.266841308013;5332.226827110067;5357.4374690657505;5366.731663416824;3654.41033150579;4081.571434999327;4705.858824235278;5185.781341384772;5225.477452254772;3127.81233126012;3285.802851828898;3439.6044454887756;4083.4691490106243;4086.1827634473225;3239.0282915125485;3654.945175822365;4081.8977577041514;4088.2232375848494;4098.016476045757;3449.2584094419694;3575.964240357589;4040.0103923096844;4051.5948405159543;4116.629503344699;4074.8370065197355;4088.2232375848494;4088.34586466164;4095.4266402703497;4123.546409894909;3126;3524.7134795799607;3526.9771766187773;3560.9109772255642;3565.5008298838184;4720.433547974265;5070.543651071422;5222.686638801646;5232.3459567554055;5236.816711415082;4290.442242508485;4405.061772819949;4619.284010978304;4657.790399432034;4664.906701865943;4812.927806082912;5344.893102137974;5547.478804774096;5558.555315574744;5563.805266815642;3266.3140740444296;3881.068543549556;3887.7472964257518;3892.610738926113;3926.038120660428;5384.892302153935;5847.56143289255;5851.561132915006;5913.645181289136;5928.584999050068;3167.479609787315;3399.5750531183603;3653.1962968146963;3867.7099217558793;3884.320243957296;3941.4876066111224;3941.507311586052;3944.560235514074;3946;3951.723379363417;3635.2184280379315;4240.872773816791;4329.20338059708;4563.726176429424;4637.327587499861;4893.412790465096;5140.558234838421;5619.4099619540175;5627.268455100892;5652.491275785199;1306.5949555637592;1337.9331033448289;1339.2165583133976;1341.7387655603702;1348.6965432777743;706.5795851468368;839.2740279658174;868.5483548554843;874.9956250218836;905.2169873059887;4584.33527138563;5185;5185.792568297301;5254.106801843695;5263.578913686896;4649.535046495391;5433;5716.742746576414;5730.828075157752;5731;3833.9233215336094;4120.464339635888;5113.386393632788;5182.8963420732125;5193.818216362485;2879.985600071986;3034.2717747740826;3044.8325342105954;3057.5872257245433;3069.2326918270433;5294.549963253166;5581.386047534768;6044.516438684895;6045.667488288098;6091.634501929899;5654.62505747587;5967.820965370959;5981.910271345933;5992.280926288873;6023.6385816851125;6059.21230240066;6068.484178844759;6076.513878889678;6101.84745381369;6185.938140618716;5223.242629818731;5604.579656525695;5697.7720891165545;5840.328362238397;5876.324222714443;5662.405447427961;5784.21913041734;5803.39064398249;5811.169481613279;5937.832691067642;5146.022255771391;5593.384727680033;6081.631632882566;6087.317173110381;6162.198914141133;3648.58041325251;3831.1571454280015;3868.129670824042;3877.903052423712;3884.223155368994;5772.480476757112;5795.449432303924;5811.738471768696;5832.066869301061;5841.211436456194;4437.844675436344;4716.127516409475;5670.603057785916;5679.302331392555;5704.486596206361;5538.750756216061;5639;5643.858903527444;5645.266115404971;5684.545236381162;5312.760925758427;5511.173324001432;5538.8121691932265;5595.440455954372;5600.691961942132;4790.976045119752;5430.642339415146;5548.583856210719;5573.442655734394;5580.576952876991;5489.4318692849965;5499.780008799763;5504.22940788282;5522.116461366162;5547.944520554905 04:45:10 INFO - PROCESS | 1977 | |5;yui.html;704.4822055788994;705.9858802823941;790.3953475591173;839.2488722593283;847.1613103028004;15168.772468412968;16261.34954601816;16297.266623001964;16320.265588048536;16331.918340408281;14577.77244599065;14768.335424905878;14780.926095369508;14801.851981480182;14822.481213157542;696.5646470955653;718.985620287594;846.1284876577135;848.0290067872282;869.3306154261215;865.8268346330727;872.3544577013029;874.4534665833854;877.3726785348474;882.0606054551881;934.387975875799;937.8358787212245;939.642935684439;940.0411580188204;944.9622015119387;929.9023602521773;932.4312169576552;932.8927173375081;932.9160375566198;934.9532523373804;532.5074306266688;664.3522565498648;691.1844024051617;693.6219760230687;693.9895901561481;14654.633634159125;14752.704945901129;14765;14766.114033158045;14782.556523304318;13043.934780326132;13147.685231476871;13978.231197284145;14346.50758140691;14378.209198494078;12778;12909.289989050598;12921.87078129216;12973.481060757558;13471.528496502673;4610.746408947506;4635.46692130406;4713.080949214905;4839;4847.297141914438;121375;123123;124746;124995.3750231243;128447;9684.46735429544;10553.261271710906;10692.62575809843;10723.83914241287;11010.064144547643;19038.619227615647;19295;21203;21236.893815530824;21248.468788280108;6798.830029249309;7724.92275077242;7759.7672069838;7842.803929901798;8021.839563208703;4370.822666918975;4577.176108300538;4624.375709279271;4686.625069994392;4729.858104256878;3520;3676.9080772980356;3681.208540163878;3684.5946945835935;3688.7786732796117;653.8398092467376;665.1851481934688;672.825065482978;681.0465348512123;686.5194363945259;612.5085417009595;636.3572791480638;663.2174034639075;691.1464341538197;697.8639165362706;9311.72064838056;9378.953105234566;9526.190023895439;9580.95209523948;9785.755356116155;593.4243783529969;641.9807405777834;663.7212370804274;674.942629876466;679.1578442730978;4434.578715022132;4607.3319368691355;4649.837255696035;4675.579197872208;4692.81228750853;98513.01486985039;128674;141587.29206354107;162235.3776462244;162276;118933;119208;119499.40250298692;121039.39480302543;123895;96329.03670963201;115512.42243788728;116397.41801290939;117734.41132794453;121218;9054.502002389932;9575.281853861128;9737;9737.415755054719;10103.292769506357;46613.30080048734;49313.50686493089;50293.49706502888;55561.444385555624;61368.69315653393;88711;90716;95092.91376363086;96527;100068.49965750416;12874.098813082994;13215.60353189406;13306.800397994231;13699.794503082661;13795.862041379458;14984.325705343505;15020.549383518512;15082.773758393414;15538.223088845287;15554.611134721723;13673.384697688827;13872.167669939836;13925.582232533041;14036.508722194674;14530.636734081732;9720.424786845268;13701.794473083111;13738.313084345542;13969.511067112602;14151.080179788281;13449.663758406117;13532.86467135316;13729.794053089012;13897.652558686113;14037.227952462712;9582.520873956135;10725.839112413163;10847.24069315172;10952.507137179005;11244.100471962387;10297.588096476056;11270;11655.417229138679;11684.70788230301;11848.763024739284 04:45:10 INFO - PROCESS | 1977 | __end_tp_report 04:45:10 INFO - PROCESS | 1977 | __start_cc_report 04:45:10 INFO - PROCESS | 1977 | _x_x_mozilla_cycle_collect,1374 04:45:10 INFO - PROCESS | 1977 | __end_cc_report 04:45:10 INFO - PROCESS | 1977 | __startTimestamp1471520710437__endTimestamp 04:45:10 INFO - PROCESS | 1977 | 04:45:10 INFO - PROCESS | 1977 | ------- Summary: start ------- 04:45:10 INFO - PROCESS | 1977 | Number of tests: 6 04:45:10 INFO - PROCESS | 1977 | 04:45:10 INFO - PROCESS | 1977 | [#0] dojo.html Cycles:165 Average:5733.77 Median:5902.20 stddev:636.01 (10.8%) stddev-sans-first:596.11 04:45:10 INFO - PROCESS | 1977 | Values: 2841.3 3325.6 3361.5 3366.5 3378.1 5590.2 5630.5 5690.2 5731.6 5737.6 5624.4 5689.8 5725.4 5764.7 5802.5 5136.9 5197.0 5202.7 5233.9 5281.9 5299.3 5313.7 5320.2 5325.7 5355.1 5153.8 5200.7 5205.9 5213.3 5279.3 5223.8 5236.1 5243.2 5246.4 5257.1 5140.7 5191.6 5193.6 5204.2 5224.9 5637.8 5673.7 5687.7 5758.6 5759.3 6206.7 6236.1 6251.3 6274.0 6280.9 6076.1 6191.9 6241.5 6284.6 6322.5 5793.4 5799.4 5827.3 5837.2 5839.2 6298.9 6312.0 6317.2 6319.4 6344.3 5750.2 5827.8 5830.8 5902.2 6147.1 5994.6 6068.8 6074.5 6084.1 6096.0 5932.6 5943.1 5946.8 5959.3 5959.5 5883.9 5953.4 5982.6 6135.0 6190.4 4972.3 4984.0 5014.7 5028.2 5033.9 4324.3 4445.6 4448.8 4454.3 4498.0 5618.7 5697.4 5755.5 5757.5 5761.9 6204.3 6253.2 6281.1 6337.6 6341.8 5730.6 5733.5 5744.3 5752.2 5790.2 5345.4 5396.7 5423.9 5439.3 5445.8 6252.9 6271.0 6316.1 6316.4 6317.7 6315.6 6333.2 6335.7 6342.5 6373.5 6253.2 6313.0 6320.0 6332.4 6344.9 6112.5 6115.1 6129.8 6159.9 6206.2 6279.9 6299.9 6307.1 6308.0 6319.7 5980.9 6150.1 6152.4 6160.4 6178.0 6045.0 6062.3 6087.8 6142.2 6148.4 5941.5 5961.9 5981.6 5998.5 6004.7 5951.8 5966.7 6133.6 6135.1 6138.4 5791.7 5857.6 5894.5 5905.6 5936.9 04:45:10 INFO - PROCESS | 1977 | 04:45:10 INFO - PROCESS | 1977 | [#1] ext.html Cycles:180 Average:70053.32 Median:35009.39 stddev:156955.75 (448.3%) stddev-sans-first:157309.22 04:45:10 INFO - PROCESS | 1977 | Values: 520.1 521.3 525.6 540.8 550.0 30701.8 40270.0 41225.8 41475.0 41829.2 42433.8 42668.6 42848.7 42871.0 42978.8 7316.4 9699.6 9884.4 9887.2 9895.9 7609.9 11922.3 12186.9 12188.3 12462.0 12230.4 12282.6 12295.9 12300.5 12304.4 12088.1 12177.2 12179.0 12203.0 12206.4 11930.5 12007.9 12013.8 12036.8 12052.3 39655.8 39905.4 39981.6 39989.2 39999.6 3350.7 3410.0 3519.0 3610.9 3748.3 30684.5 31103.8 31786.7 31821.8 32199.5 3279.8 3289.5 3387.4 3388.8 3431.4 414567.0 468775.0 474407.0 483937.0 484258.0 33193.5 46949.8 56865.7 56997.4 57111.0 33592.0 34819.8 34934.5 35084.3 35094.5 14494.8 14652.9 14657.6 14682.6 14684.7 11269.7 11427.5 12350.5 12438.1 12452.6 7310.1 7370.5 7391.5 7599.8 7770.4 3227.1 3233.5 3234.5 3239.9 3269.0 843.6 932.1 969.7 995.8 1064.2 29450.9 29982.0 30042.0 30639.5 30693.4 993.5 1033.8 1045.8 1056.1 1156.3 14813.0 15125.7 15585.8 15685.2 16035.0 646507.0 651568.0 655324.0 659366.0 659750.0 57831.0 58052.0 58129.7 58171.7 58269.0 1241.2 1274.4 1364.2 1386.9 1404.1 28418.3 30649.3 30724.8 31436.5 31535.4 56120.0 56917.0 56962.4 57027.4 57208.7 610915.0 618074.0 619090.0 625938.0 625944.9 22942.8 26910.3 27386.0 28559.7 42889.4 33278.8 40008.0 46920.3 47843.0 50891.0 33930.5 39048.2 39557.2 40376.8 41180.0 36150.8 39257.0 39361.0 39361.8 40724.4 38298.4 38630.8 38698.0 38711.8 39003.0 30268.1 32219.4 33391.8 34301.5 35548.3 22528.8 22754.3 25541.7 25967.6 26201.8 04:45:10 INFO - PROCESS | 1977 | 04:45:10 INFO - PROCESS | 1977 | [#2] jquery.html Cycles:180 Average:35421.17 Median:384.10 stddev:102940.79 (26800.7%) stddev-sans-first:103195.84 04:45:10 INFO - PROCESS | 1977 | Values: 324.9 372.6 374.1 394.1 404.0 20418.7 21367.7 21598.9 21984.7 22136.9 20755.2 21189.9 21408.4 21703.5 21749.0 5892.5 5923.4 6057.0 6132.4 6136.9 4218.3 4438.4 6025.8 6150.0 6499.5 6674.0 6761.5 6779.8 6787.6 6815.2 6471.4 6642.0 6715.2 6724.2 6739.5 4748.7 5461.9 5592.9 5823.1 5827.8 12793.5 12847.9 13022.9 13033.8 13035.0 2106.3 2131.3 2236.3 2434.9 2936.4 9625.0 9641.0 9657.3 9711.4 9793.9 1774.6 1782.9 1805.6 1815.7 1852.7 128651.4 130760.7 131778.2 132532.0 134994.2 9462.5 11317.8 11329.4 11447.8 11663.5 13892.9 14393.8 14402.4 14421.9 14423.3 8521.9 8541.1 8550.7 8669.7 8708.4 8511.7 8518.4 8524.9 8700.9 8718.3 4388.7 4471.3 4474.6 4550.0 4559.1 1439.8 1761.3 1845.1 1905.3 1979.2 899.8 915.2 916.8 944.3 1048.2 20878.8 21318.8 21453.0 21522.0 21661.5 814.6 894.7 894.7 935.9 948.5 10928.5 11291.7 11326.4 11344.5 11372.8 524167.0 624051.0 633897.0 643418.8 643854.0 96031.5 129852.0 132756.0 133453.3 134885.0 925.1 934.6 937.1 946.3 974.7 14200.8 17427.2 19503.4 19799.6 19870.9 47171.0 48984.0 54076.5 54855.5 55255.7 74322.0 77485.6 78743.0 78960.0 79416.6 7745.7 7767.1 7914.7 7942.0 7979.7 10372.4 12445.0 12651.9 12824.9 12946.3 10437.7 11003.5 11036.1 11101.7 11102.3 8418.8 10691.5 10798.2 11127.4 11351.1 11058.8 11130.3 11264.3 11269.5 11311.2 7910.7 7935.6 8094.0 8134.5 8174.6 10002.5 10311.0 10820.2 11091.0 11106.6 04:45:10 INFO - PROCESS | 1977 | 04:45:10 INFO - PROCESS | 1977 | [#3] mootools.html Cycles:180 Average:5599.47 Median:4944.23 stddev:3107.56 (62.9%) stddev-sans-first:3101.23 04:45:10 INFO - PROCESS | 1977 | Values: 1530.8 1557.9 1717.1 1809.2 1852.2 8172.8 8726.2 8733.7 8735.1 8744.3 7657.0 7854.6 8258.8 8427.6 8501.2 2349.1 2381.4 2382.6 2385.0 2385.4 2356.2 2369.7 2402.1 2403.9 2406.7 1920.9 2119.9 2198.2 2380.1 2385.3 2375.9 2381.0 2398.3 2401.7 2406.6 2366.8 2381.4 2382.8 2385.0 2388.3 8693.9 8747.9 8750.6 8756.2 8763.9 5055.2 5255.1 5280.0 5292.3 5293.3 3426.6 3480.4 3546.1 3616.3 3621.9 1028.6 1063.1 1078.4 1083.5 1086.5 11103.7 11248.9 11267.0 11278.3 11304.7 4484.0 4639.0 4752.5 4762.2 4765.0 9743.1 9745.6 9752.4 9754.3 9782.5 5286.6 5288.7 5290.0 5290.1 5307.4 3948.9 4812.8 4874.2 4875.4 4878.8 3737.6 3767.9 3809.9 3835.8 3871.4 1118.9 1196.5 1199.7 1209.3 1211.0 754.7 874.7 889.7 905.7 962.9 4844.9 5328.3 5835.7 5923.3 5969.3 923.3 957.9 958.4 981.6 1013.5 3731.3 3888.9 3990.6 4038.3 4063.1 9997.3 10341.6 10388.3 10543.9 10592.8 10535.1 10549.5 10550.8 10561.7 10573.9 7902.2 8124.9 8128.1 8128.4 8135.6 5643.6 5804.0 5992.7 6043.1 6068.8 9843.2 9848.7 9880.8 9882.7 9890.4 9446.4 9784.1 9807.0 10018.4 10046.1 7328.3 7506.2 7507.8 7521.4 7525.3 7546.5 7694.4 7799.8 8155.5 8293.4 5902.8 7314.3 7365.6 7407.3 7422.9 7199.6 7213.6 7274.2 7296.6 7413.6 6694.6 6706.9 6765.7 6768.6 6783.0 4799.4 4833.9 4901.0 4911.2 4931.0 4472.0 4801.2 4847.9 4957.5 5065.9 04:45:10 INFO - PROCESS | 1977 | 04:45:10 INFO - PROCESS | 1977 | [#4] prototype.html Cycles:180 Average:4531.18 Median:5185.39 stddev:1436.02 (27.7%) stddev-sans-first:1403.89 04:45:10 INFO - PROCESS | 1977 | Values: 264.8 296.2 296.6 306.3 315.8 5025.0 5056.3 5332.2 5357.4 5366.7 3654.4 4081.6 4705.9 5185.8 5225.5 3127.8 3285.8 3439.6 4083.5 4086.2 3239.0 3654.9 4081.9 4088.2 4098.0 3449.3 3576.0 4040.0 4051.6 4116.6 4074.8 4088.2 4088.3 4095.4 4123.5 3126.0 3524.7 3527.0 3560.9 3565.5 4720.4 5070.5 5222.7 5232.3 5236.8 4290.4 4405.1 4619.3 4657.8 4664.9 4812.9 5344.9 5547.5 5558.6 5563.8 3266.3 3881.1 3887.7 3892.6 3926.0 5384.9 5847.6 5851.6 5913.6 5928.6 3167.5 3399.6 3653.2 3867.7 3884.3 3941.5 3941.5 3944.6 3946.0 3951.7 3635.2 4240.9 4329.2 4563.7 4637.3 4893.4 5140.6 5619.4 5627.3 5652.5 1306.6 1337.9 1339.2 1341.7 1348.7 706.6 839.3 868.5 875.0 905.2 4584.3 5185.0 5185.8 5254.1 5263.6 4649.5 5433.0 5716.7 5730.8 5731.0 3833.9 4120.5 5113.4 5182.9 5193.8 2880.0 3034.3 3044.8 3057.6 3069.2 5294.5 5581.4 6044.5 6045.7 6091.6 5654.6 5967.8 5981.9 5992.3 6023.6 6059.2 6068.5 6076.5 6101.8 6185.9 5223.2 5604.6 5697.8 5840.3 5876.3 5662.4 5784.2 5803.4 5811.2 5937.8 5146.0 5593.4 6081.6 6087.3 6162.2 3648.6 3831.2 3868.1 3877.9 3884.2 5772.5 5795.4 5811.7 5832.1 5841.2 4437.8 4716.1 5670.6 5679.3 5704.5 5538.8 5639.0 5643.9 5645.3 5684.5 5312.8 5511.2 5538.8 5595.4 5600.7 4791.0 5430.6 5548.6 5573.4 5580.6 5489.4 5499.8 5504.2 5522.1 5547.9 04:45:10 INFO - PROCESS | 1977 | 04:45:10 INFO - PROCESS | 1977 | [#5] yui.html Cycles:180 Average:24723.96 Median:3686.69 stddev:39404.18 (1068.8%) stddev-sans-first:39473.45 04:45:10 INFO - PROCESS | 1977 | Values: 704.5 706.0 790.4 839.2 847.2 15168.8 16261.3 16297.3 16320.3 16331.9 14577.8 14768.3 14780.9 14801.9 14822.5 696.6 719.0 846.1 848.0 869.3 865.8 872.4 874.5 877.4 882.1 934.4 937.8 939.6 940.0 945.0 929.9 932.4 932.9 932.9 935.0 532.5 664.4 691.2 693.6 694.0 14654.6 14752.7 14765.0 14766.1 14782.6 13043.9 13147.7 13978.2 14346.5 14378.2 12778.0 12909.3 12921.9 12973.5 13471.5 4610.7 4635.5 4713.1 4839.0 4847.3 121375.0 123123.0 124746.0 124995.4 128447.0 9684.5 10553.3 10692.6 10723.8 11010.1 19038.6 19295.0 21203.0 21236.9 21248.5 6798.8 7724.9 7759.8 7842.8 8021.8 4370.8 4577.2 4624.4 4686.6 4729.9 3520.0 3676.9 3681.2 3684.6 3688.8 653.8 665.2 672.8 681.0 686.5 612.5 636.4 663.2 691.1 697.9 9311.7 9379.0 9526.2 9581.0 9785.8 593.4 642.0 663.7 674.9 679.2 4434.6 4607.3 4649.8 4675.6 4692.8 98513.0 128674.0 141587.3 162235.4 162276.0 118933.0 119208.0 119499.4 121039.4 123895.0 96329.0 115512.4 116397.4 117734.4 121218.0 9054.5 9575.3 9737.0 9737.4 10103.3 46613.3 49313.5 50293.5 55561.4 61368.7 88711.0 90716.0 95092.9 96527.0 100068.5 12874.1 13215.6 13306.8 13699.8 13795.9 14984.3 15020.5 15082.8 15538.2 15554.6 13673.4 13872.2 13925.6 14036.5 14530.6 9720.4 13701.8 13738.3 13969.5 14151.1 13449.7 13532.9 13729.8 13897.7 14037.2 9582.5 10725.8 10847.2 10952.5 11244.1 10297.6 11270.0 11655.4 11684.7 11848.8 04:45:10 INFO - PROCESS | 1977 | -------- Summary: end -------- 04:45:10 INFO - PROCESS | 1977 | 04:45:11 INFO - TEST-INFO | 1977: exit 0 04:45:11 INFO - TEST-OK | dromaeo_css | took 1118829ms 04:45:11 INFO - TEST-START | kraken 04:45:11 INFO - operating with platform_type : mac_ 04:45:11 INFO - Initialising browser for kraken test... 04:45:11 INFO - TEST-INFO | started process 1981 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpyHQizV/profile http://localhost:49565/getInfo.html) 04:45:13 INFO - PROCESS | 1981 | __metrics Screen width/height:1600/1200 04:45:13 INFO - PROCESS | 1981 | colorDepth:24 04:45:13 INFO - PROCESS | 1981 | Browser inner width/height: 1024/689 04:45:13 INFO - PROCESS | 1981 | __metrics 04:45:13 INFO - PROCESS | 1981 | 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" 04:45:13 INFO - PROCESS | 1981 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:45:14 INFO - TEST-INFO | 1981: exit 0 04:45:14 INFO - Browser initialized. 04:45:14 INFO - Running cycle 1/1 for kraken test... 04:45:14 INFO - TEST-INFO | started process 1982 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpyHQizV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:45:26 INFO - PROCESS | 1982 | RSS: Main: 228327424 04:45:26 INFO - PROCESS | 1982 | 04:45:29 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49565/tests/kraken/driver.html?testName=audio-beat-detection) 04:45:30 INFO - PROCESS | 1982 | RSS: Main: 286752768 04:45:30 INFO - PROCESS | 1982 | 04:45:32 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49565/tests/kraken/driver.html?testName=audio-dft) 04:45:33 INFO - PROCESS | 1982 | RSS: Main: 295395328 04:45:33 INFO - PROCESS | 1982 | 04:45:36 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49565/tests/kraken/driver.html?testName=audio-fft) 04:45:37 INFO - PROCESS | 1982 | RSS: Main: 388014080 04:45:37 INFO - PROCESS | 1982 | 04:45:39 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49565/tests/kraken/driver.html?testName=audio-oscillator) 04:45:40 INFO - PROCESS | 1982 | RSS: Main: 290119680 04:45:40 INFO - PROCESS | 1982 | 04:45:42 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49565/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:45:42 INFO - PROCESS | 1982 | RSS: Main: 294256640 04:45:42 INFO - PROCESS | 1982 | 04:45:45 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49565/tests/kraken/driver.html?testName=imaging-darkroom) 04:45:46 INFO - PROCESS | 1982 | RSS: Main: 330510336 04:45:46 INFO - PROCESS | 1982 | 04:45:49 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49565/tests/kraken/driver.html?testName=imaging-desaturate) 04:45:49 INFO - PROCESS | 1982 | RSS: Main: 332845056 04:45:49 INFO - PROCESS | 1982 | 04:45:52 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49565/tests/kraken/driver.html?testName=json-parse-financial) 04:45:53 INFO - PROCESS | 1982 | RSS: Main: 329895936 04:45:53 INFO - PROCESS | 1982 | 04:45:54 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49565/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:45:55 INFO - PROCESS | 1982 | RSS: Main: 300756992 04:45:55 INFO - PROCESS | 1982 | 04:45:57 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49565/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:45:57 INFO - PROCESS | 1982 | RSS: Main: 308514816 04:45:57 INFO - PROCESS | 1982 | 04:45:59 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49565/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:46:00 INFO - PROCESS | 1982 | RSS: Main: 307187712 04:46:00 INFO - PROCESS | 1982 | 04:46:03 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49565/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:46:04 INFO - PROCESS | 1982 | RSS: Main: 313516032 04:46:04 INFO - PROCESS | 1982 | 04:46:07 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49565/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:46:07 INFO - PROCESS | 1982 | RSS: Main: 314642432 04:46:07 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:49565/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49565/tests/kraken/driver.html?testName=ai-astar) 04:46:09 INFO - PROCESS | 1982 | RSS: Main: 358285312 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | __start_tp_report 04:46:09 INFO - PROCESS | 1982 | _x_x_mozilla_page_load 04:46:09 INFO - PROCESS | 1982 | _x_x_mozilla_page_load_details 04:46:09 INFO - PROCESS | 1982 | |i|pagename|runs| 04:46:09 INFO - PROCESS | 1982 | |0;ai-astar;104;113;115;114;110;119;93;155;171;168 04:46:09 INFO - PROCESS | 1982 | |1;audio-beat-detection;143;111;109;170;107;107;131;109;139;107 04:46:09 INFO - PROCESS | 1982 | |2;audio-dft;182;198;261;197;182;183;186;191;183;179 04:46:09 INFO - PROCESS | 1982 | |3;audio-fft;80;58;61;88;60;58;64;65;86;72 04:46:09 INFO - PROCESS | 1982 | |4;audio-oscillator;85;90;96;71;74;68;96;71;68;103 04:46:09 INFO - PROCESS | 1982 | |5;imaging-gaussian-blur;86;86;87;85;88;83;87;86;88;84 04:46:09 INFO - PROCESS | 1982 | |6;imaging-darkroom;93;93;94;93;93;92;93;93;93;92 04:46:09 INFO - PROCESS | 1982 | |7;imaging-desaturate;84;85;86;86;86;84;85;84;86;84 04:46:09 INFO - PROCESS | 1982 | |8;json-parse-financial;58;56;57;54;55;57;56;54;56;54 04:46:09 INFO - PROCESS | 1982 | |9;json-stringify-tinderbox;63;70;69;74;73;68;68;70;69;74 04:46:09 INFO - PROCESS | 1982 | |10;stanford-crypto-aes;88;85;88;86;85;84;90;89;86;81 04:46:09 INFO - PROCESS | 1982 | |11;stanford-crypto-ccm;157;171;151;138;147;150;151;138;159;140 04:46:09 INFO - PROCESS | 1982 | |12;stanford-crypto-pbkdf2;170;173;173;168;170;174;171;171;170;179 04:46:09 INFO - PROCESS | 1982 | |13;stanford-crypto-sha256-iterative;68;69;70;68;68;69;68;68;68;68 04:46:09 INFO - PROCESS | 1982 | __end_tp_report 04:46:09 INFO - PROCESS | 1982 | __start_cc_report 04:46:09 INFO - PROCESS | 1982 | _x_x_mozilla_cycle_collect,2072 04:46:09 INFO - PROCESS | 1982 | __end_cc_report 04:46:09 INFO - PROCESS | 1982 | __startTimestamp1471520769830__endTimestamp 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | ------- Summary: start ------- 04:46:09 INFO - PROCESS | 1982 | Number of tests: 14 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#0] ai-astar Cycles:10 Average:126.20 Median:137.00 stddev:27.77 (20.3%) stddev-sans-first:28.27 04:46:09 INFO - PROCESS | 1982 | Values: 104.0 113.0 115.0 114.0 110.0 119.0 93.0 155.0 171.0 168.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:121.00 stddev:21.68 (17.9%) stddev-sans-first:21.80 04:46:09 INFO - PROCESS | 1982 | Values: 143.0 111.0 109.0 170.0 107.0 107.0 131.0 109.0 139.0 107.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#2] audio-dft Cycles:10 Average:194.20 Median:188.50 stddev:24.36 (12.9%) stddev-sans-first:25.44 04:46:09 INFO - PROCESS | 1982 | Values: 182.0 198.0 261.0 197.0 182.0 183.0 186.0 191.0 183.0 179.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#3] audio-fft Cycles:10 Average:69.20 Median:68.50 stddev:11.58 (16.9%) stddev-sans-first:11.61 04:46:09 INFO - PROCESS | 1982 | Values: 80.0 58.0 61.0 88.0 60.0 58.0 64.0 65.0 86.0 72.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#4] audio-oscillator Cycles:10 Average:82.20 Median:79.50 stddev:13.35 (16.8%) stddev-sans-first:14.12 04:46:09 INFO - PROCESS | 1982 | Values: 85.0 90.0 96.0 71.0 74.0 68.0 96.0 71.0 68.0 103.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:86.50 stddev:1.63 (1.9%) stddev-sans-first:1.73 04:46:09 INFO - PROCESS | 1982 | Values: 86.0 86.0 87.0 85.0 88.0 83.0 87.0 86.0 88.0 84.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 04:46:09 INFO - PROCESS | 1982 | Values: 93.0 93.0 94.0 93.0 93.0 92.0 93.0 93.0 93.0 92.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.50 stddev:0.94 (1.1%) stddev-sans-first:0.93 04:46:09 INFO - PROCESS | 1982 | Values: 84.0 85.0 86.0 86.0 86.0 84.0 85.0 84.0 86.0 84.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:1.42 (2.5%) stddev-sans-first:1.24 04:46:09 INFO - PROCESS | 1982 | Values: 58.0 56.0 57.0 54.0 55.0 57.0 56.0 54.0 56.0 54.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:3.33 (4.8%) stddev-sans-first:2.46 04:46:09 INFO - PROCESS | 1982 | Values: 63.0 70.0 69.0 74.0 73.0 68.0 68.0 70.0 69.0 74.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:87.00 stddev:2.66 (3.1%) stddev-sans-first:2.74 04:46:09 INFO - PROCESS | 1982 | Values: 88.0 85.0 88.0 86.0 85.0 84.0 90.0 89.0 86.0 81.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:151.00 stddev:10.38 (6.9%) stddev-sans-first:10.71 04:46:09 INFO - PROCESS | 1982 | Values: 157.0 171.0 151.0 138.0 147.0 150.0 151.0 138.0 159.0 140.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.00 stddev:3.07 (1.8%) stddev-sans-first:3.18 04:46:09 INFO - PROCESS | 1982 | Values: 170.0 173.0 173.0 168.0 170.0 174.0 171.0 171.0 170.0 179.0 04:46:09 INFO - PROCESS | 1982 | 04:46:09 INFO - PROCESS | 1982 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 04:46:09 INFO - PROCESS | 1982 | Values: 68.0 69.0 70.0 68.0 68.0 69.0 68.0 68.0 68.0 68.0 04:46:09 INFO - PROCESS | 1982 | -------- Summary: end -------- 04:46:09 INFO - PROCESS | 1982 | 04:46:10 INFO - TEST-INFO | 1982: exit 0 04:46:10 INFO - TEST-OK | kraken | took 59537ms 04:46:10 INFO - SUITE-END | took 1178s 04:46:10 INFO - Completed test suite (00:19:38) 04:46:10 INFO - javascript benchmark 04:46:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2841.2896775806043, 3325.5510506081673, 3361.54619126418, 3366.4613661814114, 3378.0879162626084, 5590.217369568264, 5630.493255606994, 5690.231818704474, 5731.598788084836, 5737.569682273831, 5624.3813180550105, 5689.829305120843, 5725.370209276986, 5764.654120752758, 5802.47777700008, 5136.922946155811, 5197.038547868638, 5202.725332293593, 5233.869153271161, 5281.86795330116, 5299.311089558352, 5313.681179129246, 5320.228566857803, 5325.733713314338, 5355.143177091666, 5153.845384638429, 5200.6879587224885, 5205.869853253661, 5213.348331458567, 5279.313689220415, 5223.817166399158, 5236.083685355085, 5243.161094224944, 5246.396664383607, 5257.08001099806, 5140.7172605506685, 5191.5846732261325, 5193.63644544882, 5204.193350030751, 5224.895502089975, 5637.830865074055, 5673.716314184274, 5687.658740475544, 5758.596898217126, 5759.3376761672525, 6206.689665516707, 6236.064590311445, 6251.343608921044, 6274.0, 6280.905786413207, 6076.058210977261, 6191.87616247677, 6241.469475094623, 6284.622922624657, 6322.462590679797, 5793.420657934173, 5799.449052340021, 5827.271591051119, 5837.153612726216, 5839.153322768175, 6298.905516417258, 6311.96844015777, 6317.178766760292, 6319.368063193644, 6344.333844946306, 5750.166225897222, 5827.76688932438, 5830.833833233371, 5902.203202567598, 6147.108669242934, 5994.610350327215, 6068.757249709962, 6074.514038876968, 6084.148219249309, 6096.0, 5932.58471906971, 5943.078822782476, 5946.791862284799, 5959.28488581373, 5959.4934430573885, 5883.911741323884, 5953.404659534012, 5982.611130276518, 6135.01831721223, 6190.350013248634, 4972.328735620717, 4983.9782844516785, 5014.674046186986, 5028.170351891897, 5033.874153146201, 4324.329728892027, 4445.599896009375, 4448.844290449819, 4454.265046267395, 4497.955020449819, 5618.6628802272, 5697.401772813878, 5755.539556835443, 5757.539396848242, 5761.9135712964335, 6204.317525072329, 6253.218347706536, 6281.120643109968, 6337.556371054075, 6341.778037768656, 5730.598858079894, 5733.455321744427, 5744.281964754406, 5752.223449834302, 5790.247267855236, 5345.3852806926925, 5396.703181325065, 5423.945760542344, 5439.3200849893765, 5445.782168713286, 6252.937470625236, 6270.968645156745, 6316.115743795965, 6316.399942005502, 6317.652529110943, 6315.5894866833505, 6333.240011198685, 6335.65153916539, 6342.49260059185, 6373.521985850987, 6253.155823963707, 6312.968435157794, 6319.988801791691, 6332.366763323631, 6344.904826427515, 6112.480439162721, 6115.057807494094, 6129.847850912965, 6159.876802464015, 6206.224221972254, 6279.874402512016, 6299.874002520016, 6307.148534947723, 6307.990721484541, 6319.652419116993, 5980.8803823924145, 6150.077488376779, 6152.354002829818, 6160.414760597736, 6178.0, 6045.0, 6062.302835173835, 6087.756489740361, 6142.188054524016, 6148.4466398022605, 5941.494972927349, 5961.910571341346, 5981.551383646331, 5998.4601385875485, 6004.669743164168, 5951.821445356646, 5966.731497082728, 6133.6319820810895, 6135.07973803933, 6138.416850399205, 5791.652500849962, 5857.560682948882, 5894.528437724886, 5905.645661260338, 5936.8812623748145], "value": 5691.525720069273, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.1365732883414, 521.3379008659002, 525.6189262784479, 540.8242321245593, 549.9780008799643, 30701.84649076757, 40269.99325016872, 41225.76322710316, 41475.0, 41829.163416731644, 42433.78783106088, 42668.57331426685, 42848.714538563814, 42871.0, 42978.78510607443, 7316.378107860838, 9699.612015519371, 9884.406935583887, 9887.209023278156, 9895.851562226573, 7609.923900761005, 11922.344271065089, 12186.878131218664, 12188.268703877795, 12462.003039756797, 12230.449629766637, 12282.631521054383, 12295.877041229607, 12300.507979680802, 12304.38478076097, 12088.093392995517, 12177.208481448677, 12179.025677945743, 12203.02375809933, 12206.389680516028, 11930.508686414198, 12007.93996030023, 12013.819792703029, 12036.759264814655, 12052.337121458317, 39655.8017209915, 39905.40141897874, 39981.60018399808, 39989.200215995515, 39999.600003999876, 3350.698437140669, 3409.9659003409897, 3519.03226612681, 3610.9277814443562, 3748.306563285801, 30684.53973190404, 31103.84448077745, 31786.682133178605, 31821.84089079563, 32199.51700724491, 3279.8196099214533, 3289.490129030004, 3387.4072037393603, 3388.8305584720665, 3431.365197438482, 414566.999999997, 468775.0, 474407.0, 483937.0, 484258.0, 33193.502097468554, 46949.76525117353, 56865.71567142138, 56997.43002570004, 57111.0, 33591.99224023283, 34819.82590087033, 34934.47598286028, 35084.29831403358, 35094.47358289628, 14494.782578261334, 14652.926735366467, 14657.633559160893, 14682.63293417673, 14684.706305873822, 11269.661910142544, 11427.48576314068, 12350.505979760888, 12438.129330946927, 12452.564160254347, 7310.122785265802, 7370.520916140434, 7391.482596218213, 7599.848003039909, 7770.378369730408, 3227.064151396069, 3233.4826427771445, 3234.450143475615, 3239.8704051838135, 3268.9836550817095, 843.5908584336592, 932.1424289653535, 969.7236287657984, 995.7510622344414, 1064.2497039587072, 29450.852745736134, 29981.950631727785, 30042.0, 30639.540406893913, 30693.386132277676, 993.4734590666958, 1033.7674023344837, 1045.8431235314763, 1056.1023130339304, 1156.2773266708307, 14813.006590975832, 15125.697486050218, 15585.766213506806, 15685.215739212994, 16035.0, 646507.0, 651568.0, 655324.0, 659366.0, 659750.0, 57831.0, 58052.0, 58129.70935145297, 58171.70914145402, 58269.0, 1241.1932244041445, 1274.3564499927663, 1364.1542243808901, 1386.9306534673428, 1404.1434724817852, 28418.28954276077, 30649.31428771439, 30724.846375768873, 31436.528452073693, 31535.36929261448, 56120.0, 56917.0, 56962.430375695716, 57027.42972570221, 57208.71395642995, 610915.0, 618074.0, 619090.0, 625938.0, 625944.9244060373, 22942.770572294732, 26910.32724181911, 27386.0, 28559.714402855705, 42889.35665964951, 33278.83360583182, 40007.99980000523, 46920.29619555641, 47843.0, 50891.0, 33930.49104263487, 39048.21903561856, 39557.208855822144, 40376.79811601041, 41180.0, 36150.8192459036, 39257.0, 39361.015974600865, 39361.80319098386, 40724.38913416242, 38298.42552361773, 38630.80684596559, 38698.0, 38711.806440967615, 39003.0, 30268.09195724132, 32219.35561288808, 33391.827776361206, 34301.48547771735, 35548.28903421969, 22528.774712252667, 22754.330348713804, 25541.744582553936, 25967.610485843106, 26201.820918059115], "value": 19132.717607405084, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.88628979857043, 372.5827073677482, 374.0704349691018, 394.1250424058589, 403.96970227232964, 20418.748750749543, 21367.679484807722, 21598.89200553995, 21984.670229946565, 22136.88931555344, 20755.16979320829, 21189.894050529765, 21408.357749267507, 21703.45741356467, 21749.0, 5892.469677729004, 5923.378045305246, 6056.969715151418, 6132.356102609228, 6136.9079463808075, 4218.346156345771, 4438.378626992224, 6025.789097381593, 6150.0, 6499.480041596661, 6674.032265321525, 6761.459083273351, 6779.76270830521, 6787.592744435325, 6815.216250131249, 6471.352864713538, 6641.966790166067, 6715.227748808852, 6724.226713927912, 6739.494537909627, 4748.667593268473, 5461.9453805462235, 5592.909382670381, 5823.068309070529, 5827.79602713903, 12793.48826046957, 12847.935760321232, 13022.934885325607, 13033.804492932613, 13035.0, 2106.29439137889, 2131.3286314810784, 2236.262033528948, 2434.866082365469, 2936.3540021195304, 9624.95187524065, 9641.0, 9657.275704322205, 9711.417314961125, 9793.90206097937, 1774.6361995790833, 1782.9019403932778, 1805.5847155154388, 1815.700409432442, 1852.7498787663628, 128651.35674321569, 130760.69239307675, 131778.18661760553, 132532.0, 134994.17555332227, 9462.492439503538, 11317.773644527064, 11329.37688427128, 11447.771044579062, 11663.533458661559, 13892.86107138936, 14393.78409323861, 14402.423903043971, 14421.92789036048, 14423.278836058154, 8521.914780852236, 8541.103184165697, 8550.700725474702, 8669.653213871501, 8708.433951793113, 8511.74464766058, 8518.403711740242, 8524.872126917977, 8700.869486957701, 8718.346124040723, 4388.692791504564, 4471.28459446487, 4474.597286244189, 4550.0, 4559.133764584718, 1439.7624391975207, 1761.339497688367, 1845.0590198998607, 1905.3045638341905, 1979.237993372562, 899.8335307967916, 915.2403505090758, 916.8028873792167, 944.2729098594182, 1048.2400259811575, 20878.79121208799, 21318.82746448929, 21453.034613442433, 21522.031508582153, 21661.458463538536, 814.5927036481759, 894.7136916186757, 894.7226359828474, 935.9344845860725, 948.4735971535864, 10928.453577321103, 11291.68520453305, 11326.37704926237, 11344.48949797261, 11372.805855385232, 524167.0, 624051.0, 633897.0, 643418.7829060825, 643854.0, 96031.51984240035, 129852.0, 132756.0086598702, 133453.32373410102, 134885.0, 925.0703043441298, 934.56075644447, 937.1425145991478, 946.2950102173787, 974.7075877236658, 14200.786988195392, 17427.21577528989, 19503.414897553095, 19799.60400792005, 19870.90064549668, 47171.0, 48984.0, 54076.45923540714, 54855.45144548663, 55255.72372138114, 74322.0, 77485.61257193904, 78743.0, 78960.0, 79416.60291698505, 7745.728899488491, 7767.145613982344, 7914.6834126636595, 7942.0, 7979.680812767424, 10372.377657340583, 12445.0, 12651.86509710433, 12824.871751282368, 12946.287954162612, 10437.68686939393, 11003.504842282277, 11036.061934735637, 11101.666949991513, 11102.333859968428, 8418.831623367621, 10691.465426728788, 10798.244122911636, 11127.443627818415, 11351.091912646803, 11058.778824423627, 11130.332180069221, 11264.324140551265, 11269.549218031187, 11311.151663625425, 7910.655188618064, 7935.563544005136, 8094.0, 8134.468561378083, 8174.632141553763, 10002.549885255326, 10311.0, 10820.18848586375, 11091.0, 11106.611673540807], "value": 9400.31139602033, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1530.7550791873302, 1557.9298931548078, 1717.1156854220078, 1809.2220345251537, 1852.2313240005399, 8172.795680107993, 8726.1710137537, 8733.737987860359, 8735.082816304284, 8744.256738177246, 7656.96171519143, 7854.607269636524, 8258.83482330353, 8427.578621068953, 8501.234888860017, 2349.0721165139757, 2381.416552944522, 2382.5711371953043, 2385.0340612052146, 2385.42749740062, 2356.175338631474, 2369.727481339642, 2402.0872068613903, 2403.8677872716994, 2406.68713067301, 1920.894350810705, 2119.9364019079453, 2198.1756841184556, 2380.131252092991, 2385.2605692235375, 2375.857448553092, 2381.0475809676095, 2398.280515845257, 2401.7358090610105, 2406.554787364343, 2366.810655147584, 2381.38084098134, 2382.845115067515, 2384.9880750596135, 2388.2835149455095, 8693.869591956125, 8747.868781968276, 8750.562471876381, 8756.168164024408, 8763.868541971875, 5055.165897626887, 5255.080360936859, 5279.996800607911, 5292.338457692788, 5293.258943747878, 3426.640202778697, 3480.443129099357, 3546.131197856516, 3616.276744651054, 3621.8551257949653, 1028.5775919125726, 1063.1388575254068, 1078.3529882070773, 1083.496174278964, 1086.473060565625, 11103.666889993232, 11248.887511124865, 11266.985971262544, 11278.323300601991, 11304.660860174208, 4483.955160448419, 4638.95361046392, 4752.453467851172, 4762.166620841339, 4765.0, 9743.074407931235, 9745.56144973478, 9752.366096203725, 9754.317197796086, 9782.461964592016, 5286.550643195372, 5288.65623734456, 5290.047791397508, 5290.074237008508, 5307.416184219733, 3948.8815335539407, 4812.759362031885, 4874.171390863562, 4875.390576177978, 4878.780454879468, 3737.626237376295, 3767.9246415071543, 3809.904752381213, 3835.827387767557, 3871.4386413969814, 1118.904893084097, 1196.5273716881784, 1199.7000749812546, 1209.2986068080493, 1210.9939450302693, 754.7169811320667, 874.7200895713438, 889.6530353162271, 905.7328088213993, 962.9374090684083, 4844.854654360375, 5328.307320048369, 5835.67903765297, 5923.259592550931, 5969.343372229051, 923.3121324613206, 957.8658987741721, 958.3674774648698, 981.5632043740467, 1013.4729940430934, 3731.3097077040834, 3888.922221555553, 3990.620891015349, 4038.273110840077, 4063.065494936181, 9997.300188986701, 10341.638042668621, 10388.324758890649, 10543.947280263703, 10592.841107383396, 10535.104516116062, 10549.525271362807, 10550.841737373945, 10561.683149505527, 10573.894261057445, 7902.17027212146, 8124.918750812534, 8128.065272493739, 8128.431009829374, 8135.593220338959, 5643.633163844337, 5804.0, 5992.700364981646, 6043.063325184604, 6068.757249709962, 9843.163331116935, 9848.655297064568, 9880.752981175241, 9882.703518894445, 9890.406575605199, 9446.433214007182, 9784.070513301225, 9806.950965245414, 10018.398896065968, 10046.146077583488, 7328.303811137934, 7506.1743208248145, 7507.849843003, 7521.398288137046, 7525.3227209551405, 7546.547207167588, 7694.384449243935, 7799.766007019798, 8155.469894456842, 8293.377996650395, 5902.763889444374, 7314.268573142643, 7365.558066516026, 7407.333339999426, 7422.925770742439, 7199.568025918462, 7213.6032518212005, 7274.16347120088, 7296.635168241673, 7413.629318533944, 6694.564853284521, 6706.865862682622, 6765.695543700447, 6768.56004359715, 6783.0, 4799.424069111729, 4833.903321933612, 4900.95099049005, 4911.165101932608, 4931.013797240495, 4471.977640111779, 4801.18379875429, 4847.878803029953, 4957.454679985271, 5065.885505188852], "value": 4520.168490031488, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.7855237257821, 296.2312798288442, 296.6277321960939, 306.32608261823987, 315.80262336039976, 5025.0, 5056.266841308013, 5332.226827110067, 5357.4374690657505, 5366.731663416824, 3654.41033150579, 4081.571434999327, 4705.858824235278, 5185.781341384772, 5225.477452254772, 3127.81233126012, 3285.802851828898, 3439.6044454887756, 4083.4691490106243, 4086.1827634473225, 3239.0282915125485, 3654.945175822365, 4081.8977577041514, 4088.2232375848494, 4098.016476045757, 3449.2584094419694, 3575.964240357589, 4040.0103923096844, 4051.5948405159543, 4116.629503344699, 4074.8370065197355, 4088.2232375848494, 4088.34586466164, 4095.4266402703497, 4123.546409894909, 3126.0, 3524.7134795799607, 3526.9771766187773, 3560.9109772255642, 3565.5008298838184, 4720.433547974265, 5070.543651071422, 5222.686638801646, 5232.3459567554055, 5236.816711415082, 4290.442242508485, 4405.061772819949, 4619.284010978304, 4657.790399432034, 4664.906701865943, 4812.927806082912, 5344.893102137974, 5547.478804774096, 5558.555315574744, 5563.805266815642, 3266.3140740444296, 3881.068543549556, 3887.7472964257518, 3892.610738926113, 3926.038120660428, 5384.892302153935, 5847.56143289255, 5851.561132915006, 5913.645181289136, 5928.584999050068, 3167.479609787315, 3399.5750531183603, 3653.1962968146963, 3867.7099217558793, 3884.320243957296, 3941.4876066111224, 3941.507311586052, 3944.560235514074, 3946.0, 3951.723379363417, 3635.2184280379315, 4240.872773816791, 4329.20338059708, 4563.726176429424, 4637.327587499861, 4893.412790465096, 5140.558234838421, 5619.4099619540175, 5627.268455100892, 5652.491275785199, 1306.5949555637592, 1337.9331033448289, 1339.2165583133976, 1341.7387655603702, 1348.6965432777743, 706.5795851468368, 839.2740279658174, 868.5483548554843, 874.9956250218836, 905.2169873059887, 4584.33527138563, 5185.0, 5185.792568297301, 5254.106801843695, 5263.578913686896, 4649.535046495391, 5433.0, 5716.742746576414, 5730.828075157752, 5731.0, 3833.9233215336094, 4120.464339635888, 5113.386393632788, 5182.8963420732125, 5193.818216362485, 2879.985600071986, 3034.2717747740826, 3044.8325342105954, 3057.5872257245433, 3069.2326918270433, 5294.549963253166, 5581.386047534768, 6044.516438684895, 6045.667488288098, 6091.634501929899, 5654.62505747587, 5967.820965370959, 5981.910271345933, 5992.280926288873, 6023.6385816851125, 6059.21230240066, 6068.484178844759, 6076.513878889678, 6101.84745381369, 6185.938140618716, 5223.242629818731, 5604.579656525695, 5697.7720891165545, 5840.328362238397, 5876.324222714443, 5662.405447427961, 5784.21913041734, 5803.39064398249, 5811.169481613279, 5937.832691067642, 5146.022255771391, 5593.384727680033, 6081.631632882566, 6087.317173110381, 6162.198914141133, 3648.58041325251, 3831.1571454280015, 3868.129670824042, 3877.903052423712, 3884.223155368994, 5772.480476757112, 5795.449432303924, 5811.738471768696, 5832.066869301061, 5841.211436456194, 4437.844675436344, 4716.127516409475, 5670.603057785916, 5679.302331392555, 5704.486596206361, 5538.750756216061, 5639.0, 5643.858903527444, 5645.266115404971, 5684.545236381162, 5312.760925758427, 5511.173324001432, 5538.8121691932265, 5595.440455954372, 5600.691961942132, 4790.976045119752, 5430.642339415146, 5548.583856210719, 5573.442655734394, 5580.576952876991, 5489.4318692849965, 5499.780008799763, 5504.22940788282, 5522.116461366162, 5547.944520554905], "value": 4071.104187865613, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.4822055788994, 705.9858802823941, 790.3953475591173, 839.2488722593283, 847.1613103028004, 15168.772468412968, 16261.34954601816, 16297.266623001964, 16320.265588048536, 16331.918340408281, 14577.77244599065, 14768.335424905878, 14780.926095369508, 14801.851981480182, 14822.481213157542, 696.5646470955653, 718.985620287594, 846.1284876577135, 848.0290067872282, 869.3306154261215, 865.8268346330727, 872.3544577013029, 874.4534665833854, 877.3726785348474, 882.0606054551881, 934.387975875799, 937.8358787212245, 939.642935684439, 940.0411580188204, 944.9622015119387, 929.9023602521773, 932.4312169576552, 932.8927173375081, 932.9160375566198, 934.9532523373804, 532.5074306266688, 664.3522565498648, 691.1844024051617, 693.6219760230687, 693.9895901561481, 14654.633634159125, 14752.704945901129, 14765.0, 14766.114033158045, 14782.556523304318, 13043.934780326132, 13147.685231476871, 13978.231197284145, 14346.50758140691, 14378.209198494078, 12778.0, 12909.289989050598, 12921.87078129216, 12973.481060757558, 13471.528496502673, 4610.746408947506, 4635.46692130406, 4713.080949214905, 4839.0, 4847.297141914438, 121375.0, 123123.0, 124746.0, 124995.3750231243, 128447.0, 9684.46735429544, 10553.261271710906, 10692.62575809843, 10723.83914241287, 11010.064144547643, 19038.619227615647, 19295.0, 21203.0, 21236.893815530824, 21248.468788280108, 6798.830029249309, 7724.92275077242, 7759.7672069838, 7842.803929901798, 8021.839563208703, 4370.822666918975, 4577.176108300538, 4624.375709279271, 4686.625069994392, 4729.858104256878, 3520.0, 3676.9080772980356, 3681.208540163878, 3684.5946945835935, 3688.7786732796117, 653.8398092467376, 665.1851481934688, 672.825065482978, 681.0465348512123, 686.5194363945259, 612.5085417009595, 636.3572791480638, 663.2174034639075, 691.1464341538197, 697.8639165362706, 9311.72064838056, 9378.953105234566, 9526.190023895439, 9580.95209523948, 9785.755356116155, 593.4243783529969, 641.9807405777834, 663.7212370804274, 674.942629876466, 679.1578442730978, 4434.578715022132, 4607.3319368691355, 4649.837255696035, 4675.579197872208, 4692.81228750853, 98513.01486985039, 128674.0, 141587.29206354107, 162235.3776462244, 162276.0, 118933.0, 119208.0, 119499.40250298692, 121039.39480302543, 123895.0, 96329.03670963201, 115512.42243788728, 116397.41801290939, 117734.41132794453, 121218.0, 9054.502002389932, 9575.281853861128, 9737.0, 9737.415755054719, 10103.292769506357, 46613.30080048734, 49313.50686493089, 50293.49706502888, 55561.444385555624, 61368.69315653393, 88711.0, 90716.0, 95092.91376363086, 96527.0, 100068.49965750416, 12874.098813082994, 13215.60353189406, 13306.800397994231, 13699.794503082661, 13795.862041379458, 14984.325705343505, 15020.549383518512, 15082.773758393414, 15538.223088845287, 15554.611134721723, 13673.384697688827, 13872.167669939836, 13925.582232533041, 14036.508722194674, 14530.636734081732, 9720.424786845268, 13701.794473083111, 13738.313084345542, 13969.511067112602, 14151.080179788281, 13449.663758406117, 13532.86467135316, 13729.794053089012, 13897.652558686113, 14037.227952462712, 9582.520873956135, 10725.839112413163, 10847.24069315172, 10952.507137179005, 11244.100471962387, 10297.588096476056, 11270.0, 11655.417229138679, 11684.70788230301, 11848.763024739284], "value": 7920.681898370548, "unit": "score"}], "name": "dromaeo_css", "value": 7282.888603804702, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 115.0, 114.0, 110.0, 119.0, 93.0, 155.0, 171.0, 168.0], "value": 126.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 109.0, 170.0, 107.0, 107.0, 131.0, 109.0, 139.0, 107.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 198.0, 261.0, 197.0, 182.0, 183.0, 186.0, 191.0, 183.0, 179.0], "value": 194.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 61.0, 88.0, 60.0, 58.0, 64.0, 65.0, 86.0, 72.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 90.0, 96.0, 71.0, 74.0, 68.0, 96.0, 71.0, 68.0, 103.0], "value": 82.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 85.0, 88.0, 83.0, 87.0, 86.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 92.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 86.0, 86.0, 84.0, 85.0, 84.0, 86.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 57.0, 54.0, 55.0, 57.0, 56.0, 54.0, 56.0, 54.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 69.0, 74.0, 73.0, 68.0, 68.0, 70.0, 69.0, 74.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 85.0, 84.0, 90.0, 89.0, 86.0, 81.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 171.0, 151.0, 138.0, 147.0, 150.0, 151.0, 138.0, 159.0, 140.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 173.0, 168.0, 170.0, 174.0, 171.0, 171.0, 170.0, 179.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 70.0, 68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1461.2000000000003, "alertThreshold": 2.0}]} 04:46:11 INFO - Return code: 0 04:46:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:46:11 INFO - # TBPL SUCCESS # 04:46:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:46:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:46:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:46:11 INFO - 2016-08-18 04:46:11.155 screenresolution[1986:16203] starting screenresolution argv=screenresolution get 04:46:11 INFO - 2016-08-18 04:46:11.158 screenresolution[1986:16203] Display 0: 1600x1200x32@60 04:46:11 INFO - 2016-08-18 04:46:11.164 screenresolution[1987:16206] starting screenresolution argv=screenresolution list 04:46:11 INFO - Available Modes on Display 0 04:46:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:46:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:46:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:46:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:46:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:46:11 INFO - Intel Iris: 04:46:11 INFO - Chipset Model: Intel Iris 04:46:11 INFO - Type: GPU 04:46:11 INFO - Bus: Built-In 04:46:11 INFO - VRAM (Dynamic, Max): 1536 MB 04:46:11 INFO - Vendor: Intel (0x8086) 04:46:11 INFO - Device ID: 0x0a2e 04:46:11 INFO - Revision ID: 0x0009 04:46:11 INFO - Displays: 04:46:11 INFO - Display: 04:46:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:46:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:46:11 INFO - Display Serial Number: 1600x1200 60 04:46:11 INFO - Main Display: Yes 04:46:11 INFO - Mirror: Off 04:46:11 INFO - Online: Yes 04:46:11 INFO - Rotation: Supported 04:46:11 INFO - Return code: 0 04:46:11 INFO - Running post-action listener: _resource_record_post_action 04:46:11 INFO - [mozharness: 2016-08-18 11:46:11.399683Z] Finished run-tests step (success) 04:46:11 INFO - Running post-run listener: _resource_record_post_run 04:46:11 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1183744; Write bytes: 703180288; Read time: 14; Write time: 1938 04:46:11 INFO - TinderboxPrint: CPU usage
53.7% 04:46:11 INFO - TinderboxPrint: I/O read bytes / time
1,183,744 / 14 04:46:11 INFO - TinderboxPrint: I/O write bytes / time
703,180,288 / 1,938 04:46:11 INFO - TinderboxPrint: CPU idle
2,248.5 (46.3%) 04:46:11 INFO - TinderboxPrint: CPU system
973.0 (20.0%) 04:46:11 INFO - TinderboxPrint: CPU user
1,632.1 (33.6%) 04:46:11 INFO - TinderboxPrint: Swap in / out
406,867,968 / 0 04:46:11 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400628224; Write bytes: 394467328; Read time: 29683; Write time: 803 04:46:11 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1183744; Write bytes: 294708736; Read time: 14; Write time: 1092 04:46:11 INFO - Running post-run listener: _upload_blobber_files 04:46:11 INFO - Blob upload gear active. 04:46:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:46:11 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 04:46:11 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'] 04:46:11 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 04:46:12 INFO - (blobuploader) - INFO - Open directory for files ... 04:46:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:46:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:46:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:13 INFO - (blobuploader) - INFO - Done attempting. 04:46:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:46:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:46:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:14 INFO - (blobuploader) - INFO - Done attempting. 04:46:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:46:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:46:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:16 INFO - (blobuploader) - INFO - Done attempting. 04:46:16 INFO - (blobuploader) - INFO - Iteration through files over. 04:46:16 INFO - Return code: 0 04:46:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:46:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:46:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab9749c0abd15c382a672172de30b9f7fc11acd2bbbed26f62c37eef2933aa1338959028e8fff45950a8613cd9643d3de04ecb3bbd86c501c9daa14389795c2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33035a42bcafb3113334eb45bce2e20f50a13a6ba111fb4e7e28e3a1e64524e76fedbe50b46b3901e14535fc85c7983771ff7bd35405ae74d9989957a278bdb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b8c0a6eed16cead5c202e152d088fc18e9aa3b137c8eeefe5a15cb540992990fa257994b1dcab00c89f09f9279951e5921ab11020051222d6a8394e6a2af513"} 04:46:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:46:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:46:16 INFO - Contents: 04:46:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab9749c0abd15c382a672172de30b9f7fc11acd2bbbed26f62c37eef2933aa1338959028e8fff45950a8613cd9643d3de04ecb3bbd86c501c9daa14389795c2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33035a42bcafb3113334eb45bce2e20f50a13a6ba111fb4e7e28e3a1e64524e76fedbe50b46b3901e14535fc85c7983771ff7bd35405ae74d9989957a278bdb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b8c0a6eed16cead5c202e152d088fc18e9aa3b137c8eeefe5a15cb540992990fa257994b1dcab00c89f09f9279951e5921ab11020051222d6a8394e6a2af513"} 04:46:16 INFO - Running post-run listener: copy_logs_to_upload_dir 04:46:16 INFO - Copying logs to upload dir... 04:46:16 INFO - mkdir: /builds/slave/test/build/upload/logs 04:46:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.579815 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-18 04:46:16.664896) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:46:16.668380) ========= 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.dnsFqJ3oUh/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.pInG160nWk/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab9749c0abd15c382a672172de30b9f7fc11acd2bbbed26f62c37eef2933aa1338959028e8fff45950a8613cd9643d3de04ecb3bbd86c501c9daa14389795c2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33035a42bcafb3113334eb45bce2e20f50a13a6ba111fb4e7e28e3a1e64524e76fedbe50b46b3901e14535fc85c7983771ff7bd35405ae74d9989957a278bdb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b8c0a6eed16cead5c202e152d088fc18e9aa3b137c8eeefe5a15cb540992990fa257994b1dcab00c89f09f9279951e5921ab11020051222d6a8394e6a2af513"} build_url:https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011423 build_url: 'https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab9749c0abd15c382a672172de30b9f7fc11acd2bbbed26f62c37eef2933aa1338959028e8fff45950a8613cd9643d3de04ecb3bbd86c501c9daa14389795c2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33035a42bcafb3113334eb45bce2e20f50a13a6ba111fb4e7e28e3a1e64524e76fedbe50b46b3901e14535fc85c7983771ff7bd35405ae74d9989957a278bdb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b8c0a6eed16cead5c202e152d088fc18e9aa3b137c8eeefe5a15cb540992990fa257994b1dcab00c89f09f9279951e5921ab11020051222d6a8394e6a2af513"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:46:16.701966) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:46:16.702306) ========= 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.dnsFqJ3oUh/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.pInG160nWk/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.005366 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:46:16.751015) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:46:16.751347) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:46:16.751696) ========= ========= Total master_lag: 0.19 =========