builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0086 starttime: 1470659113.96 results: success (0) buildid: 20160808025952 builduid: 11a7928b0f4c4b74b71a9b3e92985edd revision: e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:13.957959) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:13.965797) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:13.966091) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:13.990144) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:13.990696) ========= 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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-08 05:25:14-- 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% 10.3M=0.001s 2016-08-08 05:25:14 (10.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118155 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:14.131882) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:14.132249) ========= 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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.020370 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:14.181842) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:14.182225) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 --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 e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-08 05:25:14,262 truncating revision to first 12 chars 2016-08-08 05:25:14,262 Setting DEBUG logging. 2016-08-08 05:25:14,262 attempt 1/10 2016-08-08 05:25:14,262 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e78975b53563?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 05:25:14,551 unpacking tar archive at: mozilla-central-e78975b53563/testing/mozharness/ program finished with exit code 0 elapsedTime=0.567817 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:14.776862) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:14.777670) ========= script_repo_revision: e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:14.778620) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:14.779326) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:25:14.799360) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-08-08 05:25:14.800083) ========= /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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:25:14 INFO - MultiFileLogger online at 20160808 05:25:14 in /builds/slave/test 05:25:14 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 05:25:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:25:14 INFO - {'append_to_log': False, 05:25:14 INFO - 'base_work_dir': '/builds/slave/test', 05:25:14 INFO - 'blob_upload_branch': 'Firefox', 05:25:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:25:14 INFO - 'branch': 'Firefox', 05:25:14 INFO - 'buildbot_json_path': 'buildprops.json', 05:25:14 INFO - 'config_files': ('talos/mac_config.py',), 05:25:14 INFO - 'default_actions': ('clobber', 05:25:14 INFO - 'read-buildbot-config', 05:25:14 INFO - 'download-and-extract', 05:25:14 INFO - 'populate-webroot', 05:25:14 INFO - 'create-virtualenv', 05:25:14 INFO - 'install', 05:25:14 INFO - 'run-tests'), 05:25:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:25:14 INFO - 'download_minidump_stackwalk': True, 05:25:14 INFO - 'download_symbols': 'ondemand', 05:25:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:25:14 INFO - 'tooltool.py': '/tools/tooltool.py', 05:25:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:25:14 INFO - '/tools/misc-python/virtualenv.py')}, 05:25:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:25:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:25:14 INFO - 'installer_path': 'installer.exe', 05:25:14 INFO - 'log_level': 'info', 05:25:14 INFO - 'log_name': 'talos', 05:25:14 INFO - 'log_to_console': True, 05:25:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:25:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:25:14 INFO - 'opt_config_files': (), 05:25:14 INFO - 'pip_index': False, 05:25:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:25:14 INFO - 'cmd': ('bash', 05:25:14 INFO - '-c', 05:25:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:25:14 INFO - 'enabled': True, 05:25:14 INFO - 'halt_on_failure': False, 05:25:14 INFO - 'name': 'check_screen_resolution'},), 05:25:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:25:14 INFO - 'cmd': ('bash', 05:25:14 INFO - '-c', 05:25:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:25:14 INFO - 'enabled': True, 05:25:14 INFO - 'halt_on_failure': False, 05:25:14 INFO - 'name': 'check_screen_resolution'},), 05:25:14 INFO - 'run_cmd_checks_enabled': True, 05:25:14 INFO - 'sps_profile': False, 05:25:14 INFO - 'sps_profile_interval': 0, 05:25:14 INFO - 'suite': 'dromaeojs', 05:25:14 INFO - 'system_bits': '32', 05:25:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:25:14 INFO - 'title': 't-yosemite-r7-0086', 05:25:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:25:14 INFO - 'use_talos_json': True, 05:25:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:25:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:25:14 INFO - 'work_dir': 'build'} 05:25:14 INFO - [mozharness: 2016-08-08 12:25:14.991351Z] Running clobber step. 05:25:14 INFO - Running pre-action listener: _resource_record_pre_action 05:25:14 INFO - Running main action method: clobber 05:25:14 INFO - rmtree: /builds/slave/test/build 05:25:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:25:15 INFO - Running post-action listener: _resource_record_post_action 05:25:15 INFO - [mozharness: 2016-08-08 12:25:15.859674Z] Finished clobber step (success) 05:25:15 INFO - [mozharness: 2016-08-08 12:25:15.859822Z] Running read-buildbot-config step. 05:25:15 INFO - Running pre-action listener: _resource_record_pre_action 05:25:15 INFO - Running main action method: read_buildbot_config 05:25:15 INFO - Using buildbot properties: 05:25:15 INFO - { 05:25:15 INFO - "project": "", 05:25:15 INFO - "product": "firefox", 05:25:15 INFO - "script_repo_revision": "production", 05:25:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:25:15 INFO - "repository": "", 05:25:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:25:15 INFO - "buildid": "20160808025952", 05:25:15 INFO - "pgo_build": "False", 05:25:15 INFO - "basedir": "/builds/slave/test", 05:25:15 INFO - "buildnumber": 57, 05:25:15 INFO - "slavename": "t-yosemite-r7-0086", 05:25:15 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:25:15 INFO - "platform": "yosemite_r7", 05:25:15 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:25:15 INFO - "branch": "mozilla-central", 05:25:15 INFO - "repo_path": "mozilla-central", 05:25:15 INFO - "revision": "e78975b53563d80c99ebfbdf8a9fbf6b829a8a48", 05:25:15 INFO - "stage_platform": "macosx64", 05:25:15 INFO - "builduid": "11a7928b0f4c4b74b71a9b3e92985edd", 05:25:15 INFO - "slavebuilddir": "test" 05:25:15 INFO - } 05:25:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:25:15 INFO - Running post-action listener: _resource_record_post_action 05:25:15 INFO - [mozharness: 2016-08-08 12:25:15.863158Z] Finished read-buildbot-config step (success) 05:25:15 INFO - [mozharness: 2016-08-08 12:25:15.863266Z] Running download-and-extract step. 05:25:15 INFO - Running pre-action listener: _resource_record_pre_action 05:25:15 INFO - Running main action method: download_and_extract 05:25:15 INFO - mkdir: /builds/slave/test/build/tests 05:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:15 INFO - trying https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:25:15 INFO - Downloading https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/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 05:25:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/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 05:25:17 INFO - Downloaded 1199 bytes. 05:25:17 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:25:17 INFO - Using the following test package requirements: 05:25:17 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:25:17 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:25:17 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:25:17 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:25:17 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:25:17 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:25:17 INFO - u'jsshell-mac.zip'], 05:25:17 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:25:17 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:25:17 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:25:17 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:25:17 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:25:17 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:25:17 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:25:17 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:25:17 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:25:17 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:25:17 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:25:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:17 INFO - trying https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:25:17 INFO - Downloading https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/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 05:25:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/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 05:25:18 INFO - Downloaded 16324245 bytes. 05:25:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:25:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:25:21 INFO - Return code: 0 05:25:21 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 05:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:21 INFO - trying https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:25:21 INFO - Downloading https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/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 05:25:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/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 05:25:22 INFO - Downloaded 16324245 bytes. 05:25:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:25:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:25:25 INFO - Return code: 0 05:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:25 INFO - trying https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:25:25 INFO - Downloading https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/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 05:25:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/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 05:25:26 INFO - Downloaded 14413723 bytes. 05:25:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:25:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:25:26 INFO - Return code: 0 05:25:26 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')]} 05:25:26 INFO - trying https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:25:26 INFO - Downloading https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:25:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/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 05:25:29 INFO - Downloaded 111885214 bytes. 05:25:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:25:29 INFO - mkdir: /builds/slave/test/properties 05:25:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:25:29 INFO - Writing to file /builds/slave/test/properties/build_url 05:25:29 INFO - Contents: 05:25:29 INFO - build_url:https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:25:30 INFO - Running post-action listener: _resource_record_post_action 05:25:30 INFO - Running post-action listener: set_extra_try_arguments 05:25:30 INFO - [mozharness: 2016-08-08 12:25:30.339846Z] Finished download-and-extract step (success) 05:25:30 INFO - [mozharness: 2016-08-08 12:25:30.340000Z] Running populate-webroot step. 05:25:30 INFO - Running pre-action listener: _resource_record_pre_action 05:25:30 INFO - Running main action method: populate_webroot 05:25:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:25:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:25:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:25:30 INFO - '--tppagecycles', 05:25:30 INFO - '7'], 05:25:30 INFO - 'tests': ['tsvgm']}}, 05:25:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:25:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:25:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:25:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:25:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:25:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:25:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:30 INFO - 'talos_options': ['--disable-e10s'], 05:25:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:30 INFO - 'talos_options': ['--disable-e10s'], 05:25:30 INFO - 'tests': ['damp', 'tps']}, 05:25:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:30 INFO - 'tests': ['damp', 'tps']}, 05:25:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:25:30 INFO - 'tests': ['dromaeo_dom']}, 05:25:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:25:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:25:30 INFO - 'tests': ['basic_compositor_video']}, 05:25:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:25:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:25:30 INFO - 'tests': ['a11yr', 05:25:30 INFO - 'ts_paint', 05:25:30 INFO - 'tpaint', 05:25:30 INFO - 'sessionrestore', 05:25:30 INFO - 'sessionrestore_no_auto_restore', 05:25:30 INFO - 'tabpaint']}, 05:25:30 INFO - 'other-e10s': {'tests': ['a11yr', 05:25:30 INFO - 'ts_paint', 05:25:30 INFO - 'tpaint', 05:25:30 INFO - 'sessionrestore', 05:25:30 INFO - 'sessionrestore_no_auto_restore', 05:25:30 INFO - 'tabpaint']}, 05:25:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:25:30 INFO - 'ts_paint', 05:25:30 INFO - 'tpaint', 05:25:30 INFO - 'sessionrestore', 05:25:30 INFO - 'sessionrestore_no_auto_restore', 05:25:30 INFO - 'tabpaint']}, 05:25:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:25:30 INFO - 'ts_paint', 05:25:30 INFO - 'tpaint', 05:25:30 INFO - 'sessionrestore', 05:25:30 INFO - 'sessionrestore_no_auto_restore', 05:25:30 INFO - 'tabpaint']}, 05:25:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:25:30 INFO - 'tests': ['a11yr', 05:25:30 INFO - 'ts_paint', 05:25:30 INFO - 'tpaint', 05:25:30 INFO - 'sessionrestore', 05:25:30 INFO - 'sessionrestore_no_auto_restore', 05:25:30 INFO - 'tabpaint']}, 05:25:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:25:30 INFO - 'tests': ['a11yr', 05:25:30 INFO - 'ts_paint', 05:25:30 INFO - 'tpaint', 05:25:30 INFO - 'sessionrestore', 05:25:30 INFO - 'sessionrestore_no_auto_restore', 05:25:30 INFO - 'tabpaint']}, 05:25:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:25:30 INFO - 'tests': ['tsvgx', 05:25:30 INFO - 'tsvgr_opacity', 05:25:30 INFO - 'tart', 05:25:30 INFO - 'tscrollx', 05:25:30 INFO - 'cart']}, 05:25:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:25:30 INFO - 'tsvgr_opacity', 05:25:30 INFO - 'tart', 05:25:30 INFO - 'tscrollx', 05:25:30 INFO - 'cart']}, 05:25:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:30 INFO - 'talos_options': ['--disable-e10s'], 05:25:30 INFO - 'tests': ['tp5o']}, 05:25:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:30 INFO - 'tests': ['tp5o']}, 05:25:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:30 INFO - 'talos_options': ['--disable-e10s', 05:25:30 INFO - '--xperf_path', 05:25:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:30 INFO - 'tests': ['tp5n']}, 05:25:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:30 INFO - 'talos_options': ['--xperf_path', 05:25:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:30 INFO - 'tests': ['tp5n']}}, 05:25:30 INFO - 'talos.zip': {'path': '', 05:25:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:25:30 INFO - Running post-action listener: _resource_record_post_action 05:25:30 INFO - [mozharness: 2016-08-08 12:25:30.359243Z] Finished populate-webroot step (success) 05:25:30 INFO - [mozharness: 2016-08-08 12:25:30.359384Z] Running create-virtualenv step. 05:25:30 INFO - Running pre-action listener: _resource_record_pre_action 05:25:30 INFO - Running main action method: create_virtualenv 05:25:30 INFO - Creating virtualenv /builds/slave/test/build/venv 05:25:30 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 05:25:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:25:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:25:30 INFO - Using real prefix '/tools/python27' 05:25:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:25:31 INFO - Installing distribute.............................................................................................................................................................................................done. 05:25:34 INFO - Installing pip.................done. 05:25:34 INFO - Return code: 0 05:25:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109868618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d9d6ea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109737d30>, '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 0x10949cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10972fb20>, '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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:25:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:25:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:25:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZuvLoAP2KG/Render', 05:25:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:34 INFO - 'HOME': '/Users/cltbld', 05:25:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:34 INFO - 'LOGNAME': 'cltbld', 05:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:34 INFO - 'MOZ_NO_REMOTE': '1', 05:25:34 INFO - 'NO_EM_RESTART': '1', 05:25:34 INFO - 'PAGER': '/bin/cat', 05:25:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:34 INFO - 'PWD': '/builds/slave/test', 05:25:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:34 INFO - 'SHELL': '/bin/bash', 05:25:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwZ1xFgb91/Listeners', 05:25:34 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:25:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:34 INFO - 'USER': 'cltbld', 05:25:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:34 INFO - 'XPC_FLAGS': '0x0', 05:25:34 INFO - 'XPC_SERVICE_NAME': '0', 05:25:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:34 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 05:25:34 INFO - Cleaning up... 05:25:34 INFO - Return code: 0 05:25:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1097d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109868618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d9d6ea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109737d30>, '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 0x10949cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10972fb20>, '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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:25:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:25:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:25:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZuvLoAP2KG/Render', 05:25:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:34 INFO - 'HOME': '/Users/cltbld', 05:25:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:34 INFO - 'LOGNAME': 'cltbld', 05:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:34 INFO - 'MOZ_NO_REMOTE': '1', 05:25:34 INFO - 'NO_EM_RESTART': '1', 05:25:34 INFO - 'PAGER': '/bin/cat', 05:25:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:34 INFO - 'PWD': '/builds/slave/test', 05:25:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:34 INFO - 'SHELL': '/bin/bash', 05:25:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwZ1xFgb91/Listeners', 05:25:34 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:25:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:34 INFO - 'USER': 'cltbld', 05:25:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:34 INFO - 'XPC_FLAGS': '0x0', 05:25:34 INFO - 'XPC_SERVICE_NAME': '0', 05:25:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:34 INFO - Downloading/unpacking psutil>=3.1.1 05:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:25:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:37 INFO - Installing collected packages: psutil 05:25:37 INFO - Running setup.py install for psutil 05:25:37 INFO - building 'psutil._psutil_osx' extension 05:25:37 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 05:25:38 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 05:25:38 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 05:25:38 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 05:25:38 INFO - building 'psutil._psutil_posix' extension 05:25:38 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 05:25:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:25:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:25:38 INFO - ^ 05:25:38 INFO - 1 warning generated. 05:25:38 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 05:25:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:38 INFO - Successfully installed psutil 05:25:38 INFO - Cleaning up... 05:25:38 INFO - Return code: 0 05:25:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109868618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d9d6ea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109737d30>, '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 0x10949cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10972fb20>, '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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:25:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:25:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZuvLoAP2KG/Render', 05:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:38 INFO - 'HOME': '/Users/cltbld', 05:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:38 INFO - 'LOGNAME': 'cltbld', 05:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:38 INFO - 'MOZ_NO_REMOTE': '1', 05:25:38 INFO - 'NO_EM_RESTART': '1', 05:25:38 INFO - 'PAGER': '/bin/cat', 05:25:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:38 INFO - 'PWD': '/builds/slave/test', 05:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:38 INFO - 'SHELL': '/bin/bash', 05:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwZ1xFgb91/Listeners', 05:25:38 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:38 INFO - 'USER': 'cltbld', 05:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:38 INFO - 'XPC_FLAGS': '0x0', 05:25:38 INFO - 'XPC_SERVICE_NAME': '0', 05:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:25:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:25:41 INFO - Installing collected packages: mozsystemmonitor 05:25:41 INFO - Running setup.py install for mozsystemmonitor 05:25:41 INFO - Successfully installed mozsystemmonitor 05:25:41 INFO - Cleaning up... 05:25:41 INFO - Return code: 0 05:25:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:25:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1097d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109868618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d9d6ea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109737d30>, '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 0x10949cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10972fb20>, '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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:25:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:25:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:25:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZuvLoAP2KG/Render', 05:25:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:41 INFO - 'HOME': '/Users/cltbld', 05:25:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:41 INFO - 'LOGNAME': 'cltbld', 05:25:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:41 INFO - 'MOZ_NO_REMOTE': '1', 05:25:41 INFO - 'NO_EM_RESTART': '1', 05:25:41 INFO - 'PAGER': '/bin/cat', 05:25:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:41 INFO - 'PWD': '/builds/slave/test', 05:25:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:41 INFO - 'SHELL': '/bin/bash', 05:25:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwZ1xFgb91/Listeners', 05:25:41 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:25:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:41 INFO - 'USER': 'cltbld', 05:25:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:41 INFO - 'XPC_FLAGS': '0x0', 05:25:41 INFO - 'XPC_SERVICE_NAME': '0', 05:25:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:41 INFO - Downloading/unpacking jsonschema==2.5.1 05:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:25:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:44 INFO - Downloading functools32-3.2.3-2.tar.gz 05:25:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:25:44 INFO - warning: no files found matching '*.txt' 05:25:44 INFO - no previously-included directories found matching 'build' 05:25:44 INFO - no previously-included directories found matching 'dist' 05:25:44 INFO - no previously-included directories found matching '.git*' 05:25:44 INFO - Installing collected packages: jsonschema, functools32 05:25:44 INFO - Running setup.py install for functools32 05:25:44 INFO - warning: no files found matching '*.txt' 05:25:44 INFO - no previously-included directories found matching 'build' 05:25:44 INFO - no previously-included directories found matching 'dist' 05:25:44 INFO - no previously-included directories found matching '.git*' 05:25:44 INFO - Successfully installed jsonschema functools32 05:25:44 INFO - Cleaning up... 05:25:45 INFO - Return code: 0 05:25:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:25:45 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')]} 05:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:45 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')]} 05:25:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:45 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 0x1097d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109868618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d9d6ea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109737d30>, '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 0x10949cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10972fb20>, '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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:25:45 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 05:25:45 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 05:25:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZuvLoAP2KG/Render', 05:25:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:45 INFO - 'HOME': '/Users/cltbld', 05:25:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:45 INFO - 'LOGNAME': 'cltbld', 05:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:45 INFO - 'MOZ_NO_REMOTE': '1', 05:25:45 INFO - 'NO_EM_RESTART': '1', 05:25:45 INFO - 'PAGER': '/bin/cat', 05:25:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:45 INFO - 'PWD': '/builds/slave/test', 05:25:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:45 INFO - 'SHELL': '/bin/bash', 05:25:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwZ1xFgb91/Listeners', 05:25:45 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:25:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:45 INFO - 'USER': 'cltbld', 05:25:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:45 INFO - 'XPC_FLAGS': '0x0', 05:25:45 INFO - 'XPC_SERVICE_NAME': '0', 05:25:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:25:45 INFO - Cleaning up... 05:25:45 INFO - Return code: 0 05:25:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:25:45 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')]} 05:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:45 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')]} 05:25:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:45 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 0x1097d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109868618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d9d6ea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109737d30>, '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 0x10949cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10972fb20>, '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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:25:45 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 05:25:45 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 05:25:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZuvLoAP2KG/Render', 05:25:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:45 INFO - 'HOME': '/Users/cltbld', 05:25:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:45 INFO - 'LOGNAME': 'cltbld', 05:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:45 INFO - 'MOZ_NO_REMOTE': '1', 05:25:45 INFO - 'NO_EM_RESTART': '1', 05:25:45 INFO - 'PAGER': '/bin/cat', 05:25:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:45 INFO - 'PWD': '/builds/slave/test', 05:25:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:45 INFO - 'SHELL': '/bin/bash', 05:25:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwZ1xFgb91/Listeners', 05:25:45 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:25:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:45 INFO - 'USER': 'cltbld', 05:25:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:45 INFO - 'XPC_FLAGS': '0x0', 05:25:45 INFO - 'XPC_SERVICE_NAME': '0', 05:25:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:45 INFO - Downloading/unpacking blobuploader==1.2.4 05:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:47 INFO - Downloading blobuploader-1.2.4.tar.gz 05:25:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:25:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:25:48 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 05:25:48 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 05:25:48 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 05:25:48 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 05:25:48 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 05:25:48 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 05:25:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:25:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:25:48 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 05:25:48 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 05:25:48 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 05:25:48 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 05:25:48 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 05:25:48 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 05:25:49 INFO - Downloading docopt-0.6.1.tar.gz 05:25:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:25:49 INFO - Installing collected packages: blobuploader, requests, docopt 05:25:49 INFO - Running setup.py install for blobuploader 05:25:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:25:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:25:49 INFO - Running setup.py install for requests 05:25:49 INFO - Running setup.py install for docopt 05:25:50 INFO - Successfully installed blobuploader requests docopt 05:25:50 INFO - Cleaning up... 05:25:50 INFO - Return code: 0 05:25:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:50 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 0x1097d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109868618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d9d6ea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109737d30>, '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 0x10949cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10972fb20>, '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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:25:50 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 05:25:50 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 05:25:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZuvLoAP2KG/Render', 05:25:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:50 INFO - 'HOME': '/Users/cltbld', 05:25:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:50 INFO - 'LOGNAME': 'cltbld', 05:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:50 INFO - 'MOZ_NO_REMOTE': '1', 05:25:50 INFO - 'NO_EM_RESTART': '1', 05:25:50 INFO - 'PAGER': '/bin/cat', 05:25:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:50 INFO - 'PWD': '/builds/slave/test', 05:25:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:50 INFO - 'SHELL': '/bin/bash', 05:25:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwZ1xFgb91/Listeners', 05:25:50 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:25:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:50 INFO - 'USER': 'cltbld', 05:25:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:50 INFO - 'XPC_FLAGS': '0x0', 05:25:50 INFO - 'XPC_SERVICE_NAME': '0', 05:25:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:25:50 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-bWJgOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:25:50 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-DexUEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:25:50 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-bA6u3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:25:50 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-__na45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:25:50 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-GSaMjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:25:50 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-SoFPQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:25:51 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ZEEPQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:25:51 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ZxY72e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:25:51 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-9YHuYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:25:51 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-zf0Frt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:25:51 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-iLd9C7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:25:51 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-HBZ1rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:25:51 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-MXoTzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:25:51 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-MXFwu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:51 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-5nzntf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:25:52 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-wPctHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:25:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:25:52 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-JHoaB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:25:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:25:52 INFO - Running setup.py install for manifestparser 05:25:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:25:52 INFO - Running setup.py install for mozcrash 05:25:52 INFO - Running setup.py install for mozdebug 05:25:52 INFO - Running setup.py install for mozdevice 05:25:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:25:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:25:52 INFO - Running setup.py install for mozfile 05:25:52 INFO - Running setup.py install for mozhttpd 05:25:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:25:53 INFO - Running setup.py install for mozinfo 05:25:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:25:53 INFO - Running setup.py install for mozInstall 05:25:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:25:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:25:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:25:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:25:53 INFO - Running setup.py install for mozleak 05:25:53 INFO - Running setup.py install for mozlog 05:25:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:25:53 INFO - Running setup.py install for moznetwork 05:25:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:25:53 INFO - Running setup.py install for mozprocess 05:25:54 INFO - Running setup.py install for mozprofile 05:25:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:25:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:25:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:25:54 INFO - Running setup.py install for mozrunner 05:25:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:25:54 INFO - Running setup.py install for mozscreenshot 05:25:54 INFO - Running setup.py install for moztest 05:25:54 INFO - Running setup.py install for mozversion 05:25:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:25:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:25:54 INFO - Cleaning up... 05:25:54 INFO - Return code: 0 05:25:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25:54 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')]} 05:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:54 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')]} 05:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:54 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 0x1097d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109868618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d9d6ea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109737d30>, '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 0x10949cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10972fb20>, '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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:25:54 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 05:25:54 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 05:25:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZuvLoAP2KG/Render', 05:25:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:54 INFO - 'HOME': '/Users/cltbld', 05:25:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:54 INFO - 'LOGNAME': 'cltbld', 05:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:54 INFO - 'MOZ_NO_REMOTE': '1', 05:25:54 INFO - 'NO_EM_RESTART': '1', 05:25:54 INFO - 'PAGER': '/bin/cat', 05:25:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:54 INFO - 'PWD': '/builds/slave/test', 05:25:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:54 INFO - 'SHELL': '/bin/bash', 05:25:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwZ1xFgb91/Listeners', 05:25:54 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:25:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:54 INFO - 'USER': 'cltbld', 05:25:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:54 INFO - 'XPC_FLAGS': '0x0', 05:25:54 INFO - 'XPC_SERVICE_NAME': '0', 05:25:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:25:55 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-F1CF_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:25:55 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)) 05:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:25:55 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-KUE5vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:25:55 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)) 05:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:25:55 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-dCw2AP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:25:55 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)) 05:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:25:55 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-vW6r6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:25:55 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)) 05:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:25:55 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-rFM10b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:25:55 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)) 05:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:25:55 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-vM7gZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:25:55 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)) 05:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:25:55 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-DUoY3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:25:55 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)) 05:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:25:55 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-iTcixU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:25:55 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)) 05:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:25:55 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-IQOLWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:25:56 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)) 05:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:25:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-POaOIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:25:56 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)) 05:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:25:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-H_MLOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:25:56 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)) 05:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:25:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-09XABB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:25:56 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)) 05:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:25:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-HSxslk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:25:56 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)) 05:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:25:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-769ZQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:25:56 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)) 05:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-hufGWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:56 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)) 05:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:25:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-hsRllm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:25:56 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)) 05:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:25:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-HCBngb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:25:56 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)) 05:25:56 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)) 05:25:56 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)) 05:25:56 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)) 05:25:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:25:56 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)) 05:25:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25:59 INFO - Downloading blessings-1.6.tar.gz 05:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:25:59 INFO - Installing collected packages: blessings 05:25:59 INFO - Running setup.py install for blessings 05:25:59 INFO - Successfully installed blessings 05:25:59 INFO - Cleaning up... 05:25:59 INFO - Return code: 0 05:25:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:25:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:25:59 INFO - Reading from file tmpfile_stdout 05:25:59 INFO - Current package versions: 05:25:59 INFO - blessings == 1.6 05:25:59 INFO - blobuploader == 1.2.4 05:25:59 INFO - docopt == 0.6.1 05:25:59 INFO - functools32 == 3.2.3-2 05:25:59 INFO - jsonschema == 2.5.1 05:25:59 INFO - manifestparser == 1.1 05:25:59 INFO - mozInstall == 1.12 05:25:59 INFO - mozcrash == 0.17 05:25:59 INFO - mozdebug == 0.1 05:25:59 INFO - mozdevice == 0.48 05:25:59 INFO - mozfile == 1.2 05:25:59 INFO - mozhttpd == 0.7 05:25:59 INFO - mozinfo == 0.9 05:25:59 INFO - mozleak == 0.1 05:25:59 INFO - mozlog == 3.3 05:25:59 INFO - moznetwork == 0.27 05:25:59 INFO - mozprocess == 0.23 05:25:59 INFO - mozprofile == 0.28 05:25:59 INFO - mozrunner == 6.12 05:25:59 INFO - mozscreenshot == 0.1 05:25:59 INFO - mozsystemmonitor == 0.3 05:25:59 INFO - moztest == 0.7 05:25:59 INFO - mozversion == 1.4 05:25:59 INFO - psutil == 3.1.1 05:25:59 INFO - requests == 1.2.3 05:25:59 INFO - wsgiref == 0.1.2 05:25:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25: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')]} 05:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:59 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 0x1097d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109868618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d9d6ea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109737d30>, '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 0x10949cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10972fb20>, '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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:25:59 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 05:25:59 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 05:25:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZuvLoAP2KG/Render', 05:25:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:59 INFO - 'HOME': '/Users/cltbld', 05:25:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:59 INFO - 'LOGNAME': 'cltbld', 05:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:59 INFO - 'MOZ_NO_REMOTE': '1', 05:25:59 INFO - 'NO_EM_RESTART': '1', 05:25:59 INFO - 'PAGER': '/bin/cat', 05:25:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:59 INFO - 'PWD': '/builds/slave/test', 05:25:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:59 INFO - 'SHELL': '/bin/bash', 05:25:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwZ1xFgb91/Listeners', 05:25:59 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:25:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:59 INFO - 'USER': 'cltbld', 05:25:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:59 INFO - 'XPC_FLAGS': '0x0', 05:25:59 INFO - 'XPC_SERVICE_NAME': '0', 05:25:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:00 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)) 05:26:00 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)) 05:26:00 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)) 05:26:00 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)) 05:26:00 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)) 05:26:00 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)) 05:26:00 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)) 05:26:00 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)) 05:26:00 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)) 05:26:00 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)) 05:26:00 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)) 05:26:00 INFO - Cleaning up... 05:26:00 INFO - Return code: 0 05:26:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:00 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 0x1097d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109868618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1d9d6ea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109737d30>, '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 0x10949cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10972fb20>, '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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:26:00 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 05:26:00 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 05:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZuvLoAP2KG/Render', 05:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:00 INFO - 'HOME': '/Users/cltbld', 05:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:00 INFO - 'LOGNAME': 'cltbld', 05:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:00 INFO - 'MOZ_NO_REMOTE': '1', 05:26:00 INFO - 'NO_EM_RESTART': '1', 05:26:00 INFO - 'PAGER': '/bin/cat', 05:26:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:00 INFO - 'PWD': '/builds/slave/test', 05:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:00 INFO - 'SHELL': '/bin/bash', 05:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwZ1xFgb91/Listeners', 05:26:00 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:00 INFO - 'USER': 'cltbld', 05:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:00 INFO - 'XPC_FLAGS': '0x0', 05:26:00 INFO - 'XPC_SERVICE_NAME': '0', 05:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:26:00 INFO - Cleaning up... 05:26:00 INFO - Return code: 0 05:26:00 INFO - Running post-action listener: _resource_record_post_action 05:26:00 INFO - Running post-action listener: _start_resource_monitoring 05:26:00 INFO - Starting resource monitoring. 05:26:00 INFO - [mozharness: 2016-08-08 12:26:00.487354Z] Finished create-virtualenv step (success) 05:26:00 INFO - [mozharness: 2016-08-08 12:26:00.488286Z] Running install step. 05:26:00 INFO - Running pre-action listener: _resource_record_pre_action 05:26:00 INFO - Running main action method: install 05:26:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:26:00 INFO - Reading from file tmpfile_stdout 05:26:00 INFO - Detecting whether we're running mozinstall >=1.0... 05:26:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:26:00 INFO - Reading from file tmpfile_stdout 05:26:00 INFO - Output received: 05:26:00 INFO - Usage: mozinstall [options] installer 05:26:00 INFO - Options: 05:26:00 INFO - -h, --help show this help message and exit 05:26:00 INFO - -d DEST, --destination=DEST 05:26:00 INFO - Directory to install application into. [default: 05:26:00 INFO - "/builds/slave/test"] 05:26:00 INFO - --app=APP Application being installed. [default: firefox] 05:26:00 INFO - mkdir: /builds/slave/test/build/application 05:26:00 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'] 05:26:00 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 05:26:35 INFO - Reading from file tmpfile_stdout 05:26:35 INFO - Output received: 05:26:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:26:35 INFO - Running post-action listener: _resource_record_post_action 05:26:35 INFO - [mozharness: 2016-08-08 12:26:35.526893Z] Finished install step (success) 05:26:35 INFO - [mozharness: 2016-08-08 12:26:35.527114Z] Running run-tests step. 05:26:35 INFO - Running pre-action listener: _resource_record_pre_action 05:26:35 INFO - Running main action method: run_tests 05:26:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:35 INFO - 2016-08-08 05:26:35.541 screenresolution[1921:14847] starting screenresolution argv=screenresolution get 05:26:35 INFO - 2016-08-08 05:26:35.544 screenresolution[1921:14847] Display 0: 1600x1200x32@60 05:26:35 INFO - 2016-08-08 05:26:35.550 screenresolution[1922:14849] starting screenresolution argv=screenresolution list 05:26:35 INFO - Available Modes on Display 0 05:26:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:35 INFO - Intel Iris: 05:26:35 INFO - Chipset Model: Intel Iris 05:26:35 INFO - Type: GPU 05:26:35 INFO - Bus: Built-In 05:26:35 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:35 INFO - Vendor: Intel (0x8086) 05:26:35 INFO - Device ID: 0x0a2e 05:26:35 INFO - Revision ID: 0x0009 05:26:35 INFO - Displays: 05:26:35 INFO - Display: 05:26:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:35 INFO - Display Serial Number: 1600x1200 60 05:26:35 INFO - Main Display: Yes 05:26:35 INFO - Mirror: Off 05:26:35 INFO - Online: Yes 05:26:35 INFO - Rotation: Supported 05:26:35 INFO - Return code: 0 05:26:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:26:35 INFO - Python 2.7.3 05:26:35 INFO - Return code: 0 05:26:35 INFO - grabbing minidump binary from tooltool 05:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:35 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 0x109737d30>, '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 0x10949cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10972fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:26:35 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 05:26:35 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 05:26:35 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 05:26:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:26:35 INFO - Return code: 0 05:26:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:26:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:26:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:26:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:26:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:26:35 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/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 05:26:35 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/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0086 --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 05:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZuvLoAP2KG/Render', 05:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:35 INFO - 'HOME': '/Users/cltbld', 05:26:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:35 INFO - 'LOGNAME': 'cltbld', 05:26:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:26:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:35 INFO - 'MOZ_NO_REMOTE': '1', 05:26:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:26:35 INFO - 'NO_EM_RESTART': '1', 05:26:35 INFO - 'PAGER': '/bin/cat', 05:26:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:35 INFO - 'PWD': '/builds/slave/test', 05:26:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:26:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:35 INFO - 'SHELL': '/bin/bash', 05:26:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwZ1xFgb91/Listeners', 05:26:35 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:26:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:35 INFO - 'USER': 'cltbld', 05:26:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:35 INFO - 'XPC_FLAGS': '0x0', 05:26:35 INFO - 'XPC_SERVICE_NAME': '0', 05:26:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:35 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/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 05:26:36 INFO - mozversion application_buildid: 20160808025952 05:26:36 INFO - mozversion application_changeset: e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 05:26:36 INFO - mozversion application_display_name: Nightly 05:26:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:26:36 INFO - mozversion application_name: Firefox 05:26:36 INFO - mozversion application_remotingname: firefox 05:26:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:26:36 INFO - mozversion application_vendor: Mozilla 05:26:36 INFO - mozversion application_version: 51.0a1 05:26:36 INFO - mozversion platform_buildid: 20160808025952 05:26:36 INFO - mozversion platform_changeset: e78975b53563d80c99ebfbdf8a9fbf6b829a8a48 05:26:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:26:36 INFO - mozversion platform_version: 51.0a1 05:26:36 INFO - using testdate: 1470659196 05:26:36 INFO - actual date: 1470659196 05:26:36 INFO - starting webserver on 'localhost:49438' 05:26:36 INFO - SUITE-START | Running 2 tests 05:26:36 INFO - TEST-START | dromaeo_css 05:26:36 INFO - operating with platform_type : mac_ 05:26:36 INFO - Initialising browser for dromaeo_css test... 05:26:36 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpzf7ad5/profile http://localhost:49438/getInfo.html) 05:26:38 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 05:26:38 INFO - PROCESS | 1932 | colorDepth:24 05:26:38 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 05:26:38 INFO - PROCESS | 1932 | __metrics 05:26:38 INFO - PROCESS | 1932 | 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" 05:26:38 INFO - PROCESS | 1932 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:26:39 INFO - TEST-INFO | 1932: exit 0 05:26:39 INFO - Browser initialized. 05:26:39 INFO - Running cycle 1/1 for dromaeo_css test... 05:26:39 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpzf7ad5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:26:51 INFO - PROCESS | 1934 | RSS: Main: 243605504 05:26:51 INFO - PROCESS | 1934 | 05:27:42 INFO - PROCESS | 1934 | 1470659262436 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] 05:29:55 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49438/tests/dromaeo/cssquery-ext.html) 05:29:56 INFO - PROCESS | 1934 | RSS: Main: 383426560 05:29:56 INFO - PROCESS | 1934 | 05:32:59 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-ext.html (next: http://localhost:49438/tests/dromaeo/cssquery-jquery.html) 05:33:00 INFO - PROCESS | 1934 | RSS: Main: 388263936 05:33:00 INFO - PROCESS | 1934 | 05:36:02 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49438/tests/dromaeo/cssquery-mootools.html) 05:36:03 INFO - PROCESS | 1934 | RSS: Main: 379215872 05:36:03 INFO - PROCESS | 1934 | 05:39:05 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49438/tests/dromaeo/cssquery-prototype.html) 05:39:06 INFO - PROCESS | 1934 | RSS: Main: 360980480 05:39:06 INFO - PROCESS | 1934 | 05:42:09 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49438/tests/dromaeo/cssquery-yui.html) 05:42:10 INFO - PROCESS | 1934 | RSS: Main: 346046464 05:42:10 INFO - PROCESS | 1934 | 05:45:13 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-yui.html (next: http://localhost:49438/tests/dromaeo/cssquery-dojo.html) 05:45:13 INFO - PROCESS | 1934 | RSS: Main: 396132352 05:45:13 INFO - PROCESS | 1934 | 05:45:13 INFO - PROCESS | 1934 | __start_tp_report 05:45:13 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 05:45:13 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 05:45:13 INFO - PROCESS | 1934 | |i|pagename|runs| 05:45:13 INFO - PROCESS | 1934 | |0;dojo.html;3328.301056778075;3329.683680050395;3337.21575429774;3355.3457075870215;3388.4578467445212;5570.777168913249;5611.410801865806;5651.350094739107;5706.600537962339;5713.428657134285;5561.388247292804;5640.5487560995125;5727.3699893011735;5747.051736463489;5771.884562308751;5079.974600127012;5144.588432925376;5147.305113809644;5167.819126330579;5225.555827754645;5229.973850130763;5266.262723218752;5268.394134674504;5274.498922602365;5308.309919710432;5135.6918584885025;5178.223266510054;5184.377874655047;5206.271122042917;5272.841814745564;5138.640295179339;5224.555912747421;5228.241904923802;5242.1088414969545;5245.31810864589;5124.718140502271;5136.691798492065;5179.870503237412;5226.216067589854;5255.3168088148295;5670.347909990322;5705.942940570624;5710.314762228559;5727.169560413717;5736.627119237278;6053;6076.512766265323;6223.502119830403;6242.063690446424;6248.125262463259;6206.937930620681;6219.533534984849;6312.52656050798;6336.96831515844;6351.237851457855;5856.180134781134;5863.824085277449;5882.470577648032;5899.528037757055;5910.940890591125;6198.566100373021;6257.687115644199;6274.466670333073;6310.936890631035;6331.810045698637;6149.877002459926;6174.44430001302;6182.227221597301;6182.907256391158;6227.906581401283;6278.152449419361;6297.779577714758;6308.085327627468;6310.9368906311265;6325.936740632626;6082.513398928075;6087.026075827846;6253.74985000604;6257.4681152102585;6268.686565671698;5121.692698438105;6127.5404344674325;6152.815415537541;6168.321484636686;6266.060090986389;4972.925406118911;4979.3526841510375;4979.925301120486;4986.825461108844;4987.451380348159;4509.797059132281;4513.932291015637;4527.071950250257;4533.184026875194;4582.198115329804;5569.19246709233;5615.634983726045;5679.261695979496;5712.942870571324;5734.856628584235;6330.936690633127;6335.588186767845;6350.841228969312;6355.205599300088;6358.904616430757;5680.091185410314;5720.11338242573;5724.74238659253;5739.081746920473;5747.166660834156;5319.175527793198;5349.679019258857;5358;5371.274877891513;5466.207399926989;6295.842603934847;6321.431071203614;6329.367063293634;6335.556511044183;6338.936610633835;6279.215098112736;6303.401176888188;6306.211723534559;6322.968385158045;6334.3665633436285;6191.318954914866;6241.282252540835;6254.3745625437095;6299.401556852092;6315.5894866833505;6176.474999625082;6177.84555386119;6219.315875253809;6232.407921247474;6233.906491402542;6159.876802464015;6286.748530058747;6304.621722696653;6312.43188113072;6313.526485513513;5886.676232807236;6048.727807248596;6076;6099.634021958696;6112.755489780359;6111.622125543816;6131.8467038322615;6150.616111375084;6153.723082461211;6158.3841615838055;5974.761009559569;5980.132880732356;5983;6012.939870601238;6044.0933859919605;5899.321578018411;5984;5999.460048595647;6140.263168419819;6142.846428839315;5907.793227237026;5910;5914.556408269483;5917.644941303535;5950.583459157818 05:45:13 INFO - PROCESS | 1934 | |1;ext.html;466.9439667239932;480.70196478183533;521.3587287636208;526.6524094097895;548.9972550137251;30527;32288.515672264904;41930.95172620686;42486;42729.65879265092;44109;44436.11127777443;44439.33340999879;44482.33276500847;44501.10997780043;9889.159421449167;10034.498275086253;10037;10041.799164016715;10047.397156170653;9825.901740982606;9928.851067233998;9935.751606209866;9941.254405919548;9961.55173017216;8481.618327175262;9904.25718071144;9958.302918795687;9963.202943764481;9964.850527242097;9861.60553577856;9896.059874311928;9914.55384507699;9935.900640993643;9938.652147174851;9724.465154416504;9815.705528834147;9821.214302855755;9841.803163936682;9850.261230407748;40999.130060895746;41303.380449292985;41371.7931410344;41504.58495415037;41509.58490415088;3579.6241394653707;3595.6224596417524;3644.380455322575;3692.2430901665093;3930.6265904739003;31685;32064.358712825848;32869.3426131476;33160.834195828866;33302.3339533208;3192.201949512622;3263.379957808009;3289.5394644749513;3353.1784712745534;3373.291608762163;406591;475551;476817.9999999965;478819;486518;33290.334193316;57639.42360576424;58034;58099.70950145222;58347;34022.48966265508;35740;35760.28479430449;35814;35821.82089089538;14364.640883977776;14489.058211216237;14522.709545809024;14533.563993080224;14557.199354035418;12210.145289829805;12304.938475307566;12323;12324.630261092181;12348.259104453762;7799.727009554638;7819.452638315377;7821.335186509211;7910.327622152066;7931.762047138595;3299.637039925606;3305.074579117851;3309.8014119152926;3319.186799234203;3350.865965361358;955.8900726416551;966.2028826218398;970.9563069661882;1085.6037546295545;1093.8906109388997;30243.848780755954;30508.69491305103;30656;30868.69131308703;31166.532502012487;1029.1509504658686;1037.4501514197486;1059.2267644619471;1068.8129577323937;1073.3291692692067;13243.867561324456;15821.288042038135;15852;15864.832189282868;15946.92026539883;190485;191352;196013;702899;703539;59344.40655593475;59819.10271345933;60043.699781500814;60046.39953600408;60149.699251503465;1184.9170558060853;1364.1405914273944;1385.1896640465238;1428.5357258890901;1430.542226487535;29922.25194370158;29930.102096937127;30209;30223.784020547348;30428.239294017825;51201;51510.74244628753;51558;51613.741931290104;51636;604830;605955;610099;610240;610856;27162.45675086527;27478.313042174104;27566.862165689043;27591.89632414681;27596.586051209648;48688.75655621699;48890.53328400154;48955.26567101425;49465.50534494609;50295.99408011892;38949.6105038946;39487.407688884115;39826;40425.79787101046;40870.59129408668;39722.60277397189;40295.59704403036;40355.79822100988;41112.794436028824;41522;29607.85196074006;30620;33291.33417331591;33533;39443.81668549948;30203.093907182818;31347.529787052757;31895.68104318927;32137.517937230492;32587;24151.15470958508;24237.394065147808;25425.110121146412;25548.852478616358;25698.35754106162 05:45:13 INFO - PROCESS | 1934 | |2;jquery.html;357.7781775299315;373.32428304768365;373.3690063792191;376.99623003769955;403.2881963334713;12387.38063096845;16825;17199.3120275189;20896;21095.367138985817;20728.170873165094;20897.582048359025;21229;21239.362819115453;21452;5874.206982057411;5925.881482370361;6127.693615319238;6202.689865506729;6213.502919766431;4196.139791342767;6127.816165515042;6212.78255261066;6214.751409943597;6220.875582488347;6384.361563843625;6708.563943343692;6726.192856857184;6805.251422343538;6825.931740682604;6560.081588577603;6623.635700036521;6766.086578311939;6767.35710107539;6773.45812335012;5881.67650779207;5889.558283128783;5894.086416605433;5902.557308201902;5919.822405327847;28608.998685045925;29517.852410738025;29791.55312670312;29966;30377.544336834966;2217.7782221777857;2446.1927563904046;2462.790662793647;2519.4583164619694;3073.0627158716584;16608.501744947673;17417;17426.825731742774;17461.476155715223;18523.35168269104;1970.3300877701506;2026.5744193719336;2050.3643870400074;2122.7664956854733;2127.1385089038963;79110;81612.59193704053;114604.42697786541;121345;141094;16364.83635163657;21150.682739758915;21949.67075493869;22080.779192208192;22174.556508869733;36166.819165904;36197;36228.45657315142;36239.094022649646;36249.45625815615;10711.46442677863;10732.946335268274;10932.890671093188;10958.28771129874;11095.778084438427;7333.853322933512;8905;8922.152395522415;9087.81824363509;9119;8601.354898382646;9545.284103692251;9838.900746264904;9971.45157016371;9974.501274936225;3168.778185526991;3221.7100460958627;3580.462930560385;3725.8137093145233;3763.7741735495956;882.9249513791272;965.5124162297994;972.9075737804898;1000.7590587671381;1044.4777611194402;20698.58602827935;21754.673679894815;22126;22417.43077984558;23148.18981335645;895.583553647557;918.0177210384958;927.6474939522938;953.0945601678433;1010.5806090472519;10662.093722033715;11232.382218977871;11395.259308144945;11625.7404335242;11727.885850844326;566245;603031;603568;604876;605240;104475;116252.84647037782;120806.3959680196;123797;129742;913.2693844924034;919.2094798473441;945.4043952310001;949.1552518258756;952.7951490429591;19991.42041940173;20309.695354569398;20380.28668996578;20410.18359265613;20468.283610073577;42514.78742606267;53679.900409461035;54881.45118548923;56612.15081773859;57685.42314576801;82574;83750.58124709337;85862.14137858541;86152.56923715341;86178;6733.730650773914;8194.139615340542;8270.875936861072;8375.664973401239;8398.580070996304;10924.781504370027;12754.744905101661;12834.486620535074;13010.804837927628;13524.729505410034;11162.274452160584;11244.66266012021;11669.241499302518;11674.474648641002;11713.941430293135;8816.603252853765;11090.889091108986;11203.887961120285;11535.826962595736;11746.70633234176;9808.215342772419;11398.430078496207;11446.427678616203;11628.418579071182;11957.760844782882;7973.72091976778;8249.752507424786;8263.173682631928;8290.170982901904;8463.942007249094;8345.874811877706;10864.348139111678;11581.305121692725;11643.650690479299;11722.94138529336 05:45:13 INFO - PROCESS | 1934 | |3;mootools.html;1532.7394342961695;1554.0520282627597;1754.3070487157572;1832.1755210155434;1905.5426697592573;8245.958770206147;8748.081451447586;8756.080611535792;8760.693375731851;8778.473291602508;7742.341900938413;7977.401694872879;8299.709510167146;8535.658573657047;8554.486730796156;2283.4976305212867;2334.404726794665;2334.5564342774906;2335.824813139013;2337.567550003246;2296.0471404367167;2308.9422764430856;2348.4598542335275;2352.811775058;2353.435175557871;2325.2907863101746;2332.416895776056;2340.953180936389;2345.061975209913;2348.9412764680933;2302.5049614332997;2318.4783423729696;2326.860388376686;2328.7671232876746;2341.8477798943013;2314.132200424841;2315.8378913476063;2318.119114736406;2319.060780383947;2320.4082958845556;8677.609507572175;8716.956415217883;8774.868376974351;8786.780330491725;8801.207891289814;5145.691258524463;5301.178317360776;5301.1783173608155;5326.866828329284;5327.121025030865;3198.504231844068;3296.1100502864333;3383.7631365804405;3588.910277243064;3600.4059330210484;937.6671281694968;1030.340582027503;1066.1896958311738;1074.3446497636514;1091.3124731419155;10984.890151098547;11348.489317980711;11510.654680359603;11512.366819824907;11533.961943425049;4493.191225579427;4568.725876447424;4743.905121897543;4746.881327966828;4756.334113224151;9770.51147442625;9776.706698799047;9787.657431989846;9798.853017204749;9799.85300220483;5266.8419947401635;5292.153255479182;5296.496832800878;5300.575953923677;5309.46905309466;4207.431996680471;4294.613484786384;4315.309550471972;4444.466664000276;4857.441394239708;3639.6906262967414;3742.8315725792404;3745.4756334113245;3826.617338266207;3955.3078211312904;1266.5681890769745;1268.5560053981033;1294.1264646363668;1294.1458637299525;1294.6504443800122;807.8909347238048;815.1888870573698;859.2052351574769;894.6421431427351;957.9042095790365;5599.832005039774;5603.663780173202;5676.375598684142;5834.15404766315;5942.257217847769;916.4134953629682;936.1527817325332;950.7955789505289;974.7611835100304;995.3729150635054;3768.8881779874455;3907.33575292201;3921.8234529640995;3949.19041596472;4069.715119941635;8529.232369086812;10661.626843060456;10673.412962286993;10702.678919632424;10713.678589642323;10569.894301057046;10628.574857005633;10645.308054976402;10672.30630009047;10694.35833849956;7308.451866110169;7609.581473019037;8130.674773009132;8226.670933162726;8250.38122140842;5867.970660146842;5899.705014749331;5931.940680593311;6011.72947217385;6045.727942242697;9245.491497967676;9924.553395097093;9929.900700992897;9934.006599340008;9953.303268771117;9150.039245879087;9918.950405247928;9938.354006989812;10067.395956242648;10122.443265620166;6177.2587289525545;7543.547387156788;8051.758447246358;8190;8267.710630127916;8121.634526446443;8129.3090087343235;8153.1846815317995;8161.551114688749;8202.712905048533;7162.355388015104;7335.193128755939;7467.477276590586;7550.3582195514;7578.317951384402;7281.490295679463;7297.562146271241;7300.525465844703;7370.889436658347;7471.775846724607;6750.257471678014;6885.311468853275;6940;6956.79129626112;6965.164180298397;4284.507281662649;4911.950880491292;4951.801927922987;4962.851114466572;5119.232115182753;4409.955900441083;4940.580050695731;4956.9752151240455;5035.395752509722;5144.562712169508 05:45:13 INFO - PROCESS | 1934 | |4;prototype.html;298.74308095038276;302.330338300664;312.70918046217025;315.974722022238;318.6988296060224;4717.504662010491;4748.596369308613;5134.845954621358;5326.8401947941575;5373.731313434332;5104.876927076047;5252.343457067866;5303.469653034695;5317.388500322464;5317.607076815439;3350.530925670414;4041.2725709372303;4087.2847251730827;4092.3247664135383;4099.3850922361735;3233.9353212935844;4081.632653061224;4091.7954102294916;4099.385092236159;4131.917361652765;3712.9071773205615;4038.818253178599;4048.817803198863;4057.6550993165615;4068.532118806331;3807.904802379935;4038.6769058475397;4038.9192216155807;4043.2722110020186;4062.8984275393204;3307.68576985186;3479.8434070466888;3495.2310491691787;3530.8764193253114;3547.308274886398;4275.893102672427;5172.939547392775;5199.582194279678;5217.34783152106;5273.920891186635;4143.6892233082635;4528.826163441534;4599.011212589276;4601.700889442209;4607.631389488833;5234.979179060085;5316;5364.249005139283;5438.912217556465;5618.31450564833;3319.7344212463186;3808.8954203281014;3842;3844.3464611016193;3855.306044911915;4557.95442045582;5145.665531740462;5626.578006649518;5698.743556539925;5867.882642347172;3051.588035615208;3703.0557207912348;3850.942235866464;3864.555576108727;3864.864729734446;3921.8235179416993;3925.941110883339;3954.1103251768695;3962.5046869141356;3969.900752481211;3818.427235914635;4525.321201819754;4537.115262523842;4574.313852922089;4602.516735742765;5513.558915286768;5534.225208470817;5548.72256387179;5605.66366018032;5761.164631128463;1224.0330139189941;1262.0282382565556;1344.8251727275588;1349.959501214965;1354.498835430877;786.7255046824181;804.9718259860877;823.5017814222429;823.5552801487249;830.3108420011376;3769.679577235911;3946.4869566956113;4303.655707543389;4331.263685173528;4356.934645980313;3860.606424017127;3923.6861051115843;4562.406887104655;4721.976390118028;4820.807167713252;3591.533100696945;3792.544894612664;4068.108329626738;4286.442762440863;4287.656987440997;2420.8426452280546;2529.8735063246763;2533.3413312538873;2542.262743804276;2566.5636841736577;4283.443152390231;4293.527711951683;4923.433805112458;4924.374956264424;4975.328330675385;4086.141910198832;4391.7584532851;4561.361409402686;4783.904321913612;4909;4216;4979.916468495799;5061.468545802638;5097;6102;5867.442592953663;5884.264466941632;5889.322727886348;5918;5923.46688797993;5506.449355064461;5780.5953583248765;5824.854378640568;5832.854178645568;5842.620229685057;5027.235020738653;5728.427157284238;6048.580567772926;6071.241094863142;6097.878042439038;3057.752551874415;3805.980970095132;3849.634284742945;3880.80595970206;3934.4098385241296;4546.681732278708;5443.863903402288;5780.7800305777855;5803.47768700819;5806.303243610793;5251.26482292487;5308.946910531;5563.582731295088;5636.3799982002765;5667.20659107717;4831.710097394168;5265.763040663102;5520.365158006881;5601.271834661468;5661.688607126647;5475.479829416199;5492.5331346835965;5504.587155963239;5510.338759348904;5519.724013799214;5167.044096842096;5382.865428364165;5408.972955135199;5567.137093750475;5573.77704891816;4367.410399596141;5196.272521846869;5447.428020057837;5520.530754885719;5634.211210430461 05:45:13 INFO - PROCESS | 1934 | |5;yui.html;688.4561196654643;708.9326513981173;724.6376811594203;724.7390939261867;803.807086299288;15069.62325941852;16314.428994985177;16379.426720064785;16405.343786248537;16406.589835254093;14649.26753662318;14768.483103091394;14778.482753103644;14790.778138327934;14821.407143714265;694.9027136200936;719.9928000720005;847.1020718038868;855.9058503564619;879.5074758135464;870.6996086350231;882.5410786391072;882.9161229683202;885.2033170146888;886.6719313853883;947.0766003146946;948.3788118782209;949.6248981652243;950.353759443578;951.8286708392486;721.4012369733109;903.480498713236;942.6983365323099;943.056943056943;946.4463288975958;705.2172088981226;706.5831159615851;708.2244941788733;708.7129712466459;712.1276436365463;14783;14828.777568336483;14845.628859278604;14848;14850.480233191787;14167.645808854759;14251;14304.928475357661;14392.424303027867;14613.78079328811;12174.756504869853;12228.327441990687;12239.237731685136;12288.262704237774;12412.193207441487;4524.8416305429155;4657.88355291117;4767.404074490689;4771.28430735389;4831.975840120812;117097;117160.82839171584;117967.4101629495;118908;119040;11111.722206944729;11288.661340159808;11362;11419.771604567863;11748.29510229389;23430;23439.88280058589;23449.648255276184;23486.7651323488;23494;8005.679772809022;8101.374100469016;8117.1476994915865;8228.958855205805;8371.916280837113;4411.8230509819605;4479.484859241164;4548.74981876;4578.267477203632;4692.7653617319;3483.89548313551;3608.729345299113;3609.5668519777796;3610.656987586175;3612.3497770401054;647.9190101237346;652.4877970793;661.0612929639923;662.1623646087703;662.8309781005813;516.5789881246772;570.3070769015666;580.8751118509541;606.4777408704097;609.3662590905409;8828.822575767346;9077.954610226907;9310.487923164292;9310.649955756377;9449.338546301693;545.6998650742077;579.5103137848512;612.6599737145926;619.9109002661061;629.4492319220682;4161.542230354658;4392.780360981938;4535.183666939916;4570.657200709894;4578.839740609063;166375;168825;169130.15434922746;173116;177347;116464;117326;118687;131862;149276;72582.63708681529;73039.63480182672;73373.2662673377;74865.6256718713;113116;9516.38143520669;9551.52242387864;10037.69886903394;10166.085052345325;10169.237307201842;47875.76062119667;53290.73354633357;58570;60973;62177.06734398897;89079;93191;99595;100074;100199.99800001907;13054;13082.542111026069;13316.334183290603;13389.464421423423;13849.653758656113;14667.934131439855;14918.925405372904;14922.701545968803;15154.340531404063;15215.619609509851;13931.512397066053;14129.505467308594;14175.574732758034;14210.573682789533;14231.430742770173;11639.185257032264;13743.931280343535;13812.654683632989;13958.232297223343;14072.648183795078;13012.934935325642;13481.59555213345;13648.249346285653;13976;14645.92677036608;9379.906200937903;9966.651167209113;11360.602378916697;11456;11957;10598.364098154136;10686.412247326472;10748.731281718019;10814.783704326026;10943 05:45:13 INFO - PROCESS | 1934 | __end_tp_report 05:45:13 INFO - PROCESS | 1934 | __start_cc_report 05:45:13 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,1477 05:45:13 INFO - PROCESS | 1934 | __end_cc_report 05:45:13 INFO - PROCESS | 1934 | __startTimestamp1470660313636__endTimestamp 05:45:13 INFO - PROCESS | 1934 | 05:45:13 INFO - PROCESS | 1934 | ------- Summary: start ------- 05:45:13 INFO - PROCESS | 1934 | Number of tests: 6 05:45:13 INFO - PROCESS | 1934 | 05:45:13 INFO - PROCESS | 1934 | [#0] dojo.html Cycles:165 Average:5751.72 Median:5950.58 stddev:639.32 (10.7%) stddev-sans-first:612.37 05:45:13 INFO - PROCESS | 1934 | Values: 3328.3 3329.7 3337.2 3355.3 3388.5 5570.8 5611.4 5651.4 5706.6 5713.4 5561.4 5640.5 5727.4 5747.1 5771.9 5080.0 5144.6 5147.3 5167.8 5225.6 5230.0 5266.3 5268.4 5274.5 5308.3 5135.7 5178.2 5184.4 5206.3 5272.8 5138.6 5224.6 5228.2 5242.1 5245.3 5124.7 5136.7 5179.9 5226.2 5255.3 5670.3 5705.9 5710.3 5727.2 5736.6 6053.0 6076.5 6223.5 6242.1 6248.1 6206.9 6219.5 6312.5 6337.0 6351.2 5856.2 5863.8 5882.5 5899.5 5910.9 6198.6 6257.7 6274.5 6310.9 6331.8 6149.9 6174.4 6182.2 6182.9 6227.9 6278.2 6297.8 6308.1 6310.9 6325.9 6082.5 6087.0 6253.7 6257.5 6268.7 5121.7 6127.5 6152.8 6168.3 6266.1 4972.9 4979.4 4979.9 4986.8 4987.5 4509.8 4513.9 4527.1 4533.2 4582.2 5569.2 5615.6 5679.3 5712.9 5734.9 6330.9 6335.6 6350.8 6355.2 6358.9 5680.1 5720.1 5724.7 5739.1 5747.2 5319.2 5349.7 5358.0 5371.3 5466.2 6295.8 6321.4 6329.4 6335.6 6338.9 6279.2 6303.4 6306.2 6323.0 6334.4 6191.3 6241.3 6254.4 6299.4 6315.6 6176.5 6177.8 6219.3 6232.4 6233.9 6159.9 6286.7 6304.6 6312.4 6313.5 5886.7 6048.7 6076.0 6099.6 6112.8 6111.6 6131.8 6150.6 6153.7 6158.4 5974.8 5980.1 5983.0 6012.9 6044.1 5899.3 5984.0 5999.5 6140.3 6142.8 5907.8 5910.0 5914.6 5917.6 5950.6 05:45:13 INFO - PROCESS | 1934 | 05:45:13 INFO - PROCESS | 1934 | [#1] ext.html Cycles:180 Average:62442.14 Median:21440.12 stddev:139397.27 (650.2%) stddev-sans-first:139710.66 05:45:13 INFO - PROCESS | 1934 | Values: 466.9 480.7 521.4 526.7 549.0 30527.0 32288.5 41931.0 42486.0 42729.7 44109.0 44436.1 44439.3 44482.3 44501.1 9889.2 10034.5 10037.0 10041.8 10047.4 9825.9 9928.9 9935.8 9941.3 9961.6 8481.6 9904.3 9958.3 9963.2 9964.9 9861.6 9896.1 9914.6 9935.9 9938.7 9724.5 9815.7 9821.2 9841.8 9850.3 40999.1 41303.4 41371.8 41504.6 41509.6 3579.6 3595.6 3644.4 3692.2 3930.6 31685.0 32064.4 32869.3 33160.8 33302.3 3192.2 3263.4 3289.5 3353.2 3373.3 406591.0 475551.0 476818.0 478819.0 486518.0 33290.3 57639.4 58034.0 58099.7 58347.0 34022.5 35740.0 35760.3 35814.0 35821.8 14364.6 14489.1 14522.7 14533.6 14557.2 12210.1 12304.9 12323.0 12324.6 12348.3 7799.7 7819.5 7821.3 7910.3 7931.8 3299.6 3305.1 3309.8 3319.2 3350.9 955.9 966.2 971.0 1085.6 1093.9 30243.8 30508.7 30656.0 30868.7 31166.5 1029.2 1037.5 1059.2 1068.8 1073.3 13243.9 15821.3 15852.0 15864.8 15946.9 190485.0 191352.0 196013.0 702899.0 703539.0 59344.4 59819.1 60043.7 60046.4 60149.7 1184.9 1364.1 1385.2 1428.5 1430.5 29922.3 29930.1 30209.0 30223.8 30428.2 51201.0 51510.7 51558.0 51613.7 51636.0 604830.0 605955.0 610099.0 610240.0 610856.0 27162.5 27478.3 27566.9 27591.9 27596.6 48688.8 48890.5 48955.3 49465.5 50296.0 38949.6 39487.4 39826.0 40425.8 40870.6 39722.6 40295.6 40355.8 41112.8 41522.0 29607.9 30620.0 33291.3 33533.0 39443.8 30203.1 31347.5 31895.7 32137.5 32587.0 24151.2 24237.4 25425.1 25548.9 25698.4 05:45:13 INFO - PROCESS | 1934 | 05:45:13 INFO - PROCESS | 1934 | [#2] jquery.html Cycles:180 Average:36183.87 Median:1969.12 stddev:99084.62 (5031.9%) stddev-sans-first:99326.07 05:45:13 INFO - PROCESS | 1934 | Values: 357.8 373.3 373.4 377.0 403.3 12387.4 16825.0 17199.3 20896.0 21095.4 20728.2 20897.6 21229.0 21239.4 21452.0 5874.2 5925.9 6127.7 6202.7 6213.5 4196.1 6127.8 6212.8 6214.8 6220.9 6384.4 6708.6 6726.2 6805.3 6825.9 6560.1 6623.6 6766.1 6767.4 6773.5 5881.7 5889.6 5894.1 5902.6 5919.8 28609.0 29517.9 29791.6 29966.0 30377.5 2217.8 2446.2 2462.8 2519.5 3073.1 16608.5 17417.0 17426.8 17461.5 18523.4 1970.3 2026.6 2050.4 2122.8 2127.1 79110.0 81612.6 114604.4 121345.0 141094.0 16364.8 21150.7 21949.7 22080.8 22174.6 36166.8 36197.0 36228.5 36239.1 36249.5 10711.5 10732.9 10932.9 10958.3 11095.8 7333.9 8905.0 8922.2 9087.8 9119.0 8601.4 9545.3 9838.9 9971.5 9974.5 3168.8 3221.7 3580.5 3725.8 3763.8 882.9 965.5 972.9 1000.8 1044.5 20698.6 21754.7 22126.0 22417.4 23148.2 895.6 918.0 927.6 953.1 1010.6 10662.1 11232.4 11395.3 11625.7 11727.9 566245.0 603031.0 603568.0 604876.0 605240.0 104475.0 116252.8 120806.4 123797.0 129742.0 913.3 919.2 945.4 949.2 952.8 19991.4 20309.7 20380.3 20410.2 20468.3 42514.8 53679.9 54881.5 56612.2 57685.4 82574.0 83750.6 85862.1 86152.6 86178.0 6733.7 8194.1 8270.9 8375.7 8398.6 10924.8 12754.7 12834.5 13010.8 13524.7 11162.3 11244.7 11669.2 11674.5 11713.9 8816.6 11090.9 11203.9 11535.8 11746.7 9808.2 11398.4 11446.4 11628.4 11957.8 7973.7 8249.8 8263.2 8290.2 8463.9 8345.9 10864.3 11581.3 11643.7 11722.9 05:45:13 INFO - PROCESS | 1934 | 05:45:13 INFO - PROCESS | 1934 | [#3] mootools.html Cycles:180 Average:5622.25 Median:5206.27 stddev:3138.55 (60.3%) stddev-sans-first:3132.31 05:45:13 INFO - PROCESS | 1934 | Values: 1532.7 1554.1 1754.3 1832.2 1905.5 8246.0 8748.1 8756.1 8760.7 8778.5 7742.3 7977.4 8299.7 8535.7 8554.5 2283.5 2334.4 2334.6 2335.8 2337.6 2296.0 2308.9 2348.5 2352.8 2353.4 2325.3 2332.4 2341.0 2345.1 2348.9 2302.5 2318.5 2326.9 2328.8 2341.8 2314.1 2315.8 2318.1 2319.1 2320.4 8677.6 8717.0 8774.9 8786.8 8801.2 5145.7 5301.2 5301.2 5326.9 5327.1 3198.5 3296.1 3383.8 3588.9 3600.4 937.7 1030.3 1066.2 1074.3 1091.3 10984.9 11348.5 11510.7 11512.4 11534.0 4493.2 4568.7 4743.9 4746.9 4756.3 9770.5 9776.7 9787.7 9798.9 9799.9 5266.8 5292.2 5296.5 5300.6 5309.5 4207.4 4294.6 4315.3 4444.5 4857.4 3639.7 3742.8 3745.5 3826.6 3955.3 1266.6 1268.6 1294.1 1294.1 1294.7 807.9 815.2 859.2 894.6 957.9 5599.8 5603.7 5676.4 5834.2 5942.3 916.4 936.2 950.8 974.8 995.4 3768.9 3907.3 3921.8 3949.2 4069.7 8529.2 10661.6 10673.4 10702.7 10713.7 10569.9 10628.6 10645.3 10672.3 10694.4 7308.5 7609.6 8130.7 8226.7 8250.4 5868.0 5899.7 5931.9 6011.7 6045.7 9245.5 9924.6 9929.9 9934.0 9953.3 9150.0 9919.0 9938.4 10067.4 10122.4 6177.3 7543.5 8051.8 8190.0 8267.7 8121.6 8129.3 8153.2 8161.6 8202.7 7162.4 7335.2 7467.5 7550.4 7578.3 7281.5 7297.6 7300.5 7370.9 7471.8 6750.3 6885.3 6940.0 6956.8 6965.2 4284.5 4912.0 4951.8 4962.9 5119.2 4410.0 4940.6 4957.0 5035.4 5144.6 05:45:13 INFO - PROCESS | 1934 | 05:45:13 INFO - PROCESS | 1934 | [#4] prototype.html Cycles:180 Average:4353.47 Median:4602.11 stddev:1364.37 (29.6%) stddev-sans-first:1333.82 05:45:13 INFO - PROCESS | 1934 | Values: 298.7 302.3 312.7 316.0 318.7 4717.5 4748.6 5134.8 5326.8 5373.7 5104.9 5252.3 5303.5 5317.4 5317.6 3350.5 4041.3 4087.3 4092.3 4099.4 3233.9 4081.6 4091.8 4099.4 4131.9 3712.9 4038.8 4048.8 4057.7 4068.5 3807.9 4038.7 4038.9 4043.3 4062.9 3307.7 3479.8 3495.2 3530.9 3547.3 4275.9 5172.9 5199.6 5217.3 5273.9 4143.7 4528.8 4599.0 4601.7 4607.6 5235.0 5316.0 5364.2 5438.9 5618.3 3319.7 3808.9 3842.0 3844.3 3855.3 4558.0 5145.7 5626.6 5698.7 5867.9 3051.6 3703.1 3850.9 3864.6 3864.9 3921.8 3925.9 3954.1 3962.5 3969.9 3818.4 4525.3 4537.1 4574.3 4602.5 5513.6 5534.2 5548.7 5605.7 5761.2 1224.0 1262.0 1344.8 1350.0 1354.5 786.7 805.0 823.5 823.6 830.3 3769.7 3946.5 4303.7 4331.3 4356.9 3860.6 3923.7 4562.4 4722.0 4820.8 3591.5 3792.5 4068.1 4286.4 4287.7 2420.8 2529.9 2533.3 2542.3 2566.6 4283.4 4293.5 4923.4 4924.4 4975.3 4086.1 4391.8 4561.4 4783.9 4909.0 4216.0 4979.9 5061.5 5097.0 6102.0 5867.4 5884.3 5889.3 5918.0 5923.5 5506.4 5780.6 5824.9 5832.9 5842.6 5027.2 5728.4 6048.6 6071.2 6097.9 3057.8 3806.0 3849.6 3880.8 3934.4 4546.7 5443.9 5780.8 5803.5 5806.3 5251.3 5308.9 5563.6 5636.4 5667.2 4831.7 5265.8 5520.4 5601.3 5661.7 5475.5 5492.5 5504.6 5510.3 5519.7 5167.0 5382.9 5409.0 5567.1 5573.8 4367.4 5196.3 5447.4 5520.5 5634.2 05:45:13 INFO - PROCESS | 1934 | 05:45:13 INFO - PROCESS | 1934 | [#5] yui.html Cycles:180 Average:24995.61 Median:3609.15 stddev:40800.78 (1130.5%) stddev-sans-first:40874.42 05:45:13 INFO - PROCESS | 1934 | Values: 688.5 708.9 724.6 724.7 803.8 15069.6 16314.4 16379.4 16405.3 16406.6 14649.3 14768.5 14778.5 14790.8 14821.4 694.9 720.0 847.1 855.9 879.5 870.7 882.5 882.9 885.2 886.7 947.1 948.4 949.6 950.4 951.8 721.4 903.5 942.7 943.1 946.4 705.2 706.6 708.2 708.7 712.1 14783.0 14828.8 14845.6 14848.0 14850.5 14167.6 14251.0 14304.9 14392.4 14613.8 12174.8 12228.3 12239.2 12288.3 12412.2 4524.8 4657.9 4767.4 4771.3 4832.0 117097.0 117160.8 117967.4 118908.0 119040.0 11111.7 11288.7 11362.0 11419.8 11748.3 23430.0 23439.9 23449.6 23486.8 23494.0 8005.7 8101.4 8117.1 8229.0 8371.9 4411.8 4479.5 4548.7 4578.3 4692.8 3483.9 3608.7 3609.6 3610.7 3612.3 647.9 652.5 661.1 662.2 662.8 516.6 570.3 580.9 606.5 609.4 8828.8 9078.0 9310.5 9310.6 9449.3 545.7 579.5 612.7 619.9 629.4 4161.5 4392.8 4535.2 4570.7 4578.8 166375.0 168825.0 169130.2 173116.0 177347.0 116464.0 117326.0 118687.0 131862.0 149276.0 72582.6 73039.6 73373.3 74865.6 113116.0 9516.4 9551.5 10037.7 10166.1 10169.2 47875.8 53290.7 58570.0 60973.0 62177.1 89079.0 93191.0 99595.0 100074.0 100200.0 13054.0 13082.5 13316.3 13389.5 13849.7 14667.9 14918.9 14922.7 15154.3 15215.6 13931.5 14129.5 14175.6 14210.6 14231.4 11639.2 13743.9 13812.7 13958.2 14072.6 13012.9 13481.6 13648.2 13976.0 14645.9 9379.9 9966.7 11360.6 11456.0 11957.0 10598.4 10686.4 10748.7 10814.8 10943.0 05:45:13 INFO - PROCESS | 1934 | -------- Summary: end -------- 05:45:13 INFO - PROCESS | 1934 | 05:45:14 INFO - TEST-INFO | 1934: exit 0 05:45:14 INFO - TEST-OK | dromaeo_css | took 1118170ms 05:45:14 INFO - TEST-START | kraken 05:45:14 INFO - operating with platform_type : mac_ 05:45:14 INFO - Initialising browser for kraken test... 05:45:14 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpMgEFPM/profile http://localhost:49438/getInfo.html) 05:45:16 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 05:45:16 INFO - PROCESS | 1938 | colorDepth:24 05:45:16 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 05:45:16 INFO - PROCESS | 1938 | __metrics 05:45:16 INFO - PROCESS | 1938 | 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" 05:45:16 INFO - PROCESS | 1938 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:45:17 INFO - TEST-INFO | 1938: exit 0 05:45:17 INFO - Browser initialized. 05:45:17 INFO - Running cycle 1/1 for kraken test... 05:45:17 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpMgEFPM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:45:29 INFO - PROCESS | 1939 | RSS: Main: 243343360 05:45:29 INFO - PROCESS | 1939 | 05:45:31 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-beat-detection) 05:45:32 INFO - PROCESS | 1939 | RSS: Main: 293683200 05:45:32 INFO - PROCESS | 1939 | 05:45:35 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-dft) 05:45:36 INFO - PROCESS | 1939 | RSS: Main: 299778048 05:45:36 INFO - PROCESS | 1939 | 05:45:39 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-fft) 05:45:40 INFO - PROCESS | 1939 | RSS: Main: 394911744 05:45:40 INFO - PROCESS | 1939 | 05:45:42 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-oscillator) 05:45:43 INFO - PROCESS | 1939 | RSS: Main: 297472000 05:45:43 INFO - PROCESS | 1939 | 05:45:45 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49438/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:45:45 INFO - PROCESS | 1939 | RSS: Main: 301174784 05:45:45 INFO - PROCESS | 1939 | 05:45:48 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49438/tests/kraken/driver.html?testName=imaging-darkroom) 05:45:49 INFO - PROCESS | 1939 | RSS: Main: 338468864 05:45:49 INFO - PROCESS | 1939 | 05:45:51 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49438/tests/kraken/driver.html?testName=imaging-desaturate) 05:45:52 INFO - PROCESS | 1939 | RSS: Main: 340443136 05:45:52 INFO - PROCESS | 1939 | 05:45:55 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49438/tests/kraken/driver.html?testName=json-parse-financial) 05:45:56 INFO - PROCESS | 1939 | RSS: Main: 341721088 05:45:56 INFO - PROCESS | 1939 | 05:45:57 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49438/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:45:58 INFO - PROCESS | 1939 | RSS: Main: 313032704 05:45:58 INFO - PROCESS | 1939 | 05:46:00 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:46:00 INFO - PROCESS | 1939 | RSS: Main: 318779392 05:46:00 INFO - PROCESS | 1939 | 05:46:03 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:46:03 INFO - PROCESS | 1939 | RSS: Main: 321429504 05:46:03 INFO - PROCESS | 1939 | 05:46:06 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:46:07 INFO - PROCESS | 1939 | RSS: Main: 330465280 05:46:07 INFO - PROCESS | 1939 | 05:46:10 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:46:10 INFO - PROCESS | 1939 | RSS: Main: 331710464 05:46:10 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49438/tests/kraken/driver.html?testName=ai-astar) 05:46:12 INFO - PROCESS | 1939 | RSS: Main: 375545856 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | __start_tp_report 05:46:12 INFO - PROCESS | 1939 | _x_x_mozilla_page_load 05:46:12 INFO - PROCESS | 1939 | _x_x_mozilla_page_load_details 05:46:12 INFO - PROCESS | 1939 | |i|pagename|runs| 05:46:12 INFO - PROCESS | 1939 | |0;ai-astar;101;116;112;113;113;113;112;112;111;113 05:46:12 INFO - PROCESS | 1939 | |1;audio-beat-detection;144;107;91;178;95;123;158;134;140;126 05:46:12 INFO - PROCESS | 1939 | |2;audio-dft;184;185;186;184;183;182;183;182;182;197 05:46:12 INFO - PROCESS | 1939 | |3;audio-fft;73;57;63;96;81;80;88;65;83;56 05:46:12 INFO - PROCESS | 1939 | |4;audio-oscillator;85;68;101;70;74;68;97;70;67;101 05:46:12 INFO - PROCESS | 1939 | |5;imaging-gaussian-blur;86;104;135;83;87;83;86;84;86;83 05:46:12 INFO - PROCESS | 1939 | |6;imaging-darkroom;92;93;92;101;93;92;91;93;92;92 05:46:12 INFO - PROCESS | 1939 | |7;imaging-desaturate;85;81;124;138;117;85;85;86;84;83 05:46:12 INFO - PROCESS | 1939 | |8;json-parse-financial;55;53;52;53;52;53;53;54;52;54 05:46:12 INFO - PROCESS | 1939 | |9;json-stringify-tinderbox;67;65;75;84;105;107;102;74;71;70 05:46:12 INFO - PROCESS | 1939 | |10;stanford-crypto-aes;84;84;83;84;83;83;81;89;84;86 05:46:12 INFO - PROCESS | 1939 | |11;stanford-crypto-ccm;138;135;136;135;136;135;134;142;152;139 05:46:12 INFO - PROCESS | 1939 | |12;stanford-crypto-pbkdf2;163;166;163;163;165;165;164;164;162;179 05:46:12 INFO - PROCESS | 1939 | |13;stanford-crypto-sha256-iterative;67;66;67;66;67;65;67;66;66;64 05:46:12 INFO - PROCESS | 1939 | __end_tp_report 05:46:12 INFO - PROCESS | 1939 | __start_cc_report 05:46:12 INFO - PROCESS | 1939 | _x_x_mozilla_cycle_collect,2037 05:46:12 INFO - PROCESS | 1939 | __end_cc_report 05:46:12 INFO - PROCESS | 1939 | __startTimestamp1470660372688__endTimestamp 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | ------- Summary: start ------- 05:46:12 INFO - PROCESS | 1939 | Number of tests: 14 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:3.95 (3.5%) stddev-sans-first:1.39 05:46:12 INFO - PROCESS | 1939 | Values: 101.0 116.0 112.0 113.0 113.0 113.0 112.0 112.0 111.0 113.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:151.00 stddev:27.36 (18.1%) stddev-sans-first:28.52 05:46:12 INFO - PROCESS | 1939 | Values: 144.0 107.0 91.0 178.0 95.0 123.0 158.0 134.0 140.0 126.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#2] audio-dft Cycles:10 Average:184.80 Median:184.00 stddev:4.49 (2.4%) stddev-sans-first:4.76 05:46:12 INFO - PROCESS | 1939 | Values: 184.0 185.0 186.0 184.0 183.0 182.0 183.0 182.0 182.0 197.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#3] audio-fft Cycles:10 Average:74.20 Median:80.50 stddev:13.59 (16.9%) stddev-sans-first:14.40 05:46:12 INFO - PROCESS | 1939 | Values: 73.0 57.0 63.0 96.0 81.0 80.0 88.0 65.0 83.0 56.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:70.00 stddev:14.49 (20.7%) stddev-sans-first:15.26 05:46:12 INFO - PROCESS | 1939 | Values: 85.0 68.0 101.0 70.0 74.0 68.0 97.0 70.0 67.0 101.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:85.00 stddev:16.44 (19.3%) stddev-sans-first:17.31 05:46:12 INFO - PROCESS | 1939 | Values: 86.0 104.0 135.0 83.0 87.0 83.0 86.0 84.0 86.0 83.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:92.00 stddev:2.85 (3.1%) stddev-sans-first:2.99 05:46:12 INFO - PROCESS | 1939 | Values: 92.0 93.0 92.0 101.0 93.0 92.0 91.0 93.0 92.0 92.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:84.50 stddev:21.04 (24.9%) stddev-sans-first:21.88 05:46:12 INFO - PROCESS | 1939 | Values: 85.0 81.0 124.0 138.0 117.0 85.0 85.0 86.0 84.0 83.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:53.00 stddev:0.99 (1.9%) stddev-sans-first:0.78 05:46:12 INFO - PROCESS | 1939 | Values: 55.0 53.0 52.0 53.0 52.0 53.0 53.0 54.0 52.0 54.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#9] json-stringify-tinderbox Cycles:10 Average:82.00 Median:70.50 stddev:16.50 (23.4%) stddev-sans-first:16.58 05:46:12 INFO - PROCESS | 1939 | Values: 67.0 65.0 75.0 84.0 105.0 107.0 102.0 74.0 71.0 70.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.00 stddev:2.13 (2.5%) stddev-sans-first:2.26 05:46:12 INFO - PROCESS | 1939 | Values: 84.0 84.0 83.0 84.0 83.0 83.0 81.0 89.0 84.0 86.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:137.00 stddev:5.41 (4.0%) stddev-sans-first:5.74 05:46:12 INFO - PROCESS | 1939 | Values: 138.0 135.0 136.0 135.0 136.0 135.0 134.0 142.0 152.0 139.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:164.50 stddev:4.93 (3.0%) stddev-sans-first:5.15 05:46:12 INFO - PROCESS | 1939 | Values: 163.0 166.0 163.0 163.0 165.0 165.0 164.0 164.0 162.0 179.0 05:46:12 INFO - PROCESS | 1939 | 05:46:12 INFO - PROCESS | 1939 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:0.99 (1.5%) stddev-sans-first:1.00 05:46:12 INFO - PROCESS | 1939 | Values: 67.0 66.0 67.0 66.0 67.0 65.0 67.0 66.0 66.0 64.0 05:46:12 INFO - PROCESS | 1939 | -------- Summary: end -------- 05:46:12 INFO - PROCESS | 1939 | 05:46:13 INFO - TEST-INFO | 1939: exit 0 05:46:13 INFO - TEST-OK | kraken | took 59264ms 05:46:13 INFO - SUITE-END | took 1177s 05:46:13 INFO - Completed test suite (00:19:37) 05:46:13 INFO - javascript benchmark 05:46:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3328.301056778075, 3329.683680050395, 3337.21575429774, 3355.3457075870215, 3388.4578467445212, 5570.777168913249, 5611.410801865806, 5651.350094739107, 5706.600537962339, 5713.428657134285, 5561.388247292804, 5640.5487560995125, 5727.3699893011735, 5747.051736463489, 5771.884562308751, 5079.974600127012, 5144.588432925376, 5147.305113809644, 5167.819126330579, 5225.555827754645, 5229.973850130763, 5266.262723218752, 5268.394134674504, 5274.498922602365, 5308.309919710432, 5135.6918584885025, 5178.223266510054, 5184.377874655047, 5206.271122042917, 5272.841814745564, 5138.640295179339, 5224.555912747421, 5228.241904923802, 5242.1088414969545, 5245.31810864589, 5124.718140502271, 5136.691798492065, 5179.870503237412, 5226.216067589854, 5255.3168088148295, 5670.347909990322, 5705.942940570624, 5710.314762228559, 5727.169560413717, 5736.627119237278, 6053.0, 6076.512766265323, 6223.502119830403, 6242.063690446424, 6248.125262463259, 6206.937930620681, 6219.533534984849, 6312.52656050798, 6336.96831515844, 6351.237851457855, 5856.180134781134, 5863.824085277449, 5882.470577648032, 5899.528037757055, 5910.940890591125, 6198.566100373021, 6257.687115644199, 6274.466670333073, 6310.936890631035, 6331.810045698637, 6149.877002459926, 6174.44430001302, 6182.227221597301, 6182.907256391158, 6227.906581401283, 6278.152449419361, 6297.779577714758, 6308.085327627468, 6310.9368906311265, 6325.936740632626, 6082.513398928075, 6087.026075827846, 6253.74985000604, 6257.4681152102585, 6268.686565671698, 5121.692698438105, 6127.5404344674325, 6152.815415537541, 6168.321484636686, 6266.060090986389, 4972.925406118911, 4979.3526841510375, 4979.925301120486, 4986.825461108844, 4987.451380348159, 4509.797059132281, 4513.932291015637, 4527.071950250257, 4533.184026875194, 4582.198115329804, 5569.19246709233, 5615.634983726045, 5679.261695979496, 5712.942870571324, 5734.856628584235, 6330.936690633127, 6335.588186767845, 6350.841228969312, 6355.205599300088, 6358.904616430757, 5680.091185410314, 5720.11338242573, 5724.74238659253, 5739.081746920473, 5747.166660834156, 5319.175527793198, 5349.679019258857, 5358.0, 5371.274877891513, 5466.207399926989, 6295.842603934847, 6321.431071203614, 6329.367063293634, 6335.556511044183, 6338.936610633835, 6279.215098112736, 6303.401176888188, 6306.211723534559, 6322.968385158045, 6334.3665633436285, 6191.318954914866, 6241.282252540835, 6254.3745625437095, 6299.401556852092, 6315.5894866833505, 6176.474999625082, 6177.84555386119, 6219.315875253809, 6232.407921247474, 6233.906491402542, 6159.876802464015, 6286.748530058747, 6304.621722696653, 6312.43188113072, 6313.526485513513, 5886.676232807236, 6048.727807248596, 6076.0, 6099.634021958696, 6112.755489780359, 6111.622125543816, 6131.8467038322615, 6150.616111375084, 6153.723082461211, 6158.3841615838055, 5974.761009559569, 5980.132880732356, 5983.0, 6012.939870601238, 6044.0933859919605, 5899.321578018411, 5984.0, 5999.460048595647, 6140.263168419819, 6142.846428839315, 5907.793227237026, 5910.0, 5914.556408269483, 5917.644941303535, 5950.583459157818], "value": 5710.370263026142, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.9439667239932, 480.70196478183533, 521.3587287636208, 526.6524094097895, 548.9972550137251, 30527.0, 32288.515672264904, 41930.95172620686, 42486.0, 42729.65879265092, 44109.0, 44436.11127777443, 44439.33340999879, 44482.33276500847, 44501.10997780043, 9889.159421449167, 10034.498275086253, 10037.0, 10041.799164016715, 10047.397156170653, 9825.901740982606, 9928.851067233998, 9935.751606209866, 9941.254405919548, 9961.55173017216, 8481.618327175262, 9904.25718071144, 9958.302918795687, 9963.202943764481, 9964.850527242097, 9861.60553577856, 9896.059874311928, 9914.55384507699, 9935.900640993643, 9938.652147174851, 9724.465154416504, 9815.705528834147, 9821.214302855755, 9841.803163936682, 9850.261230407748, 40999.130060895746, 41303.380449292985, 41371.7931410344, 41504.58495415037, 41509.58490415088, 3579.6241394653707, 3595.6224596417524, 3644.380455322575, 3692.2430901665093, 3930.6265904739003, 31685.0, 32064.358712825848, 32869.3426131476, 33160.834195828866, 33302.3339533208, 3192.201949512622, 3263.379957808009, 3289.5394644749513, 3353.1784712745534, 3373.291608762163, 406591.0, 475551.0, 476817.9999999965, 478819.0, 486518.0, 33290.334193316, 57639.42360576424, 58034.0, 58099.70950145222, 58347.0, 34022.48966265508, 35740.0, 35760.28479430449, 35814.0, 35821.82089089538, 14364.640883977776, 14489.058211216237, 14522.709545809024, 14533.563993080224, 14557.199354035418, 12210.145289829805, 12304.938475307566, 12323.0, 12324.630261092181, 12348.259104453762, 7799.727009554638, 7819.452638315377, 7821.335186509211, 7910.327622152066, 7931.762047138595, 3299.637039925606, 3305.074579117851, 3309.8014119152926, 3319.186799234203, 3350.865965361358, 955.8900726416551, 966.2028826218398, 970.9563069661882, 1085.6037546295545, 1093.8906109388997, 30243.848780755954, 30508.69491305103, 30656.0, 30868.69131308703, 31166.532502012487, 1029.1509504658686, 1037.4501514197486, 1059.2267644619471, 1068.8129577323937, 1073.3291692692067, 13243.867561324456, 15821.288042038135, 15852.0, 15864.832189282868, 15946.92026539883, 190485.0, 191352.0, 196013.0, 702899.0, 703539.0, 59344.40655593475, 59819.10271345933, 60043.699781500814, 60046.39953600408, 60149.699251503465, 1184.9170558060853, 1364.1405914273944, 1385.1896640465238, 1428.5357258890901, 1430.542226487535, 29922.25194370158, 29930.102096937127, 30209.0, 30223.784020547348, 30428.239294017825, 51201.0, 51510.74244628753, 51558.0, 51613.741931290104, 51636.0, 604830.0, 605955.0, 610099.0, 610240.0, 610856.0, 27162.45675086527, 27478.313042174104, 27566.862165689043, 27591.89632414681, 27596.586051209648, 48688.75655621699, 48890.53328400154, 48955.26567101425, 49465.50534494609, 50295.99408011892, 38949.6105038946, 39487.407688884115, 39826.0, 40425.79787101046, 40870.59129408668, 39722.60277397189, 40295.59704403036, 40355.79822100988, 41112.794436028824, 41522.0, 29607.85196074006, 30620.0, 33291.33417331591, 33533.0, 39443.81668549948, 30203.093907182818, 31347.529787052757, 31895.68104318927, 32137.517937230492, 32587.0, 24151.15470958508, 24237.394065147808, 25425.110121146412, 25548.852478616358, 25698.35754106162], "value": 18568.960841682736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.7781775299315, 373.32428304768365, 373.3690063792191, 376.99623003769955, 403.2881963334713, 12387.38063096845, 16825.0, 17199.3120275189, 20896.0, 21095.367138985817, 20728.170873165094, 20897.582048359025, 21229.0, 21239.362819115453, 21452.0, 5874.206982057411, 5925.881482370361, 6127.693615319238, 6202.689865506729, 6213.502919766431, 4196.139791342767, 6127.816165515042, 6212.78255261066, 6214.751409943597, 6220.875582488347, 6384.361563843625, 6708.563943343692, 6726.192856857184, 6805.251422343538, 6825.931740682604, 6560.081588577603, 6623.635700036521, 6766.086578311939, 6767.35710107539, 6773.45812335012, 5881.67650779207, 5889.558283128783, 5894.086416605433, 5902.557308201902, 5919.822405327847, 28608.998685045925, 29517.852410738025, 29791.55312670312, 29966.0, 30377.544336834966, 2217.7782221777857, 2446.1927563904046, 2462.790662793647, 2519.4583164619694, 3073.0627158716584, 16608.501744947673, 17417.0, 17426.825731742774, 17461.476155715223, 18523.35168269104, 1970.3300877701506, 2026.5744193719336, 2050.3643870400074, 2122.7664956854733, 2127.1385089038963, 79110.0, 81612.59193704053, 114604.42697786541, 121345.0, 141094.0, 16364.83635163657, 21150.682739758915, 21949.67075493869, 22080.779192208192, 22174.556508869733, 36166.819165904, 36197.0, 36228.45657315142, 36239.094022649646, 36249.45625815615, 10711.46442677863, 10732.946335268274, 10932.890671093188, 10958.28771129874, 11095.778084438427, 7333.853322933512, 8905.0, 8922.152395522415, 9087.81824363509, 9119.0, 8601.354898382646, 9545.284103692251, 9838.900746264904, 9971.45157016371, 9974.501274936225, 3168.778185526991, 3221.7100460958627, 3580.462930560385, 3725.8137093145233, 3763.7741735495956, 882.9249513791272, 965.5124162297994, 972.9075737804898, 1000.7590587671381, 1044.4777611194402, 20698.58602827935, 21754.673679894815, 22126.0, 22417.43077984558, 23148.18981335645, 895.583553647557, 918.0177210384958, 927.6474939522938, 953.0945601678433, 1010.5806090472519, 10662.093722033715, 11232.382218977871, 11395.259308144945, 11625.7404335242, 11727.885850844326, 566245.0, 603031.0, 603568.0, 604876.0, 605240.0, 104475.0, 116252.84647037782, 120806.3959680196, 123797.0, 129742.0, 913.2693844924034, 919.2094798473441, 945.4043952310001, 949.1552518258756, 952.7951490429591, 19991.42041940173, 20309.695354569398, 20380.28668996578, 20410.18359265613, 20468.283610073577, 42514.78742606267, 53679.900409461035, 54881.45118548923, 56612.15081773859, 57685.42314576801, 82574.0, 83750.58124709337, 85862.14137858541, 86152.56923715341, 86178.0, 6733.730650773914, 8194.139615340542, 8270.875936861072, 8375.664973401239, 8398.580070996304, 10924.781504370027, 12754.744905101661, 12834.486620535074, 13010.804837927628, 13524.729505410034, 11162.274452160584, 11244.66266012021, 11669.241499302518, 11674.474648641002, 11713.941430293135, 8816.603252853765, 11090.889091108986, 11203.887961120285, 11535.826962595736, 11746.70633234176, 9808.215342772419, 11398.430078496207, 11446.427678616203, 11628.418579071182, 11957.760844782882, 7973.72091976778, 8249.752507424786, 8263.173682631928, 8290.170982901904, 8463.942007249094, 8345.874811877706, 10864.348139111678, 11581.305121692725, 11643.650690479299, 11722.94138529336], "value": 10801.911384775063, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1532.7394342961695, 1554.0520282627597, 1754.3070487157572, 1832.1755210155434, 1905.5426697592573, 8245.958770206147, 8748.081451447586, 8756.080611535792, 8760.693375731851, 8778.473291602508, 7742.341900938413, 7977.401694872879, 8299.709510167146, 8535.658573657047, 8554.486730796156, 2283.4976305212867, 2334.404726794665, 2334.5564342774906, 2335.824813139013, 2337.567550003246, 2296.0471404367167, 2308.9422764430856, 2348.4598542335275, 2352.811775058, 2353.435175557871, 2325.2907863101746, 2332.416895776056, 2340.953180936389, 2345.061975209913, 2348.9412764680933, 2302.5049614332997, 2318.4783423729696, 2326.860388376686, 2328.7671232876746, 2341.8477798943013, 2314.132200424841, 2315.8378913476063, 2318.119114736406, 2319.060780383947, 2320.4082958845556, 8677.609507572175, 8716.956415217883, 8774.868376974351, 8786.780330491725, 8801.207891289814, 5145.691258524463, 5301.178317360776, 5301.1783173608155, 5326.866828329284, 5327.121025030865, 3198.504231844068, 3296.1100502864333, 3383.7631365804405, 3588.910277243064, 3600.4059330210484, 937.6671281694968, 1030.340582027503, 1066.1896958311738, 1074.3446497636514, 1091.3124731419155, 10984.890151098547, 11348.489317980711, 11510.654680359603, 11512.366819824907, 11533.961943425049, 4493.191225579427, 4568.725876447424, 4743.905121897543, 4746.881327966828, 4756.334113224151, 9770.51147442625, 9776.706698799047, 9787.657431989846, 9798.853017204749, 9799.85300220483, 5266.8419947401635, 5292.153255479182, 5296.496832800878, 5300.575953923677, 5309.46905309466, 4207.431996680471, 4294.613484786384, 4315.309550471972, 4444.466664000276, 4857.441394239708, 3639.6906262967414, 3742.8315725792404, 3745.4756334113245, 3826.617338266207, 3955.3078211312904, 1266.5681890769745, 1268.5560053981033, 1294.1264646363668, 1294.1458637299525, 1294.6504443800122, 807.8909347238048, 815.1888870573698, 859.2052351574769, 894.6421431427351, 957.9042095790365, 5599.832005039774, 5603.663780173202, 5676.375598684142, 5834.15404766315, 5942.257217847769, 916.4134953629682, 936.1527817325332, 950.7955789505289, 974.7611835100304, 995.3729150635054, 3768.8881779874455, 3907.33575292201, 3921.8234529640995, 3949.19041596472, 4069.715119941635, 8529.232369086812, 10661.626843060456, 10673.412962286993, 10702.678919632424, 10713.678589642323, 10569.894301057046, 10628.574857005633, 10645.308054976402, 10672.30630009047, 10694.35833849956, 7308.451866110169, 7609.581473019037, 8130.674773009132, 8226.670933162726, 8250.38122140842, 5867.970660146842, 5899.705014749331, 5931.940680593311, 6011.72947217385, 6045.727942242697, 9245.491497967676, 9924.553395097093, 9929.900700992897, 9934.006599340008, 9953.303268771117, 9150.039245879087, 9918.950405247928, 9938.354006989812, 10067.395956242648, 10122.443265620166, 6177.2587289525545, 7543.547387156788, 8051.758447246358, 8190.0, 8267.710630127916, 8121.634526446443, 8129.3090087343235, 8153.1846815317995, 8161.551114688749, 8202.712905048533, 7162.355388015104, 7335.193128755939, 7467.477276590586, 7550.3582195514, 7578.317951384402, 7281.490295679463, 7297.562146271241, 7300.525465844703, 7370.889436658347, 7471.775846724607, 6750.257471678014, 6885.311468853275, 6940.0, 6956.79129626112, 6965.164180298397, 4284.507281662649, 4911.950880491292, 4951.801927922987, 4962.851114466572, 5119.232115182753, 4409.955900441083, 4940.580050695731, 4956.9752151240455, 5035.395752509722, 5144.562712169508], "value": 4529.542205479945, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.74308095038276, 302.330338300664, 312.70918046217025, 315.974722022238, 318.6988296060224, 4717.504662010491, 4748.596369308613, 5134.845954621358, 5326.8401947941575, 5373.731313434332, 5104.876927076047, 5252.343457067866, 5303.469653034695, 5317.388500322464, 5317.607076815439, 3350.530925670414, 4041.2725709372303, 4087.2847251730827, 4092.3247664135383, 4099.3850922361735, 3233.9353212935844, 4081.632653061224, 4091.7954102294916, 4099.385092236159, 4131.917361652765, 3712.9071773205615, 4038.818253178599, 4048.817803198863, 4057.6550993165615, 4068.532118806331, 3807.904802379935, 4038.6769058475397, 4038.9192216155807, 4043.2722110020186, 4062.8984275393204, 3307.68576985186, 3479.8434070466888, 3495.2310491691787, 3530.8764193253114, 3547.308274886398, 4275.893102672427, 5172.939547392775, 5199.582194279678, 5217.34783152106, 5273.920891186635, 4143.6892233082635, 4528.826163441534, 4599.011212589276, 4601.700889442209, 4607.631389488833, 5234.979179060085, 5316.0, 5364.249005139283, 5438.912217556465, 5618.31450564833, 3319.7344212463186, 3808.8954203281014, 3842.0, 3844.3464611016193, 3855.306044911915, 4557.95442045582, 5145.665531740462, 5626.578006649518, 5698.743556539925, 5867.882642347172, 3051.588035615208, 3703.0557207912348, 3850.942235866464, 3864.555576108727, 3864.864729734446, 3921.8235179416993, 3925.941110883339, 3954.1103251768695, 3962.5046869141356, 3969.900752481211, 3818.427235914635, 4525.321201819754, 4537.115262523842, 4574.313852922089, 4602.516735742765, 5513.558915286768, 5534.225208470817, 5548.72256387179, 5605.66366018032, 5761.164631128463, 1224.0330139189941, 1262.0282382565556, 1344.8251727275588, 1349.959501214965, 1354.498835430877, 786.7255046824181, 804.9718259860877, 823.5017814222429, 823.5552801487249, 830.3108420011376, 3769.679577235911, 3946.4869566956113, 4303.655707543389, 4331.263685173528, 4356.934645980313, 3860.606424017127, 3923.6861051115843, 4562.406887104655, 4721.976390118028, 4820.807167713252, 3591.533100696945, 3792.544894612664, 4068.108329626738, 4286.442762440863, 4287.656987440997, 2420.8426452280546, 2529.8735063246763, 2533.3413312538873, 2542.262743804276, 2566.5636841736577, 4283.443152390231, 4293.527711951683, 4923.433805112458, 4924.374956264424, 4975.328330675385, 4086.141910198832, 4391.7584532851, 4561.361409402686, 4783.904321913612, 4909.0, 4216.0, 4979.916468495799, 5061.468545802638, 5097.0, 6102.0, 5867.442592953663, 5884.264466941632, 5889.322727886348, 5918.0, 5923.46688797993, 5506.449355064461, 5780.5953583248765, 5824.854378640568, 5832.854178645568, 5842.620229685057, 5027.235020738653, 5728.427157284238, 6048.580567772926, 6071.241094863142, 6097.878042439038, 3057.752551874415, 3805.980970095132, 3849.634284742945, 3880.80595970206, 3934.4098385241296, 4546.681732278708, 5443.863903402288, 5780.7800305777855, 5803.47768700819, 5806.303243610793, 5251.26482292487, 5308.946910531, 5563.582731295088, 5636.3799982002765, 5667.20659107717, 4831.710097394168, 5265.763040663102, 5520.365158006881, 5601.271834661468, 5661.688607126647, 5475.479829416199, 5492.5331346835965, 5504.587155963239, 5510.338759348904, 5519.724013799214, 5167.044096842096, 5382.865428364165, 5408.972955135199, 5567.137093750475, 5573.77704891816, 4367.410399596141, 5196.272521846869, 5447.428020057837, 5520.530754885719, 5634.211210430461], "value": 3925.228635952343, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.4561196654643, 708.9326513981173, 724.6376811594203, 724.7390939261867, 803.807086299288, 15069.62325941852, 16314.428994985177, 16379.426720064785, 16405.343786248537, 16406.589835254093, 14649.26753662318, 14768.483103091394, 14778.482753103644, 14790.778138327934, 14821.407143714265, 694.9027136200936, 719.9928000720005, 847.1020718038868, 855.9058503564619, 879.5074758135464, 870.6996086350231, 882.5410786391072, 882.9161229683202, 885.2033170146888, 886.6719313853883, 947.0766003146946, 948.3788118782209, 949.6248981652243, 950.353759443578, 951.8286708392486, 721.4012369733109, 903.480498713236, 942.6983365323099, 943.056943056943, 946.4463288975958, 705.2172088981226, 706.5831159615851, 708.2244941788733, 708.7129712466459, 712.1276436365463, 14783.0, 14828.777568336483, 14845.628859278604, 14848.0, 14850.480233191787, 14167.645808854759, 14251.0, 14304.928475357661, 14392.424303027867, 14613.78079328811, 12174.756504869853, 12228.327441990687, 12239.237731685136, 12288.262704237774, 12412.193207441487, 4524.8416305429155, 4657.88355291117, 4767.404074490689, 4771.28430735389, 4831.975840120812, 117097.0, 117160.82839171584, 117967.4101629495, 118908.0, 119040.0, 11111.722206944729, 11288.661340159808, 11362.0, 11419.771604567863, 11748.29510229389, 23430.0, 23439.88280058589, 23449.648255276184, 23486.7651323488, 23494.0, 8005.679772809022, 8101.374100469016, 8117.1476994915865, 8228.958855205805, 8371.916280837113, 4411.8230509819605, 4479.484859241164, 4548.74981876, 4578.267477203632, 4692.7653617319, 3483.89548313551, 3608.729345299113, 3609.5668519777796, 3610.656987586175, 3612.3497770401054, 647.9190101237346, 652.4877970793, 661.0612929639923, 662.1623646087703, 662.8309781005813, 516.5789881246772, 570.3070769015666, 580.8751118509541, 606.4777408704097, 609.3662590905409, 8828.822575767346, 9077.954610226907, 9310.487923164292, 9310.649955756377, 9449.338546301693, 545.6998650742077, 579.5103137848512, 612.6599737145926, 619.9109002661061, 629.4492319220682, 4161.542230354658, 4392.780360981938, 4535.183666939916, 4570.657200709894, 4578.839740609063, 166375.0, 168825.0, 169130.15434922746, 173116.0, 177347.0, 116464.0, 117326.0, 118687.0, 131862.0, 149276.0, 72582.63708681529, 73039.63480182672, 73373.2662673377, 74865.6256718713, 113116.0, 9516.38143520669, 9551.52242387864, 10037.69886903394, 10166.085052345325, 10169.237307201842, 47875.76062119667, 53290.73354633357, 58570.0, 60973.0, 62177.06734398897, 89079.0, 93191.0, 99595.0, 100074.0, 100199.99800001907, 13054.0, 13082.542111026069, 13316.334183290603, 13389.464421423423, 13849.653758656113, 14667.934131439855, 14918.925405372904, 14922.701545968803, 15154.340531404063, 15215.619609509851, 13931.512397066053, 14129.505467308594, 14175.574732758034, 14210.573682789533, 14231.430742770173, 11639.185257032264, 13743.931280343535, 13812.654683632989, 13958.232297223343, 14072.648183795078, 13012.934935325642, 13481.59555213345, 13648.249346285653, 13976.0, 14645.92677036608, 9379.906200937903, 9966.651167209113, 11360.602378916697, 11456.0, 11957.0, 10598.364098154136, 10686.412247326472, 10748.731281718019, 10814.783704326026, 10943.0], "value": 7898.234617688254, "unit": "score"}], "name": "dromaeo_css", "value": 7374.653110524274, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 116.0, 112.0, 113.0, 113.0, 113.0, 112.0, 112.0, 111.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 91.0, 178.0, 95.0, 123.0, 158.0, 134.0, 140.0, 126.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 186.0, 184.0, 183.0, 182.0, 183.0, 182.0, 182.0, 197.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 57.0, 63.0, 96.0, 81.0, 80.0, 88.0, 65.0, 83.0, 56.0], "value": 74.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 68.0, 101.0, 70.0, 74.0, 68.0, 97.0, 70.0, 67.0, 101.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 104.0, 135.0, 83.0, 87.0, 83.0, 86.0, 84.0, 86.0, 83.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 101.0, 93.0, 92.0, 91.0, 93.0, 92.0, 92.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 124.0, 138.0, 117.0, 85.0, 85.0, 86.0, 84.0, 83.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 53.0, 52.0, 53.0, 53.0, 54.0, 52.0, 54.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 75.0, 84.0, 105.0, 107.0, 102.0, 74.0, 71.0, 70.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 84.0, 83.0, 83.0, 81.0, 89.0, 84.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 136.0, 135.0, 136.0, 135.0, 134.0, 142.0, 152.0, 139.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 163.0, 163.0, 165.0, 165.0, 164.0, 164.0, 162.0, 179.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 67.0, 65.0, 67.0, 66.0, 66.0, 64.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1450.8, "alertThreshold": 2.0}]} 05:46:13 INFO - Return code: 0 05:46:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:46:13 INFO - # TBPL SUCCESS # 05:46:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:46:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:46:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:46:14 INFO - 2016-08-08 05:46:14.012 screenresolution[1943:16734] starting screenresolution argv=screenresolution get 05:46:14 INFO - 2016-08-08 05:46:14.015 screenresolution[1943:16734] Display 0: 1600x1200x32@60 05:46:14 INFO - 2016-08-08 05:46:14.021 screenresolution[1944:16737] starting screenresolution argv=screenresolution list 05:46:14 INFO - Available Modes on Display 0 05:46:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:46:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:46:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:46:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:46:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:46:14 INFO - Intel Iris: 05:46:14 INFO - Chipset Model: Intel Iris 05:46:14 INFO - Type: GPU 05:46:14 INFO - Bus: Built-In 05:46:14 INFO - VRAM (Dynamic, Max): 1536 MB 05:46:14 INFO - Vendor: Intel (0x8086) 05:46:14 INFO - Device ID: 0x0a2e 05:46:14 INFO - Revision ID: 0x0009 05:46:14 INFO - Displays: 05:46:14 INFO - Display: 05:46:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:46:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:46:14 INFO - Display Serial Number: 1600x1200 60 05:46:14 INFO - Main Display: Yes 05:46:14 INFO - Mirror: Off 05:46:14 INFO - Online: Yes 05:46:14 INFO - Rotation: Supported 05:46:14 INFO - Return code: 0 05:46:14 INFO - Running post-action listener: _resource_record_post_action 05:46:14 INFO - [mozharness: 2016-08-08 12:46:14.255028Z] Finished run-tests step (success) 05:46:14 INFO - Running post-run listener: _resource_record_post_run 05:46:14 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1077248; Write bytes: 704826368; Read time: 14; Write time: 2203 05:46:14 INFO - TinderboxPrint: CPU usage
53.5% 05:46:14 INFO - TinderboxPrint: I/O read bytes / time
1,077,248 / 14 05:46:14 INFO - TinderboxPrint: I/O write bytes / time
704,826,368 / 2,203 05:46:14 INFO - TinderboxPrint: CPU idle
2,256.5 (46.5%) 05:46:14 INFO - TinderboxPrint: CPU system
968.6 (20.0%) 05:46:14 INFO - TinderboxPrint: CPU user
1,628.5 (33.6%) 05:46:14 INFO - TinderboxPrint: Swap in / out
408,444,928 / 0 05:46:14 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 407652864; Write bytes: 410414592; Read time: 31173; Write time: 910 05:46:14 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 1077248; Write bytes: 292924928; Read time: 14; Write time: 1287 05:46:14 INFO - Running post-run listener: _upload_blobber_files 05:46:14 INFO - Blob upload gear active. 05:46:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:46:14 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 05:46:14 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'] 05:46:14 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 05:46:15 INFO - (blobuploader) - INFO - Open directory for files ... 05:46:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:46:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:46:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:15 INFO - (blobuploader) - INFO - Done attempting. 05:46:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:46:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:46:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:16 INFO - (blobuploader) - INFO - Done attempting. 05:46:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:46:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:46:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:19 INFO - (blobuploader) - INFO - Done attempting. 05:46:19 INFO - (blobuploader) - INFO - Iteration through files over. 05:46:19 INFO - Return code: 0 05:46:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:46:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:46:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fb412c8df739d70ed780bc38fe76ec5285c24fb947833020560175177cefe885302a27ed9aa659fb52064a001519bd6419bfe15a71e8399a23ea8b54086819f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16c19ae291c7799ce12e4b340484f95fa4e2829f80dd8d3b6a23fcde562bc10debce99ae14c72b5f35f8483cb9f94729e526a6f246bf1ae0afa2f25f27b2aeb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/128eec7dcd85b2ca9980f954b15ccbc746528e440edc859c694dc4a5d3820092e64d22ee23a87ff7f603652022f552f196f9c27c33e1f53958f5b862574fb913"} 05:46:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:46:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:46:19 INFO - Contents: 05:46:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fb412c8df739d70ed780bc38fe76ec5285c24fb947833020560175177cefe885302a27ed9aa659fb52064a001519bd6419bfe15a71e8399a23ea8b54086819f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16c19ae291c7799ce12e4b340484f95fa4e2829f80dd8d3b6a23fcde562bc10debce99ae14c72b5f35f8483cb9f94729e526a6f246bf1ae0afa2f25f27b2aeb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/128eec7dcd85b2ca9980f954b15ccbc746528e440edc859c694dc4a5d3820092e64d22ee23a87ff7f603652022f552f196f9c27c33e1f53958f5b862574fb913"} 05:46:19 INFO - Running post-run listener: copy_logs_to_upload_dir 05:46:19 INFO - Copying logs to upload dir... 05:46:19 INFO - mkdir: /builds/slave/test/build/upload/logs 05:46:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.632439 ========= master_lag: 0.24 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-08-08 05:46:19.667706) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 05:46:19.671567) ========= 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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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/1fb412c8df739d70ed780bc38fe76ec5285c24fb947833020560175177cefe885302a27ed9aa659fb52064a001519bd6419bfe15a71e8399a23ea8b54086819f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16c19ae291c7799ce12e4b340484f95fa4e2829f80dd8d3b6a23fcde562bc10debce99ae14c72b5f35f8483cb9f94729e526a6f246bf1ae0afa2f25f27b2aeb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/128eec7dcd85b2ca9980f954b15ccbc746528e440edc859c694dc4a5d3820092e64d22ee23a87ff7f603652022f552f196f9c27c33e1f53958f5b862574fb913"} build_url:https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010787 build_url: 'https://queue.taskcluster.net/v1/task/MrHUC6uTSviN_bWKDhUMCA/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/1fb412c8df739d70ed780bc38fe76ec5285c24fb947833020560175177cefe885302a27ed9aa659fb52064a001519bd6419bfe15a71e8399a23ea8b54086819f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16c19ae291c7799ce12e4b340484f95fa4e2829f80dd8d3b6a23fcde562bc10debce99ae14c72b5f35f8483cb9f94729e526a6f246bf1ae0afa2f25f27b2aeb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/128eec7dcd85b2ca9980f954b15ccbc746528e440edc859c694dc4a5d3820092e64d22ee23a87ff7f603652022f552f196f9c27c33e1f53958f5b862574fb913"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 05:46:19.709388) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:46:19.709789) ========= 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.ZuvLoAP2KG/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.HwZ1xFgb91/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.005265 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:46:19.758955) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 05:46:19.759277) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 05:46:19.759617) ========= ========= Total master_lag: 0.39 =========