builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0222 starttime: 1472137207.56 results: success (0) buildid: 20160825054326 builduid: 7e29b840d0b84dbe8217f7fde66c11ba revision: 7963ebdd52b93f96b812eff2eab8d94097147b9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:07.560185) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:07.560607) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:07.560880) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:07.586701) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:07.587060) ========= 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.N8m9sFlDQj/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.QR0awII8SF/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-25 08:00:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.3M=0.001s 2016-08-25 08:00:07 (17.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081440 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:07.691667) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:07.695044) ========= 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.N8m9sFlDQj/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.QR0awII8SF/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.031045 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:07.747571) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 08:00:07.750974) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7963ebdd52b93f96b812eff2eab8d94097147b9c --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 7963ebdd52b93f96b812eff2eab8d94097147b9c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.N8m9sFlDQj/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.QR0awII8SF/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-25 08:00:07,840 truncating revision to first 12 chars 2016-08-25 08:00:07,840 Setting DEBUG logging. 2016-08-25 08:00:07,840 attempt 1/10 2016-08-25 08:00:07,840 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7963ebdd52b9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 08:00:08,861 unpacking tar archive at: mozilla-central-7963ebdd52b9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.284338 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 08:00:09.057588) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:09.057928) ========= script_repo_revision: 7963ebdd52b93f96b812eff2eab8d94097147b9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:09.058328) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:09.058600) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 08:00:09.077461) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-25 08:00:09.077870) ========= /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.N8m9sFlDQj/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.QR0awII8SF/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 08:00:09 INFO - MultiFileLogger online at 20160825 08:00:09 in /builds/slave/test 08:00:09 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 08:00:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:00:09 INFO - {'append_to_log': False, 08:00:09 INFO - 'base_work_dir': '/builds/slave/test', 08:00:09 INFO - 'blob_upload_branch': 'Firefox', 08:00:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:00:09 INFO - 'branch': 'Firefox', 08:00:09 INFO - 'buildbot_json_path': 'buildprops.json', 08:00:09 INFO - 'config_files': ('talos/mac_config.py',), 08:00:09 INFO - 'default_actions': ('clobber', 08:00:09 INFO - 'read-buildbot-config', 08:00:09 INFO - 'download-and-extract', 08:00:09 INFO - 'populate-webroot', 08:00:09 INFO - 'create-virtualenv', 08:00:09 INFO - 'install', 08:00:09 INFO - 'run-tests'), 08:00:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:00:09 INFO - 'download_minidump_stackwalk': True, 08:00:09 INFO - 'download_symbols': 'ondemand', 08:00:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:00:09 INFO - 'tooltool.py': '/tools/tooltool.py', 08:00:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:00:09 INFO - '/tools/misc-python/virtualenv.py')}, 08:00:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:00:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:00:09 INFO - 'installer_path': 'installer.exe', 08:00:09 INFO - 'log_level': 'info', 08:00:09 INFO - 'log_name': 'talos', 08:00:09 INFO - 'log_to_console': True, 08:00:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:00:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:00:09 INFO - 'opt_config_files': (), 08:00:09 INFO - 'pip_index': False, 08:00:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:00:09 INFO - 'cmd': ('bash', 08:00:09 INFO - '-c', 08:00:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:00:09 INFO - 'enabled': True, 08:00:09 INFO - 'halt_on_failure': False, 08:00:09 INFO - 'name': 'check_screen_resolution'},), 08:00:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:00:09 INFO - 'cmd': ('bash', 08:00:09 INFO - '-c', 08:00:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:00:09 INFO - 'enabled': True, 08:00:09 INFO - 'halt_on_failure': False, 08:00:09 INFO - 'name': 'check_screen_resolution'},), 08:00:09 INFO - 'run_cmd_checks_enabled': True, 08:00:09 INFO - 'sps_profile': False, 08:00:09 INFO - 'sps_profile_interval': 0, 08:00:09 INFO - 'suite': 'dromaeojs', 08:00:09 INFO - 'system_bits': '32', 08:00:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:00:09 INFO - 'title': 't-yosemite-r7-0222', 08:00:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:00:09 INFO - 'use_talos_json': True, 08:00:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:00:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:00:09 INFO - 'work_dir': 'build'} 08:00:09 INFO - [mozharness: 2016-08-25 15:00:09.282554Z] Running clobber step. 08:00:09 INFO - Running pre-action listener: _resource_record_pre_action 08:00:09 INFO - Running main action method: clobber 08:00:09 INFO - rmtree: /builds/slave/test/build 08:00:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:00:11 INFO - Running post-action listener: _resource_record_post_action 08:00:11 INFO - [mozharness: 2016-08-25 15:00:11.542339Z] Finished clobber step (success) 08:00:11 INFO - [mozharness: 2016-08-25 15:00:11.542452Z] Running read-buildbot-config step. 08:00:11 INFO - Running pre-action listener: _resource_record_pre_action 08:00:11 INFO - Running main action method: read_buildbot_config 08:00:11 INFO - Using buildbot properties: 08:00:11 INFO - { 08:00:11 INFO - "project": "", 08:00:11 INFO - "product": "firefox", 08:00:11 INFO - "script_repo_revision": "production", 08:00:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:00:11 INFO - "repository": "", 08:00:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:00:11 INFO - "buildid": "20160825054326", 08:00:11 INFO - "pgo_build": "False", 08:00:11 INFO - "basedir": "/builds/slave/test", 08:00:11 INFO - "buildnumber": 12, 08:00:11 INFO - "slavename": "t-yosemite-r7-0222", 08:00:11 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:00:11 INFO - "platform": "yosemite_r7", 08:00:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:00:11 INFO - "branch": "mozilla-central", 08:00:11 INFO - "repo_path": "mozilla-central", 08:00:11 INFO - "revision": "7963ebdd52b93f96b812eff2eab8d94097147b9c", 08:00:11 INFO - "stage_platform": "macosx64", 08:00:11 INFO - "builduid": "7e29b840d0b84dbe8217f7fde66c11ba", 08:00:11 INFO - "slavebuilddir": "test" 08:00:11 INFO - } 08:00:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:00:11 INFO - Running post-action listener: _resource_record_post_action 08:00:11 INFO - [mozharness: 2016-08-25 15:00:11.545272Z] Finished read-buildbot-config step (success) 08:00:11 INFO - [mozharness: 2016-08-25 15:00:11.545385Z] Running download-and-extract step. 08:00:11 INFO - Running pre-action listener: _resource_record_pre_action 08:00:11 INFO - Running main action method: download_and_extract 08:00:11 INFO - mkdir: /builds/slave/test/build/tests 08:00:11 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')]} 08:00:11 INFO - trying https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:00:11 INFO - Downloading https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/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 08:00:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/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 08:00:12 INFO - Downloaded 1199 bytes. 08:00:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:00:12 INFO - Using the following test package requirements: 08:00:12 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:00:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:00:12 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:00:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:00:12 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:00:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:00:12 INFO - u'jsshell-mac.zip'], 08:00:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:00:12 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:00:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:00:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:00:12 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:00:12 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:00:12 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:00:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:00:12 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:00:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:00:12 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:00:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:00:12 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')]} 08:00:12 INFO - trying https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:00:12 INFO - Downloading https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/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 08:00:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/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 08:00:13 INFO - Downloaded 16345973 bytes. 08:00:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:00:20 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 08:00:20 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')]} 08:00:20 INFO - trying https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:00:20 INFO - Downloading https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/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 08:00:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/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 08:00:21 INFO - Downloaded 16345973 bytes. 08:00:21 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:00:28 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')]} 08:00:28 INFO - trying https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:00:28 INFO - Downloading https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/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 08:00:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/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 08:00:29 INFO - Downloaded 14413981 bytes. 08:00:29 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 08:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:30 INFO - trying https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:00:30 INFO - Downloading https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:00:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/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 08:00:33 INFO - Downloaded 111556790 bytes. 08:00:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:00:33 INFO - mkdir: /builds/slave/test/properties 08:00:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:00:33 INFO - Writing to file /builds/slave/test/properties/build_url 08:00:33 INFO - Contents: 08:00:33 INFO - build_url:https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:00:33 INFO - Running post-action listener: _resource_record_post_action 08:00:33 INFO - Running post-action listener: set_extra_try_arguments 08:00:33 INFO - [mozharness: 2016-08-25 15:00:33.704186Z] Finished download-and-extract step (success) 08:00:33 INFO - [mozharness: 2016-08-25 15:00:33.704315Z] Running populate-webroot step. 08:00:33 INFO - Running pre-action listener: _resource_record_pre_action 08:00:33 INFO - Running main action method: populate_webroot 08:00:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:00:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:00:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:00:33 INFO - '--tppagecycles', 08:00:33 INFO - '7'], 08:00:33 INFO - 'tests': ['tsvgm']}}, 08:00:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:00:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:00:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:00:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:00:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:00:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:00:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:33 INFO - 'talos_options': ['--disable-e10s'], 08:00:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:33 INFO - 'talos_options': ['--disable-e10s'], 08:00:33 INFO - 'tests': ['damp', 'tps']}, 08:00:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:33 INFO - 'tests': ['damp', 'tps']}, 08:00:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:00:33 INFO - 'tests': ['dromaeo_dom']}, 08:00:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:00:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:00:33 INFO - 'tests': ['basic_compositor_video']}, 08:00:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:00:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:00:33 INFO - 'tests': ['a11yr', 08:00:33 INFO - 'ts_paint', 08:00:33 INFO - 'tpaint', 08:00:33 INFO - 'sessionrestore', 08:00:33 INFO - 'sessionrestore_no_auto_restore', 08:00:33 INFO - 'tabpaint']}, 08:00:33 INFO - 'other-e10s': {'tests': ['a11yr', 08:00:33 INFO - 'ts_paint', 08:00:33 INFO - 'tpaint', 08:00:33 INFO - 'sessionrestore', 08:00:33 INFO - 'sessionrestore_no_auto_restore', 08:00:33 INFO - 'tabpaint']}, 08:00:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:00:33 INFO - 'ts_paint', 08:00:33 INFO - 'tpaint', 08:00:33 INFO - 'sessionrestore', 08:00:33 INFO - 'sessionrestore_no_auto_restore', 08:00:33 INFO - 'tabpaint']}, 08:00:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:00:33 INFO - 'ts_paint', 08:00:33 INFO - 'tpaint', 08:00:33 INFO - 'sessionrestore', 08:00:33 INFO - 'sessionrestore_no_auto_restore', 08:00:33 INFO - 'tabpaint']}, 08:00:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:00:33 INFO - 'tests': ['a11yr', 08:00:33 INFO - 'ts_paint', 08:00:33 INFO - 'tpaint', 08:00:33 INFO - 'sessionrestore', 08:00:33 INFO - 'sessionrestore_no_auto_restore', 08:00:33 INFO - 'tabpaint']}, 08:00:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:00:33 INFO - 'tests': ['a11yr', 08:00:33 INFO - 'ts_paint', 08:00:33 INFO - 'tpaint', 08:00:33 INFO - 'sessionrestore', 08:00:33 INFO - 'sessionrestore_no_auto_restore', 08:00:33 INFO - 'tabpaint']}, 08:00:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:00:33 INFO - 'tests': ['tsvgx', 08:00:33 INFO - 'tsvgr_opacity', 08:00:33 INFO - 'tart', 08:00:33 INFO - 'tscrollx', 08:00:33 INFO - 'cart']}, 08:00:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:00:33 INFO - 'tsvgr_opacity', 08:00:33 INFO - 'tart', 08:00:33 INFO - 'tscrollx', 08:00:33 INFO - 'cart']}, 08:00:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:33 INFO - 'talos_options': ['--disable-e10s'], 08:00:33 INFO - 'tests': ['tp5o']}, 08:00:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:33 INFO - 'tests': ['tp5o']}, 08:00:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:33 INFO - 'talos_options': ['--disable-e10s', 08:00:33 INFO - '--xperf_path', 08:00:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:33 INFO - 'tests': ['tp5n']}, 08:00:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:33 INFO - 'talos_options': ['--xperf_path', 08:00:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:33 INFO - 'tests': ['tp5n']}}, 08:00:33 INFO - 'talos.zip': {'path': '', 08:00:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:00:33 INFO - Running post-action listener: _resource_record_post_action 08:00:33 INFO - [mozharness: 2016-08-25 15:00:33.723135Z] Finished populate-webroot step (success) 08:00:33 INFO - [mozharness: 2016-08-25 15:00:33.723274Z] Running create-virtualenv step. 08:00:33 INFO - Running pre-action listener: _resource_record_pre_action 08:00:33 INFO - Running main action method: create_virtualenv 08:00:33 INFO - Creating virtualenv /builds/slave/test/build/venv 08:00:33 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 08:00:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:00:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:00:33 INFO - Using real prefix '/tools/python27' 08:00:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:00:34 INFO - Installing distribute.............................................................................................................................................................................................done. 08:00:37 INFO - Installing pip.................done. 08:00:37 INFO - Return code: 0 08:00:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b0ca1940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca36d30>, '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 0x10c79bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca2eb20>, '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.N8m9sFlDQj/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.QR0awII8SF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 08:00:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:00:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N8m9sFlDQj/Render', 08:00:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:37 INFO - 'HOME': '/Users/cltbld', 08:00:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:37 INFO - 'LOGNAME': 'cltbld', 08:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:37 INFO - 'MOZ_NO_REMOTE': '1', 08:00:37 INFO - 'NO_EM_RESTART': '1', 08:00:37 INFO - 'PAGER': '/bin/cat', 08:00:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:37 INFO - 'PWD': '/builds/slave/test', 08:00:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:37 INFO - 'SHELL': '/bin/bash', 08:00:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QR0awII8SF/Listeners', 08:00:37 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 08:00:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:37 INFO - 'USER': 'cltbld', 08:00:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:37 INFO - 'XPC_FLAGS': '0x0', 08:00:37 INFO - 'XPC_SERVICE_NAME': '0', 08:00:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:37 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 08:00:37 INFO - Cleaning up... 08:00:37 INFO - Return code: 0 08:00:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b0ca1940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca36d30>, '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 0x10c79bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca2eb20>, '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.N8m9sFlDQj/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.QR0awII8SF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 08:00:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:00:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N8m9sFlDQj/Render', 08:00:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:37 INFO - 'HOME': '/Users/cltbld', 08:00:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:37 INFO - 'LOGNAME': 'cltbld', 08:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:37 INFO - 'MOZ_NO_REMOTE': '1', 08:00:37 INFO - 'NO_EM_RESTART': '1', 08:00:37 INFO - 'PAGER': '/bin/cat', 08:00:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:37 INFO - 'PWD': '/builds/slave/test', 08:00:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:37 INFO - 'SHELL': '/bin/bash', 08:00:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QR0awII8SF/Listeners', 08:00:37 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 08:00:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:37 INFO - 'USER': 'cltbld', 08:00:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:37 INFO - 'XPC_FLAGS': '0x0', 08:00:37 INFO - 'XPC_SERVICE_NAME': '0', 08:00:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:37 INFO - Downloading/unpacking psutil>=3.1.1 08:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:00:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:41 INFO - Installing collected packages: psutil 08:00:41 INFO - Running setup.py install for psutil 08:00:41 INFO - building 'psutil._psutil_osx' extension 08:00:41 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 08:00:41 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 08:00:41 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 08:00:42 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 08:00:42 INFO - building 'psutil._psutil_posix' extension 08:00:42 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 08:00:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:00:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:00:42 INFO - ^ 08:00:42 INFO - 1 warning generated. 08:00:42 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 08:00:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:42 INFO - Successfully installed psutil 08:00:42 INFO - Cleaning up... 08:00:42 INFO - Return code: 0 08:00:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b0ca1940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca36d30>, '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 0x10c79bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca2eb20>, '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.N8m9sFlDQj/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.QR0awII8SF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 08:00:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:00:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N8m9sFlDQj/Render', 08:00:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:42 INFO - 'HOME': '/Users/cltbld', 08:00:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:42 INFO - 'LOGNAME': 'cltbld', 08:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:42 INFO - 'MOZ_NO_REMOTE': '1', 08:00:42 INFO - 'NO_EM_RESTART': '1', 08:00:42 INFO - 'PAGER': '/bin/cat', 08:00:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:42 INFO - 'PWD': '/builds/slave/test', 08:00:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:42 INFO - 'SHELL': '/bin/bash', 08:00:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QR0awII8SF/Listeners', 08:00:42 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 08:00:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:42 INFO - 'USER': 'cltbld', 08:00:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:42 INFO - 'XPC_FLAGS': '0x0', 08:00:42 INFO - 'XPC_SERVICE_NAME': '0', 08:00:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:00:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:00:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:00:45 INFO - Installing collected packages: mozsystemmonitor 08:00:45 INFO - Running setup.py install for mozsystemmonitor 08:00:45 INFO - Successfully installed mozsystemmonitor 08:00:45 INFO - Cleaning up... 08:00:45 INFO - Return code: 0 08:00:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:00: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')]} 08:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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')]} 08:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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', '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 0x10cad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b0ca1940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca36d30>, '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 0x10c79bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca2eb20>, '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.N8m9sFlDQj/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.QR0awII8SF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 08:00: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:00: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 jsonschema==2.5.1 08:00:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N8m9sFlDQj/Render', 08:00:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:45 INFO - 'HOME': '/Users/cltbld', 08:00:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:45 INFO - 'LOGNAME': 'cltbld', 08:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:45 INFO - 'MOZ_NO_REMOTE': '1', 08:00:45 INFO - 'NO_EM_RESTART': '1', 08:00:45 INFO - 'PAGER': '/bin/cat', 08:00:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:45 INFO - 'PWD': '/builds/slave/test', 08:00:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:45 INFO - 'SHELL': '/bin/bash', 08:00:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QR0awII8SF/Listeners', 08:00:45 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 08:00:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:45 INFO - 'USER': 'cltbld', 08:00:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:45 INFO - 'XPC_FLAGS': '0x0', 08:00:45 INFO - 'XPC_SERVICE_NAME': '0', 08:00:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:45 INFO - Downloading/unpacking jsonschema==2.5.1 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:00:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00:48 INFO - Downloading functools32-3.2.3-2.tar.gz 08:00:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:00:48 INFO - warning: no files found matching '*.txt' 08:00:48 INFO - no previously-included directories found matching 'build' 08:00:48 INFO - no previously-included directories found matching 'dist' 08:00:48 INFO - no previously-included directories found matching '.git*' 08:00:48 INFO - Installing collected packages: jsonschema, functools32 08:00:48 INFO - Running setup.py install for functools32 08:00:48 INFO - warning: no files found matching '*.txt' 08:00:48 INFO - no previously-included directories found matching 'build' 08:00:48 INFO - no previously-included directories found matching 'dist' 08:00:48 INFO - no previously-included directories found matching '.git*' 08:00:48 INFO - Successfully installed jsonschema functools32 08:00:48 INFO - Cleaning up... 08:00:48 INFO - Return code: 0 08:00:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b0ca1940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca36d30>, '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 0x10c79bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca2eb20>, '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.N8m9sFlDQj/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.QR0awII8SF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 08:00:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:00:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N8m9sFlDQj/Render', 08:00:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:48 INFO - 'HOME': '/Users/cltbld', 08:00:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:48 INFO - 'LOGNAME': 'cltbld', 08:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:48 INFO - 'MOZ_NO_REMOTE': '1', 08:00:48 INFO - 'NO_EM_RESTART': '1', 08:00:48 INFO - 'PAGER': '/bin/cat', 08:00:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:48 INFO - 'PWD': '/builds/slave/test', 08:00:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:48 INFO - 'SHELL': '/bin/bash', 08:00:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QR0awII8SF/Listeners', 08:00:48 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 08:00:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:48 INFO - 'USER': 'cltbld', 08:00:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:48 INFO - 'XPC_FLAGS': '0x0', 08:00:48 INFO - 'XPC_SERVICE_NAME': '0', 08:00:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:00:49 INFO - Cleaning up... 08:00:49 INFO - Return code: 0 08:00:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:00:49 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')]} 08:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:49 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')]} 08:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:49 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 0x10cad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b0ca1940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca36d30>, '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 0x10c79bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca2eb20>, '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.N8m9sFlDQj/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.QR0awII8SF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 08:00:49 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 08:00:49 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 08:00:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N8m9sFlDQj/Render', 08:00:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:49 INFO - 'HOME': '/Users/cltbld', 08:00:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:49 INFO - 'LOGNAME': 'cltbld', 08:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:49 INFO - 'MOZ_NO_REMOTE': '1', 08:00:49 INFO - 'NO_EM_RESTART': '1', 08:00:49 INFO - 'PAGER': '/bin/cat', 08:00:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:49 INFO - 'PWD': '/builds/slave/test', 08:00:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:49 INFO - 'SHELL': '/bin/bash', 08:00:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QR0awII8SF/Listeners', 08:00:49 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 08:00:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:49 INFO - 'USER': 'cltbld', 08:00:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:49 INFO - 'XPC_FLAGS': '0x0', 08:00:49 INFO - 'XPC_SERVICE_NAME': '0', 08:00:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:49 INFO - Downloading/unpacking blobuploader==1.2.4 08:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:51 INFO - Downloading blobuploader-1.2.4.tar.gz 08:00:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:00:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:00:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:53 INFO - Downloading docopt-0.6.1.tar.gz 08:00:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:00:53 INFO - Installing collected packages: blobuploader, requests, docopt 08:00:53 INFO - Running setup.py install for blobuploader 08:00:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:00:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:00:53 INFO - Running setup.py install for requests 08:00:53 INFO - Running setup.py install for docopt 08:00:54 INFO - Successfully installed blobuploader requests docopt 08:00:54 INFO - Cleaning up... 08:00:54 INFO - Return code: 0 08:00:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00: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')]} 08:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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')]} 08:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:54 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 0x10cad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b0ca1940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca36d30>, '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 0x10c79bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca2eb20>, '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.N8m9sFlDQj/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.QR0awII8SF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 08:00:54 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 08:00:54 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 08:00:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N8m9sFlDQj/Render', 08:00:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:54 INFO - 'HOME': '/Users/cltbld', 08:00:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:54 INFO - 'LOGNAME': 'cltbld', 08:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:54 INFO - 'MOZ_NO_REMOTE': '1', 08:00:54 INFO - 'NO_EM_RESTART': '1', 08:00:54 INFO - 'PAGER': '/bin/cat', 08:00:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:54 INFO - 'PWD': '/builds/slave/test', 08:00:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:54 INFO - 'SHELL': '/bin/bash', 08:00:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QR0awII8SF/Listeners', 08:00:54 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 08:00:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:54 INFO - 'USER': 'cltbld', 08:00:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:54 INFO - 'XPC_FLAGS': '0x0', 08:00:54 INFO - 'XPC_SERVICE_NAME': '0', 08:00:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:54 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-dwRhOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:54 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-0xzcvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:54 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-lDzS4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:54 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-Bu_6bB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:54 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-ADq5lX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-kMP165-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-rADZX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-sbRL0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-0NSu3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-mgkRcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-3RZuPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-n6H3_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-8XjWge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-M2XiTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:55 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-ihrKF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:56 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-4LX3kR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:56 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-L4rM_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:00:56 INFO - Running setup.py install for manifestparser 08:00:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:00:56 INFO - Running setup.py install for mozcrash 08:00:56 INFO - Running setup.py install for mozdebug 08:00:56 INFO - Running setup.py install for mozdevice 08:00:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:00:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:00:56 INFO - Running setup.py install for mozfile 08:00:57 INFO - Running setup.py install for mozhttpd 08:00:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:00:57 INFO - Running setup.py install for mozinfo 08:00:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:00:57 INFO - Running setup.py install for mozInstall 08:00:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:00:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:00:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:00:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:00:57 INFO - Running setup.py install for mozleak 08:00:57 INFO - Running setup.py install for mozlog 08:00:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:00:57 INFO - Running setup.py install for moznetwork 08:00:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:00:57 INFO - Running setup.py install for mozprocess 08:00:58 INFO - Running setup.py install for mozprofile 08:00:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:00:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:00:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:00:58 INFO - Running setup.py install for mozrunner 08:00:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:00:58 INFO - Running setup.py install for mozscreenshot 08:00:58 INFO - Running setup.py install for moztest 08:00:58 INFO - Running setup.py install for mozversion 08:00:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:00:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:00:58 INFO - Cleaning up... 08:00:58 INFO - Return code: 0 08:00:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:58 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')]} 08:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:58 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')]} 08:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:58 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 0x10cad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b0ca1940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca36d30>, '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 0x10c79bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca2eb20>, '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.N8m9sFlDQj/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.QR0awII8SF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 08:00:58 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 08:00:58 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 08:00:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N8m9sFlDQj/Render', 08:00:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:58 INFO - 'HOME': '/Users/cltbld', 08:00:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:58 INFO - 'LOGNAME': 'cltbld', 08:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:58 INFO - 'MOZ_NO_REMOTE': '1', 08:00:58 INFO - 'NO_EM_RESTART': '1', 08:00:58 INFO - 'PAGER': '/bin/cat', 08:00:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:58 INFO - 'PWD': '/builds/slave/test', 08:00:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:58 INFO - 'SHELL': '/bin/bash', 08:00:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QR0awII8SF/Listeners', 08:00:58 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 08:00:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:58 INFO - 'USER': 'cltbld', 08:00:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:58 INFO - 'XPC_FLAGS': '0x0', 08:00:58 INFO - 'XPC_SERVICE_NAME': '0', 08:00:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:59 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-roQCF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:59 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)) 08:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:59 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-gl8U6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:59 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)) 08:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:59 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-TIVQFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:59 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)) 08:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:59 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-oZH33N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:59 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)) 08:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:59 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-6uWy0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:59 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)) 08:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:59 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-lInCRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:59 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)) 08:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:59 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-w9SgKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:59 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)) 08:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:59 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-uNkg7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:01:00 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)) 08:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:01:00 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-433x9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:01:00 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)) 08:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:01:00 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-Vbu12s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:01:00 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)) 08:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:01:00 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-5Zl9Am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:01:00 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)) 08:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:01:00 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-u7w1LZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:01:00 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)) 08:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:01:00 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-ZIzYkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:01:00 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)) 08:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:01:00 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-lDkeUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:01:00 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)) 08:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:01:00 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-fAiKZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:01:00 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)) 08:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:01:00 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-ekPQ5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:01:00 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)) 08:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:01:00 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-rUJRQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:01:00 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)) 08:01:00 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)) 08:01:00 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)) 08:01:00 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)) 08:01:00 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)) 08:01:00 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)) 08:01:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:03 INFO - Downloading blessings-1.6.tar.gz 08:01:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:01:03 INFO - Installing collected packages: blessings 08:01:03 INFO - Running setup.py install for blessings 08:01:03 INFO - Successfully installed blessings 08:01:03 INFO - Cleaning up... 08:01:03 INFO - Return code: 0 08:01:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:01:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:01:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:01:04 INFO - Reading from file tmpfile_stdout 08:01:04 INFO - Current package versions: 08:01:04 INFO - blessings == 1.6 08:01:04 INFO - blobuploader == 1.2.4 08:01:04 INFO - docopt == 0.6.1 08:01:04 INFO - functools32 == 3.2.3-2 08:01:04 INFO - jsonschema == 2.5.1 08:01:04 INFO - manifestparser == 1.1 08:01:04 INFO - mozInstall == 1.12 08:01:04 INFO - mozcrash == 0.17 08:01:04 INFO - mozdebug == 0.1 08:01:04 INFO - mozdevice == 0.48 08:01:04 INFO - mozfile == 1.2 08:01:04 INFO - mozhttpd == 0.7 08:01:04 INFO - mozinfo == 0.9 08:01:04 INFO - mozleak == 0.1 08:01:04 INFO - mozlog == 3.3 08:01:04 INFO - moznetwork == 0.27 08:01:04 INFO - mozprocess == 0.23 08:01:04 INFO - mozprofile == 0.28 08:01:04 INFO - mozrunner == 6.12 08:01:04 INFO - mozscreenshot == 0.1 08:01:04 INFO - mozsystemmonitor == 0.3 08:01:04 INFO - moztest == 0.7 08:01:04 INFO - mozversion == 1.4 08:01:04 INFO - psutil == 3.1.1 08:01:04 INFO - requests == 1.2.3 08:01:04 INFO - wsgiref == 0.1.2 08:01:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:04 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 0x10cad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b0ca1940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca36d30>, '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 0x10c79bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca2eb20>, '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.N8m9sFlDQj/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.QR0awII8SF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 08:01:04 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 08:01:04 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 08:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N8m9sFlDQj/Render', 08:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:04 INFO - 'HOME': '/Users/cltbld', 08:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:04 INFO - 'LOGNAME': 'cltbld', 08:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:04 INFO - 'MOZ_NO_REMOTE': '1', 08:01:04 INFO - 'NO_EM_RESTART': '1', 08:01:04 INFO - 'PAGER': '/bin/cat', 08:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:04 INFO - 'PWD': '/builds/slave/test', 08:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:04 INFO - 'SHELL': '/bin/bash', 08:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QR0awII8SF/Listeners', 08:01:04 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 08:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:04 INFO - 'USER': 'cltbld', 08:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:04 INFO - 'XPC_FLAGS': '0x0', 08:01:04 INFO - 'XPC_SERVICE_NAME': '0', 08:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:04 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)) 08:01:04 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)) 08:01:04 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)) 08:01:04 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)) 08:01:04 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)) 08:01:04 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)) 08:01:04 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)) 08:01:04 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)) 08:01:04 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)) 08:01:04 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)) 08:01:04 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)) 08:01:04 INFO - Cleaning up... 08:01:04 INFO - Return code: 0 08:01:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:04 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 0x10cad2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b0ca1940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca36d30>, '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 0x10c79bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca2eb20>, '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.N8m9sFlDQj/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.QR0awII8SF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 08:01:04 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 08:01:04 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 08:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N8m9sFlDQj/Render', 08:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:04 INFO - 'HOME': '/Users/cltbld', 08:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:04 INFO - 'LOGNAME': 'cltbld', 08:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:04 INFO - 'MOZ_NO_REMOTE': '1', 08:01:04 INFO - 'NO_EM_RESTART': '1', 08:01:04 INFO - 'PAGER': '/bin/cat', 08:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:04 INFO - 'PWD': '/builds/slave/test', 08:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:04 INFO - 'SHELL': '/bin/bash', 08:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QR0awII8SF/Listeners', 08:01:04 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 08:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:04 INFO - 'USER': 'cltbld', 08:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:04 INFO - 'XPC_FLAGS': '0x0', 08:01:04 INFO - 'XPC_SERVICE_NAME': '0', 08:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:01:04 INFO - Cleaning up... 08:01:04 INFO - Return code: 0 08:01:04 INFO - Running post-action listener: _resource_record_post_action 08:01:04 INFO - Running post-action listener: _start_resource_monitoring 08:01:05 INFO - Starting resource monitoring. 08:01:05 INFO - [mozharness: 2016-08-25 15:01:05.022895Z] Finished create-virtualenv step (success) 08:01:05 INFO - [mozharness: 2016-08-25 15:01:05.023926Z] Running install step. 08:01:05 INFO - Running pre-action listener: _resource_record_pre_action 08:01:05 INFO - Running main action method: install 08:01:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:01:05 INFO - Reading from file tmpfile_stdout 08:01:05 INFO - Detecting whether we're running mozinstall >=1.0... 08:01:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:01:05 INFO - Reading from file tmpfile_stdout 08:01:05 INFO - Output received: 08:01:05 INFO - Usage: mozinstall [options] installer 08:01:05 INFO - Options: 08:01:05 INFO - -h, --help show this help message and exit 08:01:05 INFO - -d DEST, --destination=DEST 08:01:05 INFO - Directory to install application into. [default: 08:01:05 INFO - "/builds/slave/test"] 08:01:05 INFO - --app=APP Application being installed. [default: firefox] 08:01:05 INFO - mkdir: /builds/slave/test/build/application 08:01:05 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'] 08:01:05 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 08:01:39 INFO - Reading from file tmpfile_stdout 08:01:39 INFO - Output received: 08:01:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:01:39 INFO - Running post-action listener: _resource_record_post_action 08:01:39 INFO - [mozharness: 2016-08-25 15:01:39.703462Z] Finished install step (success) 08:01:39 INFO - [mozharness: 2016-08-25 15:01:39.703591Z] Running run-tests step. 08:01:39 INFO - Running pre-action listener: _resource_record_pre_action 08:01:39 INFO - Running main action method: run_tests 08:01:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:01:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:01:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:01:39 INFO - 2016-08-25 08:01:39.716 screenresolution[1634:7438] starting screenresolution argv=screenresolution get 08:01:39 INFO - 2016-08-25 08:01:39.718 screenresolution[1634:7438] Display 0: 1600x1200x32@60 08:01:39 INFO - 2016-08-25 08:01:39.725 screenresolution[1635:7440] starting screenresolution argv=screenresolution list 08:01:39 INFO - Available Modes on Display 0 08:01:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:01:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:01:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:01:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:01:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:01:39 INFO - Intel Iris: 08:01:39 INFO - Chipset Model: Intel Iris 08:01:39 INFO - Type: GPU 08:01:39 INFO - Bus: Built-In 08:01:39 INFO - VRAM (Dynamic, Max): 1536 MB 08:01:39 INFO - Vendor: Intel (0x8086) 08:01:39 INFO - Device ID: 0x0a2e 08:01:39 INFO - Revision ID: 0x0009 08:01:39 INFO - Displays: 08:01:39 INFO - Display: 08:01:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:01:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:01:39 INFO - Display Serial Number: 1600x1200 60 08:01:39 INFO - Main Display: Yes 08:01:39 INFO - Mirror: Off 08:01:39 INFO - Online: Yes 08:01:39 INFO - Rotation: Supported 08:01:39 INFO - Return code: 0 08:01:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:01:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:01:39 INFO - Python 2.7.3 08:01:39 INFO - Return code: 0 08:01:39 INFO - grabbing minidump binary from tooltool 08:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:39 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 0x10ca36d30>, '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 0x10c79bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca2eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:01:39 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 08:01:39 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 08:01:39 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 08:01:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:01:40 INFO - Return code: 0 08:01:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:01:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:01:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:01:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:01:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:01:40 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/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--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 08:01:40 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/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0222 --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 08:01:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N8m9sFlDQj/Render', 08:01:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:40 INFO - 'HOME': '/Users/cltbld', 08:01:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:40 INFO - 'LOGNAME': 'cltbld', 08:01:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:01:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:40 INFO - 'MOZ_NO_REMOTE': '1', 08:01:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:01:40 INFO - 'NO_EM_RESTART': '1', 08:01:40 INFO - 'PAGER': '/bin/cat', 08:01:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:40 INFO - 'PWD': '/builds/slave/test', 08:01:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:01:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:40 INFO - 'SHELL': '/bin/bash', 08:01:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QR0awII8SF/Listeners', 08:01:40 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 08:01:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:40 INFO - 'USER': 'cltbld', 08:01:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:40 INFO - 'XPC_FLAGS': '0x0', 08:01:40 INFO - 'XPC_SERVICE_NAME': '0', 08:01:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:40 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/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--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 08:01:40 INFO - mozversion application_buildid: 20160825054326 08:01:40 INFO - mozversion application_changeset: 7963ebdd52b93f96b812eff2eab8d94097147b9c 08:01:40 INFO - mozversion application_display_name: Nightly 08:01:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:01:40 INFO - mozversion application_name: Firefox 08:01:40 INFO - mozversion application_remotingname: firefox 08:01:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:01:40 INFO - mozversion application_vendor: Mozilla 08:01:40 INFO - mozversion application_version: 51.0a1 08:01:40 INFO - mozversion platform_buildid: 20160825054326 08:01:40 INFO - mozversion platform_changeset: 7963ebdd52b93f96b812eff2eab8d94097147b9c 08:01:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:01:40 INFO - mozversion platform_version: 51.0a1 08:01:40 INFO - using testdate: 1472137300 08:01:40 INFO - actual date: 1472137300 08:01:40 INFO - starting webserver on 'localhost:49277' 08:01:40 INFO - SUITE-START | Running 2 tests 08:01:40 INFO - TEST-START | dromaeo_css 08:01:40 INFO - operating with platform_type : mac_ 08:01:40 INFO - Initialising browser for dromaeo_css test... 08:01:40 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpeOf61S/profile http://localhost:49277/getInfo.html) 08:01:42 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 08:01:42 INFO - PROCESS | 1645 | colorDepth:24 08:01:42 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 08:01:42 INFO - PROCESS | 1645 | __metrics 08:01:42 INFO - PROCESS | 1645 | 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" 08:01:42 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:01:43 INFO - TEST-INFO | 1645: exit 0 08:01:43 INFO - Browser initialized. 08:01:43 INFO - Running cycle 1/1 for dromaeo_css test... 08:01:43 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpeOf61S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:01:55 INFO - PROCESS | 1647 | RSS: Main: 231997440 08:01:55 INFO - PROCESS | 1647 | 08:02:46 INFO - PROCESS | 1647 | 1472137366650 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] 08:04:58 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 08:04:59 INFO - PROCESS | 1647 | RSS: Main: 379346944 08:04:59 INFO - PROCESS | 1647 | 08:08:02 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 08:08:03 INFO - PROCESS | 1647 | RSS: Main: 372486144 08:08:03 INFO - PROCESS | 1647 | 08:11:05 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 08:11:06 INFO - PROCESS | 1647 | RSS: Main: 355123200 08:11:06 INFO - PROCESS | 1647 | 08:14:08 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 08:14:09 INFO - PROCESS | 1647 | RSS: Main: 331620352 08:14:09 INFO - PROCESS | 1647 | 08:17:12 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 08:17:13 INFO - PROCESS | 1647 | RSS: Main: 319881216 08:17:13 INFO - PROCESS | 1647 | 08:20:16 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 08:20:16 INFO - PROCESS | 1647 | RSS: Main: 407728128 08:20:16 INFO - PROCESS | 1647 | 08:20:16 INFO - PROCESS | 1647 | __start_tp_report 08:20:16 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 08:20:16 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 08:20:16 INFO - PROCESS | 1647 | |i|pagename|runs| 08:20:16 INFO - PROCESS | 1647 | |0;dojo.html;3272.492763621638;3284.441644920363;3297.6207736110355;3308.503724441335;3364.0748794081624;5440.537554307879;5495.587830912683;5543.972280138593;5576.470235327648;5577.3028371453565;5520.503154716075;5536.861578460541;5603.971980140093;5655.321361436633;5655.4910058094765;4993.925091123636;5022.773975171108;5078.720670363128;5104.183330667094;5233.973830130844;5122.3853137623355;5150.562202212816;5164.767585458663;5186.844394668147;5187.792488300483;4163.333866581363;4995.450500444948;5030.446650868402;5039.09296326661;5104.438511763703;4201.243776120297;5016.021875734234;5025.748712564357;5167.534921857051;5181.300524429192;5038.269450929594;5047.87380315495;5062.924056139124;5080.542751152377;5088.287639730441;5452.700101494416;5564.582656300794;5577.163425486169;5581.162825576196;5583.469570390806;5953.940460595382;6025.0661147522205;6040.546958978094;6067.362926892657;6071.089336599545;6105.542084343692;6107.389261073901;6171.537134714869;6177.135201071854;6191.721372538202;5393.973030134903;5535.6955367455175;5614.859628509238;5671;5674.2623458950075;6178.62928224299;6232.968835155886;6241;6253.0620406938415;6260.780872669435;5908.497777688945;5926.7925622603;5942.108683697394;5986.640801551921;5989.880202395928;5865.237519122572;5944.673042982591;6092.512598992159;6095;6110.297315808739;5838.737256823453;5934.970325148347;5947.583669143205;6017.398260173947;6031;5078.923816142761;5948.970255148696;5960.76156953717;5984.790532331347;6012.759489620454;4773.618110551148;4790.257510085942;4790.329353890458;4819.927701084486;4848.10310092634;4246.660267178619;4269.829206831754;4302.053548219386;4328.935065974013;4352.1948439538155;5557.972210138923;5563.638363506359;5588.6646801192055;5588.91616625751;5623.493885550321;6100.481459076028;6118.082287656887;6118.510519158456;6135.846603834851;6136.17161683176;4258.893527661833;5447;5481.698506582176;5565.860853478695;5712.285964254468;5226.712530810842;5277.340332458442;5286.1806420004195;5299.788008479694;5300.814471493556;6186.721597528122;6233.532485063548;6234.470070044097;6235.937640623717;6249.562530622813;6158.291796443287;6163.260408750979;6180.072989051499;6183.103449999635;6184.752609895734;5263.605229607718;6123.908141377794;6191.1641928339095;6214.5960512566535;6261.78083767066;6021.397860213943;6022.066579680157;6036.245469316335;6037.63774173551;6063.151158837678;6081.695915204311;6116.174316467398;6139.4781443577795;6141.815745527641;6144.293406258159;5706.144078388275;5791;5826.475617194473;5854.531637469077;5893.263342082239;5882.49998750111;5886.823395298148;5899.085641725539;5911.172435859069;5940.762369505172;4653.930191047205;5729.570282228933;5753.539716822728;5758.3090029196765;5765.91351129725;5698.971505142447;5766.538676905753;5834.737436815269;5901.350851406256;5916.645001299936;5749.597528172988;5762.250907382014;5771.7114144293455;5801.419858014165;5806.129080637769 08:20:16 INFO - PROCESS | 1647 | |1;ext.html;483.65902039062456;484.4259552430368;528.1364968276866;530.3979982719613;538.6121992165641;30609.69390306096;36995.81502092489;38905.4164187537;41616.37575436371;43999;42656.78671606637;42763.14473710524;42843.78578107105;42847;42851.35722964158;9768.658096966608;9956.004399560023;9958.551865166084;9962.153216976529;9992.900070999269;8866.468011919273;9067.773305667379;11991.220570662923;12235.510579576807;12249.448774805156;11977.880221197807;12026.579069732561;12034.398280086005;12048.457819398149;12088.758224835541;10988.670339889815;11148.996590306911;11894.82157767634;11996.40017999101;12011.159218854684;12037.578684746079;12116.454759535842;12122;12123.818142727867;12147.939260303641;40296;40485.59514404848;40535.7973210135;40566.79716601428;40775.592244077474;3259.3318369734147;3362.9831850840833;3438.6561343865665;3454.240067185215;3567.7859328440377;31836.840815796004;32871.17822054444;32957.6704232957;33005.834970825235;33250.84117008904;3191.824449655268;3308.5698859148397;3317.6848199421256;3346.3140056288403;3406.1825161961196;485790;492927;506327.4683626595;506483;512148;60000.39999600036;60451.79096418135;60465.69767151136;60531;60566.697166513884;62723.05915411272;63719.67223769233;63815.68092159509;63837;63864.68067659725;13685.315734213249;15386.692266154614;16205.270762815466;16219.432319868747;16219.918900405659;11319.943400282946;11588.826167607493;12517.624471265877;12608.054395920342;12611.93694031524;16847.41034063802;17167.484975450756;17269.39557115495;17483.65032699339;17547;5690.7439165237665;5705.7147142643535;6708.899366509506;6753.940048285222;6841;869.4783130121876;988.7083310423442;997.4464172384252;1018.7453136715822;1076.5424694504804;26372.681365931625;27257.454850902872;29931.40137197244;30743.846280768452;30866.228344291125;1073.4257172412722;1125.6660856884619;1142.2175809570472;1163.5927425401042;1164.55746816211;11966.700832479084;14560.344784484723;15694.68610627781;15862.462006078971;16009.359625615078;191147;191357;191511;191672;191818;59512.40487595155;59644.70177649084;59794.40205597975;59854.40145598575;59908.70045649831;1239.1883316427754;1373.7733274010077;1389.2220356600337;1410.541573988458;1414.504923276845;30736.538951915292;31895;32053;32687.836560817042;32707;50845.74577127091;51464.485355147466;51475.97048059093;51546;51657.48342516677;476077;476725.6163719159;478651;479177;479843;40026.79986600048;40699.186016278916;41354.2781149698;41598.584014159474;41650.58349416467;48233;49382;49762;49898.501014989386;49990.500094998584;39591;39594.010149746486;40115.598844012355;40291.1941761169;41584.37623435591;39771.60228397679;39893.40159897662;40654.39018414667;41318.9670258246;41448.792756036026;38760.61239387683;38874.4168837462;39440.80279598699;39636.60363396329;41936.3709544351;28049.012063123824;29665.703342966295;32093;33918.66081339155;35386.646133538336;25657.743422565534;25747.485050299263;25882.094126705473;26492.205233843015;26547.469050619267 08:20:16 INFO - PROCESS | 1647 | |2;jquery.html;352.08106841144627;367.68746565419394;370.9573399059107;382.1192152089434;401.0395103726575;15518;16338.183090845454;20066.59866802667;20524;20642.864642444678;19958.101885415155;20633.380998570028;20759.896200519015;21354.893225533888;21395.679064814;5167.870803229931;5339.06566350889;5419.349678038621;5524.1989911462815;5537.947789919923;3910.511186101737;4627;5406.351237851463;5499.3675727291475;5507.449255074501;5852;6118.63288202707;6121.173641558399;6156.138140660311;6170.321264660884;6122.602030868024;6127.958247097982;6128.785492507763;6657.300983396722;6724.193096828412;4957.330760347343;5895.616784908967;5919.437653422918;5929.703514824241;5943.3759455257;24945.376365590822;25787.35531611706;25913.61129583058;26178.476430471474;26222.9510819567;2065.2565076572423;2110.3352443980098;2310.6302991521443;2654.93802479008;3026.0619208045578;9837.163841073518;9909.90090099097;10457.320274182153;10495.947520262349;10575.947120264427;1795.838374546297;1845.1235663060074;1862.087577087231;1938.3118992757509;1968.2914150905663;120590.19114713286;122855;126904.8476121908;128052.71947280501;136796.31601842027;8792.47245165292;9038.051004644549;11454.255473394203;11506.654800355835;11546.364949927667;14615.707685846437;14627.488037918622;14630.341634626468;14648;14660.633484162981;7268.727972604773;7899.644515996794;8373.288270497076;8442.324614030986;8543.102974187745;7426.257374262531;7905.525668459911;8369.58152092393;8416.116307787594;8532.872006919777;3754.3242216400763;4499.640028797754;4510.526394728507;4510.624259600788;4511.729296242236;1761.9647607047787;1825.516238196879;1863.6365908647683;1909.2172209394082;1962.4995626115535;728.7631519756101;901.7700486375932;912.6851236323457;934.2245935873209;1000.8848982366975;18736.374806769923;20235.19059237614;21570.137194512357;21586;21792.782072179394;894.5195701114666;959.7792507723262;993.7316924430363;998.605550807427;1043.6921108273077;10154.7814262289;10421.9478902605;10647.254692171473;10764.569417223223;11138.888611113785;516885;519635;523757;538126;628617.8569107126;135855;135992;140156;140599;152590.711139333;899.6851102114208;981.1856159387692;1026.035526605004;1053.3100818963592;1054.2093429927554;13008.284544350152;13152.605421837361;18793.718094228872;20155;20597.32234809465;55008;55211.619709507584;55783;56738.432615673315;56787.86424271574;81911.39930307232;82086;83069;84410;84961;5369.57043436532;6166.599171053867;7430.922516235008;7891.368690504861;7926.207379262028;11436.599719009795;11657.358845263592;13066.54267100647;13280.667983300495;13570.728585428038;9748.85376719364;11566.361299019063;11665.59170429065;11808.350540720343;11830.290182589073;10856.782864342511;10876;11293.37886416255;11404.315741055563;11643.5342586299;10577.894221057692;10776.730581735206;11407.657770266573;11547.711307217387;11919.52321907114;6681.2650608434005;7990.121086680577;8138.918610814053;8231.506109633441;8405.234900670928;8334;10421.478926053514;11057.170712196457;11464.31214127155;11546.249493782878 08:20:16 INFO - PROCESS | 1647 | |3;mootools.html;1302.583173384517;1430.9141451512903;1540.0528674864959;1843.235057451158;1894.725264836598;8402.369822263325;8921.687740929061;8922.821543569125;8936.955315223431;8945.865812012826;7850.882236766439;8146.429749917508;8550.444221125623;8736.737897863059;8763.474191548512;2354.7056617922804;2403.6514705367713;2411.3127758588807;2411.746766589509;2412.3969007748065;2375.809935205188;2390.2948630154146;2402.1112188490197;2408.446057406789;2408.7832095111435;2047.9897600512052;2380.8452450590657;2388.868612226327;2403.639454081884;2405.4226985523524;2346.77705617966;2368.0527788884497;2371.063429945162;2378.8334816562933;2382.904683812663;2126.7022616833656;2222.2999755077262;2337.439014636475;2358.988205058981;2362.716474023111;8822.867656985087;8832.823343533157;8944.731658050268;8952.418092823946;8990.100989900957;5249.632525723163;5416.431274716177;5419.160030195326;5420.512153906128;5427.1316589345515;3420.0936751760805;3438.621751607321;3467.289205712823;3498.510208570802;3551.1122219445137;1106.9391183484904;1114.169943392178;1114.2534501883758;1130.6325444230658;1141.2638847943113;9875.40747555149;11594.6521604352;11685.006774424099;11699.590514331958;11708.765824683544;4169.958300417018;4508.684392092522;4610.884727881829;4797.376341075707;4804.73573953429;9658.90341096594;9963.25275604318;9974.102330790263;9982.650607228712;9985.051420115078;5379.35447746273;5383.757730902119;5393.9730301348245;5394.029074766501;5396.4333744957;4412.183746006935;4586.059857729158;4759.619230461624;4899.412070551557;4958.752062396866;3292.8024318540965;3341.298327351235;3810.8856734298015;3847.730658853853;3964.7224694271126;1104.9723756906142;1299.9415026323838;1306.144475368635;1313.6059182245288;1316.8551459339465;836.7949852286229;856;904.0959040959041;907.2605826251583;925.4956048953337;4781.323173393635;5623.493885550321;5644.8024319148635;5936;6016;931.5202670624634;942.3121121581285;951.538503825644;956.2780101023822;998.256299057207;3780.7353485256317;3800.890505769269;3823.541175058955;3901.044244160142;3932.665723413542;9171.678991235409;10339.017793309624;10687.519061642244;10773.245872788986;10812.243142979916;10713.410762407986;10742.194335424874;10742.462876856125;10749.355038697704;10760.892391076146;7836.608169591497;8147.551884646401;8274.669013239403;8276.751697448966;8305;4973.428055773632;5536.280283563111;5550.306211723535;5733.168690539932;5785.739641716049;9949.502524873873;9981.401115933068;10044.196464282986;10098.899011009797;10114.140298074746;9708.417494950325;10141;10205;10205.234607404325;10316.226283028653;6925.792226233221;7176.569405835667;7288.781336559911;7474.289942455458;7673.53958762476;8208.753737387888;8503.319734421355;8504.277136443472;8504.404691671523;8508.574571271289;7332.633368331455;7425.405967522694;7519.586422746802;7619.428542859197;7692.576908270099;7165.3551180394015;7339.889901651372;7393.704251829867;7500.287472690085;7570;6890.621015843976;7034.191068027242;7038.260982596754;7044.964775176296;7057.929420705727;4198.160367926488;4836.177849765619;4845.5881250094135;4903.60771138301;4991.151504244214;4367.279398899146;4793.9280910785665;4824.155772739687;4979.402471703419;4989.925151122663 08:20:16 INFO - PROCESS | 1647 | |4;prototype.html;265.4385973665697;297.16941149487184;309.1020585199995;312.18675350711396;312.8315740708455;5181.326427564416;5268.99667189702;5286.814961476349;5322.946770532293;5350.143976963677;4169.248296124559;4393.714408563441;4752.619790416767;4775.617950563956;5020.7489625518765;3479.599846017715;3986.920261594774;3993.9600603993877;4027.5771044040393;4055.4119652650347;3658.670719635232;3670.4677821715836;3845.01952002239;4015.4177644241568;4031.939520907174;3730.776153430803;3739.0278527582886;3764.3976963685814;3868.323043467382;3988.464441190148;3922.0783115967724;3957.2679054375035;3979.8856320230384;3988.142549351874;3998.3602623580086;3449.6722811332884;3450.1719587299117;3451.7238620910266;3453.6546345365514;3465.289615628815;4364.694471387004;4706.5293470653005;4953.405591329028;4985.601151907875;4989.975050124763;3751.643593858579;4466.8883277917985;4529.705569137996;4546.772661366918;4628.5140060293525;5368.32895888014;5373.516383525502;5432.70120143392;5442;5497.862553436156;3798.525184351956;3806.124591343979;3813.466114743938;3827.5789663137034;3831.923361532754;5155.252488389163;5391.299131113009;5404.756905911623;5613.494785469327;5688.232088668018;3840.539135303781;3843.9231215375535;3858.459815625815;3866.8066596670055;3869.4389313549377;3471.062813040465;3814.389697648363;3936.527616686016;3949.6445319921345;4016.5581786003518;4093.89765255871;4218.604023760902;4253.383259427365;4541.728316071505;4577.404937358122;4299.204647140289;5511.960574587429;5538.833834984957;5574.052411090124;5595.21666966625;1072.89491823422;1329.3087594451024;1363.8977076719286;1364.6861221919014;1367.2616786934943;692.9618870962146;842.8862103615934;871.5947084605689;889.4307643108286;898.5372533145435;4574.771261436915;4583.816647334136;4863.756812159378;5206.490117865852;5259.552938000236;5086.0845047891;5288.8413347599635;5590.5248053915875;5618.876224754984;5656.632318899258;4481;5006.424261210008;5073.644844860824;5173.974130129401;5215.843524694265;2477.47972925684;2919.8978035768646;2951.88192472303;2955.807872488281;2973.9405211896074;4674.929876051862;5846.619969701956;5872.559558033079;5885.323187833368;5887.676177810262;5549.694766787866;5764.971175144181;5771.971140144273;5807.535397168216;5847.766089356463;4882.267659851051;5124.8206312778875;5834.941650583524;5894.351621321651;5924.407559244128;5120.795168193231;5409.864753381197;5632.802851900164;5681.573881958952;5724.198612194379;5112.284280200849;5539.418361072029;5541.947030064275;5545.306836645419;5638.464345887134;5058.367704036996;5210.505002024802;5843.772807710428;5846.649201047951;5939.316978547522;3123.6407813100877;3649.251903359858;3664.8900532984053;3696.334659761269;3886.514185726784;4947.727874966911;5072.695638261568;5094.2613321068975;5364.436734142859;5365.3293338332705;5133.8716532087;5186.651470617591;5504.889902202013;5527;5558.305211848519;4457.732536047847;5031.798728050837;5378.97310513445;5479.424660410599;5496.422875598005;4263.445752052214;4376.715513491613;5324.733763311741;5338.078291815021;5339.279197308314;4727.4799772025735;4762.785674644719;5163.856999345229;5179.585633149414;5328.70692111922;3992.5608183099257;4447.911041779082;4755.191617424977;5144.331236939174;5489.2553723138435 08:20:16 INFO - PROCESS | 1647 | |5;yui.html;604.3442864492026;714.6176795414452;781.5271760584842;796.9321109712987;821.4824660463906;15562.45531406401;16544.090075045868;16580.253888574996;16589;16605.58486037848;14458.566243012727;14573.125612463233;14579.635509112277;14591.78112328316;14594.343254553543;669.3741351836024;826.3017750001239;830.9626066826992;847.4660963592959;855.1320409784043;850.5832142250284;851.2721623012328;851.8509260879322;853.8335024670192;855.9572021398936;909.3589019741099;910.4810258152855;911.6490151291767;911.7538264668535;914.6432891172448;702.546857277056;880.0583375787877;903.5798353765465;905.1898550797066;911;681.6251061915976;683.5215349255491;684.1687349869932;685.2428066986008;685.3044160177424;14759.262036898112;14811.629709257248;14813.629659258497;14815.259237038104;14820.777688334683;11708.297502149813;14023.859761402357;14049.578512644637;14242.92878535611;14289;13098.279594622292;13291.534796282083;13439.059265851394;13461.932690336584;13827;4574.771261436948;4637.837675681369;4639.216152961241;4672.112298663243;4875.049365373754;120323;120462;121660.39169804094;121864;122063;11143.052840508486;11165.776684466266;11354.829677554842;11568.537258509734;11794.764104717857;22770.43073923165;22794.772052279597;22803.88598056999;22805.429864253194;22868.65697014546;7085.291470852977;7737.458377913492;7741.496802707806;8024.919750802417;8065.556394398247;4448.1770872388715;4467.5532446755715;4487.461504619467;4604.723716576949;4633.675642704979;3365.730741540671;3462.0479368173956;3476.9652303477146;3484.7212223022602;3485.4074807282823;558.4638746803115;636.7230254839067;660.6267458885715;667.9031540426611;670.1656437734954;550.9366422861423;573.962692424991;615.1848800339568;629.2763322179438;653.7287025884204;7566.35685966699;9139.77150571241;9461.24310055194;9517.952410237904;9536.809263814685;619.3094699410216;642.7493277621732;654.1463390275641;661.2593894837812;672.9596224226563;4493.898994746242;4653.395058642423;4654.394928659252;4743.1697258914555;4806.735629540339;94208.05791941992;126735.3663231678;129865.70134298725;134649.0899183654;137006;117076;118350.67421966032;118597.99999999827;120774;121890;88558.55720721484;88735.11264887269;91879;92677;106865.46567267114;9053.049429809775;9475.289353298393;9651.71044868655;9694.46680432583;9728.22174226046;56786.7160664194;56850.86298274094;58660.706696466244;59018.70490647691;59489;84424;88912;88983;93965.5301723487;94025.5298723502;13169.275689836768;13317.33413329349;13500.054996150568;13656.248906310248;13850.030497865455;14783.186924718813;14968.992094469902;15152.469663561722;15309.923450382677;15360.923195383952;14068.577942661737;14069.507567235507;14313.57059288223;14373.64065898361;14863.033902796284;14028.018038737599;14059.367328470447;14178.716425671222;14213.289335533389;14661.92669036648;13683.613119063313;13748.450061997408;13937.233452160228;14052.297385130907;14417.711645767236;10847.837282440612;10900.618478353219;11218.882794557854;11900.76198476043;11985.760284794429;8874.822503549763;10620.415877126832;11557.537698491966;11626.849032928934;11674.70813229676 08:20:16 INFO - PROCESS | 1647 | __end_tp_report 08:20:16 INFO - PROCESS | 1647 | __start_cc_report 08:20:16 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1368 08:20:16 INFO - PROCESS | 1647 | __end_cc_report 08:20:16 INFO - PROCESS | 1647 | __startTimestamp1472138416882__endTimestamp 08:20:16 INFO - PROCESS | 1647 | 08:20:16 INFO - PROCESS | 1647 | ------- Summary: start ------- 08:20:16 INFO - PROCESS | 1647 | Number of tests: 6 08:20:16 INFO - PROCESS | 1647 | 08:20:16 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5563.68 Median:5753.54 stddev:647.92 (11.3%) stddev-sans-first:624.48 08:20:16 INFO - PROCESS | 1647 | Values: 3272.5 3284.4 3297.6 3308.5 3364.1 5440.5 5495.6 5544.0 5576.5 5577.3 5520.5 5536.9 5604.0 5655.3 5655.5 4993.9 5022.8 5078.7 5104.2 5234.0 5122.4 5150.6 5164.8 5186.8 5187.8 4163.3 4995.5 5030.4 5039.1 5104.4 4201.2 5016.0 5025.7 5167.5 5181.3 5038.3 5047.9 5062.9 5080.5 5088.3 5452.7 5564.6 5577.2 5581.2 5583.5 5953.9 6025.1 6040.5 6067.4 6071.1 6105.5 6107.4 6171.5 6177.1 6191.7 5394.0 5535.7 5614.9 5671.0 5674.3 6178.6 6233.0 6241.0 6253.1 6260.8 5908.5 5926.8 5942.1 5986.6 5989.9 5865.2 5944.7 6092.5 6095.0 6110.3 5838.7 5935.0 5947.6 6017.4 6031.0 5078.9 5949.0 5960.8 5984.8 6012.8 4773.6 4790.3 4790.3 4819.9 4848.1 4246.7 4269.8 4302.1 4328.9 4352.2 5558.0 5563.6 5588.7 5588.9 5623.5 6100.5 6118.1 6118.5 6135.8 6136.2 4258.9 5447.0 5481.7 5565.9 5712.3 5226.7 5277.3 5286.2 5299.8 5300.8 6186.7 6233.5 6234.5 6235.9 6249.6 6158.3 6163.3 6180.1 6183.1 6184.8 5263.6 6123.9 6191.2 6214.6 6261.8 6021.4 6022.1 6036.2 6037.6 6063.2 6081.7 6116.2 6139.5 6141.8 6144.3 5706.1 5791.0 5826.5 5854.5 5893.3 5882.5 5886.8 5899.1 5911.2 5940.8 4653.9 5729.6 5753.5 5758.3 5765.9 5699.0 5766.5 5834.7 5901.4 5916.6 5749.6 5762.3 5771.7 5801.4 5806.1 08:20:16 INFO - PROCESS | 1647 | 08:20:16 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:56206.41 Median:20281.80 stddev:110418.00 (544.4%) stddev-sans-first:110648.49 08:20:16 INFO - PROCESS | 1647 | Values: 483.7 484.4 528.1 530.4 538.6 30609.7 36995.8 38905.4 41616.4 43999.0 42656.8 42763.1 42843.8 42847.0 42851.4 9768.7 9956.0 9958.6 9962.2 9992.9 8866.5 9067.8 11991.2 12235.5 12249.4 11977.9 12026.6 12034.4 12048.5 12088.8 10988.7 11149.0 11894.8 11996.4 12011.2 12037.6 12116.5 12122.0 12123.8 12147.9 40296.0 40485.6 40535.8 40566.8 40775.6 3259.3 3363.0 3438.7 3454.2 3567.8 31836.8 32871.2 32957.7 33005.8 33250.8 3191.8 3308.6 3317.7 3346.3 3406.2 485790.0 492927.0 506327.5 506483.0 512148.0 60000.4 60451.8 60465.7 60531.0 60566.7 62723.1 63719.7 63815.7 63837.0 63864.7 13685.3 15386.7 16205.3 16219.4 16219.9 11319.9 11588.8 12517.6 12608.1 12611.9 16847.4 17167.5 17269.4 17483.7 17547.0 5690.7 5705.7 6708.9 6753.9 6841.0 869.5 988.7 997.4 1018.7 1076.5 26372.7 27257.5 29931.4 30743.8 30866.2 1073.4 1125.7 1142.2 1163.6 1164.6 11966.7 14560.3 15694.7 15862.5 16009.4 191147.0 191357.0 191511.0 191672.0 191818.0 59512.4 59644.7 59794.4 59854.4 59908.7 1239.2 1373.8 1389.2 1410.5 1414.5 30736.5 31895.0 32053.0 32687.8 32707.0 50845.7 51464.5 51476.0 51546.0 51657.5 476077.0 476725.6 478651.0 479177.0 479843.0 40026.8 40699.2 41354.3 41598.6 41650.6 48233.0 49382.0 49762.0 49898.5 49990.5 39591.0 39594.0 40115.6 40291.2 41584.4 39771.6 39893.4 40654.4 41319.0 41448.8 38760.6 38874.4 39440.8 39636.6 41936.4 28049.0 29665.7 32093.0 33918.7 35386.6 25657.7 25747.5 25882.1 26492.2 26547.5 08:20:16 INFO - PROCESS | 1647 | 08:20:16 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:34228.02 Median:26200.71 stddev:92614.14 (353.5%) stddev-sans-first:92839.02 08:20:16 INFO - PROCESS | 1647 | Values: 352.1 367.7 371.0 382.1 401.0 15518.0 16338.2 20066.6 20524.0 20642.9 19958.1 20633.4 20759.9 21354.9 21395.7 5167.9 5339.1 5419.3 5524.2 5537.9 3910.5 4627.0 5406.4 5499.4 5507.4 5852.0 6118.6 6121.2 6156.1 6170.3 6122.6 6128.0 6128.8 6657.3 6724.2 4957.3 5895.6 5919.4 5929.7 5943.4 24945.4 25787.4 25913.6 26178.5 26223.0 2065.3 2110.3 2310.6 2654.9 3026.1 9837.2 9909.9 10457.3 10495.9 10575.9 1795.8 1845.1 1862.1 1938.3 1968.3 120590.2 122855.0 126904.8 128052.7 136796.3 8792.5 9038.1 11454.3 11506.7 11546.4 14615.7 14627.5 14630.3 14648.0 14660.6 7268.7 7899.6 8373.3 8442.3 8543.1 7426.3 7905.5 8369.6 8416.1 8532.9 3754.3 4499.6 4510.5 4510.6 4511.7 1762.0 1825.5 1863.6 1909.2 1962.5 728.8 901.8 912.7 934.2 1000.9 18736.4 20235.2 21570.1 21586.0 21792.8 894.5 959.8 993.7 998.6 1043.7 10154.8 10421.9 10647.3 10764.6 11138.9 516885.0 519635.0 523757.0 538126.0 628617.9 135855.0 135992.0 140156.0 140599.0 152590.7 899.7 981.2 1026.0 1053.3 1054.2 13008.3 13152.6 18793.7 20155.0 20597.3 55008.0 55211.6 55783.0 56738.4 56787.9 81911.4 82086.0 83069.0 84410.0 84961.0 5369.6 6166.6 7430.9 7891.4 7926.2 11436.6 11657.4 13066.5 13280.7 13570.7 9748.9 11566.4 11665.6 11808.4 11830.3 10856.8 10876.0 11293.4 11404.3 11643.5 10577.9 10776.7 11407.7 11547.7 11919.5 6681.3 7990.1 8138.9 8231.5 8405.2 8334.0 10421.5 11057.2 11464.3 11546.2 08:20:16 INFO - PROCESS | 1647 | 08:20:16 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5660.77 Median:4901.51 stddev:3188.20 (65.0%) stddev-sans-first:3180.32 08:20:16 INFO - PROCESS | 1647 | Values: 1302.6 1430.9 1540.1 1843.2 1894.7 8402.4 8921.7 8922.8 8937.0 8945.9 7850.9 8146.4 8550.4 8736.7 8763.5 2354.7 2403.7 2411.3 2411.7 2412.4 2375.8 2390.3 2402.1 2408.4 2408.8 2048.0 2380.8 2388.9 2403.6 2405.4 2346.8 2368.1 2371.1 2378.8 2382.9 2126.7 2222.3 2337.4 2359.0 2362.7 8822.9 8832.8 8944.7 8952.4 8990.1 5249.6 5416.4 5419.2 5420.5 5427.1 3420.1 3438.6 3467.3 3498.5 3551.1 1106.9 1114.2 1114.3 1130.6 1141.3 9875.4 11594.7 11685.0 11699.6 11708.8 4170.0 4508.7 4610.9 4797.4 4804.7 9658.9 9963.3 9974.1 9982.7 9985.1 5379.4 5383.8 5394.0 5394.0 5396.4 4412.2 4586.1 4759.6 4899.4 4958.8 3292.8 3341.3 3810.9 3847.7 3964.7 1105.0 1299.9 1306.1 1313.6 1316.9 836.8 856.0 904.1 907.3 925.5 4781.3 5623.5 5644.8 5936.0 6016.0 931.5 942.3 951.5 956.3 998.3 3780.7 3800.9 3823.5 3901.0 3932.7 9171.7 10339.0 10687.5 10773.2 10812.2 10713.4 10742.2 10742.5 10749.4 10760.9 7836.6 8147.6 8274.7 8276.8 8305.0 4973.4 5536.3 5550.3 5733.2 5785.7 9949.5 9981.4 10044.2 10098.9 10114.1 9708.4 10141.0 10205.0 10205.2 10316.2 6925.8 7176.6 7288.8 7474.3 7673.5 8208.8 8503.3 8504.3 8504.4 8508.6 7332.6 7425.4 7519.6 7619.4 7692.6 7165.4 7339.9 7393.7 7500.3 7570.0 6890.6 7034.2 7038.3 7045.0 7057.9 4198.2 4836.2 4845.6 4903.6 4991.2 4367.3 4793.9 4824.2 4979.4 4989.9 08:20:16 INFO - PROCESS | 1647 | 08:20:16 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4396.41 Median:4915.00 stddev:1342.80 (27.3%) stddev-sans-first:1310.29 08:20:16 INFO - PROCESS | 1647 | Values: 265.4 297.2 309.1 312.2 312.8 5181.3 5269.0 5286.8 5322.9 5350.1 4169.2 4393.7 4752.6 4775.6 5020.7 3479.6 3986.9 3994.0 4027.6 4055.4 3658.7 3670.5 3845.0 4015.4 4031.9 3730.8 3739.0 3764.4 3868.3 3988.5 3922.1 3957.3 3979.9 3988.1 3998.4 3449.7 3450.2 3451.7 3453.7 3465.3 4364.7 4706.5 4953.4 4985.6 4990.0 3751.6 4466.9 4529.7 4546.8 4628.5 5368.3 5373.5 5432.7 5442.0 5497.9 3798.5 3806.1 3813.5 3827.6 3831.9 5155.3 5391.3 5404.8 5613.5 5688.2 3840.5 3843.9 3858.5 3866.8 3869.4 3471.1 3814.4 3936.5 3949.6 4016.6 4093.9 4218.6 4253.4 4541.7 4577.4 4299.2 5512.0 5538.8 5574.1 5595.2 1072.9 1329.3 1363.9 1364.7 1367.3 693.0 842.9 871.6 889.4 898.5 4574.8 4583.8 4863.8 5206.5 5259.6 5086.1 5288.8 5590.5 5618.9 5656.6 4481.0 5006.4 5073.6 5174.0 5215.8 2477.5 2919.9 2951.9 2955.8 2973.9 4674.9 5846.6 5872.6 5885.3 5887.7 5549.7 5765.0 5772.0 5807.5 5847.8 4882.3 5124.8 5834.9 5894.4 5924.4 5120.8 5409.9 5632.8 5681.6 5724.2 5112.3 5539.4 5541.9 5545.3 5638.5 5058.4 5210.5 5843.8 5846.6 5939.3 3123.6 3649.3 3664.9 3696.3 3886.5 4947.7 5072.7 5094.3 5364.4 5365.3 5133.9 5186.7 5504.9 5527.0 5558.3 4457.7 5031.8 5379.0 5479.4 5496.4 4263.4 4376.7 5324.7 5338.1 5339.3 4727.5 4762.8 5163.9 5179.6 5328.7 3992.6 4447.9 4755.2 5144.3 5489.3 08:20:16 INFO - PROCESS | 1647 | 08:20:16 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:23830.05 Median:4477.51 stddev:36573.41 (816.8%) stddev-sans-first:36634.43 08:20:16 INFO - PROCESS | 1647 | Values: 604.3 714.6 781.5 796.9 821.5 15562.5 16544.1 16580.3 16589.0 16605.6 14458.6 14573.1 14579.6 14591.8 14594.3 669.4 826.3 831.0 847.5 855.1 850.6 851.3 851.9 853.8 856.0 909.4 910.5 911.6 911.8 914.6 702.5 880.1 903.6 905.2 911.0 681.6 683.5 684.2 685.2 685.3 14759.3 14811.6 14813.6 14815.3 14820.8 11708.3 14023.9 14049.6 14242.9 14289.0 13098.3 13291.5 13439.1 13461.9 13827.0 4574.8 4637.8 4639.2 4672.1 4875.0 120323.0 120462.0 121660.4 121864.0 122063.0 11143.1 11165.8 11354.8 11568.5 11794.8 22770.4 22794.8 22803.9 22805.4 22868.7 7085.3 7737.5 7741.5 8024.9 8065.6 4448.2 4467.6 4487.5 4604.7 4633.7 3365.7 3462.0 3477.0 3484.7 3485.4 558.5 636.7 660.6 667.9 670.2 550.9 574.0 615.2 629.3 653.7 7566.4 9139.8 9461.2 9518.0 9536.8 619.3 642.7 654.1 661.3 673.0 4493.9 4653.4 4654.4 4743.2 4806.7 94208.1 126735.4 129865.7 134649.1 137006.0 117076.0 118350.7 118598.0 120774.0 121890.0 88558.6 88735.1 91879.0 92677.0 106865.5 9053.0 9475.3 9651.7 9694.5 9728.2 56786.7 56850.9 58660.7 59018.7 59489.0 84424.0 88912.0 88983.0 93965.5 94025.5 13169.3 13317.3 13500.1 13656.2 13850.0 14783.2 14969.0 15152.5 15309.9 15360.9 14068.6 14069.5 14313.6 14373.6 14863.0 14028.0 14059.4 14178.7 14213.3 14661.9 13683.6 13748.5 13937.2 14052.3 14417.7 10847.8 10900.6 11218.9 11900.8 11985.8 8874.8 10620.4 11557.5 11626.8 11674.7 08:20:16 INFO - PROCESS | 1647 | -------- Summary: end -------- 08:20:16 INFO - PROCESS | 1647 | 08:20:17 INFO - TEST-INFO | 1647: exit 0 08:20:17 INFO - TEST-OK | dromaeo_css | took 1117154ms 08:20:17 INFO - TEST-START | kraken 08:20:17 INFO - operating with platform_type : mac_ 08:20:17 INFO - Initialising browser for kraken test... 08:20:17 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpt6ItE9/profile http://localhost:49277/getInfo.html) 08:20:19 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 08:20:19 INFO - PROCESS | 1656 | colorDepth:24 08:20:19 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 08:20:19 INFO - PROCESS | 1656 | __metrics 08:20:19 INFO - PROCESS | 1656 | 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" 08:20:19 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:20:20 INFO - TEST-INFO | 1656: exit 0 08:20:20 INFO - Browser initialized. 08:20:20 INFO - Running cycle 1/1 for kraken test... 08:20:20 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpt6ItE9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:20:32 INFO - PROCESS | 1657 | RSS: Main: 232112128 08:20:32 INFO - PROCESS | 1657 | 08:20:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 08:20:36 INFO - PROCESS | 1657 | RSS: Main: 292421632 08:20:36 INFO - PROCESS | 1657 | 08:20:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 08:20:39 INFO - PROCESS | 1657 | RSS: Main: 287588352 08:20:39 INFO - PROCESS | 1657 | 08:20:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 08:20:43 INFO - PROCESS | 1657 | RSS: Main: 377212928 08:20:43 INFO - PROCESS | 1657 | 08:20:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 08:20:46 INFO - PROCESS | 1657 | RSS: Main: 279834624 08:20:46 INFO - PROCESS | 1657 | 08:20:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:20:49 INFO - PROCESS | 1657 | RSS: Main: 281833472 08:20:49 INFO - PROCESS | 1657 | 08:20:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 08:20:52 INFO - PROCESS | 1657 | RSS: Main: 318054400 08:20:52 INFO - PROCESS | 1657 | 08:20:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 08:20:55 INFO - PROCESS | 1657 | RSS: Main: 319995904 08:20:55 INFO - PROCESS | 1657 | 08:20:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 08:20:59 INFO - PROCESS | 1657 | RSS: Main: 320413696 08:20:59 INFO - PROCESS | 1657 | 08:21:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:21:01 INFO - PROCESS | 1657 | RSS: Main: 296943616 08:21:01 INFO - PROCESS | 1657 | 08:21:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:21:04 INFO - PROCESS | 1657 | RSS: Main: 300359680 08:21:04 INFO - PROCESS | 1657 | 08:21:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:21:07 INFO - PROCESS | 1657 | RSS: Main: 303972352 08:21:07 INFO - PROCESS | 1657 | 08:21:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:21:10 INFO - PROCESS | 1657 | RSS: Main: 317968384 08:21:10 INFO - PROCESS | 1657 | 08:21:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:21:14 INFO - PROCESS | 1657 | RSS: Main: 314277888 08:21:14 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 08:21:16 INFO - PROCESS | 1657 | RSS: Main: 357629952 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | __start_tp_report 08:21:16 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 08:21:16 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 08:21:16 INFO - PROCESS | 1657 | |i|pagename|runs| 08:21:16 INFO - PROCESS | 1657 | |0;ai-astar;113;113;128;113;112;117;155;165;113;111 08:21:16 INFO - PROCESS | 1657 | |1;audio-beat-detection;143;107;127;158;108;130;139;121;155;91 08:21:16 INFO - PROCESS | 1657 | |2;audio-dft;183;184;184;183;183;184;179;185;182;182 08:21:16 INFO - PROCESS | 1657 | |3;audio-fft;77;73;73;153;64;67;81;94;161;48 08:21:16 INFO - PROCESS | 1657 | |4;audio-oscillator;83;83;135;70;81;78;124;94;100;152 08:21:16 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;90;86;91;88;89;86;90;87;90;86 08:21:16 INFO - PROCESS | 1657 | |6;imaging-darkroom;93;95;95;97;94;95;95;96;94;94 08:21:16 INFO - PROCESS | 1657 | |7;imaging-desaturate;84;86;84;112;130;136;85;87;85;86 08:21:16 INFO - PROCESS | 1657 | |8;json-parse-financial;56;55;55;55;54;55;56;55;55;55 08:21:16 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;64;69;67;81;103;108;100;68;70;68 08:21:16 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;86;87;84;84;86;87;84;88;88;95 08:21:16 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;148;152;141;137;135;137;137;140;177;138 08:21:16 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;168;171;169;173;170;170;171;168;168;169 08:21:16 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;58;64;83;71;67;67;66;67;75;83 08:21:16 INFO - PROCESS | 1657 | __end_tp_report 08:21:16 INFO - PROCESS | 1657 | __start_cc_report 08:21:16 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2157 08:21:16 INFO - PROCESS | 1657 | __end_cc_report 08:21:16 INFO - PROCESS | 1657 | __startTimestamp1472138476279__endTimestamp 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | ------- Summary: start ------- 08:21:16 INFO - PROCESS | 1657 | Number of tests: 14 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:124.00 Median:115.00 stddev:19.73 (17.2%) stddev-sans-first:20.52 08:21:16 INFO - PROCESS | 1657 | Values: 113.0 113.0 128.0 113.0 112.0 117.0 155.0 165.0 113.0 111.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:141.00 stddev:21.70 (15.4%) stddev-sans-first:22.32 08:21:16 INFO - PROCESS | 1657 | Values: 143.0 107.0 127.0 158.0 108.0 130.0 139.0 121.0 155.0 91.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.50 stddev:1.66 (0.9%) stddev-sans-first:1.76 08:21:16 INFO - PROCESS | 1657 | Values: 183.0 184.0 184.0 183.0 183.0 184.0 179.0 185.0 182.0 182.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:89.10 Median:73.00 stddev:37.73 (51.7%) stddev-sans-first:39.77 08:21:16 INFO - PROCESS | 1657 | Values: 77.0 73.0 73.0 153.0 64.0 67.0 81.0 94.0 161.0 48.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:100.00 Median:79.50 stddev:27.62 (34.7%) stddev-sans-first:28.60 08:21:16 INFO - PROCESS | 1657 | Values: 83.0 83.0 135.0 70.0 81.0 78.0 124.0 94.0 100.0 152.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.50 stddev:1.95 (2.2%) stddev-sans-first:1.96 08:21:16 INFO - PROCESS | 1657 | Values: 90.0 86.0 91.0 88.0 89.0 86.0 90.0 87.0 90.0 86.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:1.14 (1.2%) stddev-sans-first:1.00 08:21:16 INFO - PROCESS | 1657 | Values: 93.0 95.0 95.0 97.0 94.0 95.0 95.0 96.0 94.0 94.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:97.50 Median:85.00 stddev:20.55 (24.2%) stddev-sans-first:21.21 08:21:16 INFO - PROCESS | 1657 | Values: 84.0 86.0 84.0 112.0 130.0 136.0 85.0 87.0 85.0 86.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:0.57 (1.0%) stddev-sans-first:0.50 08:21:16 INFO - PROCESS | 1657 | Values: 56.0 55.0 55.0 55.0 54.0 55.0 56.0 55.0 55.0 55.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:79.80 Median:68.00 stddev:17.15 (25.2%) stddev-sans-first:17.21 08:21:16 INFO - PROCESS | 1657 | Values: 64.0 69.0 67.0 81.0 103.0 108.0 100.0 68.0 70.0 68.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:87.00 stddev:3.25 (3.7%) stddev-sans-first:3.43 08:21:16 INFO - PROCESS | 1657 | Values: 86.0 87.0 84.0 84.0 86.0 87.0 84.0 88.0 88.0 95.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:140.50 stddev:12.73 (9.1%) stddev-sans-first:13.42 08:21:16 INFO - PROCESS | 1657 | Values: 148.0 152.0 141.0 137.0 135.0 137.0 137.0 140.0 177.0 138.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:170.00 stddev:1.64 (1.0%) stddev-sans-first:1.62 08:21:16 INFO - PROCESS | 1657 | Values: 168.0 171.0 169.0 173.0 170.0 170.0 171.0 168.0 168.0 169.0 08:21:16 INFO - PROCESS | 1657 | 08:21:16 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:69.00 stddev:8.08 (11.7%) stddev-sans-first:7.28 08:21:16 INFO - PROCESS | 1657 | Values: 58.0 64.0 83.0 71.0 67.0 67.0 66.0 67.0 75.0 83.0 08:21:16 INFO - PROCESS | 1657 | -------- Summary: end -------- 08:21:16 INFO - PROCESS | 1657 | 08:21:17 INFO - TEST-INFO | 1657: exit 0 08:21:17 INFO - TEST-OK | kraken | took 59686ms 08:21:17 INFO - SUITE-END | took 1176s 08:21:17 INFO - Completed test suite (00:19:37) 08:21:17 INFO - javascript benchmark 08:21:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.492763621638, 3284.441644920363, 3297.6207736110355, 3308.503724441335, 3364.0748794081624, 5440.537554307879, 5495.587830912683, 5543.972280138593, 5576.470235327648, 5577.3028371453565, 5520.503154716075, 5536.861578460541, 5603.971980140093, 5655.321361436633, 5655.4910058094765, 4993.925091123636, 5022.773975171108, 5078.720670363128, 5104.183330667094, 5233.973830130844, 5122.3853137623355, 5150.562202212816, 5164.767585458663, 5186.844394668147, 5187.792488300483, 4163.333866581363, 4995.450500444948, 5030.446650868402, 5039.09296326661, 5104.438511763703, 4201.243776120297, 5016.021875734234, 5025.748712564357, 5167.534921857051, 5181.300524429192, 5038.269450929594, 5047.87380315495, 5062.924056139124, 5080.542751152377, 5088.287639730441, 5452.700101494416, 5564.582656300794, 5577.163425486169, 5581.162825576196, 5583.469570390806, 5953.940460595382, 6025.0661147522205, 6040.546958978094, 6067.362926892657, 6071.089336599545, 6105.542084343692, 6107.389261073901, 6171.537134714869, 6177.135201071854, 6191.721372538202, 5393.973030134903, 5535.6955367455175, 5614.859628509238, 5671.0, 5674.2623458950075, 6178.62928224299, 6232.968835155886, 6241.0, 6253.0620406938415, 6260.780872669435, 5908.497777688945, 5926.7925622603, 5942.108683697394, 5986.640801551921, 5989.880202395928, 5865.237519122572, 5944.673042982591, 6092.512598992159, 6095.0, 6110.297315808739, 5838.737256823453, 5934.970325148347, 5947.583669143205, 6017.398260173947, 6031.0, 5078.923816142761, 5948.970255148696, 5960.76156953717, 5984.790532331347, 6012.759489620454, 4773.618110551148, 4790.257510085942, 4790.329353890458, 4819.927701084486, 4848.10310092634, 4246.660267178619, 4269.829206831754, 4302.053548219386, 4328.935065974013, 4352.1948439538155, 5557.972210138923, 5563.638363506359, 5588.6646801192055, 5588.91616625751, 5623.493885550321, 6100.481459076028, 6118.082287656887, 6118.510519158456, 6135.846603834851, 6136.17161683176, 4258.893527661833, 5447.0, 5481.698506582176, 5565.860853478695, 5712.285964254468, 5226.712530810842, 5277.340332458442, 5286.1806420004195, 5299.788008479694, 5300.814471493556, 6186.721597528122, 6233.532485063548, 6234.470070044097, 6235.937640623717, 6249.562530622813, 6158.291796443287, 6163.260408750979, 6180.072989051499, 6183.103449999635, 6184.752609895734, 5263.605229607718, 6123.908141377794, 6191.1641928339095, 6214.5960512566535, 6261.78083767066, 6021.397860213943, 6022.066579680157, 6036.245469316335, 6037.63774173551, 6063.151158837678, 6081.695915204311, 6116.174316467398, 6139.4781443577795, 6141.815745527641, 6144.293406258159, 5706.144078388275, 5791.0, 5826.475617194473, 5854.531637469077, 5893.263342082239, 5882.49998750111, 5886.823395298148, 5899.085641725539, 5911.172435859069, 5940.762369505172, 4653.930191047205, 5729.570282228933, 5753.539716822728, 5758.3090029196765, 5765.91351129725, 5698.971505142447, 5766.538676905753, 5834.737436815269, 5901.350851406256, 5916.645001299936, 5749.597528172988, 5762.250907382014, 5771.7114144293455, 5801.419858014165, 5806.129080637769], "value": 5523.075142392048, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.65902039062456, 484.4259552430368, 528.1364968276866, 530.3979982719613, 538.6121992165641, 30609.69390306096, 36995.81502092489, 38905.4164187537, 41616.37575436371, 43999.0, 42656.78671606637, 42763.14473710524, 42843.78578107105, 42847.0, 42851.35722964158, 9768.658096966608, 9956.004399560023, 9958.551865166084, 9962.153216976529, 9992.900070999269, 8866.468011919273, 9067.773305667379, 11991.220570662923, 12235.510579576807, 12249.448774805156, 11977.880221197807, 12026.579069732561, 12034.398280086005, 12048.457819398149, 12088.758224835541, 10988.670339889815, 11148.996590306911, 11894.82157767634, 11996.40017999101, 12011.159218854684, 12037.578684746079, 12116.454759535842, 12122.0, 12123.818142727867, 12147.939260303641, 40296.0, 40485.59514404848, 40535.7973210135, 40566.79716601428, 40775.592244077474, 3259.3318369734147, 3362.9831850840833, 3438.6561343865665, 3454.240067185215, 3567.7859328440377, 31836.840815796004, 32871.17822054444, 32957.6704232957, 33005.834970825235, 33250.84117008904, 3191.824449655268, 3308.5698859148397, 3317.6848199421256, 3346.3140056288403, 3406.1825161961196, 485790.0, 492927.0, 506327.4683626595, 506483.0, 512148.0, 60000.39999600036, 60451.79096418135, 60465.69767151136, 60531.0, 60566.697166513884, 62723.05915411272, 63719.67223769233, 63815.68092159509, 63837.0, 63864.68067659725, 13685.315734213249, 15386.692266154614, 16205.270762815466, 16219.432319868747, 16219.918900405659, 11319.943400282946, 11588.826167607493, 12517.624471265877, 12608.054395920342, 12611.93694031524, 16847.41034063802, 17167.484975450756, 17269.39557115495, 17483.65032699339, 17547.0, 5690.7439165237665, 5705.7147142643535, 6708.899366509506, 6753.940048285222, 6841.0, 869.4783130121876, 988.7083310423442, 997.4464172384252, 1018.7453136715822, 1076.5424694504804, 26372.681365931625, 27257.454850902872, 29931.40137197244, 30743.846280768452, 30866.228344291125, 1073.4257172412722, 1125.6660856884619, 1142.2175809570472, 1163.5927425401042, 1164.55746816211, 11966.700832479084, 14560.344784484723, 15694.68610627781, 15862.462006078971, 16009.359625615078, 191147.0, 191357.0, 191511.0, 191672.0, 191818.0, 59512.40487595155, 59644.70177649084, 59794.40205597975, 59854.40145598575, 59908.70045649831, 1239.1883316427754, 1373.7733274010077, 1389.2220356600337, 1410.541573988458, 1414.504923276845, 30736.538951915292, 31895.0, 32053.0, 32687.836560817042, 32707.0, 50845.74577127091, 51464.485355147466, 51475.97048059093, 51546.0, 51657.48342516677, 476077.0, 476725.6163719159, 478651.0, 479177.0, 479843.0, 40026.79986600048, 40699.186016278916, 41354.2781149698, 41598.584014159474, 41650.58349416467, 48233.0, 49382.0, 49762.0, 49898.501014989386, 49990.500094998584, 39591.0, 39594.010149746486, 40115.598844012355, 40291.1941761169, 41584.37623435591, 39771.60228397679, 39893.40159897662, 40654.39018414667, 41318.9670258246, 41448.792756036026, 38760.61239387683, 38874.4168837462, 39440.80279598699, 39636.60363396329, 41936.3709544351, 28049.012063123824, 29665.703342966295, 32093.0, 33918.66081339155, 35386.646133538336, 25657.743422565534, 25747.485050299263, 25882.094126705473, 26492.205233843015, 26547.469050619267], "value": 19925.369379258787, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.08106841144627, 367.68746565419394, 370.9573399059107, 382.1192152089434, 401.0395103726575, 15518.0, 16338.183090845454, 20066.59866802667, 20524.0, 20642.864642444678, 19958.101885415155, 20633.380998570028, 20759.896200519015, 21354.893225533888, 21395.679064814, 5167.870803229931, 5339.06566350889, 5419.349678038621, 5524.1989911462815, 5537.947789919923, 3910.511186101737, 4627.0, 5406.351237851463, 5499.3675727291475, 5507.449255074501, 5852.0, 6118.63288202707, 6121.173641558399, 6156.138140660311, 6170.321264660884, 6122.602030868024, 6127.958247097982, 6128.785492507763, 6657.300983396722, 6724.193096828412, 4957.330760347343, 5895.616784908967, 5919.437653422918, 5929.703514824241, 5943.3759455257, 24945.376365590822, 25787.35531611706, 25913.61129583058, 26178.476430471474, 26222.9510819567, 2065.2565076572423, 2110.3352443980098, 2310.6302991521443, 2654.93802479008, 3026.0619208045578, 9837.163841073518, 9909.90090099097, 10457.320274182153, 10495.947520262349, 10575.947120264427, 1795.838374546297, 1845.1235663060074, 1862.087577087231, 1938.3118992757509, 1968.2914150905663, 120590.19114713286, 122855.0, 126904.8476121908, 128052.71947280501, 136796.31601842027, 8792.47245165292, 9038.051004644549, 11454.255473394203, 11506.654800355835, 11546.364949927667, 14615.707685846437, 14627.488037918622, 14630.341634626468, 14648.0, 14660.633484162981, 7268.727972604773, 7899.644515996794, 8373.288270497076, 8442.324614030986, 8543.102974187745, 7426.257374262531, 7905.525668459911, 8369.58152092393, 8416.116307787594, 8532.872006919777, 3754.3242216400763, 4499.640028797754, 4510.526394728507, 4510.624259600788, 4511.729296242236, 1761.9647607047787, 1825.516238196879, 1863.6365908647683, 1909.2172209394082, 1962.4995626115535, 728.7631519756101, 901.7700486375932, 912.6851236323457, 934.2245935873209, 1000.8848982366975, 18736.374806769923, 20235.19059237614, 21570.137194512357, 21586.0, 21792.782072179394, 894.5195701114666, 959.7792507723262, 993.7316924430363, 998.605550807427, 1043.6921108273077, 10154.7814262289, 10421.9478902605, 10647.254692171473, 10764.569417223223, 11138.888611113785, 516885.0, 519635.0, 523757.0, 538126.0, 628617.8569107126, 135855.0, 135992.0, 140156.0, 140599.0, 152590.711139333, 899.6851102114208, 981.1856159387692, 1026.035526605004, 1053.3100818963592, 1054.2093429927554, 13008.284544350152, 13152.605421837361, 18793.718094228872, 20155.0, 20597.32234809465, 55008.0, 55211.619709507584, 55783.0, 56738.432615673315, 56787.86424271574, 81911.39930307232, 82086.0, 83069.0, 84410.0, 84961.0, 5369.57043436532, 6166.599171053867, 7430.922516235008, 7891.368690504861, 7926.207379262028, 11436.599719009795, 11657.358845263592, 13066.54267100647, 13280.667983300495, 13570.728585428038, 9748.85376719364, 11566.361299019063, 11665.59170429065, 11808.350540720343, 11830.290182589073, 10856.782864342511, 10876.0, 11293.37886416255, 11404.315741055563, 11643.5342586299, 10577.894221057692, 10776.730581735206, 11407.657770266573, 11547.711307217387, 11919.52321907114, 6681.2650608434005, 7990.121086680577, 8138.918610814053, 8231.506109633441, 8405.234900670928, 8334.0, 10421.478926053514, 11057.170712196457, 11464.31214127155, 11546.249493782878], "value": 9462.585698906752, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.583173384517, 1430.9141451512903, 1540.0528674864959, 1843.235057451158, 1894.725264836598, 8402.369822263325, 8921.687740929061, 8922.821543569125, 8936.955315223431, 8945.865812012826, 7850.882236766439, 8146.429749917508, 8550.444221125623, 8736.737897863059, 8763.474191548512, 2354.7056617922804, 2403.6514705367713, 2411.3127758588807, 2411.746766589509, 2412.3969007748065, 2375.809935205188, 2390.2948630154146, 2402.1112188490197, 2408.446057406789, 2408.7832095111435, 2047.9897600512052, 2380.8452450590657, 2388.868612226327, 2403.639454081884, 2405.4226985523524, 2346.77705617966, 2368.0527788884497, 2371.063429945162, 2378.8334816562933, 2382.904683812663, 2126.7022616833656, 2222.2999755077262, 2337.439014636475, 2358.988205058981, 2362.716474023111, 8822.867656985087, 8832.823343533157, 8944.731658050268, 8952.418092823946, 8990.100989900957, 5249.632525723163, 5416.431274716177, 5419.160030195326, 5420.512153906128, 5427.1316589345515, 3420.0936751760805, 3438.621751607321, 3467.289205712823, 3498.510208570802, 3551.1122219445137, 1106.9391183484904, 1114.169943392178, 1114.2534501883758, 1130.6325444230658, 1141.2638847943113, 9875.40747555149, 11594.6521604352, 11685.006774424099, 11699.590514331958, 11708.765824683544, 4169.958300417018, 4508.684392092522, 4610.884727881829, 4797.376341075707, 4804.73573953429, 9658.90341096594, 9963.25275604318, 9974.102330790263, 9982.650607228712, 9985.051420115078, 5379.35447746273, 5383.757730902119, 5393.9730301348245, 5394.029074766501, 5396.4333744957, 4412.183746006935, 4586.059857729158, 4759.619230461624, 4899.412070551557, 4958.752062396866, 3292.8024318540965, 3341.298327351235, 3810.8856734298015, 3847.730658853853, 3964.7224694271126, 1104.9723756906142, 1299.9415026323838, 1306.144475368635, 1313.6059182245288, 1316.8551459339465, 836.7949852286229, 856.0, 904.0959040959041, 907.2605826251583, 925.4956048953337, 4781.323173393635, 5623.493885550321, 5644.8024319148635, 5936.0, 6016.0, 931.5202670624634, 942.3121121581285, 951.538503825644, 956.2780101023822, 998.256299057207, 3780.7353485256317, 3800.890505769269, 3823.541175058955, 3901.044244160142, 3932.665723413542, 9171.678991235409, 10339.017793309624, 10687.519061642244, 10773.245872788986, 10812.243142979916, 10713.410762407986, 10742.194335424874, 10742.462876856125, 10749.355038697704, 10760.892391076146, 7836.608169591497, 8147.551884646401, 8274.669013239403, 8276.751697448966, 8305.0, 4973.428055773632, 5536.280283563111, 5550.306211723535, 5733.168690539932, 5785.739641716049, 9949.502524873873, 9981.401115933068, 10044.196464282986, 10098.899011009797, 10114.140298074746, 9708.417494950325, 10141.0, 10205.0, 10205.234607404325, 10316.226283028653, 6925.792226233221, 7176.569405835667, 7288.781336559911, 7474.289942455458, 7673.53958762476, 8208.753737387888, 8503.319734421355, 8504.277136443472, 8504.404691671523, 8508.574571271289, 7332.633368331455, 7425.405967522694, 7519.586422746802, 7619.428542859197, 7692.576908270099, 7165.3551180394015, 7339.889901651372, 7393.704251829867, 7500.287472690085, 7570.0, 6890.621015843976, 7034.191068027242, 7038.260982596754, 7044.964775176296, 7057.929420705727, 4198.160367926488, 4836.177849765619, 4845.5881250094135, 4903.60771138301, 4991.151504244214, 4367.279398899146, 4793.9280910785665, 4824.155772739687, 4979.402471703419, 4989.925151122663], "value": 4552.25763183368, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.4385973665697, 297.16941149487184, 309.1020585199995, 312.18675350711396, 312.8315740708455, 5181.326427564416, 5268.99667189702, 5286.814961476349, 5322.946770532293, 5350.143976963677, 4169.248296124559, 4393.714408563441, 4752.619790416767, 4775.617950563956, 5020.7489625518765, 3479.599846017715, 3986.920261594774, 3993.9600603993877, 4027.5771044040393, 4055.4119652650347, 3658.670719635232, 3670.4677821715836, 3845.01952002239, 4015.4177644241568, 4031.939520907174, 3730.776153430803, 3739.0278527582886, 3764.3976963685814, 3868.323043467382, 3988.464441190148, 3922.0783115967724, 3957.2679054375035, 3979.8856320230384, 3988.142549351874, 3998.3602623580086, 3449.6722811332884, 3450.1719587299117, 3451.7238620910266, 3453.6546345365514, 3465.289615628815, 4364.694471387004, 4706.5293470653005, 4953.405591329028, 4985.601151907875, 4989.975050124763, 3751.643593858579, 4466.8883277917985, 4529.705569137996, 4546.772661366918, 4628.5140060293525, 5368.32895888014, 5373.516383525502, 5432.70120143392, 5442.0, 5497.862553436156, 3798.525184351956, 3806.124591343979, 3813.466114743938, 3827.5789663137034, 3831.923361532754, 5155.252488389163, 5391.299131113009, 5404.756905911623, 5613.494785469327, 5688.232088668018, 3840.539135303781, 3843.9231215375535, 3858.459815625815, 3866.8066596670055, 3869.4389313549377, 3471.062813040465, 3814.389697648363, 3936.527616686016, 3949.6445319921345, 4016.5581786003518, 4093.89765255871, 4218.604023760902, 4253.383259427365, 4541.728316071505, 4577.404937358122, 4299.204647140289, 5511.960574587429, 5538.833834984957, 5574.052411090124, 5595.21666966625, 1072.89491823422, 1329.3087594451024, 1363.8977076719286, 1364.6861221919014, 1367.2616786934943, 692.9618870962146, 842.8862103615934, 871.5947084605689, 889.4307643108286, 898.5372533145435, 4574.771261436915, 4583.816647334136, 4863.756812159378, 5206.490117865852, 5259.552938000236, 5086.0845047891, 5288.8413347599635, 5590.5248053915875, 5618.876224754984, 5656.632318899258, 4481.0, 5006.424261210008, 5073.644844860824, 5173.974130129401, 5215.843524694265, 2477.47972925684, 2919.8978035768646, 2951.88192472303, 2955.807872488281, 2973.9405211896074, 4674.929876051862, 5846.619969701956, 5872.559558033079, 5885.323187833368, 5887.676177810262, 5549.694766787866, 5764.971175144181, 5771.971140144273, 5807.535397168216, 5847.766089356463, 4882.267659851051, 5124.8206312778875, 5834.941650583524, 5894.351621321651, 5924.407559244128, 5120.795168193231, 5409.864753381197, 5632.802851900164, 5681.573881958952, 5724.198612194379, 5112.284280200849, 5539.418361072029, 5541.947030064275, 5545.306836645419, 5638.464345887134, 5058.367704036996, 5210.505002024802, 5843.772807710428, 5846.649201047951, 5939.316978547522, 3123.6407813100877, 3649.251903359858, 3664.8900532984053, 3696.334659761269, 3886.514185726784, 4947.727874966911, 5072.695638261568, 5094.2613321068975, 5364.436734142859, 5365.3293338332705, 5133.8716532087, 5186.651470617591, 5504.889902202013, 5527.0, 5558.305211848519, 4457.732536047847, 5031.798728050837, 5378.97310513445, 5479.424660410599, 5496.422875598005, 4263.445752052214, 4376.715513491613, 5324.733763311741, 5338.078291815021, 5339.279197308314, 4727.4799772025735, 4762.785674644719, 5163.856999345229, 5179.585633149414, 5328.70692111922, 3992.5608183099257, 4447.911041779082, 4755.191617424977, 5144.331236939174, 5489.2553723138435], "value": 3969.131630714518, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.3442864492026, 714.6176795414452, 781.5271760584842, 796.9321109712987, 821.4824660463906, 15562.45531406401, 16544.090075045868, 16580.253888574996, 16589.0, 16605.58486037848, 14458.566243012727, 14573.125612463233, 14579.635509112277, 14591.78112328316, 14594.343254553543, 669.3741351836024, 826.3017750001239, 830.9626066826992, 847.4660963592959, 855.1320409784043, 850.5832142250284, 851.2721623012328, 851.8509260879322, 853.8335024670192, 855.9572021398936, 909.3589019741099, 910.4810258152855, 911.6490151291767, 911.7538264668535, 914.6432891172448, 702.546857277056, 880.0583375787877, 903.5798353765465, 905.1898550797066, 911.0, 681.6251061915976, 683.5215349255491, 684.1687349869932, 685.2428066986008, 685.3044160177424, 14759.262036898112, 14811.629709257248, 14813.629659258497, 14815.259237038104, 14820.777688334683, 11708.297502149813, 14023.859761402357, 14049.578512644637, 14242.92878535611, 14289.0, 13098.279594622292, 13291.534796282083, 13439.059265851394, 13461.932690336584, 13827.0, 4574.771261436948, 4637.837675681369, 4639.216152961241, 4672.112298663243, 4875.049365373754, 120323.0, 120462.0, 121660.39169804094, 121864.0, 122063.0, 11143.052840508486, 11165.776684466266, 11354.829677554842, 11568.537258509734, 11794.764104717857, 22770.43073923165, 22794.772052279597, 22803.88598056999, 22805.429864253194, 22868.65697014546, 7085.291470852977, 7737.458377913492, 7741.496802707806, 8024.919750802417, 8065.556394398247, 4448.1770872388715, 4467.5532446755715, 4487.461504619467, 4604.723716576949, 4633.675642704979, 3365.730741540671, 3462.0479368173956, 3476.9652303477146, 3484.7212223022602, 3485.4074807282823, 558.4638746803115, 636.7230254839067, 660.6267458885715, 667.9031540426611, 670.1656437734954, 550.9366422861423, 573.962692424991, 615.1848800339568, 629.2763322179438, 653.7287025884204, 7566.35685966699, 9139.77150571241, 9461.24310055194, 9517.952410237904, 9536.809263814685, 619.3094699410216, 642.7493277621732, 654.1463390275641, 661.2593894837812, 672.9596224226563, 4493.898994746242, 4653.395058642423, 4654.394928659252, 4743.1697258914555, 4806.735629540339, 94208.05791941992, 126735.3663231678, 129865.70134298725, 134649.0899183654, 137006.0, 117076.0, 118350.67421966032, 118597.99999999827, 120774.0, 121890.0, 88558.55720721484, 88735.11264887269, 91879.0, 92677.0, 106865.46567267114, 9053.049429809775, 9475.289353298393, 9651.71044868655, 9694.46680432583, 9728.22174226046, 56786.7160664194, 56850.86298274094, 58660.706696466244, 59018.70490647691, 59489.0, 84424.0, 88912.0, 88983.0, 93965.5301723487, 94025.5298723502, 13169.275689836768, 13317.33413329349, 13500.054996150568, 13656.248906310248, 13850.030497865455, 14783.186924718813, 14968.992094469902, 15152.469663561722, 15309.923450382677, 15360.923195383952, 14068.577942661737, 14069.507567235507, 14313.57059288223, 14373.64065898361, 14863.033902796284, 14028.018038737599, 14059.367328470447, 14178.716425671222, 14213.289335533389, 14661.92669036648, 13683.613119063313, 13748.450061997408, 13937.233452160228, 14052.297385130907, 14417.711645767236, 10847.837282440612, 10900.618478353219, 11218.882794557854, 11900.76198476043, 11985.760284794429, 8874.822503549763, 10620.415877126832, 11557.537698491966, 11626.849032928934, 11674.70813229676], "value": 7856.9363116379245, "unit": "score"}], "name": "dromaeo_css", "value": 7271.714147655939, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 128.0, 113.0, 112.0, 117.0, 155.0, 165.0, 113.0, 111.0], "value": 124.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 127.0, 158.0, 108.0, 130.0, 139.0, 121.0, 155.0, 91.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 184.0, 183.0, 183.0, 184.0, 179.0, 185.0, 182.0, 182.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 73.0, 153.0, 64.0, 67.0, 81.0, 94.0, 161.0, 48.0], "value": 89.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 135.0, 70.0, 81.0, 78.0, 124.0, 94.0, 100.0, 152.0], "value": 100.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 91.0, 88.0, 89.0, 86.0, 90.0, 87.0, 90.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 97.0, 94.0, 95.0, 95.0, 96.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 84.0, 112.0, 130.0, 136.0, 85.0, 87.0, 85.0, 86.0], "value": 97.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 55.0, 54.0, 55.0, 56.0, 55.0, 55.0, 55.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 67.0, 81.0, 103.0, 108.0, 100.0, 68.0, 70.0, 68.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 84.0, 84.0, 86.0, 87.0, 84.0, 88.0, 88.0, 95.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 152.0, 141.0, 137.0, 135.0, 137.0, 137.0, 140.0, 177.0, 138.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 171.0, 169.0, 173.0, 170.0, 170.0, 171.0, 168.0, 168.0, 169.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 83.0, 71.0, 67.0, 67.0, 66.0, 67.0, 75.0, 83.0], "value": 70.1, "unit": "score"}], "name": "kraken", "value": 1510.3, "alertThreshold": 2.0}]} 08:21:17 INFO - Return code: 0 08:21:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:21:17 INFO - # TBPL SUCCESS # 08:21:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:21:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:21:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:21:17 INFO - 2016-08-25 08:21:17.598 screenresolution[1661:9723] starting screenresolution argv=screenresolution get 08:21:17 INFO - 2016-08-25 08:21:17.601 screenresolution[1661:9723] Display 0: 1600x1200x32@60 08:21:17 INFO - 2016-08-25 08:21:17.607 screenresolution[1662:9726] starting screenresolution argv=screenresolution list 08:21:17 INFO - Available Modes on Display 0 08:21:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:21:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:21:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:21:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:21:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:21:17 INFO - Intel Iris: 08:21:17 INFO - Chipset Model: Intel Iris 08:21:17 INFO - Type: GPU 08:21:17 INFO - Bus: Built-In 08:21:17 INFO - VRAM (Dynamic, Max): 1536 MB 08:21:17 INFO - Vendor: Intel (0x8086) 08:21:17 INFO - Device ID: 0x0a2e 08:21:17 INFO - Revision ID: 0x0009 08:21:17 INFO - Displays: 08:21:17 INFO - Display: 08:21:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:21:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:21:17 INFO - Display Serial Number: 1600x1200 60 08:21:17 INFO - Main Display: Yes 08:21:17 INFO - Mirror: Off 08:21:17 INFO - Online: Yes 08:21:17 INFO - Rotation: Supported 08:21:17 INFO - Return code: 0 08:21:17 INFO - Running post-action listener: _resource_record_post_action 08:21:17 INFO - [mozharness: 2016-08-25 15:21:17.842962Z] Finished run-tests step (success) 08:21:17 INFO - Running post-run listener: _resource_record_post_run 08:21:17 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37168128; Write bytes: 702460928; Read time: 236; Write time: 1957 08:21:17 INFO - TinderboxPrint: CPU usage
53.4% 08:21:17 INFO - TinderboxPrint: I/O read bytes / time
37,168,128 / 236 08:21:17 INFO - TinderboxPrint: I/O write bytes / time
702,460,928 / 1,957 08:21:17 INFO - TinderboxPrint: CPU idle
2,260.6 (46.6%) 08:21:17 INFO - TinderboxPrint: CPU system
968.8 (20.0%) 08:21:17 INFO - TinderboxPrint: CPU user
1,620.3 (33.4%) 08:21:17 INFO - TinderboxPrint: Swap in / out
442,425,344 / 0 08:21:17 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393071104; Write bytes: 387065344; Read time: 29405; Write time: 809 08:21:17 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 36201472; Write bytes: 294243840; Read time: 222; Write time: 1098 08:21:18 INFO - Running post-run listener: _upload_blobber_files 08:21:18 INFO - Blob upload gear active. 08:21:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:21:18 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 08:21:18 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'] 08:21:18 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 08:21:18 INFO - (blobuploader) - INFO - Open directory for files ... 08:21:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:21:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:21:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:19 INFO - (blobuploader) - INFO - Done attempting. 08:21:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:21:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:21:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:20 INFO - (blobuploader) - INFO - Done attempting. 08:21:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:21:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:21:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:22 INFO - (blobuploader) - INFO - Done attempting. 08:21:22 INFO - (blobuploader) - INFO - Iteration through files over. 08:21:22 INFO - Return code: 0 08:21:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:21:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:21:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c48b1eee31cdd7a43ec16462334a70500351a464362a0063a3ccdd19bd057337e8b5da2b1721a6f6917d69258a26a2d5154952f596094b906c8b1ee08a89cd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/353d4d2cc9898a210d71d8c121a5576a05b683fd466f31a8a8af027e4b77b67e81a6c7cd77a6ece71cfa94e1c8c3fccbb7164e7ee9aa4a77a66c7b4b4ecbbd0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd3430ae339d950c4b580dc486c879c34bb1e001f3d3aee35de33151833fb76590dac693157e1995ba23e3c6b7d1ad57bf3366e7bb5b0d51e310773431ba578b"} 08:21:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:21:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:21:22 INFO - Contents: 08:21:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c48b1eee31cdd7a43ec16462334a70500351a464362a0063a3ccdd19bd057337e8b5da2b1721a6f6917d69258a26a2d5154952f596094b906c8b1ee08a89cd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/353d4d2cc9898a210d71d8c121a5576a05b683fd466f31a8a8af027e4b77b67e81a6c7cd77a6ece71cfa94e1c8c3fccbb7164e7ee9aa4a77a66c7b4b4ecbbd0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd3430ae339d950c4b580dc486c879c34bb1e001f3d3aee35de33151833fb76590dac693157e1995ba23e3c6b7d1ad57bf3366e7bb5b0d51e310773431ba578b"} 08:21:22 INFO - Running post-run listener: copy_logs_to_upload_dir 08:21:22 INFO - Copying logs to upload dir... 08:21:22 INFO - mkdir: /builds/slave/test/build/upload/logs 08:21:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.626615 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-25 08:21:22.750635) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 08:21:22.754407) ========= 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.N8m9sFlDQj/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.QR0awII8SF/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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/1c48b1eee31cdd7a43ec16462334a70500351a464362a0063a3ccdd19bd057337e8b5da2b1721a6f6917d69258a26a2d5154952f596094b906c8b1ee08a89cd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/353d4d2cc9898a210d71d8c121a5576a05b683fd466f31a8a8af027e4b77b67e81a6c7cd77a6ece71cfa94e1c8c3fccbb7164e7ee9aa4a77a66c7b4b4ecbbd0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd3430ae339d950c4b580dc486c879c34bb1e001f3d3aee35de33151833fb76590dac693157e1995ba23e3c6b7d1ad57bf3366e7bb5b0d51e310773431ba578b"} build_url:https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010902 build_url: 'https://queue.taskcluster.net/v1/task/H0cE3MYfRgqI_2KWbjRB2Q/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/1c48b1eee31cdd7a43ec16462334a70500351a464362a0063a3ccdd19bd057337e8b5da2b1721a6f6917d69258a26a2d5154952f596094b906c8b1ee08a89cd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/353d4d2cc9898a210d71d8c121a5576a05b683fd466f31a8a8af027e4b77b67e81a6c7cd77a6ece71cfa94e1c8c3fccbb7164e7ee9aa4a77a66c7b4b4ecbbd0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd3430ae339d950c4b580dc486c879c34bb1e001f3d3aee35de33151833fb76590dac693157e1995ba23e3c6b7d1ad57bf3366e7bb5b0d51e310773431ba578b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 08:21:22.792424) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 08:21:22.793223) ========= 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.N8m9sFlDQj/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.QR0awII8SF/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.005063 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 08:21:22.844950) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 08:21:22.845699) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 08:21:22.846523) ========= ========= Total master_lag: 0.19 =========