builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0216 starttime: 1471519503.22 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.224198) ========= 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.224541) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.224815) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.255943) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.256240) ========= 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.cTSVpNurF6/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.RpKcWMXhyp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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% 7.79M=0.001s 2016-08-18 04:25:03 (7.79 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091531 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.372762) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.373091) ========= 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.cTSVpNurF6/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.RpKcWMXhyp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.020173 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:03.424031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 04:25:03.424383) ========= 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.cTSVpNurF6/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.RpKcWMXhyp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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,509 truncating revision to first 12 chars 2016-08-18 04:25:03,509 Setting DEBUG logging. 2016-08-18 04:25:03,509 attempt 1/10 2016-08-18 04:25:03,509 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,229 unpacking tar archive at: mozilla-central-b25d09b7fab5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.994848 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 04:25:04.568200) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:04.568543) ========= script_repo_revision: b25d09b7fab57ddb82f14916a443eb34fda31137 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:04.568931) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:04.569314) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:25:04.590088) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-18 04:25:04.590716) ========= /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.cTSVpNurF6/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.RpKcWMXhyp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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:04 INFO - MultiFileLogger online at 20160818 04:25:04 in /builds/slave/test 04:25:04 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:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:25:04 INFO - {'append_to_log': False, 04:25:04 INFO - 'base_work_dir': '/builds/slave/test', 04:25:04 INFO - 'blob_upload_branch': 'Firefox', 04:25:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:25:04 INFO - 'branch': 'Firefox', 04:25:04 INFO - 'buildbot_json_path': 'buildprops.json', 04:25:04 INFO - 'config_files': ('talos/mac_config.py',), 04:25:04 INFO - 'default_actions': ('clobber', 04:25:04 INFO - 'read-buildbot-config', 04:25:04 INFO - 'download-and-extract', 04:25:04 INFO - 'populate-webroot', 04:25:04 INFO - 'create-virtualenv', 04:25:04 INFO - 'install', 04:25:04 INFO - 'run-tests'), 04:25:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:25:04 INFO - 'download_minidump_stackwalk': True, 04:25:04 INFO - 'download_symbols': 'ondemand', 04:25:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:25:04 INFO - 'tooltool.py': '/tools/tooltool.py', 04:25:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:25:04 INFO - '/tools/misc-python/virtualenv.py')}, 04:25:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:25:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:25:04 INFO - 'installer_path': 'installer.exe', 04:25:04 INFO - 'log_level': 'info', 04:25:04 INFO - 'log_name': 'talos', 04:25:04 INFO - 'log_to_console': True, 04:25:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:25:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:25:04 INFO - 'opt_config_files': (), 04:25:04 INFO - 'pip_index': False, 04:25:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:25:04 INFO - 'cmd': ('bash', 04:25:04 INFO - '-c', 04:25:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:25:04 INFO - 'enabled': True, 04:25:04 INFO - 'halt_on_failure': False, 04:25:04 INFO - 'name': 'check_screen_resolution'},), 04:25:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:25:04 INFO - 'cmd': ('bash', 04:25:04 INFO - '-c', 04:25:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:25:04 INFO - 'enabled': True, 04:25:04 INFO - 'halt_on_failure': False, 04:25:04 INFO - 'name': 'check_screen_resolution'},), 04:25:04 INFO - 'run_cmd_checks_enabled': True, 04:25:04 INFO - 'sps_profile': False, 04:25:04 INFO - 'sps_profile_interval': 0, 04:25:04 INFO - 'suite': 'dromaeojs', 04:25:04 INFO - 'system_bits': '32', 04:25:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:25:04 INFO - 'title': 't-yosemite-r7-0216', 04:25:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:25:04 INFO - 'use_talos_json': True, 04:25:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:25:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:25:04 INFO - 'work_dir': 'build'} 04:25:04 INFO - [mozharness: 2016-08-18 11:25:04.782707Z] Running clobber step. 04:25:04 INFO - Running pre-action listener: _resource_record_pre_action 04:25:04 INFO - Running main action method: clobber 04:25:04 INFO - rmtree: /builds/slave/test/build 04:25:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:25:05 INFO - Running post-action listener: _resource_record_post_action 04:25:05 INFO - [mozharness: 2016-08-18 11:25:05.651552Z] Finished clobber step (success) 04:25:05 INFO - [mozharness: 2016-08-18 11:25:05.651757Z] Running read-buildbot-config step. 04:25:05 INFO - Running pre-action listener: _resource_record_pre_action 04:25:05 INFO - Running main action method: read_buildbot_config 04:25:05 INFO - Using buildbot properties: 04:25:05 INFO - { 04:25:05 INFO - "project": "", 04:25:05 INFO - "product": "firefox", 04:25:05 INFO - "script_repo_revision": "production", 04:25:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:25:05 INFO - "repository": "", 04:25:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:25:05 INFO - "buildid": "20160817163516", 04:25:05 INFO - "pgo_build": "False", 04:25:05 INFO - "basedir": "/builds/slave/test", 04:25:05 INFO - "buildnumber": 63, 04:25:05 INFO - "slavename": "t-yosemite-r7-0216", 04:25:05 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:25:05 INFO - "platform": "yosemite_r7", 04:25:05 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:25:05 INFO - "branch": "mozilla-central", 04:25:05 INFO - "repo_path": "mozilla-central", 04:25:05 INFO - "revision": "b25d09b7fab57ddb82f14916a443eb34fda31137", 04:25:05 INFO - "stage_platform": "macosx64", 04:25:05 INFO - "builduid": "d5c059133172493a96b3248b810652c9", 04:25:05 INFO - "slavebuilddir": "test" 04:25:05 INFO - } 04:25:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 04:25:05 INFO - Running post-action listener: _resource_record_post_action 04:25:05 INFO - [mozharness: 2016-08-18 11:25:05.654796Z] Finished read-buildbot-config step (success) 04:25:05 INFO - [mozharness: 2016-08-18 11:25:05.654905Z] Running download-and-extract step. 04:25:05 INFO - Running pre-action listener: _resource_record_pre_action 04:25:05 INFO - Running main action method: download_and_extract 04:25:05 INFO - mkdir: /builds/slave/test/build/tests 04:25:05 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:05 INFO - trying https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 04:25:05 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:05 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:06 INFO - Downloaded 1199 bytes. 04:25:06 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:25:06 INFO - Using the following test package requirements: 04:25:06 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:25:06 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:06 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 04:25:06 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:06 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 04:25:06 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:06 INFO - u'jsshell-mac.zip'], 04:25:06 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:06 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 04:25:06 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:25:06 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:06 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 04:25:06 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:06 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 04:25:06 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:06 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 04:25:06 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:25:06 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 04:25:06 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 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.common.tests.zip 04:25:06 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:06 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:07 INFO - Downloaded 16338716 bytes. 04:25:07 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 04:25:14 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:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:14 INFO - trying https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:25:14 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:14 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:15 INFO - Downloaded 16338716 bytes. 04:25:15 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:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:21 INFO - trying https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:25:21 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:21 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:22 INFO - Downloaded 14413719 bytes. 04:25:22 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:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:22 INFO - trying https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:25:22 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:22 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:25 INFO - Downloaded 111292281 bytes. 04:25:25 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:25 INFO - mkdir: /builds/slave/test/properties 04:25:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:25:25 INFO - Writing to file /builds/slave/test/properties/build_url 04:25:25 INFO - Contents: 04:25:25 INFO - build_url:https://queue.taskcluster.net/v1/task/bPSr0os7Tv60bl3E03y8BA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:25:26 INFO - Running post-action listener: _resource_record_post_action 04:25:26 INFO - Running post-action listener: set_extra_try_arguments 04:25:26 INFO - [mozharness: 2016-08-18 11:25:26.398904Z] Finished download-and-extract step (success) 04:25:26 INFO - [mozharness: 2016-08-18 11:25:26.399022Z] Running populate-webroot step. 04:25:26 INFO - Running pre-action listener: _resource_record_pre_action 04:25:26 INFO - Running main action method: populate_webroot 04:25:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:25:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:25:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:25:26 INFO - '--tppagecycles', 04:25:26 INFO - '7'], 04:25:26 INFO - 'tests': ['tsvgm']}}, 04:25:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:25:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:25:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:25:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:25:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:25:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:25:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:26 INFO - 'talos_options': ['--disable-e10s'], 04:25:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:25:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:25:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:26 INFO - 'talos_options': ['--disable-e10s'], 04:25:26 INFO - 'tests': ['damp', 'tps']}, 04:25:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:26 INFO - 'tests': ['damp', 'tps']}, 04:25:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:25:26 INFO - 'tests': ['dromaeo_dom']}, 04:25:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:25:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:25:26 INFO - 'tests': ['basic_compositor_video']}, 04:25:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:25:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:25:26 INFO - 'tests': ['a11yr', 04:25:26 INFO - 'ts_paint', 04:25:26 INFO - 'tpaint', 04:25:26 INFO - 'sessionrestore', 04:25:26 INFO - 'sessionrestore_no_auto_restore', 04:25:26 INFO - 'tabpaint']}, 04:25:26 INFO - 'other-e10s': {'tests': ['a11yr', 04:25:26 INFO - 'ts_paint', 04:25:26 INFO - 'tpaint', 04:25:26 INFO - 'sessionrestore', 04:25:26 INFO - 'sessionrestore_no_auto_restore', 04:25:26 INFO - 'tabpaint']}, 04:25:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:25:26 INFO - 'ts_paint', 04:25:26 INFO - 'tpaint', 04:25:26 INFO - 'sessionrestore', 04:25:26 INFO - 'sessionrestore_no_auto_restore', 04:25:26 INFO - 'tabpaint']}, 04:25:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:25:26 INFO - 'ts_paint', 04:25:26 INFO - 'tpaint', 04:25:26 INFO - 'sessionrestore', 04:25:26 INFO - 'sessionrestore_no_auto_restore', 04:25:26 INFO - 'tabpaint']}, 04:25:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:25:26 INFO - 'tests': ['a11yr', 04:25:26 INFO - 'ts_paint', 04:25:26 INFO - 'tpaint', 04:25:26 INFO - 'sessionrestore', 04:25:26 INFO - 'sessionrestore_no_auto_restore', 04:25:26 INFO - 'tabpaint']}, 04:25:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:25:26 INFO - 'tests': ['a11yr', 04:25:26 INFO - 'ts_paint', 04:25:26 INFO - 'tpaint', 04:25:26 INFO - 'sessionrestore', 04:25:26 INFO - 'sessionrestore_no_auto_restore', 04:25:26 INFO - 'tabpaint']}, 04:25:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:25:26 INFO - 'tests': ['tsvgx', 04:25:26 INFO - 'tsvgr_opacity', 04:25:26 INFO - 'tart', 04:25:26 INFO - 'tscrollx', 04:25:26 INFO - 'cart']}, 04:25:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:25:26 INFO - 'tsvgr_opacity', 04:25:26 INFO - 'tart', 04:25:26 INFO - 'tscrollx', 04:25:26 INFO - 'cart']}, 04:25:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:26 INFO - 'talos_options': ['--disable-e10s'], 04:25:26 INFO - 'tests': ['tp5o']}, 04:25:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:26 INFO - 'tests': ['tp5o']}, 04:25:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:26 INFO - 'talos_options': ['--disable-e10s', 04:25:26 INFO - '--xperf_path', 04:25:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:25:26 INFO - 'tests': ['tp5n']}, 04:25:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:26 INFO - 'talos_options': ['--xperf_path', 04:25:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:25:26 INFO - 'tests': ['tp5n']}}, 04:25:26 INFO - 'talos.zip': {'path': '', 04:25:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:25:26 INFO - Running post-action listener: _resource_record_post_action 04:25:26 INFO - [mozharness: 2016-08-18 11:25:26.417954Z] Finished populate-webroot step (success) 04:25:26 INFO - [mozharness: 2016-08-18 11:25:26.418074Z] Running create-virtualenv step. 04:25:26 INFO - Running pre-action listener: _resource_record_pre_action 04:25:26 INFO - Running main action method: create_virtualenv 04:25:26 INFO - Creating virtualenv /builds/slave/test/build/venv 04:25:26 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:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:25:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:25:26 INFO - Using real prefix '/tools/python27' 04:25:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:25:27 INFO - Installing distribute.............................................................................................................................................................................................done. 04:25:30 INFO - Installing pip.................done. 04:25:30 INFO - Return code: 0 04:25:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:25:30 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:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:30 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:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:30 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 0x10a65fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffad0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c3d30>, '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 0x10a328db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bbb20>, '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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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:30 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:30 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:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cTSVpNurF6/Render', 04:25:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:30 INFO - 'HOME': '/Users/cltbld', 04:25:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:30 INFO - 'LOGNAME': 'cltbld', 04:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:30 INFO - 'MOZ_NO_REMOTE': '1', 04:25:30 INFO - 'NO_EM_RESTART': '1', 04:25:30 INFO - 'PAGER': '/bin/cat', 04:25:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:30 INFO - 'PWD': '/builds/slave/test', 04:25:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:30 INFO - 'SHELL': '/bin/bash', 04:25:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RpKcWMXhyp/Listeners', 04:25:30 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:25:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:30 INFO - 'USER': 'cltbld', 04:25:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:30 INFO - 'XPC_FLAGS': '0x0', 04:25:30 INFO - 'XPC_SERVICE_NAME': '0', 04:25:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:30 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:30 INFO - Cleaning up... 04:25:30 INFO - Return code: 0 04:25:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:25:30 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:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:30 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:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:30 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 0x10a65fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffad0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c3d30>, '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 0x10a328db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bbb20>, '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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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:30 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:30 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:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cTSVpNurF6/Render', 04:25:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:30 INFO - 'HOME': '/Users/cltbld', 04:25:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:30 INFO - 'LOGNAME': 'cltbld', 04:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:30 INFO - 'MOZ_NO_REMOTE': '1', 04:25:30 INFO - 'NO_EM_RESTART': '1', 04:25:30 INFO - 'PAGER': '/bin/cat', 04:25:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:30 INFO - 'PWD': '/builds/slave/test', 04:25:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:30 INFO - 'SHELL': '/bin/bash', 04:25:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RpKcWMXhyp/Listeners', 04:25:30 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:25:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:30 INFO - 'USER': 'cltbld', 04:25:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:30 INFO - 'XPC_FLAGS': '0x0', 04:25:30 INFO - 'XPC_SERVICE_NAME': '0', 04:25:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:30 INFO - Downloading/unpacking psutil>=3.1.1 04:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:25:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:33 INFO - Installing collected packages: psutil 04:25:33 INFO - Running setup.py install for psutil 04:25:33 INFO - building 'psutil._psutil_osx' extension 04:25:33 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: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:25:34 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:34 INFO - building 'psutil._psutil_posix' extension 04:25:34 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:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:25:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:25:34 INFO - ^ 04:25:34 INFO - 1 warning generated. 04:25:34 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: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 - Successfully installed psutil 04:25:34 INFO - Cleaning up... 04:25:34 INFO - Return code: 0 04:25:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a65fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffad0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c3d30>, '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 0x10a328db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bbb20>, '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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:25:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:25:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cTSVpNurF6/Render', 04:25:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:34 INFO - 'HOME': '/Users/cltbld', 04:25:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:34 INFO - 'LOGNAME': 'cltbld', 04:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:34 INFO - 'MOZ_NO_REMOTE': '1', 04:25:34 INFO - 'NO_EM_RESTART': '1', 04:25:34 INFO - 'PAGER': '/bin/cat', 04:25:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:34 INFO - 'PWD': '/builds/slave/test', 04:25:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:34 INFO - 'SHELL': '/bin/bash', 04:25:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RpKcWMXhyp/Listeners', 04:25:34 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:25:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:34 INFO - 'USER': 'cltbld', 04:25:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:34 INFO - 'XPC_FLAGS': '0x0', 04:25:34 INFO - 'XPC_SERVICE_NAME': '0', 04:25:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:25:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:25:37 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:37 INFO - Installing collected packages: mozsystemmonitor 04:25:37 INFO - Running setup.py install for mozsystemmonitor 04:25:37 INFO - Successfully installed mozsystemmonitor 04:25:37 INFO - Cleaning up... 04:25:37 INFO - Return code: 0 04:25:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:37 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 0x10a65fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffad0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c3d30>, '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 0x10a328db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bbb20>, '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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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:37 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:37 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:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cTSVpNurF6/Render', 04:25:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:37 INFO - 'HOME': '/Users/cltbld', 04:25:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:37 INFO - 'LOGNAME': 'cltbld', 04:25:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:37 INFO - 'MOZ_NO_REMOTE': '1', 04:25:37 INFO - 'NO_EM_RESTART': '1', 04:25:37 INFO - 'PAGER': '/bin/cat', 04:25:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:37 INFO - 'PWD': '/builds/slave/test', 04:25:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:37 INFO - 'SHELL': '/bin/bash', 04:25:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RpKcWMXhyp/Listeners', 04:25:37 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:25:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:37 INFO - 'USER': 'cltbld', 04:25:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:37 INFO - 'XPC_FLAGS': '0x0', 04:25:37 INFO - 'XPC_SERVICE_NAME': '0', 04:25:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:37 INFO - Downloading/unpacking jsonschema==2.5.1 04:25:37 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:37 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:37 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:37 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:37 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:37 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:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:25:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:25:40 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:40 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:40 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:40 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:40 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:40 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:40 INFO - Downloading functools32-3.2.3-2.tar.gz 04:25:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:25:40 INFO - warning: no files found matching '*.txt' 04:25:40 INFO - no previously-included directories found matching 'build' 04:25:40 INFO - no previously-included directories found matching 'dist' 04:25:40 INFO - no previously-included directories found matching '.git*' 04:25:40 INFO - Installing collected packages: jsonschema, functools32 04:25:40 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 0x10a65fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffad0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c3d30>, '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 0x10a328db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bbb20>, '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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 04:25:41 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:25:41 INFO - Cleaning up... 04:25:41 INFO - Return code: 0 04:25:41 INFO - Installing blobuploader==1.2.4 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', '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 0x10a65fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffad0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c3d30>, '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 0x10a328db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bbb20>, '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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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', 'blobuploader==1.2.4'] 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 blobuploader==1.2.4 04:25:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 04:25:41 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:41 INFO - Downloading/unpacking blobuploader==1.2.4 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: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:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:25:44 INFO - Downloading/unpacking docopt==0.6.1 (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 - 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:45 INFO - Installing collected packages: blobuploader, requests, docopt 04:25:45 INFO - Running setup.py install for blobuploader 04:25:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:25:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:25:45 INFO - Running setup.py install for requests 04:25:45 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 0x10a65fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffad0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c3d30>, '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 0x10a328db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bbb20>, '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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 04:25:46 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:46 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-43gwRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:46 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-W78spL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:46 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-6rddoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:46 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-XL8LdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:46 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-mbmOLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:46 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-IRrd23-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/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-jAOCeU-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/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-M1nCeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-jg18EU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-ZJ3Yqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-UiTIns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-yQljAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-1tgiMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-WUyafc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:47 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Td36yU-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/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-kaGRKs-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/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Gxt7vT-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:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:25:48 INFO - Running setup.py install for mozcrash 04:25:48 INFO - Running setup.py install for mozdebug 04:25:48 INFO - Running setup.py install for mozdevice 04:25:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:25:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:25:48 INFO - Running setup.py install for mozfile 04:25:48 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:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:25:49 INFO - Running setup.py install for mozInstall 04:25:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:25:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:25:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:25:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:25:49 INFO - Running setup.py install for mozleak 04:25:49 INFO - Running setup.py install for mozlog 04:25:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:25:49 INFO - Running setup.py install for moznetwork 04:25:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:25:49 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:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Running setup.py install for mozscreenshot 04:25:50 INFO - Running setup.py install for moztest 04:25:50 INFO - Running setup.py install for mozversion 04:25:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:25:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:25:50 INFO - Cleaning up... 04:25:50 INFO - Return code: 0 04:25:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:50 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 0x10a65fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffad0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c3d30>, '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 0x10a328db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bbb20>, '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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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:50 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:50 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:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cTSVpNurF6/Render', 04:25:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:50 INFO - 'HOME': '/Users/cltbld', 04:25:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:50 INFO - 'LOGNAME': 'cltbld', 04:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:50 INFO - 'MOZ_NO_REMOTE': '1', 04:25:50 INFO - 'NO_EM_RESTART': '1', 04:25:50 INFO - 'PAGER': '/bin/cat', 04:25:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:50 INFO - 'PWD': '/builds/slave/test', 04:25:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:50 INFO - 'SHELL': '/bin/bash', 04:25:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RpKcWMXhyp/Listeners', 04:25:50 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:25:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:50 INFO - 'USER': 'cltbld', 04:25:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:50 INFO - 'XPC_FLAGS': '0x0', 04:25:50 INFO - 'XPC_SERVICE_NAME': '0', 04:25:50 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/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-T8BAXR-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/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-PAkim8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:51 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:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Nk0wEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:51 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:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-gZw2nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:51 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:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-G0nnQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:51 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:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-hqCdEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:51 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:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-BelFU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:51 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:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-XFmDx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:51 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:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-VEwDmL-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/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-8a3oHx-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/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-6Sv3Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:52 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:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:52 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Fn4yDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:52 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:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:52 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Yx15YN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:52 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:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:52 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-S9hzni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:52 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:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:52 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-AU54zU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:52 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:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:52 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-jBY7PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:52 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:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:52 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-njuECU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:52 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:52 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:52 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:52 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:52 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:52 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:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:25:52 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:52 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:52 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:52 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:52 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:52 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:55 INFO - Downloading blessings-1.6.tar.gz 04:25:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:25:55 INFO - Installing collected packages: blessings 04:25:55 INFO - Running setup.py install for blessings 04:25:55 INFO - Successfully installed blessings 04:25:55 INFO - Cleaning up... 04:25:55 INFO - Return code: 0 04:25:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:25:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:55 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 0x10a65fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffad0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c3d30>, '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 0x10a328db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bbb20>, '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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 04:25:56 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:56 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:56 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:56 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:56 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:56 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:56 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:56 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:56 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:56 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:56 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:56 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:56 INFO - Cleaning up... 04:25:56 INFO - Return code: 0 04:25:56 INFO - Installing jsonschema 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', '--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 0x10a65fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffad0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5c3d30>, '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 0x10a328db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bbb20>, '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.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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', '--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:56 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:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cTSVpNurF6/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.RpKcWMXhyp/Listeners', 04:25:56 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:25:56 INFO - Cleaning up... 04:25:56 INFO - Return code: 0 04:25:56 INFO - Running post-action listener: _resource_record_post_action 04:25:56 INFO - Running post-action listener: _start_resource_monitoring 04:25:56 INFO - Starting resource monitoring. 04:25:56 INFO - [mozharness: 2016-08-18 11:25:56.931344Z] Finished create-virtualenv step (success) 04:25:56 INFO - [mozharness: 2016-08-18 11:25:56.932336Z] Running install step. 04:25:56 INFO - Running pre-action listener: _resource_record_pre_action 04:25:56 INFO - Running main action method: install 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: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.280922Z] Finished install step (success) 04:26:31 INFO - [mozharness: 2016-08-18 11:26:31.281059Z] 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.295 screenresolution[2142:14138] starting screenresolution argv=screenresolution get 04:26:31 INFO - 2016-08-18 04:26:31.298 screenresolution[2142:14138] Display 0: 1600x1200x32@60 04:26:31 INFO - 2016-08-18 04:26:31.304 screenresolution[2143:14140] 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:31 INFO - Python 2.7.3 04:26:31 INFO - Return code: 0 04:26:31 INFO - grabbing minidump binary from tooltool 04:26: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:26:31 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 0x10a5c3d30>, '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 0x10a328db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:26:31 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:31 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:31 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:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:26:31 INFO - Return code: 0 04:26:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:26:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:26:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:26:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:26:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:26:31 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-0216', '--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:31 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-0216 --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:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cTSVpNurF6/Render', 04:26:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:31 INFO - 'HOME': '/Users/cltbld', 04:26:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:31 INFO - 'LOGNAME': 'cltbld', 04:26:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:26:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:31 INFO - 'MOZ_NO_REMOTE': '1', 04:26:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:26:31 INFO - 'NO_EM_RESTART': '1', 04:26:31 INFO - 'PAGER': '/bin/cat', 04:26:31 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:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:31 INFO - 'PWD': '/builds/slave/test', 04:26:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:26:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:31 INFO - 'SHELL': '/bin/bash', 04:26:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RpKcWMXhyp/Listeners', 04:26:31 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 04:26:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:31 INFO - 'USER': 'cltbld', 04:26:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:31 INFO - 'XPC_FLAGS': '0x0', 04:26:31 INFO - 'XPC_SERVICE_NAME': '0', 04:26:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:31 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-0216', '--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:31 INFO - mozversion application_buildid: 20160817163516 04:26:31 INFO - mozversion application_changeset: b25d09b7fab57ddb82f14916a443eb34fda31137 04:26:31 INFO - mozversion application_display_name: Nightly 04:26:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:26:31 INFO - mozversion application_name: Firefox 04:26:31 INFO - mozversion application_remotingname: firefox 04:26:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:26:31 INFO - mozversion application_vendor: Mozilla 04:26:31 INFO - mozversion application_version: 51.0a1 04:26:31 INFO - mozversion platform_buildid: 20160817163516 04:26:31 INFO - mozversion platform_changeset: b25d09b7fab57ddb82f14916a443eb34fda31137 04:26:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:26:31 INFO - mozversion platform_version: 51.0a1 04:26:31 INFO - using testdate: 1471519591 04:26:31 INFO - actual date: 1471519591 04:26:31 INFO - starting webserver on 'localhost:49516' 04:26:31 INFO - SUITE-START | Running 2 tests 04:26:31 INFO - TEST-START | dromaeo_css 04:26:31 INFO - operating with platform_type : mac_ 04:26:31 INFO - Initialising browser for dromaeo_css test... 04:26:31 INFO - TEST-INFO | started process 2153 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmp10RpoA/profile http://localhost:49516/getInfo.html) 04:26:34 INFO - PROCESS | 2153 | __metrics Screen width/height:1600/1200 04:26:34 INFO - PROCESS | 2153 | colorDepth:24 04:26:34 INFO - PROCESS | 2153 | Browser inner width/height: 1024/689 04:26:34 INFO - PROCESS | 2153 | __metrics 04:26:34 INFO - PROCESS | 2153 | 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 | 2153 | 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 | 2153: 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 2155 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmp10RpoA/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 | 2155 | RSS: Main: 228630528 04:26:47 INFO - PROCESS | 2155 | 04:27:38 INFO - PROCESS | 2155 | 1471519658945 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 | 2155 | Cycle 1(1): loaded http://localhost:49516/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49516/tests/dromaeo/cssquery-ext.html) 04:29:53 INFO - PROCESS | 2155 | RSS: Main: 385724416 04:29:53 INFO - PROCESS | 2155 | 04:32:56 INFO - PROCESS | 2155 | Cycle 1(1): loaded http://localhost:49516/tests/dromaeo/cssquery-ext.html (next: http://localhost:49516/tests/dromaeo/cssquery-jquery.html) 04:32:56 INFO - PROCESS | 2155 | RSS: Main: 376909824 04:32:56 INFO - PROCESS | 2155 | 04:35:59 INFO - PROCESS | 2155 | Cycle 1(1): loaded http://localhost:49516/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49516/tests/dromaeo/cssquery-mootools.html) 04:36:00 INFO - PROCESS | 2155 | RSS: Main: 336232448 04:36:00 INFO - PROCESS | 2155 | 04:39:02 INFO - PROCESS | 2155 | Cycle 1(1): loaded http://localhost:49516/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49516/tests/dromaeo/cssquery-prototype.html) 04:39:03 INFO - PROCESS | 2155 | RSS: Main: 339529728 04:39:03 INFO - PROCESS | 2155 | 04:42:06 INFO - PROCESS | 2155 | Cycle 1(1): loaded http://localhost:49516/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49516/tests/dromaeo/cssquery-yui.html) 04:42:07 INFO - PROCESS | 2155 | RSS: Main: 339005440 04:42:07 INFO - PROCESS | 2155 | 04:45:10 INFO - PROCESS | 2155 | Cycle 1(1): loaded http://localhost:49516/tests/dromaeo/cssquery-yui.html (next: http://localhost:49516/tests/dromaeo/cssquery-dojo.html) 04:45:10 INFO - PROCESS | 2155 | RSS: Main: 422793216 04:45:10 INFO - PROCESS | 2155 | 04:45:10 INFO - PROCESS | 2155 | __start_tp_report 04:45:10 INFO - PROCESS | 2155 | _x_x_mozilla_page_load 04:45:10 INFO - PROCESS | 2155 | _x_x_mozilla_page_load_details 04:45:10 INFO - PROCESS | 2155 | |i|pagename|runs| 04:45:10 INFO - PROCESS | 2155 | |0;dojo.html;2868.5553739170423;3319.2697606526553;3337.332533493302;3365.9495107573375;3370.342783157284;5568.9443105569035;5637.267155269814;5678.886422271562;5688.260526131602;5740.081586946095;5654.321481422225;5698.059820129684;5704.800331988381;5711.600187986832;5713.228714123603;5144.408393034802;5165.096108181053;5206.167013277876;5208.817691380802;5262.236975638531;5264.131418315992;5298.761555729982;5300.09898317285;5307.2039194121;5309.814156504503;5176.378834539861;5204.869878253055;5219.321488206547;5222.190560463135;5236.659617124894;5042.495750424947;5302.496262855023;5303.973480132575;5347.652402593858;5397.676139431646;5104.412992505872;5115.897682046339;5181.35233095863;5228.424873263938;5248.71132087735;4800.687955282895;5570.582206334541;5643.971780141073;5668.546516278688;5700.885982280372;5195.766190521435;6163.352847950945;6279.058141278799;6284.905726414107;6289.150964619809;6131.63210207389;6218.875622487571;6295.937040629581;6347;6377.96811015942;5789.870975159888;5824.475797178275;5831.854203644943;5871.236739223874;5871.823845284648;6232.937670623326;6246.219222597176;6247.96876015617;6262.405071518198;6268.686565671698;5745.454181852718;5782.59521833476;5804.780995990889;6045.667488288186;6067.63496630201;5271.393789714155;5646.322441307069;6240.032794916795;6279.497640188774;6292.937070629235;6222.004479283383;6222.0355844844125;6229.813105606839;6232.8130156095385;6238.1266622672865;6097.542684298695;6134.355892631299;6169.81490555284;6253.78111766086;6261.279952805395;4937.703737775745;4965.801367945314;4978.950210497848;4979.6265280104135;4982.975085124624;4433.556644335541;4489.483709373399;4492.707973981746;4494.101179763995;4502.932456013163;4913.877153071202;5804.2744656917885;5816.47651711348;5834.620749651259;5858.062709966385;5873.4126587340925;6220.471259942956;6259.123722678829;6302.275238347557;6354.555181137276;5615.0454422748235;5682.7442765075675;5687.8862422752145;5733.197352370671;5752.7698892043845;5416;5449.972750136224;5457.099578569571;5474.835754927359;5481.616286859881;5400.729963501731;6294;6328.588641738273;6343.1436756038265;6377.74489020434;6285.120083188266;6298.905516417166;6301;6301.086342480223;6310.968445157745;6191.504679625529;6259.092431597484;6309.1167236586;6336.176297081534;6362.395572420613;6215.254169499689;6247;6274.560780745304;6286.622802631857;6287.622742635456;6260.968695156495;6276.340984196594;6293.213348331458;6296.118543404019;6307.306196318493;5967.492763115184;6057;6079.3008803988105;6159.19930409044;6162.537809664203;6111.694415279308;6155.969220153871;6172.69136543162;6176.413240742122;6188.009918413032;6015.0676645120075;6017.608855424383;6047.5464340173785;6051;6068.423499767515;5900.940990590039;6039.607425517327;6131.080337949344;6151.72317245716;6161.322254551906;5883.823485295448;5960.195373624679;5998.670073146019;6003.729832157476;6006.549508786946 04:45:10 INFO - PROCESS | 2155 | |1;ext.html;470.5929371094003;520.2378515474828;523.0898237067504;524.9108100691066;542.1623591551054;30831.38337233254;40157.598424015785;41295.79352103243;41465;41630;35119.4732079019;41830.163396732045;42223.57776422235;42263.366049509284;42283.94290142748;9783.315167938246;9957.850632240523;9973.656190795158;9976.700698979042;10010.59957601695;9783.804323913517;9898.802023959517;9907.801843963116;9909.454979976099;9910.752231194207;9902.455364954925;9906.65326713565;9920.900790992106;9927.453990030546;9953.35303205293;9861.457619830906;9905.801883962353;9912.405255684609;9924.45415502147;9944.403335799876;8734.2139207471;9655.662051828152;9775.706728798077;9829.90170098297;9857.457839818808;39545.80227098875;39912.401313980314;39958;40033.39949900754;40204.59795402067;3482.2165012872156;3517.296540691872;3577.3918433866047;3668.614795446493;4133.937990930139;30977.76088956439;32162.356752865046;32164.839175804205;32320.19199520007;32354.029379118663;2879;2919.693432189632;3213.7268332191557;3314.4696848504122;3352.932941341184;487954;488064;491090;492674;497164;59593;60267.69866150729;60487.69756151191;60498.79002419927;60569;33999.4900076499;34708;34830.47754283688;35898.923032309074;36272.63727362746;16647.33410663584;16791.328346866234;16859.578510537336;16868.746968795476;16906.239219235165;12860.421281042376;12888.80666789999;12924.676883077811;12954.222746635232;12977.740445191042;18762.968036757837;18795.90602046981;18813.341533046278;18837.81162188388;18842.71735923962;6803;7747.457677962601;7837.294643482;7899.669597468645;7926.207379262143;869.3827382558328;998.4708104704519;1024.3853687787416;1034.6637342863598;1152.4353066997112;28857.134285971453;29955.550666740015;29967.25081872927;30716;30741.538876916864;1084.837274408845;1132.6149109302876;1134.2967360236705;1143.4168574027306;1229.2255878796302;13067.869321306855;15379.077255364715;15710.68578628421;16060.75908861368;16763;625710;625822;630109.4406975367;632187;632430;48152.759236203594;56861.43138568562;57412.712936435884;57609.99999999916;57627.711861440424;1238.7089034076985;1266.341502418737;1359.0690377091723;1379.862013798612;1414.610981979943;29068.56397154002;29570.260743481584;30585.388292234475;31438;31442;35371;48318;48320;48339;48360.75819620879;399075;400081;400289;401420.9147451162;403374;23164;25667.844946977806;26122.738772612032;26417;26735.598966015135;45016.77491612521;45885.77057114693;45955.54044459647;47099.29351059805;47136.52863471321;32133;38817.417738734504;39242.82271531859;39706.205875881744;40202.798986004884;33857.64569417196;38297.4255386175;38421.231575368896;39251.607483924796;39938.60061399465;31303.843480783362;32451;33232;33396.66603334033;38279.617203827605;28510.857445712638;29767;35204.29591408209;35368.64631353757;35382.29235415329;24325.635115472927;24723;25242.864071117212;25319.620205697298;25617 04:45:10 INFO - PROCESS | 2155 | |2;jquery.html;345.7527867574684;348.1591955427642;376.25877022266127;383.1972018620988;401.21762563002153;15571.766423503643;16675.83324166758;20335.694964575505;20892.268770593033;21010.474738131572;20885.47786305343;21165.470863228427;21404.89297553514;21475.57048859022;21744.565108697818;5741.220221731277;5806.854828629297;6043.244594425697;6046.697665116749;6073.51411887048;5241.633085684004;6035.064564992411;6080.787172448943;6107.206063211798;6116.969415152918;3944.8027598619956;5689.4879460848515;6101.664408457533;6111.419415155553;6123.632582045091;5841.211436456067;6060.454559089703;6086.569656130806;6097.481714054311;6118.877622447549;5912.970435147839;5913.231279933624;5913.467787899067;5916.792912248094;5924.763009479616;13168.209907405491;18567.257309707595;25295.494090118096;26544.336391590172;26827.195184144508;2251.4033781048033;2380.214529205358;2423.515296940619;2438.4147804526965;2935.6477222733197;9724;9860.90139098607;10261.43562104084;10267.921868203806;10320.845187322197;1650.8844380893343;1830.9176087076114;1848.6302739452162;1874.297138573035;1877.399232245682;108784.4560777191;115230.69538609264;126181;127142.3642881789;130416;11233.550657973752;11452.083833293316;11594.304341739355;11611.76776464466;11806.46870890812;14618.780718289234;14621.342039608244;14647.780283295759;14664.046836955564;14696.26518674062;8452.408331416742;8599.183077607617;8652.356052350033;8661.956690216635;8754.21212090915;6987.32304246977;8574.185452382013;8679.566021698889;8734.213920747163;8760.036395996436;3731.869384571527;4497.740632622806;4507.18870603288;4581.083783243364;4587.977060114678;1676.5724740191174;1884.9340273090377;1905.961880762377;1911.5603411215498;1940.0202897536653;801.1907972947365;873.9781505462287;958.6453012385323;981.40134517944;1050.9842352364722;21169.78830211709;21791.247932201248;21852.45368865759;21893.562128757334;22944.65583016256;907.1518130547959;951.362587066667;972.0619602084023;973.5375696544085;1043.7442826507402;11051.613193538187;11499.655010349703;11504.309741415542;11553.768924621629;11596.536138554533;617147.8285217091;622869;625461;630808;638374;91649.77786652077;116722.8327716712;119977;136896.63103368838;139627;958.3195930889146;970.8301047316833;1004.3220825942606;1006.7483129217695;1007.7833265847878;19209;19986.900720460515;20019.899900500404;20055.796652200916;20106.095225714584;56620.73067714387;58000.70999644975;58335.70832145982;58479.70760146342;58693;82907.34185316139;83339.5833020831;83561;83850;84929;7711.26742959418;7878.251566101211;8061.435699500978;8145.266925976691;8270.875936861072;10474.895251047392;12317.8298061684;12479.625611231677;12501;12975.416106275428;8959.41763785352;9236.769080773034;9946.502674866373;11362.659120226406;11452.3701196435;10684.679459616224;10734.463276836283;10769.838452423062;10900.836487452536;11445.427728613702;9886.555105020147;11086.667399978014;11131.109511239243;11235;11358.94320528392;6936.861262774615;7213.422926165999;7301.634918254173;7541.35898448638;8206.097329293892;9719.46542940145;10932;11344.149188810707;11448.885511144781;11463.369514676773 04:45:10 INFO - PROCESS | 2155 | |3;mootools.html;1465.2161093814811;1531.762576800596;1665.3921318718665;1740.3734655524001;1836.797952225255;8492.363072769544;9007.054259302777;9016.233620142288;9016.774580635487;9019.594118264677;7877.21227877212;8120.878186827203;8535.103814099508;8749.168828961254;8757.036725960139;2378.4172877645;2409.3494756415757;2414.022320960014;2414.33605758417;2415.7342692303832;2364.9881750591308;2389.3668177932864;2408.9879550602222;2409.4337830609793;2410.1684918703018;2382.094803974487;2393.7725916038034;2402.315340128064;2406.169871394366;2408.590539608262;2354.846934949223;2365.8698771567556;2380.571497130516;2382.5949588569897;2390.127603424755;2358.1982126077214;2373.715154181492;2376.512814872964;2382.321038504035;2384.141708984764;8811.471311721318;8869.778255543599;8935.64257429702;8946.507942063185;8950.14973577509;4340.60934515895;5253.474652534716;5256.763445644917;5261.316028916254;5270.894582108374;3466.7746596471147;3612.747107702462;3677.632236776301;3691.6123807000145;3698.981505092457;1082.7888561730465;1127.1490025031048;1127.7631697343568;1142.874283828778;1149.0577726264466;11718.824217636742;11851.466683999155;11859;11860.288382697066;11864;4474.955250447454;4647.814087436464;4794;4830.855074347775;4843;10016.449095299828;10022;10040.146587539995;10042.14641755459;10045.598176072876;5362.570994320445;5373.301470808769;5374.91937620936;5377.892442151135;5387.218853266255;4358.498772641124;4367.820688414174;4578.221702310615;4754.477007529169;4906.092372910952;3846.4038074098557;3894.240623078529;3963.583823698528;3973.463582416337;3998.4002399639703;1293.7412517496539;1305.3668970549277;1307.8495972963042;1317.8089177069462;1319.6700824793802;897.8159477307137;910.7176775199576;919.6137622198693;942.1473566422399;962.1629182611033;5333.12003519415;5887.116932460165;5964.671943043174;6201.782937597162;6425.646589437532;896.5562046786752;928.5190121755788;949.6723630347519;975.3276023695024;1000.309786247487;3900.2004589059175;3938.9015274618364;4011.498562679665;4059.1475790083823;4131.400946862688;10602.469876506144;10906;10996.945015275032;11012.889871101346;11068.114550835913;10937.726556835984;10954.671359859218;10960.28758130719;10965.451727413598;10985.890141098647;7761;8179.672813087529;8205.384596155312;8214.301784348398;8358.749237522885;5538.003159431341;5990.400959904149;5999.100134979788;6141.1402403664415;6168.537359697951;9931.255155863484;10164.339317944607;10201.183905287411;10207.886594588721;10277.74305642365;9726.805463890541;10188.981101889753;10217.131543818561;10295.742606434898;10324.019218174262;6981.301869812978;7210.603416812126;7312.817179570553;7478.06524184477;7655.885161722467;8151.429399942184;8441.493510389397;8445.535495547803;8455.534945578052;8467.026291976255;7324.19433862264;7444.032275804111;7450.031495905498;7520.09758828944;7610.467267291237;7283.7450689225625;7290.781276561712;7301.269873012869;7458.701651934079;7460.253974602713;6890.586564806128;7032.964835175996;7068.929310706827;7072.3634872861685;7085.468589855678;4893.681910675795;4964.602831773522;4987.775550100327;4998.800047998185;5082.33929589151;4487.339684316824;4863.2461968394955;4880.658353915192;4983.377077865267;5134.204198349262 04:45:10 INFO - PROCESS | 2155 | |4;prototype.html;276.52575832447354;301.28144375664044;302.50691373061903;309.94730895747733;314.93858697553986;5232.502912223337;5316.777141257513;5332.626716129873;5370.489803468674;5374.408815030353;4685.9062818743605;4734.218853889104;4971.384300102463;5193.870153246161;5208.739563021843;3523.9119022024397;3631.2737452509473;3634.436662317332;3703.740738148331;3766.303233901723;3809.561900381464;4067.373050779697;4068.823179598915;4091.304478238692;4121.361189015707;3821.9235615287816;3898.161895192533;4054.3918412238254;4063.6545893599077;4071.857484988011;3398.1819726446356;3700.333939890819;3763.8870833875026;4037.4953130858644;4067.5932406759234;3478.391281525748;3480.3387356402454;3505.1597911096624;3519.1729943463265;3519.7360197985;4238.470191226097;5124.692518448905;5135.537801597837;5139.7687104080405;5203.5837133029645;4464.72755264748;4593.219152744042;4628.791704373311;4640.535946405366;4670.673052886299;5425.782968681248;5466.1527463243265;5484.698341591211;5491.226930400122;5500.42245564218;3505.631908649606;3636.890893273206;3809.847606095753;3867.8066096695334;3878.720022392604;5192.299039629677;5516.675997760549;5531.502164805187;5559.360673522557;5805.564582656318;3362.7141692956375;3718.90919988011;3871.567520017555;3921.1961547883184;3964.742291751027;3367.545381373532;3779.6598306152578;3962.7622342659533;3976.940345894814;3990.521137463465;4618.330342100377;4644.95355046452;4649.604783593432;4654.069186162781;4671.902103005791;4673.766311684402;5422.213779002022;5591.049521581341;5595.272614560081;5649.12438572022;1158.6697791129488;1164.062929341871;1324.9403776830065;1371.6776557508977;1373.6881278379124;723.7828651404558;737.5464089584867;775.267372333151;779.3142035009156;822.8066404394963;4201.831926722958;4919.926201106986;5028;5107.412647545506;5135.456534990844;4557.544245575482;4661.883452913704;5043.2687260347775;5601.29983752031;5766.88466230673;4241.724287921337;4310.766924806395;4598.402207713043;5141.794328226903;5172.974135129375;2940.8088474249107;2975;2996.2471953907207;2999.43010827942;3013.9547906781418;4766.928496072562;4950.950490495121;5835.183074369592;5927.5247188372505;5973.492253158443;5402.8919421611345;5499.670019798825;5787.160861675117;5807.361190269067;5841.7371218295275;5860.677662728591;5861.7655293787775;5866.149408335682;5866.911996320144;5870.7945221917025;4656.906861862676;5105.324166600042;5627.7510479587645;5630.493255607015;5654.123610840326;5057.317262169707;5213.947860521498;5711.685857277889;5736.741846616996;5767.625104368202;4976.601871850316;5238.711870846988;5491.780328786964;5996.37038110992;6004.339522652592;3108.844557772057;3832.9425058623583;3864.705323716474;3871.941920871133;3902.9609703903734;5335.546478549367;5371.248025276386;5502.80740174092;5574.303212098488;5820.835832833535;4916.631252655993;5606.299212598426;5610.859728506657;5627.902559000955;5643.576731745054;5437.809676661298;5575.7490912909825;5615.578831587565;5616.915746263891;5657.717114144359;5464.426235245242;5470.480304371078;5483.616146869681;5491.231227628055;5492.4507549246355;4548.863534093983;4657.742409549471;4781.330613714013;5098.337216161875;5309.920351194658;4718.598919091915;5052.7010210353465;5200.557952573922;5354.839354819362;5467.234587157881 04:45:10 INFO - PROCESS | 2155 | |5;yui.html;723.2911746488442;724.20699334229;734.742840005998;782.7182214402818;848.6393282854785;14433.278336083193;15070.623234419118;15368.077915325088;15429.691406171869;15951.52145435636;14852.628684282898;14876.033057851231;15029.248537573132;15035.624109397244;15089.62275943102;689.6138162628919;850.1201256699302;865.9134086591354;867.8654808504692;874.204473928725;707.3916431868589;853.5220276645099;878.3149143667949;883.7437143228456;887.5517863478934;935.6584846531035;938.3619138985521;940.5391358234484;941.6751220828803;942.839717248066;716.7240612364225;933.9673111441101;935.4948327902924;935.7379933618598;937.4422218779883;665.2050799294844;688.401091050784;690.7651398524475;691.2016620802981;692.3595674001529;14638.634034149125;14697.926510367486;14712.705745885129;14725.190114543695;14729.337179826933;13681.657958551017;14038.929805350908;14164.645883852883;14318.928405357907;14352.138871667734;13075.77861535404;13122.278274694889;13150.342482875818;13186;13501.98735094862;4212.515560710527;4261.595148460922;4422.292433210703;4501.617362524189;4502.797374118172;104494.47752761187;104748.47625761823;104900.47549762279;105002;113230.30154547689;10572.682834463489;10978.670639880816;10998.450077496092;10999;11523.07815374768;21517.246896358552;21623.026963786666;21674.24140155087;21675.891620541795;22011.33965981023;7801.4929029612895;7831.295183433518;7913.604319783988;7919.802004949923;8036.598170091472;3765.3034188675183;4289.785510724514;4299.548547402478;4316.978415107905;4358.847440339573;2744.547149720314;3449.275652113034;3452.016175390002;3457.481377793301;3462.065242384542;574.5260160367714;589.9882002360015;590.141344343987;590.8079874040953;598.1297212555721;487.4029314090211;539.3473896585176;583.3670467542763;585.8857522783102;594.5184400635499;8463.322934165251;8852.203301702877;8854.645814167361;8901.198892099743;9046.86429703555;461.7714231455451;490.96808707433905;538.1013707109137;578.644133857683;579.0561384942516;4297.247981603207;4510;4535.206338890681;4546.954530454719;4599;128688.3565582166;130842;138334.92497612545;142758;142879;113032.86967130388;115981.42009289899;119307;119321;121958;107010;107377;108233.17668233113;109872.4506377463;110243.44878275557;8764.868526972228;8832.514211718417;8917.464952102635;8967.237784788365;9069.18377346042;58339.12491312549;59250.70374648099;59431.405685942584;60518.394816051274;61511.6924415375;70867.64566177136;78033;85271.14728852881;85373;90071;11020.228583999044;11448.3703396314;11512.424378781196;11899.167058305835;11971.341576213044;11435.199536032398;12049.277043377426;12428.937855310665;12541.561045363369;12956.611301660965;8216.548089855116;9193.678221262224;11667.00830429423;12065;12357.196782209128;12500.624981250578;12785.680357990752;13009.804852927024;13407.262600556673;13409.865901340861;12862.163959342613;12894.484220631444;13004.154729942524;13032.739345213233;13089.541866034642;10727.946360268148;10882;10956.178286628376;11092.168087393316;11341.886581134084;10605.681829545127;11121.888781112086;11168.38573878412;11311.660650180507;11513.251638643462 04:45:10 INFO - PROCESS | 2155 | __end_tp_report 04:45:10 INFO - PROCESS | 2155 | __start_cc_report 04:45:10 INFO - PROCESS | 2155 | _x_x_mozilla_cycle_collect,1506 04:45:10 INFO - PROCESS | 2155 | __end_cc_report 04:45:10 INFO - PROCESS | 2155 | __startTimestamp1471520710779__endTimestamp 04:45:10 INFO - PROCESS | 2155 | 04:45:10 INFO - PROCESS | 2155 | ------- Summary: start ------- 04:45:10 INFO - PROCESS | 2155 | Number of tests: 6 04:45:10 INFO - PROCESS | 2155 | 04:45:10 INFO - PROCESS | 2155 | [#0] dojo.html Cycles:165 Average:5726.47 Median:5873.41 stddev:653.19 (11.1%) stddev-sans-first:615.52 04:45:10 INFO - PROCESS | 2155 | Values: 2868.6 3319.3 3337.3 3365.9 3370.3 5568.9 5637.3 5678.9 5688.3 5740.1 5654.3 5698.1 5704.8 5711.6 5713.2 5144.4 5165.1 5206.2 5208.8 5262.2 5264.1 5298.8 5300.1 5307.2 5309.8 5176.4 5204.9 5219.3 5222.2 5236.7 5042.5 5302.5 5304.0 5347.7 5397.7 5104.4 5115.9 5181.4 5228.4 5248.7 4800.7 5570.6 5644.0 5668.5 5700.9 5195.8 6163.4 6279.1 6284.9 6289.2 6131.6 6218.9 6295.9 6347.0 6378.0 5789.9 5824.5 5831.9 5871.2 5871.8 6232.9 6246.2 6248.0 6262.4 6268.7 5745.5 5782.6 5804.8 6045.7 6067.6 5271.4 5646.3 6240.0 6279.5 6292.9 6222.0 6222.0 6229.8 6232.8 6238.1 6097.5 6134.4 6169.8 6253.8 6261.3 4937.7 4965.8 4979.0 4979.6 4983.0 4433.6 4489.5 4492.7 4494.1 4502.9 4913.9 5804.3 5816.5 5834.6 5858.1 5873.4 6220.5 6259.1 6302.3 6354.6 5615.0 5682.7 5687.9 5733.2 5752.8 5416.0 5450.0 5457.1 5474.8 5481.6 5400.7 6294.0 6328.6 6343.1 6377.7 6285.1 6298.9 6301.0 6301.1 6311.0 6191.5 6259.1 6309.1 6336.2 6362.4 6215.3 6247.0 6274.6 6286.6 6287.6 6261.0 6276.3 6293.2 6296.1 6307.3 5967.5 6057.0 6079.3 6159.2 6162.5 6111.7 6156.0 6172.7 6176.4 6188.0 6015.1 6017.6 6047.5 6051.0 6068.4 5900.9 6039.6 6131.1 6151.7 6161.3 5883.8 5960.2 5998.7 6003.7 6006.5 04:45:10 INFO - PROCESS | 2155 | 04:45:10 INFO - PROCESS | 2155 | [#1] ext.html Cycles:180 Average:63929.82 Median:39030.12 stddev:137709.12 (352.8%) stddev-sans-first:138013.00 04:45:10 INFO - PROCESS | 2155 | Values: 470.6 520.2 523.1 524.9 542.2 30831.4 40157.6 41295.8 41465.0 41630.0 35119.5 41830.2 42223.6 42263.4 42283.9 9783.3 9957.9 9973.7 9976.7 10010.6 9783.8 9898.8 9907.8 9909.5 9910.8 9902.5 9906.7 9920.9 9927.5 9953.4 9861.5 9905.8 9912.4 9924.5 9944.4 8734.2 9655.7 9775.7 9829.9 9857.5 39545.8 39912.4 39958.0 40033.4 40204.6 3482.2 3517.3 3577.4 3668.6 4133.9 30977.8 32162.4 32164.8 32320.2 32354.0 2879.0 2919.7 3213.7 3314.5 3352.9 487954.0 488064.0 491090.0 492674.0 497164.0 59593.0 60267.7 60487.7 60498.8 60569.0 33999.5 34708.0 34830.5 35898.9 36272.6 16647.3 16791.3 16859.6 16868.7 16906.2 12860.4 12888.8 12924.7 12954.2 12977.7 18763.0 18795.9 18813.3 18837.8 18842.7 6803.0 7747.5 7837.3 7899.7 7926.2 869.4 998.5 1024.4 1034.7 1152.4 28857.1 29955.6 29967.3 30716.0 30741.5 1084.8 1132.6 1134.3 1143.4 1229.2 13067.9 15379.1 15710.7 16060.8 16763.0 625710.0 625822.0 630109.4 632187.0 632430.0 48152.8 56861.4 57412.7 57610.0 57627.7 1238.7 1266.3 1359.1 1379.9 1414.6 29068.6 29570.3 30585.4 31438.0 31442.0 35371.0 48318.0 48320.0 48339.0 48360.8 399075.0 400081.0 400289.0 401420.9 403374.0 23164.0 25667.8 26122.7 26417.0 26735.6 45016.8 45885.8 45955.5 47099.3 47136.5 32133.0 38817.4 39242.8 39706.2 40202.8 33857.6 38297.4 38421.2 39251.6 39938.6 31303.8 32451.0 33232.0 33396.7 38279.6 28510.9 29767.0 35204.3 35368.6 35382.3 24325.6 24723.0 25242.9 25319.6 25617.0 04:45:10 INFO - PROCESS | 2155 | 04:45:10 INFO - PROCESS | 2155 | [#2] jquery.html Cycles:180 Average:35940.32 Median:14881.42 stddev:104456.74 (701.9%) stddev-sans-first:104715.58 04:45:10 INFO - PROCESS | 2155 | Values: 345.8 348.2 376.3 383.2 401.2 15571.8 16675.8 20335.7 20892.3 21010.5 20885.5 21165.5 21404.9 21475.6 21744.6 5741.2 5806.9 6043.2 6046.7 6073.5 5241.6 6035.1 6080.8 6107.2 6117.0 3944.8 5689.5 6101.7 6111.4 6123.6 5841.2 6060.5 6086.6 6097.5 6118.9 5913.0 5913.2 5913.5 5916.8 5924.8 13168.2 18567.3 25295.5 26544.3 26827.2 2251.4 2380.2 2423.5 2438.4 2935.6 9724.0 9860.9 10261.4 10267.9 10320.8 1650.9 1830.9 1848.6 1874.3 1877.4 108784.5 115230.7 126181.0 127142.4 130416.0 11233.6 11452.1 11594.3 11611.8 11806.5 14618.8 14621.3 14647.8 14664.0 14696.3 8452.4 8599.2 8652.4 8662.0 8754.2 6987.3 8574.2 8679.6 8734.2 8760.0 3731.9 4497.7 4507.2 4581.1 4588.0 1676.6 1884.9 1906.0 1911.6 1940.0 801.2 874.0 958.6 981.4 1051.0 21169.8 21791.2 21852.5 21893.6 22944.7 907.2 951.4 972.1 973.5 1043.7 11051.6 11499.7 11504.3 11553.8 11596.5 617147.8 622869.0 625461.0 630808.0 638374.0 91649.8 116722.8 119977.0 136896.6 139627.0 958.3 970.8 1004.3 1006.7 1007.8 19209.0 19986.9 20019.9 20055.8 20106.1 56620.7 58000.7 58335.7 58479.7 58693.0 82907.3 83339.6 83561.0 83850.0 84929.0 7711.3 7878.3 8061.4 8145.3 8270.9 10474.9 12317.8 12479.6 12501.0 12975.4 8959.4 9236.8 9946.5 11362.7 11452.4 10684.7 10734.5 10769.8 10900.8 11445.4 9886.6 11086.7 11131.1 11235.0 11358.9 6936.9 7213.4 7301.6 7541.4 8206.1 9719.5 10932.0 11344.1 11448.9 11463.4 04:45:10 INFO - PROCESS | 2155 | 04:45:10 INFO - PROCESS | 2155 | [#3] mootools.html Cycles:180 Average:5750.67 Median:4701.15 stddev:3232.83 (68.8%) stddev-sans-first:3225.86 04:45:10 INFO - PROCESS | 2155 | Values: 1465.2 1531.8 1665.4 1740.4 1836.8 8492.4 9007.1 9016.2 9016.8 9019.6 7877.2 8120.9 8535.1 8749.2 8757.0 2378.4 2409.3 2414.0 2414.3 2415.7 2365.0 2389.4 2409.0 2409.4 2410.2 2382.1 2393.8 2402.3 2406.2 2408.6 2354.8 2365.9 2380.6 2382.6 2390.1 2358.2 2373.7 2376.5 2382.3 2384.1 8811.5 8869.8 8935.6 8946.5 8950.1 4340.6 5253.5 5256.8 5261.3 5270.9 3466.8 3612.7 3677.6 3691.6 3699.0 1082.8 1127.1 1127.8 1142.9 1149.1 11718.8 11851.5 11859.0 11860.3 11864.0 4475.0 4647.8 4794.0 4830.9 4843.0 10016.4 10022.0 10040.1 10042.1 10045.6 5362.6 5373.3 5374.9 5377.9 5387.2 4358.5 4367.8 4578.2 4754.5 4906.1 3846.4 3894.2 3963.6 3973.5 3998.4 1293.7 1305.4 1307.8 1317.8 1319.7 897.8 910.7 919.6 942.1 962.2 5333.1 5887.1 5964.7 6201.8 6425.6 896.6 928.5 949.7 975.3 1000.3 3900.2 3938.9 4011.5 4059.1 4131.4 10602.5 10906.0 10996.9 11012.9 11068.1 10937.7 10954.7 10960.3 10965.5 10985.9 7761.0 8179.7 8205.4 8214.3 8358.7 5538.0 5990.4 5999.1 6141.1 6168.5 9931.3 10164.3 10201.2 10207.9 10277.7 9726.8 10189.0 10217.1 10295.7 10324.0 6981.3 7210.6 7312.8 7478.1 7655.9 8151.4 8441.5 8445.5 8455.5 8467.0 7324.2 7444.0 7450.0 7520.1 7610.5 7283.7 7290.8 7301.3 7458.7 7460.3 6890.6 7033.0 7068.9 7072.4 7085.5 4893.7 4964.6 4987.8 4998.8 5082.3 4487.3 4863.2 4880.7 4983.4 5134.2 04:45:10 INFO - PROCESS | 2155 | 04:45:10 INFO - PROCESS | 2155 | [#4] prototype.html Cycles:180 Average:4455.82 Median:5002.30 stddev:1372.45 (27.4%) stddev-sans-first:1339.97 04:45:10 INFO - PROCESS | 2155 | Values: 276.5 301.3 302.5 309.9 314.9 5232.5 5316.8 5332.6 5370.5 5374.4 4685.9 4734.2 4971.4 5193.9 5208.7 3523.9 3631.3 3634.4 3703.7 3766.3 3809.6 4067.4 4068.8 4091.3 4121.4 3821.9 3898.2 4054.4 4063.7 4071.9 3398.2 3700.3 3763.9 4037.5 4067.6 3478.4 3480.3 3505.2 3519.2 3519.7 4238.5 5124.7 5135.5 5139.8 5203.6 4464.7 4593.2 4628.8 4640.5 4670.7 5425.8 5466.2 5484.7 5491.2 5500.4 3505.6 3636.9 3809.8 3867.8 3878.7 5192.3 5516.7 5531.5 5559.4 5805.6 3362.7 3718.9 3871.6 3921.2 3964.7 3367.5 3779.7 3962.8 3976.9 3990.5 4618.3 4645.0 4649.6 4654.1 4671.9 4673.8 5422.2 5591.0 5595.3 5649.1 1158.7 1164.1 1324.9 1371.7 1373.7 723.8 737.5 775.3 779.3 822.8 4201.8 4919.9 5028.0 5107.4 5135.5 4557.5 4661.9 5043.3 5601.3 5766.9 4241.7 4310.8 4598.4 5141.8 5173.0 2940.8 2975.0 2996.2 2999.4 3014.0 4766.9 4951.0 5835.2 5927.5 5973.5 5402.9 5499.7 5787.2 5807.4 5841.7 5860.7 5861.8 5866.1 5866.9 5870.8 4656.9 5105.3 5627.8 5630.5 5654.1 5057.3 5213.9 5711.7 5736.7 5767.6 4976.6 5238.7 5491.8 5996.4 6004.3 3108.8 3832.9 3864.7 3871.9 3903.0 5335.5 5371.2 5502.8 5574.3 5820.8 4916.6 5606.3 5610.9 5627.9 5643.6 5437.8 5575.7 5615.6 5616.9 5657.7 5464.4 5470.5 5483.6 5491.2 5492.5 4548.9 4657.7 4781.3 5098.3 5309.9 4718.6 5052.7 5200.6 5354.8 5467.2 04:45:10 INFO - PROCESS | 2155 | 04:45:10 INFO - PROCESS | 2155 | [#5] yui.html Cycles:180 Average:23547.03 Median:3454.75 stddev:37019.63 (1071.6%) stddev-sans-first:37083.82 04:45:10 INFO - PROCESS | 2155 | Values: 723.3 724.2 734.7 782.7 848.6 14433.3 15070.6 15368.1 15429.7 15951.5 14852.6 14876.0 15029.2 15035.6 15089.6 689.6 850.1 865.9 867.9 874.2 707.4 853.5 878.3 883.7 887.6 935.7 938.4 940.5 941.7 942.8 716.7 934.0 935.5 935.7 937.4 665.2 688.4 690.8 691.2 692.4 14638.6 14697.9 14712.7 14725.2 14729.3 13681.7 14038.9 14164.6 14318.9 14352.1 13075.8 13122.3 13150.3 13186.0 13502.0 4212.5 4261.6 4422.3 4501.6 4502.8 104494.5 104748.5 104900.5 105002.0 113230.3 10572.7 10978.7 10998.5 10999.0 11523.1 21517.2 21623.0 21674.2 21675.9 22011.3 7801.5 7831.3 7913.6 7919.8 8036.6 3765.3 4289.8 4299.5 4317.0 4358.8 2744.5 3449.3 3452.0 3457.5 3462.1 574.5 590.0 590.1 590.8 598.1 487.4 539.3 583.4 585.9 594.5 8463.3 8852.2 8854.6 8901.2 9046.9 461.8 491.0 538.1 578.6 579.1 4297.2 4510.0 4535.2 4547.0 4599.0 128688.4 130842.0 138334.9 142758.0 142879.0 113032.9 115981.4 119307.0 119321.0 121958.0 107010.0 107377.0 108233.2 109872.5 110243.4 8764.9 8832.5 8917.5 8967.2 9069.2 58339.1 59250.7 59431.4 60518.4 61511.7 70867.6 78033.0 85271.1 85373.0 90071.0 11020.2 11448.4 11512.4 11899.2 11971.3 11435.2 12049.3 12428.9 12541.6 12956.6 8216.5 9193.7 11667.0 12065.0 12357.2 12500.6 12785.7 13009.8 13407.3 13409.9 12862.2 12894.5 13004.2 13032.7 13089.5 10727.9 10882.0 10956.2 11092.2 11341.9 10605.7 11121.9 11168.4 11311.7 11513.3 04:45:10 INFO - PROCESS | 2155 | -------- Summary: end -------- 04:45:10 INFO - PROCESS | 2155 | 04:45:11 INFO - TEST-INFO | 2155: exit 0 04:45:11 INFO - TEST-OK | dromaeo_css | took 1119545ms 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 2270 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpLS2mXg/profile http://localhost:49516/getInfo.html) 04:45:13 INFO - PROCESS | 2270 | __metrics Screen width/height:1600/1200 04:45:13 INFO - PROCESS | 2270 | colorDepth:24 04:45:13 INFO - PROCESS | 2270 | Browser inner width/height: 1024/689 04:45:13 INFO - PROCESS | 2270 | __metrics 04:45:14 INFO - PROCESS | 2270 | 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:14 INFO - PROCESS | 2270 | 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 | 2270: 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 2272 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpLS2mXg/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 | 2272 | RSS: Main: 228618240 04:45:26 INFO - PROCESS | 2272 | 04:45:29 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49516/tests/kraken/driver.html?testName=audio-beat-detection) 04:45:30 INFO - PROCESS | 2272 | RSS: Main: 284860416 04:45:30 INFO - PROCESS | 2272 | 04:45:32 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49516/tests/kraken/driver.html?testName=audio-dft) 04:45:33 INFO - PROCESS | 2272 | RSS: Main: 292859904 04:45:33 INFO - PROCESS | 2272 | 04:45:37 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49516/tests/kraken/driver.html?testName=audio-fft) 04:45:37 INFO - PROCESS | 2272 | RSS: Main: 387198976 04:45:37 INFO - PROCESS | 2272 | 04:45:39 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49516/tests/kraken/driver.html?testName=audio-oscillator) 04:45:40 INFO - PROCESS | 2272 | RSS: Main: 288235520 04:45:40 INFO - PROCESS | 2272 | 04:45:42 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49516/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:45:43 INFO - PROCESS | 2272 | RSS: Main: 294006784 04:45:43 INFO - PROCESS | 2272 | 04:45:45 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49516/tests/kraken/driver.html?testName=imaging-darkroom) 04:45:46 INFO - PROCESS | 2272 | RSS: Main: 331526144 04:45:46 INFO - PROCESS | 2272 | 04:45:49 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49516/tests/kraken/driver.html?testName=imaging-desaturate) 04:45:49 INFO - PROCESS | 2272 | RSS: Main: 331730944 04:45:49 INFO - PROCESS | 2272 | 04:45:52 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49516/tests/kraken/driver.html?testName=json-parse-financial) 04:45:53 INFO - PROCESS | 2272 | RSS: Main: 332705792 04:45:53 INFO - PROCESS | 2272 | 04:45:54 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49516/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:45:55 INFO - PROCESS | 2272 | RSS: Main: 300560384 04:45:55 INFO - PROCESS | 2272 | 04:45:57 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49516/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:45:58 INFO - PROCESS | 2272 | RSS: Main: 306929664 04:45:58 INFO - PROCESS | 2272 | 04:46:00 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49516/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:46:00 INFO - PROCESS | 2272 | RSS: Main: 307437568 04:46:00 INFO - PROCESS | 2272 | 04:46:03 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49516/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:46:04 INFO - PROCESS | 2272 | RSS: Main: 315367424 04:46:04 INFO - PROCESS | 2272 | 04:46:07 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49516/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:46:07 INFO - PROCESS | 2272 | RSS: Main: 316579840 04:46:07 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:49516/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49516/tests/kraken/driver.html?testName=ai-astar) 04:46:09 INFO - PROCESS | 2272 | RSS: Main: 359919616 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | __start_tp_report 04:46:09 INFO - PROCESS | 2272 | _x_x_mozilla_page_load 04:46:09 INFO - PROCESS | 2272 | _x_x_mozilla_page_load_details 04:46:09 INFO - PROCESS | 2272 | |i|pagename|runs| 04:46:09 INFO - PROCESS | 2272 | |0;ai-astar;102;114;112;113;107;113;111;115;112;113 04:46:09 INFO - PROCESS | 2272 | |1;audio-beat-detection;145;111;135;202;136;130;127;109;152;125 04:46:09 INFO - PROCESS | 2272 | |2;audio-dft;187;185;184;183;184;221;262;180;181;183 04:46:09 INFO - PROCESS | 2272 | |3;audio-fft;74;58;62;87;59;57;65;65;84;57 04:46:09 INFO - PROCESS | 2272 | |4;audio-oscillator;83;69;105;70;76;68;97;71;68;103 04:46:09 INFO - PROCESS | 2272 | |5;imaging-gaussian-blur;88;83;87;85;86;83;88;85;87;84 04:46:09 INFO - PROCESS | 2272 | |6;imaging-darkroom;93;92;95;93;92;92;93;94;93;91 04:46:09 INFO - PROCESS | 2272 | |7;imaging-desaturate;85;84;83;85;85;83;86;84;84;83 04:46:09 INFO - PROCESS | 2272 | |8;json-parse-financial;56;55;55;55;54;56;55;55;55;55 04:46:09 INFO - PROCESS | 2272 | |9;json-stringify-tinderbox;64;65;68;105;103;100;71;68;70;71 04:46:09 INFO - PROCESS | 2272 | |10;stanford-crypto-aes;85;87;86;87;84;85;84;88;89;81 04:46:09 INFO - PROCESS | 2272 | |11;stanford-crypto-ccm;138;141;136;139;139;134;139;137;157;136 04:46:09 INFO - PROCESS | 2272 | |12;stanford-crypto-pbkdf2;170;170;173;172;173;171;171;170;170;233 04:46:09 INFO - PROCESS | 2272 | |13;stanford-crypto-sha256-iterative;68;68;69;67;68;70;69;68;66;67 04:46:09 INFO - PROCESS | 2272 | __end_tp_report 04:46:09 INFO - PROCESS | 2272 | __start_cc_report 04:46:09 INFO - PROCESS | 2272 | _x_x_mozilla_cycle_collect,2053 04:46:09 INFO - PROCESS | 2272 | __end_cc_report 04:46:09 INFO - PROCESS | 2272 | __startTimestamp1471520769937__endTimestamp 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | ------- Summary: start ------- 04:46:09 INFO - PROCESS | 2272 | Number of tests: 14 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#0] ai-astar Cycles:10 Average:111.20 Median:113.00 stddev:3.88 (3.4%) stddev-sans-first:2.28 04:46:09 INFO - PROCESS | 2272 | Values: 102.0 114.0 112.0 113.0 107.0 113.0 111.0 115.0 112.0 113.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#1] audio-beat-detection Cycles:10 Average:137.20 Median:135.50 stddev:26.40 (19.5%) stddev-sans-first:27.85 04:46:09 INFO - PROCESS | 2272 | Values: 145.0 111.0 135.0 202.0 136.0 130.0 127.0 109.0 152.0 125.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#2] audio-dft Cycles:10 Average:195.00 Median:184.50 stddev:26.42 (14.3%) stddev-sans-first:27.86 04:46:09 INFO - PROCESS | 2272 | Values: 187.0 185.0 184.0 183.0 184.0 221.0 262.0 180.0 181.0 183.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#3] audio-fft Cycles:10 Average:66.80 Median:65.00 stddev:11.13 (17.1%) stddev-sans-first:11.50 04:46:09 INFO - PROCESS | 2272 | Values: 74.0 58.0 62.0 87.0 59.0 57.0 65.0 65.0 84.0 57.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:70.50 stddev:15.08 (21.4%) stddev-sans-first:15.98 04:46:09 INFO - PROCESS | 2272 | Values: 83.0 69.0 105.0 70.0 76.0 68.0 97.0 71.0 68.0 103.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:1.90 (2.2%) stddev-sans-first:1.80 04:46:09 INFO - PROCESS | 2272 | Values: 88.0 83.0 87.0 85.0 86.0 83.0 88.0 85.0 87.0 84.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:1.14 (1.2%) stddev-sans-first:1.20 04:46:09 INFO - PROCESS | 2272 | Values: 93.0 92.0 95.0 93.0 92.0 92.0 93.0 94.0 93.0 91.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.50 stddev:1.03 (1.2%) stddev-sans-first:1.05 04:46:09 INFO - PROCESS | 2272 | Values: 85.0 84.0 83.0 85.0 85.0 83.0 86.0 84.0 84.0 83.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:0.57 (1.0%) stddev-sans-first:0.50 04:46:09 INFO - PROCESS | 2272 | Values: 56.0 55.0 55.0 55.0 54.0 56.0 55.0 55.0 55.0 55.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#9] json-stringify-tinderbox Cycles:10 Average:78.50 Median:68.00 stddev:16.87 (24.8%) stddev-sans-first:17.06 04:46:09 INFO - PROCESS | 2272 | Values: 64.0 65.0 68.0 105.0 103.0 100.0 71.0 68.0 70.0 71.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:86.50 stddev:2.32 (2.7%) stddev-sans-first:2.45 04:46:09 INFO - PROCESS | 2272 | Values: 85.0 87.0 86.0 87.0 84.0 85.0 84.0 88.0 89.0 81.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#11] stanford-crypto-ccm Cycles:10 Average:139.60 Median:139.00 stddev:6.43 (4.6%) stddev-sans-first:6.80 04:46:09 INFO - PROCESS | 2272 | Values: 138.0 141.0 136.0 139.0 139.0 134.0 139.0 137.0 157.0 136.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:171.50 stddev:19.61 (11.4%) stddev-sans-first:20.62 04:46:09 INFO - PROCESS | 2272 | Values: 170.0 170.0 173.0 172.0 173.0 171.0 171.0 170.0 170.0 233.0 04:46:09 INFO - PROCESS | 2272 | 04:46:09 INFO - PROCESS | 2272 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.15 (1.7%) stddev-sans-first:1.22 04:46:09 INFO - PROCESS | 2272 | Values: 68.0 68.0 69.0 67.0 68.0 70.0 69.0 68.0 66.0 67.0 04:46:09 INFO - PROCESS | 2272 | -------- Summary: end -------- 04:46:09 INFO - PROCESS | 2272 | 04:46:10 INFO - TEST-INFO | 2272: exit 0 04:46:10 INFO - TEST-OK | kraken | took 59360ms 04:46:10 INFO - SUITE-END | took 1178s 04:46:11 INFO - Completed test suite (00:19:39) 04:46:11 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": [2868.5553739170423, 3319.2697606526553, 3337.332533493302, 3365.9495107573375, 3370.342783157284, 5568.9443105569035, 5637.267155269814, 5678.886422271562, 5688.260526131602, 5740.081586946095, 5654.321481422225, 5698.059820129684, 5704.800331988381, 5711.600187986832, 5713.228714123603, 5144.408393034802, 5165.096108181053, 5206.167013277876, 5208.817691380802, 5262.236975638531, 5264.131418315992, 5298.761555729982, 5300.09898317285, 5307.2039194121, 5309.814156504503, 5176.378834539861, 5204.869878253055, 5219.321488206547, 5222.190560463135, 5236.659617124894, 5042.495750424947, 5302.496262855023, 5303.973480132575, 5347.652402593858, 5397.676139431646, 5104.412992505872, 5115.897682046339, 5181.35233095863, 5228.424873263938, 5248.71132087735, 4800.687955282895, 5570.582206334541, 5643.971780141073, 5668.546516278688, 5700.885982280372, 5195.766190521435, 6163.352847950945, 6279.058141278799, 6284.905726414107, 6289.150964619809, 6131.63210207389, 6218.875622487571, 6295.937040629581, 6347.0, 6377.96811015942, 5789.870975159888, 5824.475797178275, 5831.854203644943, 5871.236739223874, 5871.823845284648, 6232.937670623326, 6246.219222597176, 6247.96876015617, 6262.405071518198, 6268.686565671698, 5745.454181852718, 5782.59521833476, 5804.780995990889, 6045.667488288186, 6067.63496630201, 5271.393789714155, 5646.322441307069, 6240.032794916795, 6279.497640188774, 6292.937070629235, 6222.004479283383, 6222.0355844844125, 6229.813105606839, 6232.8130156095385, 6238.1266622672865, 6097.542684298695, 6134.355892631299, 6169.81490555284, 6253.78111766086, 6261.279952805395, 4937.703737775745, 4965.801367945314, 4978.950210497848, 4979.6265280104135, 4982.975085124624, 4433.556644335541, 4489.483709373399, 4492.707973981746, 4494.101179763995, 4502.932456013163, 4913.877153071202, 5804.2744656917885, 5816.47651711348, 5834.620749651259, 5858.062709966385, 5873.4126587340925, 6220.471259942956, 6259.123722678829, 6302.275238347557, 6354.555181137276, 5615.0454422748235, 5682.7442765075675, 5687.8862422752145, 5733.197352370671, 5752.7698892043845, 5416.0, 5449.972750136224, 5457.099578569571, 5474.835754927359, 5481.616286859881, 5400.729963501731, 6294.0, 6328.588641738273, 6343.1436756038265, 6377.74489020434, 6285.120083188266, 6298.905516417166, 6301.0, 6301.086342480223, 6310.968445157745, 6191.504679625529, 6259.092431597484, 6309.1167236586, 6336.176297081534, 6362.395572420613, 6215.254169499689, 6247.0, 6274.560780745304, 6286.622802631857, 6287.622742635456, 6260.968695156495, 6276.340984196594, 6293.213348331458, 6296.118543404019, 6307.306196318493, 5967.492763115184, 6057.0, 6079.3008803988105, 6159.19930409044, 6162.537809664203, 6111.694415279308, 6155.969220153871, 6172.69136543162, 6176.413240742122, 6188.009918413032, 6015.0676645120075, 6017.608855424383, 6047.5464340173785, 6051.0, 6068.423499767515, 5900.940990590039, 6039.607425517327, 6131.080337949344, 6151.72317245716, 6161.322254551906, 5883.823485295448, 5960.195373624679, 5998.670073146019, 6003.729832157476, 6006.549508786946], "value": 5684.387540033361, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.5929371094003, 520.2378515474828, 523.0898237067504, 524.9108100691066, 542.1623591551054, 30831.38337233254, 40157.598424015785, 41295.79352103243, 41465.0, 41630.0, 35119.4732079019, 41830.163396732045, 42223.57776422235, 42263.366049509284, 42283.94290142748, 9783.315167938246, 9957.850632240523, 9973.656190795158, 9976.700698979042, 10010.59957601695, 9783.804323913517, 9898.802023959517, 9907.801843963116, 9909.454979976099, 9910.752231194207, 9902.455364954925, 9906.65326713565, 9920.900790992106, 9927.453990030546, 9953.35303205293, 9861.457619830906, 9905.801883962353, 9912.405255684609, 9924.45415502147, 9944.403335799876, 8734.2139207471, 9655.662051828152, 9775.706728798077, 9829.90170098297, 9857.457839818808, 39545.80227098875, 39912.401313980314, 39958.0, 40033.39949900754, 40204.59795402067, 3482.2165012872156, 3517.296540691872, 3577.3918433866047, 3668.614795446493, 4133.937990930139, 30977.76088956439, 32162.356752865046, 32164.839175804205, 32320.19199520007, 32354.029379118663, 2879.0, 2919.693432189632, 3213.7268332191557, 3314.4696848504122, 3352.932941341184, 487954.0, 488064.0, 491090.0, 492674.0, 497164.0, 59593.0, 60267.69866150729, 60487.69756151191, 60498.79002419927, 60569.0, 33999.4900076499, 34708.0, 34830.47754283688, 35898.923032309074, 36272.63727362746, 16647.33410663584, 16791.328346866234, 16859.578510537336, 16868.746968795476, 16906.239219235165, 12860.421281042376, 12888.80666789999, 12924.676883077811, 12954.222746635232, 12977.740445191042, 18762.968036757837, 18795.90602046981, 18813.341533046278, 18837.81162188388, 18842.71735923962, 6803.0, 7747.457677962601, 7837.294643482, 7899.669597468645, 7926.207379262143, 869.3827382558328, 998.4708104704519, 1024.3853687787416, 1034.6637342863598, 1152.4353066997112, 28857.134285971453, 29955.550666740015, 29967.25081872927, 30716.0, 30741.538876916864, 1084.837274408845, 1132.6149109302876, 1134.2967360236705, 1143.4168574027306, 1229.2255878796302, 13067.869321306855, 15379.077255364715, 15710.68578628421, 16060.75908861368, 16763.0, 625710.0, 625822.0, 630109.4406975367, 632187.0, 632430.0, 48152.759236203594, 56861.43138568562, 57412.712936435884, 57609.99999999916, 57627.711861440424, 1238.7089034076985, 1266.341502418737, 1359.0690377091723, 1379.862013798612, 1414.610981979943, 29068.56397154002, 29570.260743481584, 30585.388292234475, 31438.0, 31442.0, 35371.0, 48318.0, 48320.0, 48339.0, 48360.75819620879, 399075.0, 400081.0, 400289.0, 401420.9147451162, 403374.0, 23164.0, 25667.844946977806, 26122.738772612032, 26417.0, 26735.598966015135, 45016.77491612521, 45885.77057114693, 45955.54044459647, 47099.29351059805, 47136.52863471321, 32133.0, 38817.417738734504, 39242.82271531859, 39706.205875881744, 40202.798986004884, 33857.64569417196, 38297.4255386175, 38421.231575368896, 39251.607483924796, 39938.60061399465, 31303.843480783362, 32451.0, 33232.0, 33396.66603334033, 38279.617203827605, 28510.857445712638, 29767.0, 35204.29591408209, 35368.64631353757, 35382.29235415329, 24325.635115472927, 24723.0, 25242.864071117212, 25319.620205697298, 25617.0], "value": 19428.641962125585, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.7527867574684, 348.1591955427642, 376.25877022266127, 383.1972018620988, 401.21762563002153, 15571.766423503643, 16675.83324166758, 20335.694964575505, 20892.268770593033, 21010.474738131572, 20885.47786305343, 21165.470863228427, 21404.89297553514, 21475.57048859022, 21744.565108697818, 5741.220221731277, 5806.854828629297, 6043.244594425697, 6046.697665116749, 6073.51411887048, 5241.633085684004, 6035.064564992411, 6080.787172448943, 6107.206063211798, 6116.969415152918, 3944.8027598619956, 5689.4879460848515, 6101.664408457533, 6111.419415155553, 6123.632582045091, 5841.211436456067, 6060.454559089703, 6086.569656130806, 6097.481714054311, 6118.877622447549, 5912.970435147839, 5913.231279933624, 5913.467787899067, 5916.792912248094, 5924.763009479616, 13168.209907405491, 18567.257309707595, 25295.494090118096, 26544.336391590172, 26827.195184144508, 2251.4033781048033, 2380.214529205358, 2423.515296940619, 2438.4147804526965, 2935.6477222733197, 9724.0, 9860.90139098607, 10261.43562104084, 10267.921868203806, 10320.845187322197, 1650.8844380893343, 1830.9176087076114, 1848.6302739452162, 1874.297138573035, 1877.399232245682, 108784.4560777191, 115230.69538609264, 126181.0, 127142.3642881789, 130416.0, 11233.550657973752, 11452.083833293316, 11594.304341739355, 11611.76776464466, 11806.46870890812, 14618.780718289234, 14621.342039608244, 14647.780283295759, 14664.046836955564, 14696.26518674062, 8452.408331416742, 8599.183077607617, 8652.356052350033, 8661.956690216635, 8754.21212090915, 6987.32304246977, 8574.185452382013, 8679.566021698889, 8734.213920747163, 8760.036395996436, 3731.869384571527, 4497.740632622806, 4507.18870603288, 4581.083783243364, 4587.977060114678, 1676.5724740191174, 1884.9340273090377, 1905.961880762377, 1911.5603411215498, 1940.0202897536653, 801.1907972947365, 873.9781505462287, 958.6453012385323, 981.40134517944, 1050.9842352364722, 21169.78830211709, 21791.247932201248, 21852.45368865759, 21893.562128757334, 22944.65583016256, 907.1518130547959, 951.362587066667, 972.0619602084023, 973.5375696544085, 1043.7442826507402, 11051.613193538187, 11499.655010349703, 11504.309741415542, 11553.768924621629, 11596.536138554533, 617147.8285217091, 622869.0, 625461.0, 630808.0, 638374.0, 91649.77786652077, 116722.8327716712, 119977.0, 136896.63103368838, 139627.0, 958.3195930889146, 970.8301047316833, 1004.3220825942606, 1006.7483129217695, 1007.7833265847878, 19209.0, 19986.900720460515, 20019.899900500404, 20055.796652200916, 20106.095225714584, 56620.73067714387, 58000.70999644975, 58335.70832145982, 58479.70760146342, 58693.0, 82907.34185316139, 83339.5833020831, 83561.0, 83850.0, 84929.0, 7711.26742959418, 7878.251566101211, 8061.435699500978, 8145.266925976691, 8270.875936861072, 10474.895251047392, 12317.8298061684, 12479.625611231677, 12501.0, 12975.416106275428, 8959.41763785352, 9236.769080773034, 9946.502674866373, 11362.659120226406, 11452.3701196435, 10684.679459616224, 10734.463276836283, 10769.838452423062, 10900.836487452536, 11445.427728613702, 9886.555105020147, 11086.667399978014, 11131.109511239243, 11235.0, 11358.94320528392, 6936.861262774615, 7213.422926165999, 7301.634918254173, 7541.35898448638, 8206.097329293892, 9719.46542940145, 10932.0, 11344.149188810707, 11448.885511144781, 11463.369514676773], "value": 9568.645486801164, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1465.2161093814811, 1531.762576800596, 1665.3921318718665, 1740.3734655524001, 1836.797952225255, 8492.363072769544, 9007.054259302777, 9016.233620142288, 9016.774580635487, 9019.594118264677, 7877.21227877212, 8120.878186827203, 8535.103814099508, 8749.168828961254, 8757.036725960139, 2378.4172877645, 2409.3494756415757, 2414.022320960014, 2414.33605758417, 2415.7342692303832, 2364.9881750591308, 2389.3668177932864, 2408.9879550602222, 2409.4337830609793, 2410.1684918703018, 2382.094803974487, 2393.7725916038034, 2402.315340128064, 2406.169871394366, 2408.590539608262, 2354.846934949223, 2365.8698771567556, 2380.571497130516, 2382.5949588569897, 2390.127603424755, 2358.1982126077214, 2373.715154181492, 2376.512814872964, 2382.321038504035, 2384.141708984764, 8811.471311721318, 8869.778255543599, 8935.64257429702, 8946.507942063185, 8950.14973577509, 4340.60934515895, 5253.474652534716, 5256.763445644917, 5261.316028916254, 5270.894582108374, 3466.7746596471147, 3612.747107702462, 3677.632236776301, 3691.6123807000145, 3698.981505092457, 1082.7888561730465, 1127.1490025031048, 1127.7631697343568, 1142.874283828778, 1149.0577726264466, 11718.824217636742, 11851.466683999155, 11859.0, 11860.288382697066, 11864.0, 4474.955250447454, 4647.814087436464, 4794.0, 4830.855074347775, 4843.0, 10016.449095299828, 10022.0, 10040.146587539995, 10042.14641755459, 10045.598176072876, 5362.570994320445, 5373.301470808769, 5374.91937620936, 5377.892442151135, 5387.218853266255, 4358.498772641124, 4367.820688414174, 4578.221702310615, 4754.477007529169, 4906.092372910952, 3846.4038074098557, 3894.240623078529, 3963.583823698528, 3973.463582416337, 3998.4002399639703, 1293.7412517496539, 1305.3668970549277, 1307.8495972963042, 1317.8089177069462, 1319.6700824793802, 897.8159477307137, 910.7176775199576, 919.6137622198693, 942.1473566422399, 962.1629182611033, 5333.12003519415, 5887.116932460165, 5964.671943043174, 6201.782937597162, 6425.646589437532, 896.5562046786752, 928.5190121755788, 949.6723630347519, 975.3276023695024, 1000.309786247487, 3900.2004589059175, 3938.9015274618364, 4011.498562679665, 4059.1475790083823, 4131.400946862688, 10602.469876506144, 10906.0, 10996.945015275032, 11012.889871101346, 11068.114550835913, 10937.726556835984, 10954.671359859218, 10960.28758130719, 10965.451727413598, 10985.890141098647, 7761.0, 8179.672813087529, 8205.384596155312, 8214.301784348398, 8358.749237522885, 5538.003159431341, 5990.400959904149, 5999.100134979788, 6141.1402403664415, 6168.537359697951, 9931.255155863484, 10164.339317944607, 10201.183905287411, 10207.886594588721, 10277.74305642365, 9726.805463890541, 10188.981101889753, 10217.131543818561, 10295.742606434898, 10324.019218174262, 6981.301869812978, 7210.603416812126, 7312.817179570553, 7478.06524184477, 7655.885161722467, 8151.429399942184, 8441.493510389397, 8445.535495547803, 8455.534945578052, 8467.026291976255, 7324.19433862264, 7444.032275804111, 7450.031495905498, 7520.09758828944, 7610.467267291237, 7283.7450689225625, 7290.781276561712, 7301.269873012869, 7458.701651934079, 7460.253974602713, 6890.586564806128, 7032.964835175996, 7068.929310706827, 7072.3634872861685, 7085.468589855678, 4893.681910675795, 4964.602831773522, 4987.775550100327, 4998.800047998185, 5082.33929589151, 4487.339684316824, 4863.2461968394955, 4880.658353915192, 4983.377077865267, 5134.204198349262], "value": 4631.468466660386, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.52575832447354, 301.28144375664044, 302.50691373061903, 309.94730895747733, 314.93858697553986, 5232.502912223337, 5316.777141257513, 5332.626716129873, 5370.489803468674, 5374.408815030353, 4685.9062818743605, 4734.218853889104, 4971.384300102463, 5193.870153246161, 5208.739563021843, 3523.9119022024397, 3631.2737452509473, 3634.436662317332, 3703.740738148331, 3766.303233901723, 3809.561900381464, 4067.373050779697, 4068.823179598915, 4091.304478238692, 4121.361189015707, 3821.9235615287816, 3898.161895192533, 4054.3918412238254, 4063.6545893599077, 4071.857484988011, 3398.1819726446356, 3700.333939890819, 3763.8870833875026, 4037.4953130858644, 4067.5932406759234, 3478.391281525748, 3480.3387356402454, 3505.1597911096624, 3519.1729943463265, 3519.7360197985, 4238.470191226097, 5124.692518448905, 5135.537801597837, 5139.7687104080405, 5203.5837133029645, 4464.72755264748, 4593.219152744042, 4628.791704373311, 4640.535946405366, 4670.673052886299, 5425.782968681248, 5466.1527463243265, 5484.698341591211, 5491.226930400122, 5500.42245564218, 3505.631908649606, 3636.890893273206, 3809.847606095753, 3867.8066096695334, 3878.720022392604, 5192.299039629677, 5516.675997760549, 5531.502164805187, 5559.360673522557, 5805.564582656318, 3362.7141692956375, 3718.90919988011, 3871.567520017555, 3921.1961547883184, 3964.742291751027, 3367.545381373532, 3779.6598306152578, 3962.7622342659533, 3976.940345894814, 3990.521137463465, 4618.330342100377, 4644.95355046452, 4649.604783593432, 4654.069186162781, 4671.902103005791, 4673.766311684402, 5422.213779002022, 5591.049521581341, 5595.272614560081, 5649.12438572022, 1158.6697791129488, 1164.062929341871, 1324.9403776830065, 1371.6776557508977, 1373.6881278379124, 723.7828651404558, 737.5464089584867, 775.267372333151, 779.3142035009156, 822.8066404394963, 4201.831926722958, 4919.926201106986, 5028.0, 5107.412647545506, 5135.456534990844, 4557.544245575482, 4661.883452913704, 5043.2687260347775, 5601.29983752031, 5766.88466230673, 4241.724287921337, 4310.766924806395, 4598.402207713043, 5141.794328226903, 5172.974135129375, 2940.8088474249107, 2975.0, 2996.2471953907207, 2999.43010827942, 3013.9547906781418, 4766.928496072562, 4950.950490495121, 5835.183074369592, 5927.5247188372505, 5973.492253158443, 5402.8919421611345, 5499.670019798825, 5787.160861675117, 5807.361190269067, 5841.7371218295275, 5860.677662728591, 5861.7655293787775, 5866.149408335682, 5866.911996320144, 5870.7945221917025, 4656.906861862676, 5105.324166600042, 5627.7510479587645, 5630.493255607015, 5654.123610840326, 5057.317262169707, 5213.947860521498, 5711.685857277889, 5736.741846616996, 5767.625104368202, 4976.601871850316, 5238.711870846988, 5491.780328786964, 5996.37038110992, 6004.339522652592, 3108.844557772057, 3832.9425058623583, 3864.705323716474, 3871.941920871133, 3902.9609703903734, 5335.546478549367, 5371.248025276386, 5502.80740174092, 5574.303212098488, 5820.835832833535, 4916.631252655993, 5606.299212598426, 5610.859728506657, 5627.902559000955, 5643.576731745054, 5437.809676661298, 5575.7490912909825, 5615.578831587565, 5616.915746263891, 5657.717114144359, 5464.426235245242, 5470.480304371078, 5483.616146869681, 5491.231227628055, 5492.4507549246355, 4548.863534093983, 4657.742409549471, 4781.330613714013, 5098.337216161875, 5309.920351194658, 4718.598919091915, 5052.7010210353465, 5200.557952573922, 5354.839354819362, 5467.234587157881], "value": 4008.5490872938767, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.2911746488442, 724.20699334229, 734.742840005998, 782.7182214402818, 848.6393282854785, 14433.278336083193, 15070.623234419118, 15368.077915325088, 15429.691406171869, 15951.52145435636, 14852.628684282898, 14876.033057851231, 15029.248537573132, 15035.624109397244, 15089.62275943102, 689.6138162628919, 850.1201256699302, 865.9134086591354, 867.8654808504692, 874.204473928725, 707.3916431868589, 853.5220276645099, 878.3149143667949, 883.7437143228456, 887.5517863478934, 935.6584846531035, 938.3619138985521, 940.5391358234484, 941.6751220828803, 942.839717248066, 716.7240612364225, 933.9673111441101, 935.4948327902924, 935.7379933618598, 937.4422218779883, 665.2050799294844, 688.401091050784, 690.7651398524475, 691.2016620802981, 692.3595674001529, 14638.634034149125, 14697.926510367486, 14712.705745885129, 14725.190114543695, 14729.337179826933, 13681.657958551017, 14038.929805350908, 14164.645883852883, 14318.928405357907, 14352.138871667734, 13075.77861535404, 13122.278274694889, 13150.342482875818, 13186.0, 13501.98735094862, 4212.515560710527, 4261.595148460922, 4422.292433210703, 4501.617362524189, 4502.797374118172, 104494.47752761187, 104748.47625761823, 104900.47549762279, 105002.0, 113230.30154547689, 10572.682834463489, 10978.670639880816, 10998.450077496092, 10999.0, 11523.07815374768, 21517.246896358552, 21623.026963786666, 21674.24140155087, 21675.891620541795, 22011.33965981023, 7801.4929029612895, 7831.295183433518, 7913.604319783988, 7919.802004949923, 8036.598170091472, 3765.3034188675183, 4289.785510724514, 4299.548547402478, 4316.978415107905, 4358.847440339573, 2744.547149720314, 3449.275652113034, 3452.016175390002, 3457.481377793301, 3462.065242384542, 574.5260160367714, 589.9882002360015, 590.141344343987, 590.8079874040953, 598.1297212555721, 487.4029314090211, 539.3473896585176, 583.3670467542763, 585.8857522783102, 594.5184400635499, 8463.322934165251, 8852.203301702877, 8854.645814167361, 8901.198892099743, 9046.86429703555, 461.7714231455451, 490.96808707433905, 538.1013707109137, 578.644133857683, 579.0561384942516, 4297.247981603207, 4510.0, 4535.206338890681, 4546.954530454719, 4599.0, 128688.3565582166, 130842.0, 138334.92497612545, 142758.0, 142879.0, 113032.86967130388, 115981.42009289899, 119307.0, 119321.0, 121958.0, 107010.0, 107377.0, 108233.17668233113, 109872.4506377463, 110243.44878275557, 8764.868526972228, 8832.514211718417, 8917.464952102635, 8967.237784788365, 9069.18377346042, 58339.12491312549, 59250.70374648099, 59431.405685942584, 60518.394816051274, 61511.6924415375, 70867.64566177136, 78033.0, 85271.14728852881, 85373.0, 90071.0, 11020.228583999044, 11448.3703396314, 11512.424378781196, 11899.167058305835, 11971.341576213044, 11435.199536032398, 12049.277043377426, 12428.937855310665, 12541.561045363369, 12956.611301660965, 8216.548089855116, 9193.678221262224, 11667.00830429423, 12065.0, 12357.196782209128, 12500.624981250578, 12785.680357990752, 13009.804852927024, 13407.262600556673, 13409.865901340861, 12862.163959342613, 12894.484220631444, 13004.154729942524, 13032.739345213233, 13089.541866034642, 10727.946360268148, 10882.0, 10956.178286628376, 11092.168087393316, 11341.886581134084, 10605.681829545127, 11121.888781112086, 11168.38573878412, 11311.660650180507, 11513.251638643462], "value": 7545.377600016771, "unit": "score"}], "name": "dromaeo_css", "value": 7273.355866401821, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 112.0, 113.0, 107.0, 113.0, 111.0, 115.0, 112.0, 113.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 135.0, 202.0, 136.0, 130.0, 127.0, 109.0, 152.0, 125.0], "value": 137.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 184.0, 183.0, 184.0, 221.0, 262.0, 180.0, 181.0, 183.0], "value": 195.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 58.0, 62.0, 87.0, 59.0, 57.0, 65.0, 65.0, 84.0, 57.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 105.0, 70.0, 76.0, 68.0, 97.0, 71.0, 68.0, 103.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 87.0, 85.0, 86.0, 83.0, 88.0, 85.0, 87.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 95.0, 93.0, 92.0, 92.0, 93.0, 94.0, 93.0, 91.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 85.0, 85.0, 83.0, 86.0, 84.0, 84.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 55.0, 54.0, 56.0, 55.0, 55.0, 55.0, 55.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 68.0, 105.0, 103.0, 100.0, 71.0, 68.0, 70.0, 71.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 87.0, 84.0, 85.0, 84.0, 88.0, 89.0, 81.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 136.0, 139.0, 139.0, 134.0, 139.0, 137.0, 157.0, 136.0], "value": 139.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 173.0, 172.0, 173.0, 171.0, 171.0, 170.0, 170.0, 233.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 67.0, 68.0, 70.0, 69.0, 68.0, 66.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1457.8999999999999, "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.267 screenresolution[2281:16304] starting screenresolution argv=screenresolution get 04:46:11 INFO - 2016-08-18 04:46:11.270 screenresolution[2281:16304] Display 0: 1600x1200x32@60 04:46:11 INFO - 2016-08-18 04:46:11.276 screenresolution[2282:16307] 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.510483Z] 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: 1214s; CPU: 54.0%; Read bytes: 1191936; Write bytes: 706292224; Read time: 14; Write time: 1963 04:46:11 INFO - TinderboxPrint: CPU usage
53.6% 04:46:11 INFO - TinderboxPrint: I/O read bytes / time
1,191,936 / 14 04:46:11 INFO - TinderboxPrint: I/O write bytes / time
706,292,224 / 1,963 04:46:11 INFO - TinderboxPrint: CPU idle
2,253.6 (46.4%) 04:46:11 INFO - TinderboxPrint: CPU system
977.7 (20.1%) 04:46:11 INFO - TinderboxPrint: CPU user
1,626.3 (33.5%) 04:46:11 INFO - TinderboxPrint: Swap in / out
406,798,336 / 0 04:46:11 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400628224; Write bytes: 395937792; Read time: 29500; Write time: 793 04:46:11 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1191936; Write bytes: 296350208; Read time: 14; Write time: 1130 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:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:46:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:17 INFO - (blobuploader) - INFO - Done attempting. 04:46:17 INFO - (blobuploader) - INFO - Iteration through files over. 04:46:17 INFO - Return code: 0 04:46:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:46:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:46:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c455cd2c9a0cea85c065073352ded08597445216163fbb3a41af4dcf875bff79471460d1e0e1a25137e477246e92e0a36cd07a677cdf482e38733b1e683b7be", "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/330b36a53e53073a858b7b61abb296e4e51f2b7da261a66b4f5c8a9970be8a082b131c4f3f65f076bebe36b55571c69897c12bcb532a5a3ba58111d86c6f5a7c"} 04:46:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:46:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:46:17 INFO - Contents: 04:46:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c455cd2c9a0cea85c065073352ded08597445216163fbb3a41af4dcf875bff79471460d1e0e1a25137e477246e92e0a36cd07a677cdf482e38733b1e683b7be", "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/330b36a53e53073a858b7b61abb296e4e51f2b7da261a66b4f5c8a9970be8a082b131c4f3f65f076bebe36b55571c69897c12bcb532a5a3ba58111d86c6f5a7c"} 04:46:17 INFO - Running post-run listener: copy_logs_to_upload_dir 04:46:17 INFO - Copying logs to upload dir... 04:46:17 INFO - mkdir: /builds/slave/test/build/upload/logs 04:46:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.017878 ========= master_lag: 2.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-18 04:46:19.638460) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:46:19.642374) ========= 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.cTSVpNurF6/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.RpKcWMXhyp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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/8c455cd2c9a0cea85c065073352ded08597445216163fbb3a41af4dcf875bff79471460d1e0e1a25137e477246e92e0a36cd07a677cdf482e38733b1e683b7be", "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/330b36a53e53073a858b7b61abb296e4e51f2b7da261a66b4f5c8a9970be8a082b131c4f3f65f076bebe36b55571c69897c12bcb532a5a3ba58111d86c6f5a7c"} 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.011989 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/8c455cd2c9a0cea85c065073352ded08597445216163fbb3a41af4dcf875bff79471460d1e0e1a25137e477246e92e0a36cd07a677cdf482e38733b1e683b7be", "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/330b36a53e53073a858b7b61abb296e4e51f2b7da261a66b4f5c8a9970be8a082b131c4f3f65f076bebe36b55571c69897c12bcb532a5a3ba58111d86c6f5a7c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:46:19.681340) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:46:19.681661) ========= 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.cTSVpNurF6/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.RpKcWMXhyp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.005093 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:46:19.730961) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:46:19.731291) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:46:19.731592) ========= ========= Total master_lag: 2.31 =========