builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0360 starttime: 1472496980.38 results: success (0) buildid: 20160829094830 builduid: d7579ff13b4740fd83615f8d3735f405 revision: acfb2c3ac6ae0a704e2756184815296ac1314f89 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:20.378725) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:20.379233) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:20.379532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:20.408716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:20.409152) ========= 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.5m51pPaVVi/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.8TTl7OWC1l/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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-29 11:56:20-- 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% 13.4M=0.001s 2016-08-29 11:56:20 (13.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126412 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:20.560155) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:20.560536) ========= 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.5m51pPaVVi/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.8TTl7OWC1l/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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.017968 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:20.610658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 11:56:20.615320) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev acfb2c3ac6ae0a704e2756184815296ac1314f89 --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 acfb2c3ac6ae0a704e2756184815296ac1314f89 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5m51pPaVVi/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.8TTl7OWC1l/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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-29 11:56:20,696 truncating revision to first 12 chars 2016-08-29 11:56:20,697 Setting DEBUG logging. 2016-08-29 11:56:20,697 attempt 1/10 2016-08-29 11:56:20,697 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/acfb2c3ac6ae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 11:56:21,883 unpacking tar archive at: mozilla-central-acfb2c3ac6ae/testing/mozharness/ program finished with exit code 0 elapsedTime=1.444897 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 11:56:22.088320) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:22.088798) ========= script_repo_revision: acfb2c3ac6ae0a704e2756184815296ac1314f89 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:22.089224) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:22.089517) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 11:56:22.108392) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-29 11:56:22.108990) ========= /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.5m51pPaVVi/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.8TTl7OWC1l/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 11:56:22 INFO - MultiFileLogger online at 20160829 11:56:22 in /builds/slave/test 11:56:22 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 11:56:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:56:22 INFO - {'append_to_log': False, 11:56:22 INFO - 'base_work_dir': '/builds/slave/test', 11:56:22 INFO - 'blob_upload_branch': 'Firefox', 11:56:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:56:22 INFO - 'branch': 'Firefox', 11:56:22 INFO - 'buildbot_json_path': 'buildprops.json', 11:56:22 INFO - 'config_files': ('talos/mac_config.py',), 11:56:22 INFO - 'default_actions': ('clobber', 11:56:22 INFO - 'read-buildbot-config', 11:56:22 INFO - 'download-and-extract', 11:56:22 INFO - 'populate-webroot', 11:56:22 INFO - 'create-virtualenv', 11:56:22 INFO - 'install', 11:56:22 INFO - 'run-tests'), 11:56:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:56:22 INFO - 'download_minidump_stackwalk': True, 11:56:22 INFO - 'download_symbols': 'ondemand', 11:56:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:56:22 INFO - 'tooltool.py': '/tools/tooltool.py', 11:56:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:56:22 INFO - '/tools/misc-python/virtualenv.py')}, 11:56:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:56:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:56:22 INFO - 'installer_path': 'installer.exe', 11:56:22 INFO - 'log_level': 'info', 11:56:22 INFO - 'log_name': 'talos', 11:56:22 INFO - 'log_to_console': True, 11:56:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:56:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:56:22 INFO - 'opt_config_files': (), 11:56:22 INFO - 'pip_index': False, 11:56:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:56:22 INFO - 'cmd': ('bash', 11:56:22 INFO - '-c', 11:56:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:56:22 INFO - 'enabled': True, 11:56:22 INFO - 'halt_on_failure': False, 11:56:22 INFO - 'name': 'check_screen_resolution'},), 11:56:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:56:22 INFO - 'cmd': ('bash', 11:56:22 INFO - '-c', 11:56:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:56:22 INFO - 'enabled': True, 11:56:22 INFO - 'halt_on_failure': False, 11:56:22 INFO - 'name': 'check_screen_resolution'},), 11:56:22 INFO - 'run_cmd_checks_enabled': True, 11:56:22 INFO - 'sps_profile': False, 11:56:22 INFO - 'sps_profile_interval': 0, 11:56:22 INFO - 'suite': 'dromaeojs', 11:56:22 INFO - 'system_bits': '32', 11:56:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:56:22 INFO - 'title': 't-yosemite-r7-0360', 11:56:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:56:22 INFO - 'use_talos_json': True, 11:56:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:56:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:56:22 INFO - 'work_dir': 'build'} 11:56:22 INFO - [mozharness: 2016-08-29 18:56:22.305359Z] Running clobber step. 11:56:22 INFO - Running pre-action listener: _resource_record_pre_action 11:56:22 INFO - Running main action method: clobber 11:56:22 INFO - rmtree: /builds/slave/test/build 11:56:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:56:23 INFO - Running post-action listener: _resource_record_post_action 11:56:23 INFO - [mozharness: 2016-08-29 18:56:23.220675Z] Finished clobber step (success) 11:56:23 INFO - [mozharness: 2016-08-29 18:56:23.221499Z] Running read-buildbot-config step. 11:56:23 INFO - Running pre-action listener: _resource_record_pre_action 11:56:23 INFO - Running main action method: read_buildbot_config 11:56:23 INFO - Using buildbot properties: 11:56:23 INFO - { 11:56:23 INFO - "project": "", 11:56:23 INFO - "product": "firefox", 11:56:23 INFO - "script_repo_revision": "production", 11:56:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 11:56:23 INFO - "repository": "", 11:56:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 11:56:23 INFO - "buildid": "20160829094830", 11:56:23 INFO - "pgo_build": "False", 11:56:23 INFO - "basedir": "/builds/slave/test", 11:56:23 INFO - "buildnumber": 78, 11:56:23 INFO - "slavename": "t-yosemite-r7-0360", 11:56:23 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 11:56:23 INFO - "platform": "yosemite_r7", 11:56:23 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:56:23 INFO - "branch": "mozilla-central", 11:56:23 INFO - "repo_path": "mozilla-central", 11:56:23 INFO - "revision": "acfb2c3ac6ae0a704e2756184815296ac1314f89", 11:56:23 INFO - "stage_platform": "macosx64", 11:56:23 INFO - "builduid": "d7579ff13b4740fd83615f8d3735f405", 11:56:23 INFO - "slavebuilddir": "test" 11:56:23 INFO - } 11:56:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:56:23 INFO - Running post-action listener: _resource_record_post_action 11:56:23 INFO - [mozharness: 2016-08-29 18:56:23.227484Z] Finished read-buildbot-config step (success) 11:56:23 INFO - [mozharness: 2016-08-29 18:56:23.227712Z] Running download-and-extract step. 11:56:23 INFO - Running pre-action listener: _resource_record_pre_action 11:56:23 INFO - Running main action method: download_and_extract 11:56:23 INFO - mkdir: /builds/slave/test/build/tests 11:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:23 INFO - trying https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:56:23 INFO - Downloading https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/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 11:56:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/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 11:56:24 INFO - Downloaded 1199 bytes. 11:56:24 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:56:24 INFO - Using the following test package requirements: 11:56:24 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:56:24 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:56:24 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:56:24 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'jsshell-mac.zip'], 11:56:24 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:56:24 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:56:24 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:56:24 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:56:24 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:56:24 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:56:24 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:24 INFO - trying https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:56:24 INFO - Downloading https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/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 11:56:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/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 11:56:25 INFO - Downloaded 16369879 bytes. 11:56:25 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:56:31 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 11:56: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')]} 11:56:31 INFO - trying https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:56:31 INFO - Downloading https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/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 11:56:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/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 11:56:32 INFO - Downloaded 16369879 bytes. 11:56:32 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:56:39 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')]} 11:56:39 INFO - trying https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:56:39 INFO - Downloading https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/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 11:56:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/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 11:56:41 INFO - Downloaded 14413998 bytes. 11:56:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:56: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')]} 11:56:41 INFO - trying https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:56:41 INFO - Downloading https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:56:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/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 11:56:44 INFO - Downloaded 111537458 bytes. 11:56:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:56:44 INFO - mkdir: /builds/slave/test/properties 11:56:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:56:44 INFO - Writing to file /builds/slave/test/properties/build_url 11:56:44 INFO - Contents: 11:56:44 INFO - build_url:https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:56:44 INFO - Running post-action listener: _resource_record_post_action 11:56:44 INFO - Running post-action listener: set_extra_try_arguments 11:56:44 INFO - [mozharness: 2016-08-29 18:56:44.933580Z] Finished download-and-extract step (success) 11:56:44 INFO - [mozharness: 2016-08-29 18:56:44.933723Z] Running populate-webroot step. 11:56:44 INFO - Running pre-action listener: _resource_record_pre_action 11:56:44 INFO - Running main action method: populate_webroot 11:56:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:56:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:56:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:56:44 INFO - '--tppagecycles', 11:56:44 INFO - '7'], 11:56:44 INFO - 'tests': ['tsvgm']}}, 11:56:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:56:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:56:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:56:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:56:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:56:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:56:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:44 INFO - 'talos_options': ['--disable-e10s'], 11:56:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:56:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:56:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:44 INFO - 'talos_options': ['--disable-e10s'], 11:56:44 INFO - 'tests': ['damp', 'tps']}, 11:56:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:44 INFO - 'tests': ['damp', 'tps']}, 11:56:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:56:44 INFO - 'tests': ['dromaeo_dom']}, 11:56:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:56:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:56:44 INFO - 'tests': ['basic_compositor_video']}, 11:56:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:56:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:56:44 INFO - 'tests': ['a11yr', 11:56:44 INFO - 'ts_paint', 11:56:44 INFO - 'tpaint', 11:56:44 INFO - 'sessionrestore', 11:56:44 INFO - 'sessionrestore_no_auto_restore', 11:56:44 INFO - 'tabpaint']}, 11:56:44 INFO - 'other-e10s': {'tests': ['a11yr', 11:56:44 INFO - 'ts_paint', 11:56:44 INFO - 'tpaint', 11:56:44 INFO - 'sessionrestore', 11:56:44 INFO - 'sessionrestore_no_auto_restore', 11:56:44 INFO - 'tabpaint']}, 11:56:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:56:44 INFO - 'ts_paint', 11:56:44 INFO - 'tpaint', 11:56:44 INFO - 'sessionrestore', 11:56:44 INFO - 'sessionrestore_no_auto_restore', 11:56:44 INFO - 'tabpaint']}, 11:56:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:56:44 INFO - 'ts_paint', 11:56:44 INFO - 'tpaint', 11:56:44 INFO - 'sessionrestore', 11:56:44 INFO - 'sessionrestore_no_auto_restore', 11:56:44 INFO - 'tabpaint']}, 11:56:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:56:44 INFO - 'tests': ['a11yr', 11:56:44 INFO - 'ts_paint', 11:56:44 INFO - 'tpaint', 11:56:44 INFO - 'sessionrestore', 11:56:44 INFO - 'sessionrestore_no_auto_restore', 11:56:44 INFO - 'tabpaint']}, 11:56:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:56:44 INFO - 'tests': ['a11yr', 11:56:44 INFO - 'ts_paint', 11:56:44 INFO - 'tpaint', 11:56:44 INFO - 'sessionrestore', 11:56:44 INFO - 'sessionrestore_no_auto_restore', 11:56:44 INFO - 'tabpaint']}, 11:56:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:56:44 INFO - 'tests': ['tsvgx', 11:56:44 INFO - 'tsvgr_opacity', 11:56:44 INFO - 'tart', 11:56:44 INFO - 'tscrollx', 11:56:44 INFO - 'cart']}, 11:56:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:56:44 INFO - 'tsvgr_opacity', 11:56:44 INFO - 'tart', 11:56:44 INFO - 'tscrollx', 11:56:44 INFO - 'cart']}, 11:56:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:44 INFO - 'talos_options': ['--disable-e10s'], 11:56:44 INFO - 'tests': ['tp5o']}, 11:56:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:44 INFO - 'tests': ['tp5o']}, 11:56:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:44 INFO - 'talos_options': ['--disable-e10s', 11:56:44 INFO - '--xperf_path', 11:56:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:56:44 INFO - 'tests': ['tp5n']}, 11:56:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:44 INFO - 'talos_options': ['--xperf_path', 11:56:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:56:44 INFO - 'tests': ['tp5n']}}, 11:56:44 INFO - 'talos.zip': {'path': '', 11:56:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:56:44 INFO - Running post-action listener: _resource_record_post_action 11:56:44 INFO - [mozharness: 2016-08-29 18:56:44.952514Z] Finished populate-webroot step (success) 11:56:44 INFO - [mozharness: 2016-08-29 18:56:44.952599Z] Running create-virtualenv step. 11:56:44 INFO - Running pre-action listener: _resource_record_pre_action 11:56:44 INFO - Running main action method: create_virtualenv 11:56:44 INFO - Creating virtualenv /builds/slave/test/build/venv 11:56:44 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 11:56:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:56:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:56:45 INFO - Using real prefix '/tools/python27' 11:56:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:56:45 INFO - Installing distribute.............................................................................................................................................................................................done. 11:56:48 INFO - Installing pip.................done. 11:56:48 INFO - Return code: 0 11:56:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:48 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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcd7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7305cad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba6d30>, '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 0x10d90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db9eb20>, '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.5m51pPaVVi/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.8TTl7OWC1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 11:56:48 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 11:56:48 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 11:56:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5m51pPaVVi/Render', 11:56:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:48 INFO - 'HOME': '/Users/cltbld', 11:56:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:48 INFO - 'LOGNAME': 'cltbld', 11:56:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:48 INFO - 'MOZ_NO_REMOTE': '1', 11:56:48 INFO - 'NO_EM_RESTART': '1', 11:56:48 INFO - 'PAGER': '/bin/cat', 11:56:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:48 INFO - 'PWD': '/builds/slave/test', 11:56:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:48 INFO - 'SHELL': '/bin/bash', 11:56:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8TTl7OWC1l/Listeners', 11:56:48 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 11:56:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:48 INFO - 'USER': 'cltbld', 11:56:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:48 INFO - 'XPC_FLAGS': '0x0', 11:56:48 INFO - 'XPC_SERVICE_NAME': '0', 11:56:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:48 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 11:56:48 INFO - Cleaning up... 11:56:48 INFO - Return code: 0 11:56:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:48 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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcd7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7305cad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba6d30>, '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 0x10d90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db9eb20>, '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.5m51pPaVVi/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.8TTl7OWC1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 11:56:48 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 11:56:48 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 11:56:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5m51pPaVVi/Render', 11:56:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:48 INFO - 'HOME': '/Users/cltbld', 11:56:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:48 INFO - 'LOGNAME': 'cltbld', 11:56:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:48 INFO - 'MOZ_NO_REMOTE': '1', 11:56:48 INFO - 'NO_EM_RESTART': '1', 11:56:48 INFO - 'PAGER': '/bin/cat', 11:56:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:48 INFO - 'PWD': '/builds/slave/test', 11:56:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:48 INFO - 'SHELL': '/bin/bash', 11:56:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8TTl7OWC1l/Listeners', 11:56:48 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 11:56:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:48 INFO - 'USER': 'cltbld', 11:56:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:48 INFO - 'XPC_FLAGS': '0x0', 11:56:48 INFO - 'XPC_SERVICE_NAME': '0', 11:56:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:49 INFO - Downloading/unpacking psutil>=3.1.1 11:56:49 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 11:56:49 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 11:56:49 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 11:56:49 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 11:56:49 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 11:56:49 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 11:56:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:56:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:56:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:56:52 INFO - Installing collected packages: psutil 11:56:52 INFO - Running setup.py install for psutil 11:56:52 INFO - building 'psutil._psutil_osx' extension 11:56:52 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 11:56:52 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 11:56:52 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 11:56:52 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 11:56:52 INFO - building 'psutil._psutil_posix' extension 11:56:52 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 11:56:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:56:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:56:52 INFO - ^ 11:56:52 INFO - 1 warning generated. 11:56:52 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 11:56:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:56:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:56:53 INFO - Successfully installed psutil 11:56:53 INFO - Cleaning up... 11:56:53 INFO - Return code: 0 11:56:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:56:53 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')]} 11:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:53 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')]} 11:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:53 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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcd7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7305cad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba6d30>, '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 0x10d90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db9eb20>, '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.5m51pPaVVi/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.8TTl7OWC1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 11:56:53 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 11:56:53 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 11:56:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5m51pPaVVi/Render', 11:56:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:53 INFO - 'HOME': '/Users/cltbld', 11:56:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:53 INFO - 'LOGNAME': 'cltbld', 11:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:53 INFO - 'MOZ_NO_REMOTE': '1', 11:56:53 INFO - 'NO_EM_RESTART': '1', 11:56:53 INFO - 'PAGER': '/bin/cat', 11:56:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:53 INFO - 'PWD': '/builds/slave/test', 11:56:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:53 INFO - 'SHELL': '/bin/bash', 11:56:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8TTl7OWC1l/Listeners', 11:56:53 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 11:56:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:53 INFO - 'USER': 'cltbld', 11:56:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:53 INFO - 'XPC_FLAGS': '0x0', 11:56:53 INFO - 'XPC_SERVICE_NAME': '0', 11:56:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:56:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:56:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:56:55 INFO - Installing collected packages: mozsystemmonitor 11:56:55 INFO - Running setup.py install for mozsystemmonitor 11:56:56 INFO - Successfully installed mozsystemmonitor 11:56:56 INFO - Cleaning up... 11:56:56 INFO - Return code: 0 11:56:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:56: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')]} 11:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56: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')]} 11:56:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcd7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7305cad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba6d30>, '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 0x10d90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db9eb20>, '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.5m51pPaVVi/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.8TTl7OWC1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 11:56: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==2.5.1'] in /builds/slave/test/build 11:56: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==2.5.1 11:56:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5m51pPaVVi/Render', 11:56:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:56 INFO - 'HOME': '/Users/cltbld', 11:56:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:56 INFO - 'LOGNAME': 'cltbld', 11:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:56 INFO - 'MOZ_NO_REMOTE': '1', 11:56:56 INFO - 'NO_EM_RESTART': '1', 11:56:56 INFO - 'PAGER': '/bin/cat', 11:56:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:56 INFO - 'PWD': '/builds/slave/test', 11:56:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:56 INFO - 'SHELL': '/bin/bash', 11:56:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8TTl7OWC1l/Listeners', 11:56:56 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 11:56:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:56 INFO - 'USER': 'cltbld', 11:56:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:56 INFO - 'XPC_FLAGS': '0x0', 11:56:56 INFO - 'XPC_SERVICE_NAME': '0', 11:56:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:56 INFO - Downloading/unpacking jsonschema==2.5.1 11:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:56:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:59 INFO - Downloading functools32-3.2.3-2.tar.gz 11:56:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:56:59 INFO - warning: no files found matching '*.txt' 11:56:59 INFO - no previously-included directories found matching 'build' 11:56:59 INFO - no previously-included directories found matching 'dist' 11:56:59 INFO - no previously-included directories found matching '.git*' 11:56:59 INFO - Installing collected packages: jsonschema, functools32 11:56:59 INFO - Running setup.py install for functools32 11:56:59 INFO - warning: no files found matching '*.txt' 11:56:59 INFO - no previously-included directories found matching 'build' 11:56:59 INFO - no previously-included directories found matching 'dist' 11:56:59 INFO - no previously-included directories found matching '.git*' 11:56:59 INFO - Successfully installed jsonschema functools32 11:56:59 INFO - Cleaning up... 11:56:59 INFO - Return code: 0 11:56:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:56:59 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')]} 11:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:59 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')]} 11:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:59 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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcd7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7305cad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba6d30>, '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 0x10d90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db9eb20>, '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.5m51pPaVVi/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.8TTl7OWC1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 11:56:59 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 11:56:59 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 11:56:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5m51pPaVVi/Render', 11:56:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:59 INFO - 'HOME': '/Users/cltbld', 11:56:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:59 INFO - 'LOGNAME': 'cltbld', 11:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:59 INFO - 'MOZ_NO_REMOTE': '1', 11:56:59 INFO - 'NO_EM_RESTART': '1', 11:56:59 INFO - 'PAGER': '/bin/cat', 11:56:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:59 INFO - 'PWD': '/builds/slave/test', 11:56:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:59 INFO - 'SHELL': '/bin/bash', 11:56:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8TTl7OWC1l/Listeners', 11:56:59 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 11:56:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:59 INFO - 'USER': 'cltbld', 11:56:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:59 INFO - 'XPC_FLAGS': '0x0', 11:56:59 INFO - 'XPC_SERVICE_NAME': '0', 11:56:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:56:59 INFO - Cleaning up... 11:56:59 INFO - Return code: 0 11:56:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:56:59 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')]} 11:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:59 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')]} 11:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:59 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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcd7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7305cad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba6d30>, '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 0x10d90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db9eb20>, '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.5m51pPaVVi/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.8TTl7OWC1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 11:56:59 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 11:56:59 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 11:56:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5m51pPaVVi/Render', 11:56:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:59 INFO - 'HOME': '/Users/cltbld', 11:56:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:59 INFO - 'LOGNAME': 'cltbld', 11:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:59 INFO - 'MOZ_NO_REMOTE': '1', 11:56:59 INFO - 'NO_EM_RESTART': '1', 11:56:59 INFO - 'PAGER': '/bin/cat', 11:56:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:59 INFO - 'PWD': '/builds/slave/test', 11:56:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:59 INFO - 'SHELL': '/bin/bash', 11:56:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8TTl7OWC1l/Listeners', 11:56:59 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 11:56:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:59 INFO - 'USER': 'cltbld', 11:56:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:59 INFO - 'XPC_FLAGS': '0x0', 11:56:59 INFO - 'XPC_SERVICE_NAME': '0', 11:56:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:00 INFO - Downloading/unpacking blobuploader==1.2.4 11:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:02 INFO - Downloading blobuploader-1.2.4.tar.gz 11:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:57:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:57:02 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 11:57:02 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 11:57:02 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 11:57:02 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 11:57:02 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 11:57:02 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 11:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:57:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:57:03 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 11:57:03 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 11:57:03 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 11:57:03 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 11:57:03 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 11:57:03 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 11:57:03 INFO - Downloading docopt-0.6.1.tar.gz 11:57:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:57:03 INFO - Installing collected packages: blobuploader, requests, docopt 11:57:03 INFO - Running setup.py install for blobuploader 11:57:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:57:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:57:03 INFO - Running setup.py install for requests 11:57:04 INFO - Running setup.py install for docopt 11:57:04 INFO - Successfully installed blobuploader requests docopt 11:57:04 INFO - Cleaning up... 11:57:04 INFO - Return code: 0 11:57:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:04 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')]} 11:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:04 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')]} 11:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:04 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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcd7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7305cad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba6d30>, '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 0x10d90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db9eb20>, '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.5m51pPaVVi/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.8TTl7OWC1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 11:57:04 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 11:57:04 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 11:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5m51pPaVVi/Render', 11:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:04 INFO - 'HOME': '/Users/cltbld', 11:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:04 INFO - 'LOGNAME': 'cltbld', 11:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:04 INFO - 'MOZ_NO_REMOTE': '1', 11:57:04 INFO - 'NO_EM_RESTART': '1', 11:57:04 INFO - 'PAGER': '/bin/cat', 11:57:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:04 INFO - 'PWD': '/builds/slave/test', 11:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:04 INFO - 'SHELL': '/bin/bash', 11:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8TTl7OWC1l/Listeners', 11:57:04 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 11:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:04 INFO - 'USER': 'cltbld', 11:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:04 INFO - 'XPC_FLAGS': '0x0', 11:57:04 INFO - 'XPC_SERVICE_NAME': '0', 11:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:04 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-z5DzKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:05 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-nu2xPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:05 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-5cUM23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:05 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-Dnbx40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:05 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-okQxnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:05 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-DzAl3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:05 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-0EZNwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:05 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-UTDimi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:05 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-HIrGfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:05 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-9fKmWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:06 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-UYH3Lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:06 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-C4vnKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:06 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-aRSq19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:06 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-I_8zrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:06 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-tFHO0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:06 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-rRkNXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:06 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-hAUfti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:57:06 INFO - Running setup.py install for manifestparser 11:57:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:57:06 INFO - Running setup.py install for mozcrash 11:57:07 INFO - Running setup.py install for mozdebug 11:57:07 INFO - Running setup.py install for mozdevice 11:57:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:57:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:57:07 INFO - Running setup.py install for mozfile 11:57:07 INFO - Running setup.py install for mozhttpd 11:57:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:57:07 INFO - Running setup.py install for mozinfo 11:57:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:57:07 INFO - Running setup.py install for mozInstall 11:57:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:57:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:57:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:57:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:57:07 INFO - Running setup.py install for mozleak 11:57:08 INFO - Running setup.py install for mozlog 11:57:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:57:08 INFO - Running setup.py install for moznetwork 11:57:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:57:08 INFO - Running setup.py install for mozprocess 11:57:08 INFO - Running setup.py install for mozprofile 11:57:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:57:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:57:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:57:08 INFO - Running setup.py install for mozrunner 11:57:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:57:08 INFO - Running setup.py install for mozscreenshot 11:57:09 INFO - Running setup.py install for moztest 11:57:09 INFO - Running setup.py install for mozversion 11:57:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:57:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:57:09 INFO - Cleaning up... 11:57:09 INFO - Return code: 0 11:57:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:09 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')]} 11:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:09 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')]} 11:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:09 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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcd7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7305cad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba6d30>, '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 0x10d90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db9eb20>, '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.5m51pPaVVi/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.8TTl7OWC1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 11:57:09 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 11:57:09 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 11:57:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5m51pPaVVi/Render', 11:57:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:09 INFO - 'HOME': '/Users/cltbld', 11:57:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:09 INFO - 'LOGNAME': 'cltbld', 11:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:09 INFO - 'MOZ_NO_REMOTE': '1', 11:57:09 INFO - 'NO_EM_RESTART': '1', 11:57:09 INFO - 'PAGER': '/bin/cat', 11:57:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:09 INFO - 'PWD': '/builds/slave/test', 11:57:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:09 INFO - 'SHELL': '/bin/bash', 11:57:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8TTl7OWC1l/Listeners', 11:57:09 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 11:57:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:09 INFO - 'USER': 'cltbld', 11:57:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:09 INFO - 'XPC_FLAGS': '0x0', 11:57:09 INFO - 'XPC_SERVICE_NAME': '0', 11:57:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:09 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-nN993S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:09 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)) 11:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:09 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-YgXaaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:09 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)) 11:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:09 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-OUGXIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:09 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)) 11:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:09 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-aS08vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:10 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)) 11:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:10 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-hz6Q58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:10 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)) 11:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:10 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-fhhjBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:10 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)) 11:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:10 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-PR198M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:10 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)) 11:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:10 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-3xGFYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:10 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)) 11:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:10 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-PDSQIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:10 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)) 11:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:10 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-qGMEVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:10 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)) 11:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:10 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-_lBEhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:10 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)) 11:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:10 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-2RjnHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:10 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)) 11:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:10 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-JNs72r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:11 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)) 11:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:11 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-gWCjiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:11 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)) 11:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:11 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-rQIbwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:11 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)) 11:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:11 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-IB0SqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:11 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)) 11:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:11 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-O1mCfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:11 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)) 11:57:11 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)) 11:57:11 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)) 11:57:11 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)) 11:57:11 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)) 11:57:11 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)) 11:57:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:57:11 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 11:57:11 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 11:57:11 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 11:57:11 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 11:57:11 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 11:57:11 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 11:57:13 INFO - Downloading blessings-1.6.tar.gz 11:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:57:14 INFO - Installing collected packages: blessings 11:57:14 INFO - Running setup.py install for blessings 11:57:14 INFO - Successfully installed blessings 11:57:14 INFO - Cleaning up... 11:57:14 INFO - Return code: 0 11:57:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:57:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:14 INFO - Reading from file tmpfile_stdout 11:57:14 INFO - Current package versions: 11:57:14 INFO - blessings == 1.6 11:57:14 INFO - blobuploader == 1.2.4 11:57:14 INFO - docopt == 0.6.1 11:57:14 INFO - functools32 == 3.2.3-2 11:57:14 INFO - jsonschema == 2.5.1 11:57:14 INFO - manifestparser == 1.1 11:57:14 INFO - mozInstall == 1.12 11:57:14 INFO - mozcrash == 0.17 11:57:14 INFO - mozdebug == 0.1 11:57:14 INFO - mozdevice == 0.48 11:57:14 INFO - mozfile == 1.2 11:57:14 INFO - mozhttpd == 0.7 11:57:14 INFO - mozinfo == 0.9 11:57:14 INFO - mozleak == 0.1 11:57:14 INFO - mozlog == 3.3 11:57:14 INFO - moznetwork == 0.27 11:57:14 INFO - mozprocess == 0.23 11:57:14 INFO - mozprofile == 0.28 11:57:14 INFO - mozrunner == 6.12 11:57:14 INFO - mozscreenshot == 0.1 11:57:14 INFO - mozsystemmonitor == 0.3 11:57:14 INFO - moztest == 0.7 11:57:14 INFO - mozversion == 1.4 11:57:14 INFO - psutil == 3.1.1 11:57:14 INFO - requests == 1.2.3 11:57:14 INFO - wsgiref == 0.1.2 11:57:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:14 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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcd7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7305cad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba6d30>, '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 0x10d90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db9eb20>, '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.5m51pPaVVi/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.8TTl7OWC1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 11:57:14 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 11:57:14 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 11:57:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5m51pPaVVi/Render', 11:57:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:14 INFO - 'HOME': '/Users/cltbld', 11:57:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:14 INFO - 'LOGNAME': 'cltbld', 11:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:14 INFO - 'MOZ_NO_REMOTE': '1', 11:57:14 INFO - 'NO_EM_RESTART': '1', 11:57:14 INFO - 'PAGER': '/bin/cat', 11:57:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:14 INFO - 'PWD': '/builds/slave/test', 11:57:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:14 INFO - 'SHELL': '/bin/bash', 11:57:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8TTl7OWC1l/Listeners', 11:57:14 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 11:57:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:14 INFO - 'USER': 'cltbld', 11:57:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:14 INFO - 'XPC_FLAGS': '0x0', 11:57:14 INFO - 'XPC_SERVICE_NAME': '0', 11:57:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:14 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)) 11:57:14 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)) 11:57:14 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)) 11:57:14 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)) 11:57:14 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)) 11:57:14 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)) 11:57:14 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)) 11:57:14 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)) 11:57:14 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)) 11:57:14 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)) 11:57:14 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)) 11:57:14 INFO - Cleaning up... 11:57:14 INFO - Return code: 0 11:57:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:14 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 0x10dc42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcd7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7305cad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba6d30>, '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 0x10d90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db9eb20>, '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.5m51pPaVVi/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.8TTl7OWC1l/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 11:57:14 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 11:57:14 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 11:57:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5m51pPaVVi/Render', 11:57:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:14 INFO - 'HOME': '/Users/cltbld', 11:57:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:14 INFO - 'LOGNAME': 'cltbld', 11:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:14 INFO - 'MOZ_NO_REMOTE': '1', 11:57:14 INFO - 'NO_EM_RESTART': '1', 11:57:14 INFO - 'PAGER': '/bin/cat', 11:57:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:14 INFO - 'PWD': '/builds/slave/test', 11:57:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:14 INFO - 'SHELL': '/bin/bash', 11:57:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8TTl7OWC1l/Listeners', 11:57:14 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 11:57:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:14 INFO - 'USER': 'cltbld', 11:57:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:14 INFO - 'XPC_FLAGS': '0x0', 11:57:14 INFO - 'XPC_SERVICE_NAME': '0', 11:57:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:57:15 INFO - Cleaning up... 11:57:15 INFO - Return code: 0 11:57:15 INFO - Running post-action listener: _resource_record_post_action 11:57:15 INFO - Running post-action listener: _start_resource_monitoring 11:57:15 INFO - Starting resource monitoring. 11:57:15 INFO - [mozharness: 2016-08-29 18:57:15.143891Z] Finished create-virtualenv step (success) 11:57:15 INFO - [mozharness: 2016-08-29 18:57:15.144909Z] Running install step. 11:57:15 INFO - Running pre-action listener: _resource_record_pre_action 11:57:15 INFO - Running main action method: install 11:57:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:15 INFO - Reading from file tmpfile_stdout 11:57:15 INFO - Detecting whether we're running mozinstall >=1.0... 11:57:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:57:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:57:15 INFO - Reading from file tmpfile_stdout 11:57:15 INFO - Output received: 11:57:15 INFO - Usage: mozinstall [options] installer 11:57:15 INFO - Options: 11:57:15 INFO - -h, --help show this help message and exit 11:57:15 INFO - -d DEST, --destination=DEST 11:57:15 INFO - Directory to install application into. [default: 11:57:15 INFO - "/builds/slave/test"] 11:57:15 INFO - --app=APP Application being installed. [default: firefox] 11:57:15 INFO - mkdir: /builds/slave/test/build/application 11:57:15 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'] 11:57:15 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 11:57:50 INFO - Reading from file tmpfile_stdout 11:57:50 INFO - Output received: 11:57:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:57:50 INFO - Running post-action listener: _resource_record_post_action 11:57:50 INFO - [mozharness: 2016-08-29 18:57:50.301853Z] Finished install step (success) 11:57:50 INFO - [mozharness: 2016-08-29 18:57:50.302002Z] Running run-tests step. 11:57:50 INFO - Running pre-action listener: _resource_record_pre_action 11:57:50 INFO - Running main action method: run_tests 11:57:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:57:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:57:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:57:50 INFO - 2016-08-29 11:57:50.317 screenresolution[1956:13243] starting screenresolution argv=screenresolution get 11:57:50 INFO - 2016-08-29 11:57:50.319 screenresolution[1956:13243] Display 0: 1600x1200x32@60 11:57:50 INFO - 2016-08-29 11:57:50.325 screenresolution[1957:13245] starting screenresolution argv=screenresolution list 11:57:50 INFO - Available Modes on Display 0 11:57:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:57:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:57:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:57:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:57:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:57:50 INFO - Intel Iris: 11:57:50 INFO - Chipset Model: Intel Iris 11:57:50 INFO - Type: GPU 11:57:50 INFO - Bus: Built-In 11:57:50 INFO - VRAM (Dynamic, Max): 1536 MB 11:57:50 INFO - Vendor: Intel (0x8086) 11:57:50 INFO - Device ID: 0x0a2e 11:57:50 INFO - Revision ID: 0x0009 11:57:50 INFO - Displays: 11:57:50 INFO - Display: 11:57:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:57:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:57:50 INFO - Display Serial Number: 1600x1200 60 11:57:50 INFO - Main Display: Yes 11:57:50 INFO - Mirror: Off 11:57:50 INFO - Online: Yes 11:57:50 INFO - Rotation: Supported 11:57:50 INFO - Return code: 0 11:57:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:57:50 INFO - Python 2.7.3 11:57:50 INFO - Return code: 0 11:57:50 INFO - grabbing minidump binary from tooltool 11:57: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')]} 11:57:50 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 0x10dba6d30>, '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 0x10d90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db9eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:57:50 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 11:57:50 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 11:57:50 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 11:57:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:57:50 INFO - Return code: 0 11:57:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:57:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:57:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:57:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:57:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:57:50 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/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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 11:57:50 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/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0360 --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 11:57:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5m51pPaVVi/Render', 11:57:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:50 INFO - 'HOME': '/Users/cltbld', 11:57:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:50 INFO - 'LOGNAME': 'cltbld', 11:57:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:57:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:50 INFO - 'MOZ_NO_REMOTE': '1', 11:57:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:57:50 INFO - 'NO_EM_RESTART': '1', 11:57:50 INFO - 'PAGER': '/bin/cat', 11:57:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:50 INFO - 'PWD': '/builds/slave/test', 11:57:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:57:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:50 INFO - 'SHELL': '/bin/bash', 11:57:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8TTl7OWC1l/Listeners', 11:57:50 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 11:57:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:50 INFO - 'USER': 'cltbld', 11:57:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:50 INFO - 'XPC_FLAGS': '0x0', 11:57:50 INFO - 'XPC_SERVICE_NAME': '0', 11:57:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:50 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/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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 11:57:50 INFO - mozversion application_buildid: 20160829094830 11:57:50 INFO - mozversion application_changeset: acfb2c3ac6ae0a704e2756184815296ac1314f89 11:57:50 INFO - mozversion application_display_name: Nightly 11:57:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:57:50 INFO - mozversion application_name: Firefox 11:57:50 INFO - mozversion application_remotingname: firefox 11:57:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:57:50 INFO - mozversion application_vendor: Mozilla 11:57:50 INFO - mozversion application_version: 51.0a1 11:57:50 INFO - mozversion platform_buildid: 20160829094830 11:57:50 INFO - mozversion platform_changeset: acfb2c3ac6ae0a704e2756184815296ac1314f89 11:57:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:57:50 INFO - mozversion platform_version: 51.0a1 11:57:50 INFO - using testdate: 1472497070 11:57:50 INFO - actual date: 1472497070 11:57:50 INFO - starting webserver on 'localhost:49586' 11:57:50 INFO - SUITE-START | Running 2 tests 11:57:50 INFO - TEST-START | dromaeo_css 11:57:50 INFO - operating with platform_type : mac_ 11:57:50 INFO - Initialising browser for dromaeo_css test... 11:57:50 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpG5tInd/profile http://localhost:49586/getInfo.html) 11:57:53 INFO - PROCESS | 1967 | __metrics Screen width/height:1600/1200 11:57:53 INFO - PROCESS | 1967 | colorDepth:24 11:57:53 INFO - PROCESS | 1967 | Browser inner width/height: 1024/689 11:57:53 INFO - PROCESS | 1967 | __metrics 11:57:53 INFO - PROCESS | 1967 | 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" 11:57:53 INFO - PROCESS | 1967 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:57:54 INFO - TEST-INFO | 1967: exit 0 11:57:54 INFO - Browser initialized. 11:57:54 INFO - Running cycle 1/1 for dromaeo_css test... 11:57:54 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpG5tInd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:06 INFO - PROCESS | 1969 | RSS: Main: 234893312 11:58:06 INFO - PROCESS | 1969 | 11:58:57 INFO - PROCESS | 1969 | 1472497137964 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] 12:01:09 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49586/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49586/tests/dromaeo/cssquery-ext.html) 12:01:10 INFO - PROCESS | 1969 | RSS: Main: 356777984 12:01:10 INFO - PROCESS | 1969 | 12:04:13 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49586/tests/dromaeo/cssquery-ext.html (next: http://localhost:49586/tests/dromaeo/cssquery-jquery.html) 12:04:14 INFO - PROCESS | 1969 | RSS: Main: 362967040 12:04:14 INFO - PROCESS | 1969 | 12:07:16 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49586/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49586/tests/dromaeo/cssquery-mootools.html) 12:07:17 INFO - PROCESS | 1969 | RSS: Main: 331350016 12:07:17 INFO - PROCESS | 1969 | 12:10:19 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49586/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49586/tests/dromaeo/cssquery-prototype.html) 12:10:20 INFO - PROCESS | 1969 | RSS: Main: 338558976 12:10:20 INFO - PROCESS | 1969 | 12:13:24 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49586/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49586/tests/dromaeo/cssquery-yui.html) 12:13:24 INFO - PROCESS | 1969 | RSS: Main: 332025856 12:13:24 INFO - PROCESS | 1969 | 12:16:28 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:49586/tests/dromaeo/cssquery-yui.html (next: http://localhost:49586/tests/dromaeo/cssquery-dojo.html) 12:16:28 INFO - PROCESS | 1969 | RSS: Main: 585842688 12:16:28 INFO - PROCESS | 1969 | 12:16:28 INFO - PROCESS | 1969 | __start_tp_report 12:16:28 INFO - PROCESS | 1969 | _x_x_mozilla_page_load 12:16:28 INFO - PROCESS | 1969 | _x_x_mozilla_page_load_details 12:16:28 INFO - PROCESS | 1969 | |i|pagename|runs| 12:16:28 INFO - PROCESS | 1969 | |0;dojo.html;3277.967440256319;3299.0927494938906;3307.6196237432696;3349.346877358913;3377.5609170807793;4654.720716756993;4693.601043911267;5589.608727389084;5648.322201335835;5755.165501002358;5655.943440565593;5667.5749318801045;5719.571032172594;5756.7409466574;5758.683272420026;4785.3300537924715;5083.74581270935;5085.160948443502;5182.119039763249;5236.92144617831;5286.101362768339;5294.311739473863;5310.973445132769;5322.175062865262;5339.41266460689;5128.102582048126;5166.793328266865;5170.1986192140275;5207.635465517396;5235.293235413246;5128.615353848476;5205.193195054772;5205.505476979681;5224.686518808846;5238.371395432534;5140.049090918192;5144.485551444863;5147.382314122292;5215.113430716788;5231.084560201949;5692.686902220376;5719.971400143014;5754.4533269339345;5757.68332741699;5785.161151633031;6083.543734219907;6090.964536028886;6219.564630475868;6222.128901953685;6233.065040243954;6132.325444201134;6184.938150618481;6209.751609935597;6219.657918814463;6248.687565621746;5831.125331200312;5840.883182336414;5842.415758424124;5844.064949608042;5852.12218167274;5129.820456284012;6214.223222097238;6227.346128656426;6231.719572619243;6279.937200628026;6114.877702445926;6125.724342404603;6146.7706458708435;6177.320494745574;6200.720967556471;6210.813675589739;6220;6220.0047992321015;6224.37756224374;6226.40849119333;6029.849253768691;6182.103594978703;6191.102290167901;6192.195014648067;6219.346968568326;6092.45167934888;6102.782662255756;6134.252905685651;6196.225471816023;6224.688765561704;4988.7505624718615;4999.975000125049;5007.47421520735;5009.173486374781;5040.521150490697;4458.063806600585;4471.61991230749;4483.19302525542;4490.820367185276;4528.411306530196;5540.612157148961;5569.832905012856;5598.188262701886;5651.943480565224;5662.122371032497;6146.924288249538;6165.630062196283;6179.5056395488255;6188.319284878659;6194.256689197235;5650.321961364581;5666.546676265889;5690.630109042899;5698.088305871041;5749.712514374264;5303.893922121613;5349.411564727877;5355.062863998784;5400.108982018001;5416.431274716177;6230.470410015199;6257.7184026718;6263.091851681572;6268.686565671698;6280.685965701605;6156.876862462816;6196.287426945959;6210.782622608187;6224.470919971853;6228.439440450381;6300.149479820166;6356.523260755369;6361.713722882574;6368.5860419072615;6378.170837791057;5991.610545314541;6168.290646575701;6170;6172.382761723792;6174.351693072163;6059.33347331802;6141.946315431794;6142.846428839315;6156.230471191101;6184.536159788124;5168.819091331786;6084.39156084388;6120.90429240112;6132.998044146438;6138.877222455437;6111.083337499411;6122.234720659918;6128.540359472968;6139.938600614116;6142.570020098729;5856.502197313276;5889.617174883619;5933.169356290209;5955.642661440327;5971.970140149271;6108.736706092798;6123.465646421035;6127.128444391175;6133.38666133401;6144.126041557368;5921.496672782861;5942.435468630473;5958.940410596012;5969.70151492415;6012.579119461596 12:16:28 INFO - PROCESS | 1969 | |1;ext.html;456.89948211393505;473.96682232243734;506.25327641728455;506.93156423882766;514.7143335448826;30983;41421;42419.363709544305;42452.57547424525;42509.78745106278;43168.352474712825;43680.3447948281;43681.56318436815;43709.12581748363;43821.5503364428;9826.459544725038;9992.500374981259;10020.549075291592;10024.248181386389;10033.498325083752;8916.974547926975;9048.864267036;12142.27146371216;12341.938290308535;12434.564790232344;12184;12233.571824986127;12294.139410241287;12295.692607684834;12309.507619695201;10277.486125693686;12283.263004219776;12291;12312.569060082898;12370.257784532956;12042.69893252667;12111.091668124838;12129.514819407214;12139;12146.939265303705;41015;41298.793506032576;41315.586844131474;41361.172776544605;41412.58587414148;3375.1562109472634;3386.4581666933173;3387.593488781362;3588.820558972041;3600.3879340512176;31080.844595777104;31342.843285783652;31347.37305253905;31787.364252715048;31842.363152736816;3126.4685003549453;3220.645728969835;3231.2083539532728;3254.96745032549;3315.0386387947465;487733;491483.7129071766;492859;493281;503370.96629033604;62336.33786763059;62565.37434625687;62577.687111565065;62601.74796504044;62640.68679656664;34856.65143348585;35537;64896;64964.025539616945;65121.67439162774;16261;16388.0167189969;16412.507624771275;16463.506094817174;16530.83469165317;11280.435978201058;11441.370724610226;12140.514379424723;12220.205686630341;12239.143259971894;19132.139053742616;19180.61638767217;19474.707879381822;19668.50828729293;19757.604847902963;7903.84192316162;8040.758777236692;8060.710286354155;8148.674053037812;8191.836163276701;855.2901092093548;981.9607215711435;989.8168838764708;1048.8112139814755;1055.2665897201525;29915.700842991726;29935.70064299373;30506.237344066576;30660.554219638027;30702.53946190809;990.3711143423977;1038.5067093130854;1081.5403453532217;1102.8125218712837;1215.9756804863853;15136.697266054618;15513.767293490606;15615.765763513557;15615.765763513557;15998.640285539934;191477;202691;202756;202786;203304.98347508168;42126;42178.789106054275;42186.36720449196;42192.94517637022;42230.78884605557;1054.9367037977745;1202.9939850300868;1235.178606226867;1334.8665133486768;1353.3909740616566;29496.115116546538;30302.393952121274;31217.219569510944;32061.198470038435;32101.35797284088;49790.751046244535;55333;55590.88818223694;55873.16190257068;59409.40590594039;656871;664202;664451;664906;666343;26815.463690726465;26965.89892162348;27351.58972615452;42468.78765606152;43185.7840710807;46860.7656961713;47521;47671.523284768096;48310.75844620755;48818;28594.857025714737;39356.409653855786;39364.60635393724;39574.60425395709;40012.39981400223;38755.41866871943;39310.41034384429;39872.401913971895;39996.2000759989;40184;37296.88109356724;37636;38404;38642.80678596589;38975.02562435848;39719.60280397159;40583.39124913188;41798.373024405264;42606.7217983461;43974.12051758883;26994.460110798067;31908.680913190572;31909.68090319067;32231.838840805645;32997 12:16:28 INFO - PROCESS | 1969 | |2;jquery.html;347.77568468337904;361.72328168950753;370.50537532394236;378.1585971214049;402.6517062740728;12805.359732013398;17316.393926212553;17331;21310.46723831901;21513;20771.169153233852;20905.47736306593;21449.67825482619;21546.676799848014;21695.45761355967;5949.137375080611;6042.214512113419;6106.145139680449;6196.2874269459135;6197.65912874794;3739.3456145174623;5997.790077347294;6147.47746441553;6170.7531698732;6227.097070924713;5792.536597072224;6451.806445806634;6746.662666866661;6810.523263371566;6902.6203558804245;5761.279840019997;6070.7875224366935;6113.816585502442;6788.015737718028;6798.66006699668;5758.280214973129;5909.645421274694;5917.911231331534;5929.140274660193;5969.04495280753;13264.270465124415;13441.991850611146;13753.106048106842;13754.587362379045;13766.31168441574;2132.264368792764;2360.7167139943144;2451.3258853815164;2612.6211699303494;2637.881295341714;10262.384256944608;10614.20393470485;12942;15918.363265469367;16070.75893861593;1761.5684157381525;1787.490565188928;1802.5493626593352;1847.122616757043;1847.390361180807;126090;129503.35248323699;131769;133602.33198833943;134575.6542434573;11017.228793984345;11346.602868899548;11476.368799715929;11477.368744718953;11658.825117623242;14461.78307325391;14480.420783168765;14480.710385792225;14480.782788258184;14524.564263072125;8172.264496195371;8473.322134229247;8479;8643.62300367404;8657.091005444337;7548.471606987568;7832.099308579586;8379.62291696875;8401.621927013299;8588.355873309401;3575.7318201135017;4505.549445055533;4526.751028693454;4565.360849481075;4630.930536041962;1853.1475521260102;1886.4625330355716;1907.809219078081;1937.5059359863144;1945.6108778244409;707.4729326651677;775.5966897213417;797.2784629909941;889.4663202078701;906.3791302957363;21169;21273.893630532057;21387.14451421957;21724.56550868974;22080.558388832134;927.3925578745932;930.6463543853428;979.8432250839832;998.7003898830321;1038.948052597367;10097.798044039226;10730;11481.253718508271;11495.770084598262;11526.711832204262;587111.5155393822;594558.0272098612;595887;607810;609225.9077409258;127679;128546.35726821306;129616;129619.35190324177;132903;862.6161358195543;953.8855337359561;963.1091240602415;995.721198064543;995.8904520502739;18818.52953676169;19167.32914347991;19205.135768890712;19293.22827086957;19737.70393444071;44106.55893441025;48782.268265975326;49524.50475495199;51701;52025;77308.6134569346;77528.06179845323;78821.21178788369;79213.60393197997;79848.60075699585;6742.426893713893;7865.056193256846;8195.71315003972;8446.535440550828;8596.957015214884;8262.586870656563;9708.320417570701;12290.938545307215;12611.84703824044;13197.20816750998;11097.445127743742;11332.490037948148;11492.770144597229;11545.711357215801;11791.233569817934;11123.666290011313;11140.05309548665;11507.859787927115;11566;11685;10057.346272492265;10691.786164276515;11625.767484650429;11669.88330116688;11879.762404751684;6452.580582262138;7372.410207183305;7377.372923301579;8009.799755006172;8384.119667434832;8921.375503714678;11151.219414640897;11216.88783112191;11498.310101393943;11564.363959982282 12:16:28 INFO - PROCESS | 1969 | |3;mootools.html;1541.0291516344705;1559.1892216047656;1765.876388652794;1881.5954569767498;1901.8003109673484;8414.03238627558;8980.685676001342;8981.056989016157;8988.41575297606;9002.819943601124;7813.804654883631;7994.360451163908;8420.663173473054;8632.050474447822;8745.387822852403;2043.897805109746;2355.2580937004873;2396.3529846941315;2397.3167647220544;2397.5564520563667;2353.870537120458;2374.560706269337;2378.940526486843;2399.592069348206;2402.1352313167245;1981.2471260920902;2031.7358743363343;2382.7855493005627;2389.139909632531;2393.5093305872256;2338.4688526051445;2353.8352315337847;2362.350353652742;2362.799162071226;2366.479374537599;1997.4407165993546;2362.9645555316683;2364.4679947011955;2364.7635236476385;2369.8459600126107;8765.737027889174;8791.208791208757;8865.955670221607;8881.111888811132;8917;5127.871803204913;5291.312129423189;5294;5296.205569164617;5300.9734951325;2835.2628316637615;3486.0587641336697;3606.963930360689;3618.9095272618397;3640.9271814563826;1077.6605369308643;1078.4769386847372;1088;1104.3925840787615;1110.3892858927557;11377.829332560019;11535.884641153565;11546.134039946954;11560.595379161688;11591.420428978518;4530.456345238593;4677.321788340671;4769.640652414115;4783.904321913542;4794.184988551885;9863.309568330147;9868.111869931741;9872.851907221397;9875.456849873326;9938.801223975624;5361.115415956324;5361.51746342831;5362.919556206582;5364.892702146013;5365.463453654604;4038.090472088977;4427.977860110679;4868.683535570177;4937.382827146606;4939.036887806915;3904;3904.8047597620575;3990.6009399060445;4024.5170579530645;4035.596440355941;1264.4120483974812;1269.6191142657167;1273.961781146567;1278.7378587389562;1282.1089342906591;841.381584535366;859.7205908079899;904.7783293093235;909.4452384045727;918.4626993209048;5735;5783.277090363705;5933.376995415506;5993.670348130808;6087.634741915499;791.7901756034655;935.5836652689488;935.9251259899191;938.3947353956796;988.19462138357;3902.0635047588376;3983.521977362735;4044.791245438238;4084.407760874716;4115.588441155861;10525.315854469589;10865.674029779118;10878.347299162075;10892.782144357068;10893.400862952614;10741.140708743282;10816.621418250323;10831.404272764916;10849.837252441219;10892.40091794943;8192.795180120545;8295.04606970194;8296.875546866802;8302.70940517079;8313.459625124347;5591.580631452739;5698.686572238401;5936.1689363488285;5943.138244954544;5993.370696077023;10048.69853904384;10115.544800484144;10126.898731012596;10147.492625368553;10150.949245253727;9638.084381983701;10064.647737329158;10073.992600739868;10257.384556926609;10266.024727650862;6901.62041087745;7131.144262688511;7309.744158954411;7464.626768661654;7635.580043097683;8116.837663246584;8452.450590711584;8464.44981076228;8468.53423061713;8469.237768600855;7403.777886663409;7523.435742319239;7544.735934242275;7626.313631773167;7662.233776622294;7224.313690199423;7324.19433862264;7357.632118393952;7385.187629360658;7485.513441626277;6886.586804791728;7040.8943865843075;7049.977753225856;7053.858922821618;7063.858722825617;4534.956959899176;4887.558170339687;4993.650444468997;5017.774200161075;5047.798088076436;4263.296556068214;4704.7394351537505;5020.196768517019;5046.596272298134;5218.295530103539 12:16:28 INFO - PROCESS | 1969 | |4;prototype.html;261.44703951143333;291.492802523609;303.8177093743754;308.3509213106412;313.91995041264477;5267.077516706403;5319.308489896308;5329.227262047001;5340.332458442695;5344.625876188668;5251.86870328242;5284.678830292427;5298.417174110845;5318.521333080022;5333.493318134781;2880.6399200099936;3864.111254411487;4019.5578486366476;4020.095478517325;4032.778197199153;3545.503629491873;4028.2547728670143;4040.817958302513;4059.903825966987;4073.796310184494;3113.361760839034;3864.782593483044;4019.4975628046495;4024.7182697211206;4029.0185262905534;3337.948546207937;3642.307961487309;3671.7429779915415;4029.655386029603;4051.858184963512;3028.9394212115853;3151.889683861054;3497.5103485511795;3526.8714011516327;3535.9469607955903;4888.828890988834;5235.790568377261;5249.768848239483;5252.818115923925;5271.077561426735;4148.62662360386;4358.673099517518;4432.803143151364;4623.86128416148;4643.791029403685;5336.532453575259;5411.402526787164;5470.878469913659;5474.7006257371995;5510.366307874606;3079.892203772857;3864.6908247340143;3865.7873816940055;3865.903352416184;3901.395283731026;4764.761761911891;5046.032047501478;5633.873225354945;5729.684867332337;5843.123531470271;3233.3371658810356;3882.864099756495;3882.8835134946;3900.2979463696806;3922.2155568886337;3299.6205436374644;3917.373220284741;3919.8432062717743;3927.9018024549046;3953.347697629916;4162.646175075152;4552.476465206478;4552.521985191574;4570.223062079454;4570.9314360284625;4892.48628893961;4966.751662416865;5393.718480051899;5495.807646732425;5632.718364081779;1145.2098052343856;1246.7132559511363;1305.8171855940177;1336.8930485561132;1364.1610409598027;736.443984791479;749.2769477454283;773.5088218980875;826.9793255168669;839.0979696825937;4915.4347250065985;5054.848354549369;5173.586113110942;5203.973980130075;5206.817761378334;5398.676079435247;5566.4990150886615;5654.547636189177;5657.773689052474;5778.566607504454;4446.933296000562;4887.242477416006;5126.974365128151;5239.502247286502;5251.426800867624;2647.9470410592066;2681.209043332222;2886.235147685865;3031.4543382190977;3033.0588423409035;5347.224652425377;5497.955388476177;5948;5974.970125149346;6060.424259695322;5340.652857564246;5617.494425501724;5644.491995720323;5873.823785286449;6007.729652165663;5303.231031500488;5923.229980102646;5925.881482370329;5948.672822994777;6023.66869822164;4989.476105008922;5366.463353664602;5444.156155795858;5647.717614119196;5725.679106118525;5194.298769666047;5632.915506267327;5641.689707065987;5685.573581981285;5710.743636399985;5902.940970590239;5919.260092488439;5929.881402372014;5958.374370691188;5975.551833612583;2909.0473169732313;3036.6335149183396;3145.40237354911;3281.5733954585753;3308.9172770680925;4200.4539409876525;4383.868483945486;4764.690295130806;4790.353302304145;4806.423229212517;3997.3004724173734;4206.29644993778;4634.2585186370015;4673.626109911267;4737.28940658904;4052.8986775330854;4239.639630631307;4670.9766451167525;4718.669693121448;4740.857774266778;4212.199682060399;4517.299818528133;4849.539293767087;5452.563794896478;5552.305961754781;4844.224924012141;4968.4286307075145;5279.313689220377;5307.336582927134;5407.594025553306;4890.24201248807;5313.707746074003;5554.666719996651;5568.443155684399;5601.467860553241 12:16:28 INFO - PROCESS | 1969 | |5;yui.html;647.7765171015998;709.9645017749112;730.4777084384664;778.1945686214762;810.4326971120214;15023.924880375596;15707.528774136794;15708.607284817857;15742.842571574281;15752.1336326502;14146.858531414682;14153.433862645481;14156.858431415683;14207.934404919646;14468;538.2383926743653;680.9455243580502;825.9132791056958;831.3224721851672;853.1468531468531;828.3704384667666;834.5076404921095;841.5876220651867;842.5029232752674;842.6503001254472;894.4543828264754;894.5080205886769;894.8702438146465;895.50299583731;895.771578247546;694.4687314204638;871.1593312453513;889.8531742262519;890.1543533643032;891.4873947480193;669.1434963247052;672.7544446277122;673.0173946038789;674.659297054989;674.9156355455568;14735;14814.851851481562;14816.3332650031;14818;14826;13480.93259533696;13631.318434078257;13765;13801.378937947817;13944.123832590763;11534.423278836108;11854.3480108594;12176.634700958986;12274.815877761841;13070.411831467512;4211.178820130076;4558;4596.7931443085145;4632.72203667781;4807.086653535817;112385.75228495467;113543.43228283888;116245.99999999916;117489.23766143515;118029;10566.260361774603;10665.84001239982;11012.50443730034;11055.170862185207;11283.54865805375;20551.280705175246;20569.382918512467;20595.794042059686;20596.27913023037;20697.27559535409;7751.806204844811;7958.363330933512;7996.880046799302;8098.514089154552;8103.756887293391;4320.567943205654;4485.9290891947785;4497.100579884036;4508.481524624644;4549.112922979987;3353.178471274505;3457.7233821294235;3484.8083355415188;3485.790852548855;3494.336076145524;621.7606221604715;632.3139393757732;641.788209890735;641.9165508483867;642.3031011352734;526;598.5780025082306;612.2438788096697;617.6973283091253;634.3682418156112;7487.812804679927;8678.271505496592;8933.642654293886;8936.910630893737;9082.273418126533;567.6508946997649;579.0312936017574;611.4160976267653;612.546715430587;625.4714766022704;3683.37382644951;4403.647708183337;4406.361077643724;4457.2645513489915;4519.231730605805;120610;127940;132185;161431;166965;98823;141071.8392241172;141191.29404353118;142819.57311920988;144099;115152.42423787828;115180;120356.79643203631;120829.39585302018;123909.38045309896;9332.30007749408;9463.716088517356;9771.831084213474;9777.706668799947;9781.451624994412;59900;60704.392956069874;62414.68792656008;65564.24804419158;67973;92764.60853087345;96051;97550;98670.5066474663;99876.50185247081;12956.475742786695;13230.007749419025;13519.797203042159;13708;13901.513447029305;14922.976268363784;15044.473443429428;15086.849131508545;15470.613234669223;16008.839911600735;14112.2943852809;14415.207163606103;14511.709765804835;14542.4910128145;14813.33339999681;13909;13986.930065349608;14139.787903181666;14479.493217737328;14704.117752934859;13633.863661363259;14027.01810873229;14184.432622694976;14342.713145736818;14403.927980360031;11334.356518304965;11444.479733216907;11503.482343294405;11644.184907056424;11680.649580512598;9263.722088337361;9982.700518984442;11551.76896462083;11634.6509604712;11735.706607334885 12:16:28 INFO - PROCESS | 1969 | __end_tp_report 12:16:28 INFO - PROCESS | 1969 | __start_cc_report 12:16:28 INFO - PROCESS | 1969 | _x_x_mozilla_cycle_collect,1233 12:16:28 INFO - PROCESS | 1969 | __end_cc_report 12:16:28 INFO - PROCESS | 1969 | __startTimestamp1472498188498__endTimestamp 12:16:28 INFO - PROCESS | 1969 | 12:16:28 INFO - PROCESS | 1969 | ------- Summary: start ------- 12:16:28 INFO - PROCESS | 1969 | Number of tests: 6 12:16:28 INFO - PROCESS | 1969 | 12:16:28 INFO - PROCESS | 1969 | [#0] dojo.html Cycles:165 Average:5708.76 Median:5955.64 stddev:641.82 (10.8%) stddev-sans-first:614.80 12:16:28 INFO - PROCESS | 1969 | Values: 3278.0 3299.1 3307.6 3349.3 3377.6 4654.7 4693.6 5589.6 5648.3 5755.2 5655.9 5667.6 5719.6 5756.7 5758.7 4785.3 5083.7 5085.2 5182.1 5236.9 5286.1 5294.3 5311.0 5322.2 5339.4 5128.1 5166.8 5170.2 5207.6 5235.3 5128.6 5205.2 5205.5 5224.7 5238.4 5140.0 5144.5 5147.4 5215.1 5231.1 5692.7 5720.0 5754.5 5757.7 5785.2 6083.5 6091.0 6219.6 6222.1 6233.1 6132.3 6184.9 6209.8 6219.7 6248.7 5831.1 5840.9 5842.4 5844.1 5852.1 5129.8 6214.2 6227.3 6231.7 6279.9 6114.9 6125.7 6146.8 6177.3 6200.7 6210.8 6220.0 6220.0 6224.4 6226.4 6029.8 6182.1 6191.1 6192.2 6219.3 6092.5 6102.8 6134.3 6196.2 6224.7 4988.8 5000.0 5007.5 5009.2 5040.5 4458.1 4471.6 4483.2 4490.8 4528.4 5540.6 5569.8 5598.2 5651.9 5662.1 6146.9 6165.6 6179.5 6188.3 6194.3 5650.3 5666.5 5690.6 5698.1 5749.7 5303.9 5349.4 5355.1 5400.1 5416.4 6230.5 6257.7 6263.1 6268.7 6280.7 6156.9 6196.3 6210.8 6224.5 6228.4 6300.1 6356.5 6361.7 6368.6 6378.2 5991.6 6168.3 6170.0 6172.4 6174.4 6059.3 6141.9 6142.8 6156.2 6184.5 5168.8 6084.4 6120.9 6133.0 6138.9 6111.1 6122.2 6128.5 6139.9 6142.6 5856.5 5889.6 5933.2 5955.6 5972.0 6108.7 6123.5 6127.1 6133.4 6144.1 5921.5 5942.4 5958.9 5969.7 6012.6 12:16:28 INFO - PROCESS | 1969 | 12:16:28 INFO - PROCESS | 1969 | [#1] ext.html Cycles:180 Average:61023.62 Median:35196.83 stddev:131849.40 (374.6%) stddev-sans-first:132140.86 12:16:28 INFO - PROCESS | 1969 | Values: 456.9 474.0 506.3 506.9 514.7 30983.0 41421.0 42419.4 42452.6 42509.8 43168.4 43680.3 43681.6 43709.1 43821.6 9826.5 9992.5 10020.5 10024.2 10033.5 8917.0 9048.9 12142.3 12341.9 12434.6 12184.0 12233.6 12294.1 12295.7 12309.5 10277.5 12283.3 12291.0 12312.6 12370.3 12042.7 12111.1 12129.5 12139.0 12146.9 41015.0 41298.8 41315.6 41361.2 41412.6 3375.2 3386.5 3387.6 3588.8 3600.4 31080.8 31342.8 31347.4 31787.4 31842.4 3126.5 3220.6 3231.2 3255.0 3315.0 487733.0 491483.7 492859.0 493281.0 503371.0 62336.3 62565.4 62577.7 62601.7 62640.7 34856.7 35537.0 64896.0 64964.0 65121.7 16261.0 16388.0 16412.5 16463.5 16530.8 11280.4 11441.4 12140.5 12220.2 12239.1 19132.1 19180.6 19474.7 19668.5 19757.6 7903.8 8040.8 8060.7 8148.7 8191.8 855.3 982.0 989.8 1048.8 1055.3 29915.7 29935.7 30506.2 30660.6 30702.5 990.4 1038.5 1081.5 1102.8 1216.0 15136.7 15513.8 15615.8 15615.8 15998.6 191477.0 202691.0 202756.0 202786.0 203305.0 42126.0 42178.8 42186.4 42192.9 42230.8 1054.9 1203.0 1235.2 1334.9 1353.4 29496.1 30302.4 31217.2 32061.2 32101.4 49790.8 55333.0 55590.9 55873.2 59409.4 656871.0 664202.0 664451.0 664906.0 666343.0 26815.5 26965.9 27351.6 42468.8 43185.8 46860.8 47521.0 47671.5 48310.8 48818.0 28594.9 39356.4 39364.6 39574.6 40012.4 38755.4 39310.4 39872.4 39996.2 40184.0 37296.9 37636.0 38404.0 38642.8 38975.0 39719.6 40583.4 41798.4 42606.7 43974.1 26994.5 31908.7 31909.7 32231.8 32997.0 12:16:28 INFO - PROCESS | 1969 | 12:16:28 INFO - PROCESS | 1969 | [#2] jquery.html Cycles:180 Average:35101.58 Median:2531.97 stddev:100362.91 (3963.8%) stddev-sans-first:100610.53 12:16:28 INFO - PROCESS | 1969 | Values: 347.8 361.7 370.5 378.2 402.7 12805.4 17316.4 17331.0 21310.5 21513.0 20771.2 20905.5 21449.7 21546.7 21695.5 5949.1 6042.2 6106.1 6196.3 6197.7 3739.3 5997.8 6147.5 6170.8 6227.1 5792.5 6451.8 6746.7 6810.5 6902.6 5761.3 6070.8 6113.8 6788.0 6798.7 5758.3 5909.6 5917.9 5929.1 5969.0 13264.3 13442.0 13753.1 13754.6 13766.3 2132.3 2360.7 2451.3 2612.6 2637.9 10262.4 10614.2 12942.0 15918.4 16070.8 1761.6 1787.5 1802.5 1847.1 1847.4 126090.0 129503.4 131769.0 133602.3 134575.7 11017.2 11346.6 11476.4 11477.4 11658.8 14461.8 14480.4 14480.7 14480.8 14524.6 8172.3 8473.3 8479.0 8643.6 8657.1 7548.5 7832.1 8379.6 8401.6 8588.4 3575.7 4505.5 4526.8 4565.4 4630.9 1853.1 1886.5 1907.8 1937.5 1945.6 707.5 775.6 797.3 889.5 906.4 21169.0 21273.9 21387.1 21724.6 22080.6 927.4 930.6 979.8 998.7 1038.9 10097.8 10730.0 11481.3 11495.8 11526.7 587111.5 594558.0 595887.0 607810.0 609225.9 127679.0 128546.4 129616.0 129619.4 132903.0 862.6 953.9 963.1 995.7 995.9 18818.5 19167.3 19205.1 19293.2 19737.7 44106.6 48782.3 49524.5 51701.0 52025.0 77308.6 77528.1 78821.2 79213.6 79848.6 6742.4 7865.1 8195.7 8446.5 8597.0 8262.6 9708.3 12290.9 12611.8 13197.2 11097.4 11332.5 11492.8 11545.7 11791.2 11123.7 11140.1 11507.9 11566.0 11685.0 10057.3 10691.8 11625.8 11669.9 11879.8 6452.6 7372.4 7377.4 8009.8 8384.1 8921.4 11151.2 11216.9 11498.3 11564.4 12:16:28 INFO - PROCESS | 1969 | 12:16:28 INFO - PROCESS | 1969 | [#3] mootools.html Cycles:180 Average:5709.29 Median:4912.47 stddev:3210.61 (65.4%) stddev-sans-first:3204.33 12:16:28 INFO - PROCESS | 1969 | Values: 1541.0 1559.2 1765.9 1881.6 1901.8 8414.0 8980.7 8981.1 8988.4 9002.8 7813.8 7994.4 8420.7 8632.1 8745.4 2043.9 2355.3 2396.4 2397.3 2397.6 2353.9 2374.6 2378.9 2399.6 2402.1 1981.2 2031.7 2382.8 2389.1 2393.5 2338.5 2353.8 2362.4 2362.8 2366.5 1997.4 2363.0 2364.5 2364.8 2369.8 8765.7 8791.2 8866.0 8881.1 8917.0 5127.9 5291.3 5294.0 5296.2 5301.0 2835.3 3486.1 3607.0 3618.9 3640.9 1077.7 1078.5 1088.0 1104.4 1110.4 11377.8 11535.9 11546.1 11560.6 11591.4 4530.5 4677.3 4769.6 4783.9 4794.2 9863.3 9868.1 9872.9 9875.5 9938.8 5361.1 5361.5 5362.9 5364.9 5365.5 4038.1 4428.0 4868.7 4937.4 4939.0 3904.0 3904.8 3990.6 4024.5 4035.6 1264.4 1269.6 1274.0 1278.7 1282.1 841.4 859.7 904.8 909.4 918.5 5735.0 5783.3 5933.4 5993.7 6087.6 791.8 935.6 935.9 938.4 988.2 3902.1 3983.5 4044.8 4084.4 4115.6 10525.3 10865.7 10878.3 10892.8 10893.4 10741.1 10816.6 10831.4 10849.8 10892.4 8192.8 8295.0 8296.9 8302.7 8313.5 5591.6 5698.7 5936.2 5943.1 5993.4 10048.7 10115.5 10126.9 10147.5 10150.9 9638.1 10064.6 10074.0 10257.4 10266.0 6901.6 7131.1 7309.7 7464.6 7635.6 8116.8 8452.5 8464.4 8468.5 8469.2 7403.8 7523.4 7544.7 7626.3 7662.2 7224.3 7324.2 7357.6 7385.2 7485.5 6886.6 7040.9 7050.0 7053.9 7063.9 4535.0 4887.6 4993.7 5017.8 5047.8 4263.3 4704.7 5020.2 5046.6 5218.3 12:16:28 INFO - PROCESS | 1969 | 12:16:28 INFO - PROCESS | 1969 | [#4] prototype.html Cycles:180 Average:4389.67 Median:4868.39 stddev:1390.42 (28.6%) stddev-sans-first:1359.36 12:16:28 INFO - PROCESS | 1969 | Values: 261.4 291.5 303.8 308.4 313.9 5267.1 5319.3 5329.2 5340.3 5344.6 5251.9 5284.7 5298.4 5318.5 5333.5 2880.6 3864.1 4019.6 4020.1 4032.8 3545.5 4028.3 4040.8 4059.9 4073.8 3113.4 3864.8 4019.5 4024.7 4029.0 3337.9 3642.3 3671.7 4029.7 4051.9 3028.9 3151.9 3497.5 3526.9 3535.9 4888.8 5235.8 5249.8 5252.8 5271.1 4148.6 4358.7 4432.8 4623.9 4643.8 5336.5 5411.4 5470.9 5474.7 5510.4 3079.9 3864.7 3865.8 3865.9 3901.4 4764.8 5046.0 5633.9 5729.7 5843.1 3233.3 3882.9 3882.9 3900.3 3922.2 3299.6 3917.4 3919.8 3927.9 3953.3 4162.6 4552.5 4552.5 4570.2 4570.9 4892.5 4966.8 5393.7 5495.8 5632.7 1145.2 1246.7 1305.8 1336.9 1364.2 736.4 749.3 773.5 827.0 839.1 4915.4 5054.8 5173.6 5204.0 5206.8 5398.7 5566.5 5654.5 5657.8 5778.6 4446.9 4887.2 5127.0 5239.5 5251.4 2647.9 2681.2 2886.2 3031.5 3033.1 5347.2 5498.0 5948.0 5975.0 6060.4 5340.7 5617.5 5644.5 5873.8 6007.7 5303.2 5923.2 5925.9 5948.7 6023.7 4989.5 5366.5 5444.2 5647.7 5725.7 5194.3 5632.9 5641.7 5685.6 5710.7 5902.9 5919.3 5929.9 5958.4 5975.6 2909.0 3036.6 3145.4 3281.6 3308.9 4200.5 4383.9 4764.7 4790.4 4806.4 3997.3 4206.3 4634.3 4673.6 4737.3 4052.9 4239.6 4671.0 4718.7 4740.9 4212.2 4517.3 4849.5 5452.6 5552.3 4844.2 4968.4 5279.3 5307.3 5407.6 4890.2 5313.7 5554.7 5568.4 5601.5 12:16:28 INFO - PROCESS | 1969 | 12:16:28 INFO - PROCESS | 1969 | [#5] yui.html Cycles:180 Average:25442.60 Median:3490.06 stddev:40741.14 (1167.3%) stddev-sans-first:40812.89 12:16:28 INFO - PROCESS | 1969 | Values: 647.8 710.0 730.5 778.2 810.4 15023.9 15707.5 15708.6 15742.8 15752.1 14146.9 14153.4 14156.9 14207.9 14468.0 538.2 680.9 825.9 831.3 853.1 828.4 834.5 841.6 842.5 842.7 894.5 894.5 894.9 895.5 895.8 694.5 871.2 889.9 890.2 891.5 669.1 672.8 673.0 674.7 674.9 14735.0 14814.9 14816.3 14818.0 14826.0 13480.9 13631.3 13765.0 13801.4 13944.1 11534.4 11854.3 12176.6 12274.8 13070.4 4211.2 4558.0 4596.8 4632.7 4807.1 112385.8 113543.4 116246.0 117489.2 118029.0 10566.3 10665.8 11012.5 11055.2 11283.5 20551.3 20569.4 20595.8 20596.3 20697.3 7751.8 7958.4 7996.9 8098.5 8103.8 4320.6 4485.9 4497.1 4508.5 4549.1 3353.2 3457.7 3484.8 3485.8 3494.3 621.8 632.3 641.8 641.9 642.3 526.0 598.6 612.2 617.7 634.4 7487.8 8678.3 8933.6 8936.9 9082.3 567.7 579.0 611.4 612.5 625.5 3683.4 4403.6 4406.4 4457.3 4519.2 120610.0 127940.0 132185.0 161431.0 166965.0 98823.0 141071.8 141191.3 142819.6 144099.0 115152.4 115180.0 120356.8 120829.4 123909.4 9332.3 9463.7 9771.8 9777.7 9781.5 59900.0 60704.4 62414.7 65564.2 67973.0 92764.6 96051.0 97550.0 98670.5 99876.5 12956.5 13230.0 13519.8 13708.0 13901.5 14923.0 15044.5 15086.8 15470.6 16008.8 14112.3 14415.2 14511.7 14542.5 14813.3 13909.0 13986.9 14139.8 14479.5 14704.1 13633.9 14027.0 14184.4 14342.7 14403.9 11334.4 11444.5 11503.5 11644.2 11680.6 9263.7 9982.7 11551.8 11634.7 11735.7 12:16:28 INFO - PROCESS | 1969 | -------- Summary: end -------- 12:16:28 INFO - PROCESS | 1969 | 12:16:29 INFO - TEST-INFO | 1969: exit 0 12:16:29 INFO - TEST-OK | dromaeo_css | took 1118209ms 12:16:29 INFO - TEST-START | kraken 12:16:29 INFO - operating with platform_type : mac_ 12:16:29 INFO - Initialising browser for kraken test... 12:16:29 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpl2eBeB/profile http://localhost:49586/getInfo.html) 12:16:31 INFO - PROCESS | 1973 | __metrics Screen width/height:1600/1200 12:16:31 INFO - PROCESS | 1973 | colorDepth:24 12:16:31 INFO - PROCESS | 1973 | Browser inner width/height: 1024/689 12:16:31 INFO - PROCESS | 1973 | __metrics 12:16:31 INFO - PROCESS | 1973 | 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" 12:16:31 INFO - PROCESS | 1973 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:16:32 INFO - TEST-INFO | 1973: exit 0 12:16:32 INFO - Browser initialized. 12:16:32 INFO - Running cycle 1/1 for kraken test... 12:16:32 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpl2eBeB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:16:44 INFO - PROCESS | 1974 | RSS: Main: 234758144 12:16:44 INFO - PROCESS | 1974 | 12:16:46 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49586/tests/kraken/driver.html?testName=audio-beat-detection) 12:16:47 INFO - PROCESS | 1974 | RSS: Main: 292151296 12:16:47 INFO - PROCESS | 1974 | 12:16:50 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49586/tests/kraken/driver.html?testName=audio-dft) 12:16:51 INFO - PROCESS | 1974 | RSS: Main: 288079872 12:16:51 INFO - PROCESS | 1974 | 12:16:54 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49586/tests/kraken/driver.html?testName=audio-fft) 12:16:55 INFO - PROCESS | 1974 | RSS: Main: 377360384 12:16:55 INFO - PROCESS | 1974 | 12:16:57 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49586/tests/kraken/driver.html?testName=audio-oscillator) 12:16:57 INFO - PROCESS | 1974 | RSS: Main: 279367680 12:16:57 INFO - PROCESS | 1974 | 12:16:59 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49586/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:17:00 INFO - PROCESS | 1974 | RSS: Main: 282243072 12:17:00 INFO - PROCESS | 1974 | 12:17:03 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49586/tests/kraken/driver.html?testName=imaging-darkroom) 12:17:03 INFO - PROCESS | 1974 | RSS: Main: 320499712 12:17:03 INFO - PROCESS | 1974 | 12:17:06 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49586/tests/kraken/driver.html?testName=imaging-desaturate) 12:17:07 INFO - PROCESS | 1974 | RSS: Main: 322576384 12:17:07 INFO - PROCESS | 1974 | 12:17:10 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49586/tests/kraken/driver.html?testName=json-parse-financial) 12:17:10 INFO - PROCESS | 1974 | RSS: Main: 320991232 12:17:10 INFO - PROCESS | 1974 | 12:17:12 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49586/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:17:13 INFO - PROCESS | 1974 | RSS: Main: 297963520 12:17:13 INFO - PROCESS | 1974 | 12:17:15 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49586/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:17:15 INFO - PROCESS | 1974 | RSS: Main: 305016832 12:17:15 INFO - PROCESS | 1974 | 12:17:17 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49586/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:17:18 INFO - PROCESS | 1974 | RSS: Main: 305188864 12:17:18 INFO - PROCESS | 1974 | 12:17:21 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49586/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:17:22 INFO - PROCESS | 1974 | RSS: Main: 321830912 12:17:22 INFO - PROCESS | 1974 | 12:17:25 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49586/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:17:25 INFO - PROCESS | 1974 | RSS: Main: 318099456 12:17:25 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49586/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49586/tests/kraken/driver.html?testName=ai-astar) 12:17:27 INFO - PROCESS | 1974 | RSS: Main: 361545728 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | __start_tp_report 12:17:27 INFO - PROCESS | 1974 | _x_x_mozilla_page_load 12:17:27 INFO - PROCESS | 1974 | _x_x_mozilla_page_load_details 12:17:27 INFO - PROCESS | 1974 | |i|pagename|runs| 12:17:27 INFO - PROCESS | 1974 | |0;ai-astar;112;114;132;113;113;113;113;112;113;113 12:17:27 INFO - PROCESS | 1974 | |1;audio-beat-detection;147;110;132;162;111;131;130;108;158;108 12:17:27 INFO - PROCESS | 1974 | |2;audio-dft;184;186;185;182;184;183;179;182;185;183 12:17:27 INFO - PROCESS | 1974 | |3;audio-fft;75;78;77;151;58;57;59;68;142;57 12:17:27 INFO - PROCESS | 1974 | |4;audio-oscillator;82;84;132;72;77;81;119;70;91;101 12:17:27 INFO - PROCESS | 1974 | |5;imaging-gaussian-blur;91;88;92;88;92;89;91;89;91;88 12:17:27 INFO - PROCESS | 1974 | |6;imaging-darkroom;97;97;96;97;96;97;96;96;96;100 12:17:27 INFO - PROCESS | 1974 | |7;imaging-desaturate;85;84;85;85;98;112;86;84;84;84 12:17:27 INFO - PROCESS | 1974 | |8;json-parse-financial;56;57;56;55;54;55;56;54;55;54 12:17:27 INFO - PROCESS | 1974 | |9;json-stringify-tinderbox;69;66;75;75;74;82;74;73;72;74 12:17:27 INFO - PROCESS | 1974 | |10;stanford-crypto-aes;86;87;87;87;85;88;86;89;87;87 12:17:27 INFO - PROCESS | 1974 | |11;stanford-crypto-ccm;138;141;144;148;157;141;140;138;195;158 12:17:27 INFO - PROCESS | 1974 | |12;stanford-crypto-pbkdf2;171;168;171;180;171;169;169;169;169;169 12:17:27 INFO - PROCESS | 1974 | |13;stanford-crypto-sha256-iterative;67;67;68;67;67;67;67;67;66;67 12:17:27 INFO - PROCESS | 1974 | __end_tp_report 12:17:27 INFO - PROCESS | 1974 | __start_cc_report 12:17:27 INFO - PROCESS | 1974 | _x_x_mozilla_cycle_collect,2091 12:17:27 INFO - PROCESS | 1974 | __end_cc_report 12:17:27 INFO - PROCESS | 1974 | __startTimestamp1472498247608__endTimestamp 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | ------- Summary: start ------- 12:17:27 INFO - PROCESS | 1974 | Number of tests: 14 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#0] ai-astar Cycles:10 Average:114.80 Median:113.00 stddev:6.07 (5.4%) stddev-sans-first:6.35 12:17:27 INFO - PROCESS | 1974 | Values: 112.0 114.0 132.0 113.0 113.0 113.0 113.0 112.0 113.0 113.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:131.50 stddev:20.63 (15.7%) stddev-sans-first:20.91 12:17:27 INFO - PROCESS | 1974 | Values: 147.0 110.0 132.0 162.0 111.0 131.0 130.0 108.0 158.0 108.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:2.00 (1.1%) stddev-sans-first:2.11 12:17:27 INFO - PROCESS | 1974 | Values: 184.0 186.0 185.0 182.0 184.0 183.0 179.0 182.0 185.0 183.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#3] audio-fft Cycles:10 Average:82.20 Median:63.50 stddev:34.96 (55.1%) stddev-sans-first:36.99 12:17:27 INFO - PROCESS | 1974 | Values: 75.0 78.0 77.0 151.0 58.0 57.0 59.0 68.0 142.0 57.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:79.00 stddev:20.53 (26.0%) stddev-sans-first:21.52 12:17:27 INFO - PROCESS | 1974 | Values: 82.0 84.0 132.0 72.0 77.0 81.0 119.0 70.0 91.0 101.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:91.00 stddev:1.66 (1.8%) stddev-sans-first:1.72 12:17:27 INFO - PROCESS | 1974 | Values: 91.0 88.0 92.0 88.0 92.0 89.0 91.0 89.0 91.0 88.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:96.50 stddev:1.23 (1.3%) stddev-sans-first:1.30 12:17:27 INFO - PROCESS | 1974 | Values: 97.0 97.0 96.0 97.0 96.0 97.0 96.0 96.0 96.0 100.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#7] imaging-desaturate Cycles:10 Average:88.70 Median:85.00 stddev:9.23 (10.9%) stddev-sans-first:9.69 12:17:27 INFO - PROCESS | 1974 | Values: 85.0 84.0 85.0 85.0 98.0 112.0 86.0 84.0 84.0 84.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.50 stddev:1.03 (1.9%) stddev-sans-first:1.05 12:17:27 INFO - PROCESS | 1974 | Values: 56.0 57.0 56.0 55.0 54.0 55.0 56.0 54.0 55.0 54.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:74.00 stddev:4.17 (5.6%) stddev-sans-first:4.11 12:17:27 INFO - PROCESS | 1974 | Values: 69.0 66.0 75.0 75.0 74.0 82.0 74.0 73.0 72.0 74.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:87.00 stddev:1.10 (1.3%) stddev-sans-first:1.12 12:17:27 INFO - PROCESS | 1974 | Values: 86.0 87.0 87.0 87.0 85.0 88.0 86.0 89.0 87.0 87.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:146.00 stddev:17.41 (11.9%) stddev-sans-first:17.92 12:17:27 INFO - PROCESS | 1974 | Values: 138.0 141.0 144.0 148.0 157.0 141.0 140.0 138.0 195.0 158.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:170.00 stddev:3.47 (2.0%) stddev-sans-first:3.68 12:17:27 INFO - PROCESS | 1974 | Values: 171.0 168.0 171.0 180.0 171.0 169.0 169.0 169.0 169.0 169.0 12:17:27 INFO - PROCESS | 1974 | 12:17:27 INFO - PROCESS | 1974 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.47 (0.7%) stddev-sans-first:0.50 12:17:27 INFO - PROCESS | 1974 | Values: 67.0 67.0 68.0 67.0 67.0 67.0 67.0 67.0 66.0 67.0 12:17:27 INFO - PROCESS | 1974 | -------- Summary: end -------- 12:17:27 INFO - PROCESS | 1974 | 12:17:28 INFO - TEST-INFO | 1974: exit 0 12:17:28 INFO - TEST-OK | kraken | took 59339ms 12:17:28 INFO - SUITE-END | took 1177s 12:17:28 INFO - Completed test suite (00:19:37) 12:17:28 INFO - javascript benchmark 12:17:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3277.967440256319, 3299.0927494938906, 3307.6196237432696, 3349.346877358913, 3377.5609170807793, 4654.720716756993, 4693.601043911267, 5589.608727389084, 5648.322201335835, 5755.165501002358, 5655.943440565593, 5667.5749318801045, 5719.571032172594, 5756.7409466574, 5758.683272420026, 4785.3300537924715, 5083.74581270935, 5085.160948443502, 5182.119039763249, 5236.92144617831, 5286.101362768339, 5294.311739473863, 5310.973445132769, 5322.175062865262, 5339.41266460689, 5128.102582048126, 5166.793328266865, 5170.1986192140275, 5207.635465517396, 5235.293235413246, 5128.615353848476, 5205.193195054772, 5205.505476979681, 5224.686518808846, 5238.371395432534, 5140.049090918192, 5144.485551444863, 5147.382314122292, 5215.113430716788, 5231.084560201949, 5692.686902220376, 5719.971400143014, 5754.4533269339345, 5757.68332741699, 5785.161151633031, 6083.543734219907, 6090.964536028886, 6219.564630475868, 6222.128901953685, 6233.065040243954, 6132.325444201134, 6184.938150618481, 6209.751609935597, 6219.657918814463, 6248.687565621746, 5831.125331200312, 5840.883182336414, 5842.415758424124, 5844.064949608042, 5852.12218167274, 5129.820456284012, 6214.223222097238, 6227.346128656426, 6231.719572619243, 6279.937200628026, 6114.877702445926, 6125.724342404603, 6146.7706458708435, 6177.320494745574, 6200.720967556471, 6210.813675589739, 6220.0, 6220.0047992321015, 6224.37756224374, 6226.40849119333, 6029.849253768691, 6182.103594978703, 6191.102290167901, 6192.195014648067, 6219.346968568326, 6092.45167934888, 6102.782662255756, 6134.252905685651, 6196.225471816023, 6224.688765561704, 4988.7505624718615, 4999.975000125049, 5007.47421520735, 5009.173486374781, 5040.521150490697, 4458.063806600585, 4471.61991230749, 4483.19302525542, 4490.820367185276, 4528.411306530196, 5540.612157148961, 5569.832905012856, 5598.188262701886, 5651.943480565224, 5662.122371032497, 6146.924288249538, 6165.630062196283, 6179.5056395488255, 6188.319284878659, 6194.256689197235, 5650.321961364581, 5666.546676265889, 5690.630109042899, 5698.088305871041, 5749.712514374264, 5303.893922121613, 5349.411564727877, 5355.062863998784, 5400.108982018001, 5416.431274716177, 6230.470410015199, 6257.7184026718, 6263.091851681572, 6268.686565671698, 6280.685965701605, 6156.876862462816, 6196.287426945959, 6210.782622608187, 6224.470919971853, 6228.439440450381, 6300.149479820166, 6356.523260755369, 6361.713722882574, 6368.5860419072615, 6378.170837791057, 5991.610545314541, 6168.290646575701, 6170.0, 6172.382761723792, 6174.351693072163, 6059.33347331802, 6141.946315431794, 6142.846428839315, 6156.230471191101, 6184.536159788124, 5168.819091331786, 6084.39156084388, 6120.90429240112, 6132.998044146438, 6138.877222455437, 6111.083337499411, 6122.234720659918, 6128.540359472968, 6139.938600614116, 6142.570020098729, 5856.502197313276, 5889.617174883619, 5933.169356290209, 5955.642661440327, 5971.970140149271, 6108.736706092798, 6123.465646421035, 6127.128444391175, 6133.38666133401, 6144.126041557368, 5921.496672782861, 5942.435468630473, 5958.940410596012, 5969.70151492415, 6012.579119461596], "value": 5667.949190909367, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.89948211393505, 473.96682232243734, 506.25327641728455, 506.93156423882766, 514.7143335448826, 30983.0, 41421.0, 42419.363709544305, 42452.57547424525, 42509.78745106278, 43168.352474712825, 43680.3447948281, 43681.56318436815, 43709.12581748363, 43821.5503364428, 9826.459544725038, 9992.500374981259, 10020.549075291592, 10024.248181386389, 10033.498325083752, 8916.974547926975, 9048.864267036, 12142.27146371216, 12341.938290308535, 12434.564790232344, 12184.0, 12233.571824986127, 12294.139410241287, 12295.692607684834, 12309.507619695201, 10277.486125693686, 12283.263004219776, 12291.0, 12312.569060082898, 12370.257784532956, 12042.69893252667, 12111.091668124838, 12129.514819407214, 12139.0, 12146.939265303705, 41015.0, 41298.793506032576, 41315.586844131474, 41361.172776544605, 41412.58587414148, 3375.1562109472634, 3386.4581666933173, 3387.593488781362, 3588.820558972041, 3600.3879340512176, 31080.844595777104, 31342.843285783652, 31347.37305253905, 31787.364252715048, 31842.363152736816, 3126.4685003549453, 3220.645728969835, 3231.2083539532728, 3254.96745032549, 3315.0386387947465, 487733.0, 491483.7129071766, 492859.0, 493281.0, 503370.96629033604, 62336.33786763059, 62565.37434625687, 62577.687111565065, 62601.74796504044, 62640.68679656664, 34856.65143348585, 35537.0, 64896.0, 64964.025539616945, 65121.67439162774, 16261.0, 16388.0167189969, 16412.507624771275, 16463.506094817174, 16530.83469165317, 11280.435978201058, 11441.370724610226, 12140.514379424723, 12220.205686630341, 12239.143259971894, 19132.139053742616, 19180.61638767217, 19474.707879381822, 19668.50828729293, 19757.604847902963, 7903.84192316162, 8040.758777236692, 8060.710286354155, 8148.674053037812, 8191.836163276701, 855.2901092093548, 981.9607215711435, 989.8168838764708, 1048.8112139814755, 1055.2665897201525, 29915.700842991726, 29935.70064299373, 30506.237344066576, 30660.554219638027, 30702.53946190809, 990.3711143423977, 1038.5067093130854, 1081.5403453532217, 1102.8125218712837, 1215.9756804863853, 15136.697266054618, 15513.767293490606, 15615.765763513557, 15615.765763513557, 15998.640285539934, 191477.0, 202691.0, 202756.0, 202786.0, 203304.98347508168, 42126.0, 42178.789106054275, 42186.36720449196, 42192.94517637022, 42230.78884605557, 1054.9367037977745, 1202.9939850300868, 1235.178606226867, 1334.8665133486768, 1353.3909740616566, 29496.115116546538, 30302.393952121274, 31217.219569510944, 32061.198470038435, 32101.35797284088, 49790.751046244535, 55333.0, 55590.88818223694, 55873.16190257068, 59409.40590594039, 656871.0, 664202.0, 664451.0, 664906.0, 666343.0, 26815.463690726465, 26965.89892162348, 27351.58972615452, 42468.78765606152, 43185.7840710807, 46860.7656961713, 47521.0, 47671.523284768096, 48310.75844620755, 48818.0, 28594.857025714737, 39356.409653855786, 39364.60635393724, 39574.60425395709, 40012.39981400223, 38755.41866871943, 39310.41034384429, 39872.401913971895, 39996.2000759989, 40184.0, 37296.88109356724, 37636.0, 38404.0, 38642.80678596589, 38975.02562435848, 39719.60280397159, 40583.39124913188, 41798.373024405264, 42606.7217983461, 43974.12051758883, 26994.460110798067, 31908.680913190572, 31909.68090319067, 32231.838840805645, 32997.0], "value": 20125.103357601172, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.77568468337904, 361.72328168950753, 370.50537532394236, 378.1585971214049, 402.6517062740728, 12805.359732013398, 17316.393926212553, 17331.0, 21310.46723831901, 21513.0, 20771.169153233852, 20905.47736306593, 21449.67825482619, 21546.676799848014, 21695.45761355967, 5949.137375080611, 6042.214512113419, 6106.145139680449, 6196.2874269459135, 6197.65912874794, 3739.3456145174623, 5997.790077347294, 6147.47746441553, 6170.7531698732, 6227.097070924713, 5792.536597072224, 6451.806445806634, 6746.662666866661, 6810.523263371566, 6902.6203558804245, 5761.279840019997, 6070.7875224366935, 6113.816585502442, 6788.015737718028, 6798.66006699668, 5758.280214973129, 5909.645421274694, 5917.911231331534, 5929.140274660193, 5969.04495280753, 13264.270465124415, 13441.991850611146, 13753.106048106842, 13754.587362379045, 13766.31168441574, 2132.264368792764, 2360.7167139943144, 2451.3258853815164, 2612.6211699303494, 2637.881295341714, 10262.384256944608, 10614.20393470485, 12942.0, 15918.363265469367, 16070.75893861593, 1761.5684157381525, 1787.490565188928, 1802.5493626593352, 1847.122616757043, 1847.390361180807, 126090.0, 129503.35248323699, 131769.0, 133602.33198833943, 134575.6542434573, 11017.228793984345, 11346.602868899548, 11476.368799715929, 11477.368744718953, 11658.825117623242, 14461.78307325391, 14480.420783168765, 14480.710385792225, 14480.782788258184, 14524.564263072125, 8172.264496195371, 8473.322134229247, 8479.0, 8643.62300367404, 8657.091005444337, 7548.471606987568, 7832.099308579586, 8379.62291696875, 8401.621927013299, 8588.355873309401, 3575.7318201135017, 4505.549445055533, 4526.751028693454, 4565.360849481075, 4630.930536041962, 1853.1475521260102, 1886.4625330355716, 1907.809219078081, 1937.5059359863144, 1945.6108778244409, 707.4729326651677, 775.5966897213417, 797.2784629909941, 889.4663202078701, 906.3791302957363, 21169.0, 21273.893630532057, 21387.14451421957, 21724.56550868974, 22080.558388832134, 927.3925578745932, 930.6463543853428, 979.8432250839832, 998.7003898830321, 1038.948052597367, 10097.798044039226, 10730.0, 11481.253718508271, 11495.770084598262, 11526.711832204262, 587111.5155393822, 594558.0272098612, 595887.0, 607810.0, 609225.9077409258, 127679.0, 128546.35726821306, 129616.0, 129619.35190324177, 132903.0, 862.6161358195543, 953.8855337359561, 963.1091240602415, 995.721198064543, 995.8904520502739, 18818.52953676169, 19167.32914347991, 19205.135768890712, 19293.22827086957, 19737.70393444071, 44106.55893441025, 48782.268265975326, 49524.50475495199, 51701.0, 52025.0, 77308.6134569346, 77528.06179845323, 78821.21178788369, 79213.60393197997, 79848.60075699585, 6742.426893713893, 7865.056193256846, 8195.71315003972, 8446.535440550828, 8596.957015214884, 8262.586870656563, 9708.320417570701, 12290.938545307215, 12611.84703824044, 13197.20816750998, 11097.445127743742, 11332.490037948148, 11492.770144597229, 11545.711357215801, 11791.233569817934, 11123.666290011313, 11140.05309548665, 11507.859787927115, 11566.0, 11685.0, 10057.346272492265, 10691.786164276515, 11625.767484650429, 11669.88330116688, 11879.762404751684, 6452.580582262138, 7372.410207183305, 7377.372923301579, 8009.799755006172, 8384.119667434832, 8921.375503714678, 11151.219414640897, 11216.88783112191, 11498.310101393943, 11564.363959982282], "value": 9453.286830497385, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1541.0291516344705, 1559.1892216047656, 1765.876388652794, 1881.5954569767498, 1901.8003109673484, 8414.03238627558, 8980.685676001342, 8981.056989016157, 8988.41575297606, 9002.819943601124, 7813.804654883631, 7994.360451163908, 8420.663173473054, 8632.050474447822, 8745.387822852403, 2043.897805109746, 2355.2580937004873, 2396.3529846941315, 2397.3167647220544, 2397.5564520563667, 2353.870537120458, 2374.560706269337, 2378.940526486843, 2399.592069348206, 2402.1352313167245, 1981.2471260920902, 2031.7358743363343, 2382.7855493005627, 2389.139909632531, 2393.5093305872256, 2338.4688526051445, 2353.8352315337847, 2362.350353652742, 2362.799162071226, 2366.479374537599, 1997.4407165993546, 2362.9645555316683, 2364.4679947011955, 2364.7635236476385, 2369.8459600126107, 8765.737027889174, 8791.208791208757, 8865.955670221607, 8881.111888811132, 8917.0, 5127.871803204913, 5291.312129423189, 5294.0, 5296.205569164617, 5300.9734951325, 2835.2628316637615, 3486.0587641336697, 3606.963930360689, 3618.9095272618397, 3640.9271814563826, 1077.6605369308643, 1078.4769386847372, 1088.0, 1104.3925840787615, 1110.3892858927557, 11377.829332560019, 11535.884641153565, 11546.134039946954, 11560.595379161688, 11591.420428978518, 4530.456345238593, 4677.321788340671, 4769.640652414115, 4783.904321913542, 4794.184988551885, 9863.309568330147, 9868.111869931741, 9872.851907221397, 9875.456849873326, 9938.801223975624, 5361.115415956324, 5361.51746342831, 5362.919556206582, 5364.892702146013, 5365.463453654604, 4038.090472088977, 4427.977860110679, 4868.683535570177, 4937.382827146606, 4939.036887806915, 3904.0, 3904.8047597620575, 3990.6009399060445, 4024.5170579530645, 4035.596440355941, 1264.4120483974812, 1269.6191142657167, 1273.961781146567, 1278.7378587389562, 1282.1089342906591, 841.381584535366, 859.7205908079899, 904.7783293093235, 909.4452384045727, 918.4626993209048, 5735.0, 5783.277090363705, 5933.376995415506, 5993.670348130808, 6087.634741915499, 791.7901756034655, 935.5836652689488, 935.9251259899191, 938.3947353956796, 988.19462138357, 3902.0635047588376, 3983.521977362735, 4044.791245438238, 4084.407760874716, 4115.588441155861, 10525.315854469589, 10865.674029779118, 10878.347299162075, 10892.782144357068, 10893.400862952614, 10741.140708743282, 10816.621418250323, 10831.404272764916, 10849.837252441219, 10892.40091794943, 8192.795180120545, 8295.04606970194, 8296.875546866802, 8302.70940517079, 8313.459625124347, 5591.580631452739, 5698.686572238401, 5936.1689363488285, 5943.138244954544, 5993.370696077023, 10048.69853904384, 10115.544800484144, 10126.898731012596, 10147.492625368553, 10150.949245253727, 9638.084381983701, 10064.647737329158, 10073.992600739868, 10257.384556926609, 10266.024727650862, 6901.62041087745, 7131.144262688511, 7309.744158954411, 7464.626768661654, 7635.580043097683, 8116.837663246584, 8452.450590711584, 8464.44981076228, 8468.53423061713, 8469.237768600855, 7403.777886663409, 7523.435742319239, 7544.735934242275, 7626.313631773167, 7662.233776622294, 7224.313690199423, 7324.19433862264, 7357.632118393952, 7385.187629360658, 7485.513441626277, 6886.586804791728, 7040.8943865843075, 7049.977753225856, 7053.858922821618, 7063.858722825617, 4534.956959899176, 4887.558170339687, 4993.650444468997, 5017.774200161075, 5047.798088076436, 4263.296556068214, 4704.7394351537505, 5020.196768517019, 5046.596272298134, 5218.295530103539], "value": 4583.638917026963, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.44703951143333, 291.492802523609, 303.8177093743754, 308.3509213106412, 313.91995041264477, 5267.077516706403, 5319.308489896308, 5329.227262047001, 5340.332458442695, 5344.625876188668, 5251.86870328242, 5284.678830292427, 5298.417174110845, 5318.521333080022, 5333.493318134781, 2880.6399200099936, 3864.111254411487, 4019.5578486366476, 4020.095478517325, 4032.778197199153, 3545.503629491873, 4028.2547728670143, 4040.817958302513, 4059.903825966987, 4073.796310184494, 3113.361760839034, 3864.782593483044, 4019.4975628046495, 4024.7182697211206, 4029.0185262905534, 3337.948546207937, 3642.307961487309, 3671.7429779915415, 4029.655386029603, 4051.858184963512, 3028.9394212115853, 3151.889683861054, 3497.5103485511795, 3526.8714011516327, 3535.9469607955903, 4888.828890988834, 5235.790568377261, 5249.768848239483, 5252.818115923925, 5271.077561426735, 4148.62662360386, 4358.673099517518, 4432.803143151364, 4623.86128416148, 4643.791029403685, 5336.532453575259, 5411.402526787164, 5470.878469913659, 5474.7006257371995, 5510.366307874606, 3079.892203772857, 3864.6908247340143, 3865.7873816940055, 3865.903352416184, 3901.395283731026, 4764.761761911891, 5046.032047501478, 5633.873225354945, 5729.684867332337, 5843.123531470271, 3233.3371658810356, 3882.864099756495, 3882.8835134946, 3900.2979463696806, 3922.2155568886337, 3299.6205436374644, 3917.373220284741, 3919.8432062717743, 3927.9018024549046, 3953.347697629916, 4162.646175075152, 4552.476465206478, 4552.521985191574, 4570.223062079454, 4570.9314360284625, 4892.48628893961, 4966.751662416865, 5393.718480051899, 5495.807646732425, 5632.718364081779, 1145.2098052343856, 1246.7132559511363, 1305.8171855940177, 1336.8930485561132, 1364.1610409598027, 736.443984791479, 749.2769477454283, 773.5088218980875, 826.9793255168669, 839.0979696825937, 4915.4347250065985, 5054.848354549369, 5173.586113110942, 5203.973980130075, 5206.817761378334, 5398.676079435247, 5566.4990150886615, 5654.547636189177, 5657.773689052474, 5778.566607504454, 4446.933296000562, 4887.242477416006, 5126.974365128151, 5239.502247286502, 5251.426800867624, 2647.9470410592066, 2681.209043332222, 2886.235147685865, 3031.4543382190977, 3033.0588423409035, 5347.224652425377, 5497.955388476177, 5948.0, 5974.970125149346, 6060.424259695322, 5340.652857564246, 5617.494425501724, 5644.491995720323, 5873.823785286449, 6007.729652165663, 5303.231031500488, 5923.229980102646, 5925.881482370329, 5948.672822994777, 6023.66869822164, 4989.476105008922, 5366.463353664602, 5444.156155795858, 5647.717614119196, 5725.679106118525, 5194.298769666047, 5632.915506267327, 5641.689707065987, 5685.573581981285, 5710.743636399985, 5902.940970590239, 5919.260092488439, 5929.881402372014, 5958.374370691188, 5975.551833612583, 2909.0473169732313, 3036.6335149183396, 3145.40237354911, 3281.5733954585753, 3308.9172770680925, 4200.4539409876525, 4383.868483945486, 4764.690295130806, 4790.353302304145, 4806.423229212517, 3997.3004724173734, 4206.29644993778, 4634.2585186370015, 4673.626109911267, 4737.28940658904, 4052.8986775330854, 4239.639630631307, 4670.9766451167525, 4718.669693121448, 4740.857774266778, 4212.199682060399, 4517.299818528133, 4849.539293767087, 5452.563794896478, 5552.305961754781, 4844.224924012141, 4968.4286307075145, 5279.313689220377, 5307.336582927134, 5407.594025553306, 4890.24201248807, 5313.707746074003, 5554.666719996651, 5568.443155684399, 5601.467860553241], "value": 3942.5468725678293, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.7765171015998, 709.9645017749112, 730.4777084384664, 778.1945686214762, 810.4326971120214, 15023.924880375596, 15707.528774136794, 15708.607284817857, 15742.842571574281, 15752.1336326502, 14146.858531414682, 14153.433862645481, 14156.858431415683, 14207.934404919646, 14468.0, 538.2383926743653, 680.9455243580502, 825.9132791056958, 831.3224721851672, 853.1468531468531, 828.3704384667666, 834.5076404921095, 841.5876220651867, 842.5029232752674, 842.6503001254472, 894.4543828264754, 894.5080205886769, 894.8702438146465, 895.50299583731, 895.771578247546, 694.4687314204638, 871.1593312453513, 889.8531742262519, 890.1543533643032, 891.4873947480193, 669.1434963247052, 672.7544446277122, 673.0173946038789, 674.659297054989, 674.9156355455568, 14735.0, 14814.851851481562, 14816.3332650031, 14818.0, 14826.0, 13480.93259533696, 13631.318434078257, 13765.0, 13801.378937947817, 13944.123832590763, 11534.423278836108, 11854.3480108594, 12176.634700958986, 12274.815877761841, 13070.411831467512, 4211.178820130076, 4558.0, 4596.7931443085145, 4632.72203667781, 4807.086653535817, 112385.75228495467, 113543.43228283888, 116245.99999999916, 117489.23766143515, 118029.0, 10566.260361774603, 10665.84001239982, 11012.50443730034, 11055.170862185207, 11283.54865805375, 20551.280705175246, 20569.382918512467, 20595.794042059686, 20596.27913023037, 20697.27559535409, 7751.806204844811, 7958.363330933512, 7996.880046799302, 8098.514089154552, 8103.756887293391, 4320.567943205654, 4485.9290891947785, 4497.100579884036, 4508.481524624644, 4549.112922979987, 3353.178471274505, 3457.7233821294235, 3484.8083355415188, 3485.790852548855, 3494.336076145524, 621.7606221604715, 632.3139393757732, 641.788209890735, 641.9165508483867, 642.3031011352734, 526.0, 598.5780025082306, 612.2438788096697, 617.6973283091253, 634.3682418156112, 7487.812804679927, 8678.271505496592, 8933.642654293886, 8936.910630893737, 9082.273418126533, 567.6508946997649, 579.0312936017574, 611.4160976267653, 612.546715430587, 625.4714766022704, 3683.37382644951, 4403.647708183337, 4406.361077643724, 4457.2645513489915, 4519.231730605805, 120610.0, 127940.0, 132185.0, 161431.0, 166965.0, 98823.0, 141071.8392241172, 141191.29404353118, 142819.57311920988, 144099.0, 115152.42423787828, 115180.0, 120356.79643203631, 120829.39585302018, 123909.38045309896, 9332.30007749408, 9463.716088517356, 9771.831084213474, 9777.706668799947, 9781.451624994412, 59900.0, 60704.392956069874, 62414.68792656008, 65564.24804419158, 67973.0, 92764.60853087345, 96051.0, 97550.0, 98670.5066474663, 99876.50185247081, 12956.475742786695, 13230.007749419025, 13519.797203042159, 13708.0, 13901.513447029305, 14922.976268363784, 15044.473443429428, 15086.849131508545, 15470.613234669223, 16008.839911600735, 14112.2943852809, 14415.207163606103, 14511.709765804835, 14542.4910128145, 14813.33339999681, 13909.0, 13986.930065349608, 14139.787903181666, 14479.493217737328, 14704.117752934859, 13633.863661363259, 14027.01810873229, 14184.432622694976, 14342.713145736818, 14403.927980360031, 11334.356518304965, 11444.479733216907, 11503.482343294405, 11644.184907056424, 11680.649580512598, 9263.722088337361, 9982.700518984442, 11551.76896462083, 11634.6509604712, 11735.706607334885], "value": 7844.695098119972, "unit": "score"}], "name": "dromaeo_css", "value": 7312.3961913186695, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 132.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 113.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 132.0, 162.0, 111.0, 131.0, 130.0, 108.0, 158.0, 108.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 185.0, 182.0, 184.0, 183.0, 179.0, 182.0, 185.0, 183.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 77.0, 151.0, 58.0, 57.0, 59.0, 68.0, 142.0, 57.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 132.0, 72.0, 77.0, 81.0, 119.0, 70.0, 91.0, 101.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 92.0, 88.0, 92.0, 89.0, 91.0, 89.0, 91.0, 88.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 97.0, 96.0, 97.0, 96.0, 96.0, 96.0, 100.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 85.0, 98.0, 112.0, 86.0, 84.0, 84.0, 84.0], "value": 88.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 55.0, 54.0, 55.0, 56.0, 54.0, 55.0, 54.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 75.0, 75.0, 74.0, 82.0, 74.0, 73.0, 72.0, 74.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 87.0, 85.0, 88.0, 86.0, 89.0, 87.0, 87.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 144.0, 148.0, 157.0, 141.0, 140.0, 138.0, 195.0, 158.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 171.0, 180.0, 171.0, 169.0, 169.0, 169.0, 169.0, 169.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 66.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1479.3999999999999, "alertThreshold": 2.0}]} 12:17:28 INFO - Return code: 0 12:17:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:17:28 INFO - # TBPL SUCCESS # 12:17:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:17:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:17:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:17:28 INFO - 2016-08-29 12:17:28.920 screenresolution[1978:15244] starting screenresolution argv=screenresolution get 12:17:28 INFO - 2016-08-29 12:17:28.923 screenresolution[1978:15244] Display 0: 1600x1200x32@60 12:17:28 INFO - 2016-08-29 12:17:28.929 screenresolution[1979:15248] starting screenresolution argv=screenresolution list 12:17:28 INFO - Available Modes on Display 0 12:17:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:17:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:17:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:17:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:17:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:17:29 INFO - Intel Iris: 12:17:29 INFO - Chipset Model: Intel Iris 12:17:29 INFO - Type: GPU 12:17:29 INFO - Bus: Built-In 12:17:29 INFO - VRAM (Dynamic, Max): 1536 MB 12:17:29 INFO - Vendor: Intel (0x8086) 12:17:29 INFO - Device ID: 0x0a2e 12:17:29 INFO - Revision ID: 0x0009 12:17:29 INFO - Displays: 12:17:29 INFO - Display: 12:17:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:17:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:17:29 INFO - Display Serial Number: 1600x1200 60 12:17:29 INFO - Main Display: Yes 12:17:29 INFO - Mirror: Off 12:17:29 INFO - Online: Yes 12:17:29 INFO - Rotation: Supported 12:17:29 INFO - Return code: 0 12:17:29 INFO - Running post-action listener: _resource_record_post_action 12:17:29 INFO - [mozharness: 2016-08-29 19:17:29.165885Z] Finished run-tests step (success) 12:17:29 INFO - Running post-run listener: _resource_record_post_run 12:17:29 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 811008; Write bytes: 703164416; Read time: 10; Write time: 2051 12:17:29 INFO - TinderboxPrint: CPU usage
53.5% 12:17:29 INFO - TinderboxPrint: I/O read bytes / time
811,008 / 10 12:17:29 INFO - TinderboxPrint: I/O write bytes / time
703,164,416 / 2,051 12:17:29 INFO - TinderboxPrint: CPU idle
2,256.4 (46.5%) 12:17:29 INFO - TinderboxPrint: CPU system
971.8 (20.0%) 12:17:29 INFO - TinderboxPrint: CPU user
1,625.4 (33.5%) 12:17:29 INFO - TinderboxPrint: Swap in / out
407,547,904 / 0 12:17:29 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 406731264; Write bytes: 408098304; Read time: 30843; Write time: 927 12:17:29 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 811008; Write bytes: 293665280; Read time: 10; Write time: 1113 12:17:29 INFO - Running post-run listener: _upload_blobber_files 12:17:29 INFO - Blob upload gear active. 12:17:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:17:29 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 12:17:29 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'] 12:17:29 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 12:17:30 INFO - (blobuploader) - INFO - Open directory for files ... 12:17:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:17:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:17:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:31 INFO - (blobuploader) - INFO - Done attempting. 12:17:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:17:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:17:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:32 INFO - (blobuploader) - INFO - Done attempting. 12:17:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:17:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:17:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:34 INFO - (blobuploader) - INFO - Done attempting. 12:17:34 INFO - (blobuploader) - INFO - Iteration through files over. 12:17:34 INFO - Return code: 0 12:17:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:17:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:17:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a845b87f0b1a73ca7b929286962610519fb4e7aceb36e0bd4a6ad63affc2f4591b7d6014d85a5df8b09a11fc9ec282bcee0a085fddbab42f15927d1cb46d754", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42439e9898bdd989a1b36e5aba3951becc99eeae4562b346708502976958255685525dbac97d4de3e76f6ba71c6ce7b226a0a089b3762c2bda33f61f9de9080a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e1595ab1b42c91bb39ff792c5f37faa5224099f5d5cb049151b5b2567459c05b352a08a49ead463a6ec404a8864a78b6ea84a985f7ff25920d2b4a630250275"} 12:17:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:17:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:17:34 INFO - Contents: 12:17:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a845b87f0b1a73ca7b929286962610519fb4e7aceb36e0bd4a6ad63affc2f4591b7d6014d85a5df8b09a11fc9ec282bcee0a085fddbab42f15927d1cb46d754", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42439e9898bdd989a1b36e5aba3951becc99eeae4562b346708502976958255685525dbac97d4de3e76f6ba71c6ce7b226a0a089b3762c2bda33f61f9de9080a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e1595ab1b42c91bb39ff792c5f37faa5224099f5d5cb049151b5b2567459c05b352a08a49ead463a6ec404a8864a78b6ea84a985f7ff25920d2b4a630250275"} 12:17:34 INFO - Running post-run listener: copy_logs_to_upload_dir 12:17:34 INFO - Copying logs to upload dir... 12:17:34 INFO - mkdir: /builds/slave/test/build/upload/logs 12:17:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.479083 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-29 12:17:34.629244) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 12:17:34.632424) ========= 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.5m51pPaVVi/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.8TTl7OWC1l/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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/7a845b87f0b1a73ca7b929286962610519fb4e7aceb36e0bd4a6ad63affc2f4591b7d6014d85a5df8b09a11fc9ec282bcee0a085fddbab42f15927d1cb46d754", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42439e9898bdd989a1b36e5aba3951becc99eeae4562b346708502976958255685525dbac97d4de3e76f6ba71c6ce7b226a0a089b3762c2bda33f61f9de9080a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e1595ab1b42c91bb39ff792c5f37faa5224099f5d5cb049151b5b2567459c05b352a08a49ead463a6ec404a8864a78b6ea84a985f7ff25920d2b4a630250275"} build_url:https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011153 build_url: 'https://queue.taskcluster.net/v1/task/K1IucU6bT5mcWq2Pr6IBxg/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/7a845b87f0b1a73ca7b929286962610519fb4e7aceb36e0bd4a6ad63affc2f4591b7d6014d85a5df8b09a11fc9ec282bcee0a085fddbab42f15927d1cb46d754", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42439e9898bdd989a1b36e5aba3951becc99eeae4562b346708502976958255685525dbac97d4de3e76f6ba71c6ce7b226a0a089b3762c2bda33f61f9de9080a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e1595ab1b42c91bb39ff792c5f37faa5224099f5d5cb049151b5b2567459c05b352a08a49ead463a6ec404a8864a78b6ea84a985f7ff25920d2b4a630250275"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 12:17:34.666060) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:17:34.666410) ========= 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.5m51pPaVVi/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.8TTl7OWC1l/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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.005199 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 12:17:34.714886) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 12:17:34.715220) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 12:17:34.715547) ========= ========= Total master_lag: 0.19 =========