builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0057 starttime: 1471561242.36 results: success (0) buildid: 20160818150500 builduid: 68af96d26d0344c6a338fe183920a393 revision: f90d73ed026973d4e442d1711eede5626bc9cf01 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:42.363781) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:42.364352) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:42.364690) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:42.389889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:42.390409) ========= 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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-18 16:00:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-08-18 16:00:42 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091362 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:42.504776) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:42.505122) ========= 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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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.021604 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:42.554223) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 16:00:42.554666) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev f90d73ed026973d4e442d1711eede5626bc9cf01 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev f90d73ed026973d4e442d1711eede5626bc9cf01 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-18 16:00:42,633 truncating revision to first 12 chars 2016-08-18 16:00:42,633 Setting DEBUG logging. 2016-08-18 16:00:42,633 attempt 1/10 2016-08-18 16:00:42,633 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/f90d73ed0269?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 16:00:44,028 unpacking tar archive at: autoland-f90d73ed0269/testing/mozharness/ program finished with exit code 0 elapsedTime=1.669678 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 16:00:44.247862) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:44.248197) ========= script_repo_revision: f90d73ed026973d4e442d1711eede5626bc9cf01 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:44.248592) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:44.248858) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 16:00:44.268485) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-18 16:00:44.268892) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 16:00:44 INFO - MultiFileLogger online at 20160818 16:00:44 in /builds/slave/test 16:00:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 16:00:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:00:44 INFO - {'append_to_log': False, 16:00:44 INFO - 'base_work_dir': '/builds/slave/test', 16:00:44 INFO - 'blob_upload_branch': 'Autoland', 16:00:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:00:44 INFO - 'branch': 'Autoland', 16:00:44 INFO - 'buildbot_json_path': 'buildprops.json', 16:00:44 INFO - 'config_files': ('talos/mac_config.py',), 16:00:44 INFO - 'default_actions': ('clobber', 16:00:44 INFO - 'read-buildbot-config', 16:00:44 INFO - 'download-and-extract', 16:00:44 INFO - 'populate-webroot', 16:00:44 INFO - 'create-virtualenv', 16:00:44 INFO - 'install', 16:00:44 INFO - 'run-tests'), 16:00:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:00:44 INFO - 'download_minidump_stackwalk': True, 16:00:44 INFO - 'download_symbols': 'ondemand', 16:00:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:00:44 INFO - 'tooltool.py': '/tools/tooltool.py', 16:00:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:00:44 INFO - '/tools/misc-python/virtualenv.py')}, 16:00:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:00:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:00:44 INFO - 'installer_path': 'installer.exe', 16:00:44 INFO - 'log_level': 'info', 16:00:44 INFO - 'log_name': 'talos', 16:00:44 INFO - 'log_to_console': True, 16:00:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:00:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:00:44 INFO - 'opt_config_files': (), 16:00:44 INFO - 'pip_index': False, 16:00:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:00:44 INFO - 'cmd': ('bash', 16:00:44 INFO - '-c', 16:00:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:00:44 INFO - 'enabled': True, 16:00:44 INFO - 'halt_on_failure': False, 16:00:44 INFO - 'name': 'check_screen_resolution'},), 16:00:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:00:44 INFO - 'cmd': ('bash', 16:00:44 INFO - '-c', 16:00:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:00:44 INFO - 'enabled': True, 16:00:44 INFO - 'halt_on_failure': False, 16:00:44 INFO - 'name': 'check_screen_resolution'},), 16:00:44 INFO - 'run_cmd_checks_enabled': True, 16:00:44 INFO - 'sps_profile': False, 16:00:44 INFO - 'sps_profile_interval': 0, 16:00:44 INFO - 'suite': 'dromaeojs', 16:00:44 INFO - 'system_bits': '32', 16:00:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:00:44 INFO - 'title': 't-yosemite-r7-0057', 16:00:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:00:44 INFO - 'use_talos_json': True, 16:00:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:00:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:00:44 INFO - 'work_dir': 'build'} 16:00:44 INFO - [mozharness: 2016-08-18 23:00:44.463178Z] Running clobber step. 16:00:44 INFO - Running pre-action listener: _resource_record_pre_action 16:00:44 INFO - Running main action method: clobber 16:00:44 INFO - rmtree: /builds/slave/test/build 16:00:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:00:45 INFO - Running post-action listener: _resource_record_post_action 16:00:45 INFO - [mozharness: 2016-08-18 23:00:45.949007Z] Finished clobber step (success) 16:00:45 INFO - [mozharness: 2016-08-18 23:00:45.949184Z] Running read-buildbot-config step. 16:00:45 INFO - Running pre-action listener: _resource_record_pre_action 16:00:45 INFO - Running main action method: read_buildbot_config 16:00:45 INFO - Using buildbot properties: 16:00:45 INFO - { 16:00:45 INFO - "project": "", 16:00:45 INFO - "product": "firefox", 16:00:45 INFO - "script_repo_revision": "production", 16:00:45 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:00:45 INFO - "repository": "", 16:00:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:00:45 INFO - "buildid": "20160818150500", 16:00:45 INFO - "pgo_build": "False", 16:00:45 INFO - "basedir": "/builds/slave/test", 16:00:45 INFO - "buildnumber": 133, 16:00:45 INFO - "slavename": "t-yosemite-r7-0057", 16:00:45 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:00:45 INFO - "platform": "yosemite_r7", 16:00:45 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:00:45 INFO - "branch": "autoland", 16:00:45 INFO - "repo_path": "integration/autoland", 16:00:45 INFO - "revision": "f90d73ed026973d4e442d1711eede5626bc9cf01", 16:00:45 INFO - "stage_platform": "macosx64", 16:00:45 INFO - "builduid": "68af96d26d0344c6a338fe183920a393", 16:00:45 INFO - "slavebuilddir": "test" 16:00:45 INFO - } 16:00:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:00:45 INFO - Running post-action listener: _resource_record_post_action 16:00:45 INFO - [mozharness: 2016-08-18 23:00:45.952568Z] Finished read-buildbot-config step (success) 16:00:45 INFO - [mozharness: 2016-08-18 23:00:45.952703Z] Running download-and-extract step. 16:00:45 INFO - Running pre-action listener: _resource_record_pre_action 16:00:45 INFO - Running main action method: download_and_extract 16:00:45 INFO - mkdir: /builds/slave/test/build/tests 16: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')]} 16:00:45 INFO - trying https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:00:45 INFO - Downloading https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/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 16:00:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/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 16:00:47 INFO - Downloaded 1199 bytes. 16:00:47 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:00:47 INFO - Using the following test package requirements: 16:00:47 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:00:47 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:00:47 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:00:47 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:00:47 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:00:47 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:00:47 INFO - u'jsshell-mac.zip'], 16:00:47 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:00:47 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:00:47 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:00:47 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:00:47 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:00:47 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:00:47 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:00:47 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:00:47 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:00:47 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:00:47 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:00:47 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:00:47 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')]} 16:00:47 INFO - trying https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:00:47 INFO - Downloading https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/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 16:00:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/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 16:00:48 INFO - Downloaded 16339411 bytes. 16:00:48 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:00:55 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 16:00:55 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')]} 16:00:55 INFO - trying https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:00:55 INFO - Downloading https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/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 16:00:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/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 16:00:56 INFO - Downloaded 16339411 bytes. 16:00:56 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:01:03 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')]} 16:01:03 INFO - trying https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:01:03 INFO - Downloading https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/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 16:01:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/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 16:01:04 INFO - Downloaded 14413981 bytes. 16:01:04 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 16:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:05 INFO - trying https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:01:05 INFO - Downloading https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:01:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/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 16:01:08 INFO - Downloaded 111270976 bytes. 16:01:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:01:08 INFO - mkdir: /builds/slave/test/properties 16:01:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:01:08 INFO - Writing to file /builds/slave/test/properties/build_url 16:01:08 INFO - Contents: 16:01:08 INFO - build_url:https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:01:08 INFO - Running post-action listener: _resource_record_post_action 16:01:08 INFO - Running post-action listener: set_extra_try_arguments 16:01:08 INFO - [mozharness: 2016-08-18 23:01:08.983413Z] Finished download-and-extract step (success) 16:01:08 INFO - [mozharness: 2016-08-18 23:01:08.983552Z] Running populate-webroot step. 16:01:08 INFO - Running pre-action listener: _resource_record_pre_action 16:01:08 INFO - Running main action method: populate_webroot 16:01:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:01:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:01:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:01:08 INFO - '--tppagecycles', 16:01:08 INFO - '7'], 16:01:08 INFO - 'tests': ['tsvgm']}}, 16:01:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:01:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:01:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:01:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:01:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:01:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:01:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:08 INFO - 'talos_options': ['--disable-e10s'], 16:01:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:08 INFO - 'talos_options': ['--disable-e10s'], 16:01:08 INFO - 'tests': ['damp', 'tps']}, 16:01:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:08 INFO - 'tests': ['damp', 'tps']}, 16:01:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:01:08 INFO - 'tests': ['dromaeo_dom']}, 16:01:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:01:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:01:08 INFO - 'tests': ['basic_compositor_video']}, 16:01:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:01:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:01:08 INFO - 'tests': ['a11yr', 16:01:08 INFO - 'ts_paint', 16:01:08 INFO - 'tpaint', 16:01:08 INFO - 'sessionrestore', 16:01:08 INFO - 'sessionrestore_no_auto_restore', 16:01:08 INFO - 'tabpaint']}, 16:01:08 INFO - 'other-e10s': {'tests': ['a11yr', 16:01:08 INFO - 'ts_paint', 16:01:08 INFO - 'tpaint', 16:01:08 INFO - 'sessionrestore', 16:01:08 INFO - 'sessionrestore_no_auto_restore', 16:01:08 INFO - 'tabpaint']}, 16:01:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:01:08 INFO - 'ts_paint', 16:01:08 INFO - 'tpaint', 16:01:08 INFO - 'sessionrestore', 16:01:08 INFO - 'sessionrestore_no_auto_restore', 16:01:08 INFO - 'tabpaint']}, 16:01:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:01:08 INFO - 'ts_paint', 16:01:08 INFO - 'tpaint', 16:01:08 INFO - 'sessionrestore', 16:01:08 INFO - 'sessionrestore_no_auto_restore', 16:01:08 INFO - 'tabpaint']}, 16:01:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:01:08 INFO - 'tests': ['a11yr', 16:01:08 INFO - 'ts_paint', 16:01:08 INFO - 'tpaint', 16:01:08 INFO - 'sessionrestore', 16:01:08 INFO - 'sessionrestore_no_auto_restore', 16:01:08 INFO - 'tabpaint']}, 16:01:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:01:08 INFO - 'tests': ['a11yr', 16:01:08 INFO - 'ts_paint', 16:01:08 INFO - 'tpaint', 16:01:08 INFO - 'sessionrestore', 16:01:08 INFO - 'sessionrestore_no_auto_restore', 16:01:08 INFO - 'tabpaint']}, 16:01:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:01:08 INFO - 'tests': ['tsvgx', 16:01:08 INFO - 'tsvgr_opacity', 16:01:08 INFO - 'tart', 16:01:08 INFO - 'tscrollx', 16:01:08 INFO - 'cart']}, 16:01:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:01:08 INFO - 'tsvgr_opacity', 16:01:09 INFO - 'tart', 16:01:09 INFO - 'tscrollx', 16:01:09 INFO - 'cart']}, 16:01:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:09 INFO - 'talos_options': ['--disable-e10s'], 16:01:09 INFO - 'tests': ['tp5o']}, 16:01:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:09 INFO - 'tests': ['tp5o']}, 16:01:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:09 INFO - 'talos_options': ['--disable-e10s', 16:01:09 INFO - '--xperf_path', 16:01:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:09 INFO - 'tests': ['tp5n']}, 16:01:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:09 INFO - 'talos_options': ['--xperf_path', 16:01:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:09 INFO - 'tests': ['tp5n']}}, 16:01:09 INFO - 'talos.zip': {'path': '', 16:01:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:01:09 INFO - Running post-action listener: _resource_record_post_action 16:01:09 INFO - [mozharness: 2016-08-18 23:01:09.002204Z] Finished populate-webroot step (success) 16:01:09 INFO - [mozharness: 2016-08-18 23:01:09.002340Z] Running create-virtualenv step. 16:01:09 INFO - Running pre-action listener: _resource_record_pre_action 16:01:09 INFO - Running main action method: create_virtualenv 16:01:09 INFO - Creating virtualenv /builds/slave/test/build/venv 16:01:09 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 16:01:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:01:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:01:09 INFO - Using real prefix '/tools/python27' 16:01:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:01:09 INFO - Installing distribute.............................................................................................................................................................................................done. 16:01:12 INFO - Installing pip.................done. 16:01:12 INFO - Return code: 0 16:01:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01: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')]} 16:01:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:12 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 0x1015dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101672618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd653492380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101541d30>, '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 0x1012a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101539b20>, '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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 16:01:12 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 16:01:12 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 16:01:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cdhun6QU6Y/Render', 16:01:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:12 INFO - 'HOME': '/Users/cltbld', 16:01:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:12 INFO - 'LOGNAME': 'cltbld', 16:01:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:12 INFO - 'MOZ_NO_REMOTE': '1', 16:01:12 INFO - 'NO_EM_RESTART': '1', 16:01:12 INFO - 'PAGER': '/bin/cat', 16:01:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:12 INFO - 'PWD': '/builds/slave/test', 16:01:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:12 INFO - 'SHELL': '/bin/bash', 16:01:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AEoQpPfVP9/Listeners', 16:01:12 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 16:01:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:12 INFO - 'USER': 'cltbld', 16:01:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:12 INFO - 'XPC_FLAGS': '0x0', 16:01:12 INFO - 'XPC_SERVICE_NAME': '0', 16:01:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:12 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 16:01:12 INFO - Cleaning up... 16:01:12 INFO - Return code: 0 16:01:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01: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')]} 16:01:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:12 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 0x1015dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101672618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd653492380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101541d30>, '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 0x1012a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101539b20>, '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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 16:01:12 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 16:01:12 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 16:01:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cdhun6QU6Y/Render', 16:01:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:12 INFO - 'HOME': '/Users/cltbld', 16:01:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:12 INFO - 'LOGNAME': 'cltbld', 16:01:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:12 INFO - 'MOZ_NO_REMOTE': '1', 16:01:12 INFO - 'NO_EM_RESTART': '1', 16:01:12 INFO - 'PAGER': '/bin/cat', 16:01:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:12 INFO - 'PWD': '/builds/slave/test', 16:01:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:12 INFO - 'SHELL': '/bin/bash', 16:01:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AEoQpPfVP9/Listeners', 16:01:12 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 16:01:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:12 INFO - 'USER': 'cltbld', 16:01:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:12 INFO - 'XPC_FLAGS': '0x0', 16:01:12 INFO - 'XPC_SERVICE_NAME': '0', 16:01:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:13 INFO - Downloading/unpacking psutil>=3.1.1 16:01:13 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 16:01:13 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 16:01:13 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 16:01:13 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 16:01:13 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 16:01:13 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 16:01:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:01:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:16 INFO - Installing collected packages: psutil 16:01:16 INFO - Running setup.py install for psutil 16:01:16 INFO - building 'psutil._psutil_osx' extension 16:01:16 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 16:01:17 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 16:01:17 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 16:01:17 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 16:01:17 INFO - building 'psutil._psutil_posix' extension 16:01:17 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 16:01:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:01:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:01:17 INFO - ^ 16:01:17 INFO - 1 warning generated. 16:01:17 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 16:01:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:17 INFO - Successfully installed psutil 16:01:17 INFO - Cleaning up... 16:01:17 INFO - Return code: 0 16:01:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:01:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:17 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 0x1015dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101672618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd653492380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101541d30>, '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 0x1012a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101539b20>, '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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 16:01:17 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 16:01:17 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 16:01:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cdhun6QU6Y/Render', 16:01:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:17 INFO - 'HOME': '/Users/cltbld', 16:01:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:17 INFO - 'LOGNAME': 'cltbld', 16:01:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:17 INFO - 'MOZ_NO_REMOTE': '1', 16:01:17 INFO - 'NO_EM_RESTART': '1', 16:01:17 INFO - 'PAGER': '/bin/cat', 16:01:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:17 INFO - 'PWD': '/builds/slave/test', 16:01:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:17 INFO - 'SHELL': '/bin/bash', 16:01:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AEoQpPfVP9/Listeners', 16:01:17 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 16:01:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:17 INFO - 'USER': 'cltbld', 16:01:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:17 INFO - 'XPC_FLAGS': '0x0', 16:01:17 INFO - 'XPC_SERVICE_NAME': '0', 16:01:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:01:17 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 16:01:17 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 16:01:17 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 16:01:17 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 16:01:17 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 16:01:17 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 16:01:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:01:20 INFO - Installing collected packages: mozsystemmonitor 16:01:20 INFO - Running setup.py install for mozsystemmonitor 16:01:20 INFO - Successfully installed mozsystemmonitor 16:01:20 INFO - Cleaning up... 16:01:20 INFO - Return code: 0 16:01:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01: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')]} 16:01:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:20 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 0x1015dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101672618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd653492380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101541d30>, '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 0x1012a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101539b20>, '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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 16:01:20 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 16:01:20 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 16:01:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cdhun6QU6Y/Render', 16:01:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:20 INFO - 'HOME': '/Users/cltbld', 16:01:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:20 INFO - 'LOGNAME': 'cltbld', 16:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:20 INFO - 'MOZ_NO_REMOTE': '1', 16:01:20 INFO - 'NO_EM_RESTART': '1', 16:01:20 INFO - 'PAGER': '/bin/cat', 16:01:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:20 INFO - 'PWD': '/builds/slave/test', 16:01:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:20 INFO - 'SHELL': '/bin/bash', 16:01:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AEoQpPfVP9/Listeners', 16:01:20 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 16:01:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:20 INFO - 'USER': 'cltbld', 16:01:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:20 INFO - 'XPC_FLAGS': '0x0', 16:01:20 INFO - 'XPC_SERVICE_NAME': '0', 16:01:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:20 INFO - Downloading/unpacking jsonschema==2.5.1 16:01:20 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 16:01:20 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 16:01:20 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 16:01:20 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 16:01:20 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 16:01:20 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 16:01:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:01:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:01:23 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 16:01:23 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 16:01:23 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 16:01:23 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 16:01:23 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 16:01:23 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 16:01:23 INFO - Downloading functools32-3.2.3-2.tar.gz 16:01:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:01:23 INFO - warning: no files found matching '*.txt' 16:01:23 INFO - no previously-included directories found matching 'build' 16:01:23 INFO - no previously-included directories found matching 'dist' 16:01:23 INFO - no previously-included directories found matching '.git*' 16:01:23 INFO - Installing collected packages: jsonschema, functools32 16:01:23 INFO - Running setup.py install for functools32 16:01:23 INFO - warning: no files found matching '*.txt' 16:01:23 INFO - no previously-included directories found matching 'build' 16:01:23 INFO - no previously-included directories found matching 'dist' 16:01:23 INFO - no previously-included directories found matching '.git*' 16:01:23 INFO - Successfully installed jsonschema functools32 16:01:23 INFO - Cleaning up... 16:01:24 INFO - Return code: 0 16:01:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:24 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 0x1015dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101672618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd653492380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101541d30>, '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 0x1012a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101539b20>, '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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 16:01:24 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 16:01:24 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 16:01:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cdhun6QU6Y/Render', 16:01:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:24 INFO - 'HOME': '/Users/cltbld', 16:01:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:24 INFO - 'LOGNAME': 'cltbld', 16:01:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:24 INFO - 'MOZ_NO_REMOTE': '1', 16:01:24 INFO - 'NO_EM_RESTART': '1', 16:01:24 INFO - 'PAGER': '/bin/cat', 16:01:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:24 INFO - 'PWD': '/builds/slave/test', 16:01:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:24 INFO - 'SHELL': '/bin/bash', 16:01:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AEoQpPfVP9/Listeners', 16:01:24 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 16:01:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:24 INFO - 'USER': 'cltbld', 16:01:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:24 INFO - 'XPC_FLAGS': '0x0', 16:01:24 INFO - 'XPC_SERVICE_NAME': '0', 16:01:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:01:24 INFO - Cleaning up... 16:01:24 INFO - Return code: 0 16:01:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:24 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 0x1015dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101672618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd653492380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101541d30>, '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 0x1012a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101539b20>, '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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 16:01:24 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 16:01:24 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 16:01:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cdhun6QU6Y/Render', 16:01:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:24 INFO - 'HOME': '/Users/cltbld', 16:01:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:24 INFO - 'LOGNAME': 'cltbld', 16:01:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:24 INFO - 'MOZ_NO_REMOTE': '1', 16:01:24 INFO - 'NO_EM_RESTART': '1', 16:01:24 INFO - 'PAGER': '/bin/cat', 16:01:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:24 INFO - 'PWD': '/builds/slave/test', 16:01:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:24 INFO - 'SHELL': '/bin/bash', 16:01:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AEoQpPfVP9/Listeners', 16:01:24 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 16:01:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:24 INFO - 'USER': 'cltbld', 16:01:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:24 INFO - 'XPC_FLAGS': '0x0', 16:01:24 INFO - 'XPC_SERVICE_NAME': '0', 16:01:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:24 INFO - Downloading/unpacking blobuploader==1.2.4 16:01:24 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 16:01:24 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 16:01:24 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 16:01:24 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 16:01:24 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 16:01:24 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 16:01:26 INFO - Downloading blobuploader-1.2.4.tar.gz 16:01:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:01:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:01:27 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 16:01:27 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 16:01:27 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 16:01:27 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 16:01:27 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 16:01:27 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 16:01:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:01:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:01:27 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 16:01:27 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 16:01:27 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 16:01:27 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 16:01:27 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 16:01:27 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 16:01:28 INFO - Downloading docopt-0.6.1.tar.gz 16:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:01:28 INFO - Installing collected packages: blobuploader, requests, docopt 16:01:28 INFO - Running setup.py install for blobuploader 16:01:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:01:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:01:28 INFO - Running setup.py install for requests 16:01:28 INFO - Running setup.py install for docopt 16:01:29 INFO - Successfully installed blobuploader requests docopt 16:01:29 INFO - Cleaning up... 16:01:29 INFO - Return code: 0 16:01:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:29 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')]} 16:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:29 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')]} 16:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:29 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 0x1015dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101672618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd653492380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101541d30>, '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 0x1012a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101539b20>, '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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 16:01:29 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 16:01:29 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 16:01:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cdhun6QU6Y/Render', 16:01:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:29 INFO - 'HOME': '/Users/cltbld', 16:01:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:29 INFO - 'LOGNAME': 'cltbld', 16:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:29 INFO - 'MOZ_NO_REMOTE': '1', 16:01:29 INFO - 'NO_EM_RESTART': '1', 16:01:29 INFO - 'PAGER': '/bin/cat', 16:01:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:29 INFO - 'PWD': '/builds/slave/test', 16:01:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:29 INFO - 'SHELL': '/bin/bash', 16:01:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AEoQpPfVP9/Listeners', 16:01:29 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 16:01:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:29 INFO - 'USER': 'cltbld', 16:01:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:29 INFO - 'XPC_FLAGS': '0x0', 16:01:29 INFO - 'XPC_SERVICE_NAME': '0', 16:01:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:29 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-v0dnxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:29 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-IhOqYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:29 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-pD14BS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:29 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-qaJinv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:29 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-9JRx_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:29 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-LQe7ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:30 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-DIb7it-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:30 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-tJCuV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:30 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-S0dmxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:30 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-pFFGEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:30 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-in1n7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:30 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-Olyaaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:30 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-YLBvB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:30 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-cuTmf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:30 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-NLLg_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:31 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-U7OQ7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:31 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-sclxd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:01:31 INFO - Running setup.py install for manifestparser 16:01:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:01:31 INFO - Running setup.py install for mozcrash 16:01:31 INFO - Running setup.py install for mozdebug 16:01:31 INFO - Running setup.py install for mozdevice 16:01:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:01:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:01:31 INFO - Running setup.py install for mozfile 16:01:31 INFO - Running setup.py install for mozhttpd 16:01:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:01:32 INFO - Running setup.py install for mozinfo 16:01:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:01:32 INFO - Running setup.py install for mozInstall 16:01:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:01:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:01:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:01:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:01:32 INFO - Running setup.py install for mozleak 16:01:32 INFO - Running setup.py install for mozlog 16:01:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:01:32 INFO - Running setup.py install for moznetwork 16:01:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:01:32 INFO - Running setup.py install for mozprocess 16:01:33 INFO - Running setup.py install for mozprofile 16:01:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:01:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:01:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:01:33 INFO - Running setup.py install for mozrunner 16:01:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:01:33 INFO - Running setup.py install for mozscreenshot 16:01:33 INFO - Running setup.py install for moztest 16:01:33 INFO - Running setup.py install for mozversion 16:01:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:01:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:01:33 INFO - Cleaning up... 16:01:33 INFO - Return code: 0 16:01:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:33 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')]} 16:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:33 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')]} 16:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:33 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 0x1015dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101672618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd653492380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101541d30>, '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 0x1012a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101539b20>, '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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 16:01:33 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 16:01:33 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 16:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cdhun6QU6Y/Render', 16:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:33 INFO - 'HOME': '/Users/cltbld', 16:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:33 INFO - 'LOGNAME': 'cltbld', 16:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:33 INFO - 'MOZ_NO_REMOTE': '1', 16:01:33 INFO - 'NO_EM_RESTART': '1', 16:01:33 INFO - 'PAGER': '/bin/cat', 16:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:33 INFO - 'PWD': '/builds/slave/test', 16:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:33 INFO - 'SHELL': '/bin/bash', 16:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AEoQpPfVP9/Listeners', 16:01:33 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 16:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:33 INFO - 'USER': 'cltbld', 16:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:33 INFO - 'XPC_FLAGS': '0x0', 16:01:33 INFO - 'XPC_SERVICE_NAME': '0', 16:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:34 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-NRxghp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:34 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)) 16:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:34 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-nnkyOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:34 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)) 16:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:34 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-TYignG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:34 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)) 16:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:34 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-VETmbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:34 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)) 16:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:34 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-jayuph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:34 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)) 16:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:34 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-K8udbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:34 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)) 16:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:34 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-C7OJIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:34 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)) 16:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:34 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-urrPeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:34 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)) 16:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:34 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-iG1_zG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:35 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)) 16:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:35 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-LbM8EH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:35 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)) 16:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:35 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-HbCoxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:35 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)) 16:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:35 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-wGDl0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:35 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)) 16:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:35 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-El5Gf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:35 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)) 16:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:35 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-DGA70V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:35 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)) 16:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:35 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-lMn1jr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:35 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)) 16:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:35 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-w9dSQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:35 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)) 16:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:35 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-eU5YzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:35 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)) 16:01:35 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)) 16:01:35 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)) 16:01:35 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)) 16:01:35 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)) 16:01:35 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)) 16:01:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:38 INFO - Downloading blessings-1.6.tar.gz 16:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:01:38 INFO - Installing collected packages: blessings 16:01:38 INFO - Running setup.py install for blessings 16:01:38 INFO - Successfully installed blessings 16:01:38 INFO - Cleaning up... 16:01:38 INFO - Return code: 0 16:01:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:01:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:38 INFO - Reading from file tmpfile_stdout 16:01:38 INFO - Current package versions: 16:01:38 INFO - blessings == 1.6 16:01:38 INFO - blobuploader == 1.2.4 16:01:38 INFO - docopt == 0.6.1 16:01:38 INFO - functools32 == 3.2.3-2 16:01:38 INFO - jsonschema == 2.5.1 16:01:38 INFO - manifestparser == 1.1 16:01:38 INFO - mozInstall == 1.12 16:01:38 INFO - mozcrash == 0.17 16:01:38 INFO - mozdebug == 0.1 16:01:38 INFO - mozdevice == 0.48 16:01:38 INFO - mozfile == 1.2 16:01:38 INFO - mozhttpd == 0.7 16:01:38 INFO - mozinfo == 0.9 16:01:38 INFO - mozleak == 0.1 16:01:38 INFO - mozlog == 3.3 16:01:38 INFO - moznetwork == 0.27 16:01:38 INFO - mozprocess == 0.23 16:01:38 INFO - mozprofile == 0.28 16:01:38 INFO - mozrunner == 6.12 16:01:38 INFO - mozscreenshot == 0.1 16:01:38 INFO - mozsystemmonitor == 0.3 16:01:38 INFO - moztest == 0.7 16:01:38 INFO - mozversion == 1.4 16:01:38 INFO - psutil == 3.1.1 16:01:38 INFO - requests == 1.2.3 16:01:38 INFO - wsgiref == 0.1.2 16:01:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16: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')]} 16:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:39 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 0x1015dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101672618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd653492380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101541d30>, '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 0x1012a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101539b20>, '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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 16:01:39 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 16:01:39 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 16:01:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cdhun6QU6Y/Render', 16:01:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:39 INFO - 'HOME': '/Users/cltbld', 16:01:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:39 INFO - 'LOGNAME': 'cltbld', 16:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:39 INFO - 'MOZ_NO_REMOTE': '1', 16:01:39 INFO - 'NO_EM_RESTART': '1', 16:01:39 INFO - 'PAGER': '/bin/cat', 16:01:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:39 INFO - 'PWD': '/builds/slave/test', 16:01:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:39 INFO - 'SHELL': '/bin/bash', 16:01:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AEoQpPfVP9/Listeners', 16:01:39 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 16:01:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:39 INFO - 'USER': 'cltbld', 16:01:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:39 INFO - 'XPC_FLAGS': '0x0', 16:01:39 INFO - 'XPC_SERVICE_NAME': '0', 16:01:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:39 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)) 16:01:39 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)) 16:01:39 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)) 16:01:39 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)) 16:01:39 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)) 16:01:39 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)) 16:01:39 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)) 16:01:39 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)) 16:01:39 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)) 16:01:39 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)) 16:01:39 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)) 16:01:39 INFO - Cleaning up... 16:01:39 INFO - Return code: 0 16:01:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16: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')]} 16:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16: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')]} 16:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:39 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 0x1015dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101672618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd653492380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101541d30>, '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 0x1012a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101539b20>, '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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 16:01:39 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 16:01:39 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 16:01:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cdhun6QU6Y/Render', 16:01:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:39 INFO - 'HOME': '/Users/cltbld', 16:01:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:39 INFO - 'LOGNAME': 'cltbld', 16:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:39 INFO - 'MOZ_NO_REMOTE': '1', 16:01:39 INFO - 'NO_EM_RESTART': '1', 16:01:39 INFO - 'PAGER': '/bin/cat', 16:01:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:39 INFO - 'PWD': '/builds/slave/test', 16:01:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:39 INFO - 'SHELL': '/bin/bash', 16:01:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AEoQpPfVP9/Listeners', 16:01:39 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 16:01:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:39 INFO - 'USER': 'cltbld', 16:01:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:39 INFO - 'XPC_FLAGS': '0x0', 16:01:39 INFO - 'XPC_SERVICE_NAME': '0', 16:01:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:01:39 INFO - Cleaning up... 16:01:39 INFO - Return code: 0 16:01:39 INFO - Running post-action listener: _resource_record_post_action 16:01:39 INFO - Running post-action listener: _start_resource_monitoring 16:01:39 INFO - Starting resource monitoring. 16:01:39 INFO - [mozharness: 2016-08-18 23:01:39.909338Z] Finished create-virtualenv step (success) 16:01:39 INFO - [mozharness: 2016-08-18 23:01:39.910424Z] Running install step. 16:01:39 INFO - Running pre-action listener: _resource_record_pre_action 16:01:39 INFO - Running main action method: install 16:01:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:40 INFO - Reading from file tmpfile_stdout 16:01:40 INFO - Detecting whether we're running mozinstall >=1.0... 16:01:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:01:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:01:40 INFO - Reading from file tmpfile_stdout 16:01:40 INFO - Output received: 16:01:40 INFO - Usage: mozinstall [options] installer 16:01:40 INFO - Options: 16:01:40 INFO - -h, --help show this help message and exit 16:01:40 INFO - -d DEST, --destination=DEST 16:01:40 INFO - Directory to install application into. [default: 16:01:40 INFO - "/builds/slave/test"] 16:01:40 INFO - --app=APP Application being installed. [default: firefox] 16:01:40 INFO - mkdir: /builds/slave/test/build/application 16:01:40 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'] 16:01:40 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 16:02:14 INFO - Reading from file tmpfile_stdout 16:02:14 INFO - Output received: 16:02:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:02:14 INFO - Running post-action listener: _resource_record_post_action 16:02:14 INFO - [mozharness: 2016-08-18 23:02:14.782684Z] Finished install step (success) 16:02:14 INFO - [mozharness: 2016-08-18 23:02:14.782812Z] Running run-tests step. 16:02:14 INFO - Running pre-action listener: _resource_record_pre_action 16:02:14 INFO - Running main action method: run_tests 16:02:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:02:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:02:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:02:14 INFO - 2016-08-18 16:02:14.797 screenresolution[2235:22193] starting screenresolution argv=screenresolution get 16:02:14 INFO - 2016-08-18 16:02:14.800 screenresolution[2235:22193] Display 0: 1600x1200x32@60 16:02:14 INFO - 2016-08-18 16:02:14.806 screenresolution[2236:22195] starting screenresolution argv=screenresolution list 16:02:14 INFO - Available Modes on Display 0 16:02:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:02:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:02:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:02:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:02:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:02:15 INFO - Intel Iris: 16:02:15 INFO - Chipset Model: Intel Iris 16:02:15 INFO - Type: GPU 16:02:15 INFO - Bus: Built-In 16:02:15 INFO - VRAM (Dynamic, Max): 1536 MB 16:02:15 INFO - Vendor: Intel (0x8086) 16:02:15 INFO - Device ID: 0x0a2e 16:02:15 INFO - Revision ID: 0x0009 16:02:15 INFO - Displays: 16:02:15 INFO - Display: 16:02:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:02:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:02:15 INFO - Display Serial Number: 1600x1200 60 16:02:15 INFO - Main Display: Yes 16:02:15 INFO - Mirror: Off 16:02:15 INFO - Online: Yes 16:02:15 INFO - Rotation: Supported 16:02:15 INFO - Return code: 0 16:02:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:02:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:02:15 INFO - Python 2.7.3 16:02:15 INFO - Return code: 0 16:02:15 INFO - grabbing minidump binary from tooltool 16:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:15 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 0x101541d30>, '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 0x1012a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101539b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:02:15 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 16:02:15 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 16:02:15 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 16:02:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:02:15 INFO - Return code: 0 16:02:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:02:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:02:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:02:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:02:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:02:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0057', '--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 16:02:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0057 --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 16:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cdhun6QU6Y/Render', 16:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:02:15 INFO - 'HOME': '/Users/cltbld', 16:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:02:15 INFO - 'LOGNAME': 'cltbld', 16:02:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:15 INFO - 'MOZ_NO_REMOTE': '1', 16:02:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:15 INFO - 'NO_EM_RESTART': '1', 16:02:15 INFO - 'PAGER': '/bin/cat', 16:02:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:15 INFO - 'PWD': '/builds/slave/test', 16:02:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:02:15 INFO - 'SHELL': '/bin/bash', 16:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AEoQpPfVP9/Listeners', 16:02:15 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 16:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:02:15 INFO - 'USER': 'cltbld', 16:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:15 INFO - 'XPC_FLAGS': '0x0', 16:02:15 INFO - 'XPC_SERVICE_NAME': '0', 16:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:02:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0057', '--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 16:02:15 INFO - mozversion application_buildid: 20160818150500 16:02:15 INFO - mozversion application_changeset: f90d73ed026973d4e442d1711eede5626bc9cf01 16:02:15 INFO - mozversion application_display_name: Nightly 16:02:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:02:15 INFO - mozversion application_name: Firefox 16:02:15 INFO - mozversion application_remotingname: firefox 16:02:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:02:15 INFO - mozversion application_vendor: Mozilla 16:02:15 INFO - mozversion application_version: 51.0a1 16:02:15 INFO - mozversion platform_buildid: 20160818150500 16:02:15 INFO - mozversion platform_changeset: f90d73ed026973d4e442d1711eede5626bc9cf01 16:02:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:02:15 INFO - mozversion platform_version: 51.0a1 16:02:15 INFO - using testdate: 1471561335 16:02:15 INFO - actual date: 1471561335 16:02:15 INFO - starting webserver on 'localhost:54682' 16:02:15 INFO - SUITE-START | Running 2 tests 16:02:15 INFO - TEST-START | dromaeo_css 16:02:15 INFO - operating with platform_type : mac_ 16:02:15 INFO - Initialising browser for dromaeo_css test... 16:02:15 INFO - TEST-INFO | started process 2246 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/tmpYn0Xql/profile http://localhost:54682/getInfo.html) 16:02:17 INFO - PROCESS | 2246 | __metrics Screen width/height:1600/1200 16:02:17 INFO - PROCESS | 2246 | colorDepth:24 16:02:17 INFO - PROCESS | 2246 | Browser inner width/height: 1024/689 16:02:17 INFO - PROCESS | 2246 | __metrics 16:02:17 INFO - PROCESS | 2246 | 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" 16:02:17 INFO - PROCESS | 2246 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:02:18 INFO - TEST-INFO | 2246: exit 0 16:02:18 INFO - Browser initialized. 16:02:18 INFO - Running cycle 1/1 for dromaeo_css test... 16:02:18 INFO - TEST-INFO | started process 2248 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/tmpYn0Xql/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:30 INFO - PROCESS | 2248 | RSS: Main: 228392960 16:02:30 INFO - PROCESS | 2248 | 16:03:22 INFO - PROCESS | 2248 | 1471561402131 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] 16:05:33 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:54682/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54682/tests/dromaeo/cssquery-ext.html) 16:05:34 INFO - PROCESS | 2248 | RSS: Main: 359387136 16:05:34 INFO - PROCESS | 2248 | 16:08:38 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:54682/tests/dromaeo/cssquery-ext.html (next: http://localhost:54682/tests/dromaeo/cssquery-jquery.html) 16:08:38 INFO - PROCESS | 2248 | RSS: Main: 377749504 16:08:38 INFO - PROCESS | 2248 | 16:11:41 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:54682/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54682/tests/dromaeo/cssquery-mootools.html) 16:11:41 INFO - PROCESS | 2248 | RSS: Main: 343707648 16:11:41 INFO - PROCESS | 2248 | 16:14:44 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:54682/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54682/tests/dromaeo/cssquery-prototype.html) 16:14:45 INFO - PROCESS | 2248 | RSS: Main: 356872192 16:14:45 INFO - PROCESS | 2248 | 16:17:48 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:54682/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54682/tests/dromaeo/cssquery-yui.html) 16:17:49 INFO - PROCESS | 2248 | RSS: Main: 345071616 16:17:49 INFO - PROCESS | 2248 | 16:20:52 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:54682/tests/dromaeo/cssquery-yui.html (next: http://localhost:54682/tests/dromaeo/cssquery-dojo.html) 16:20:52 INFO - PROCESS | 2248 | RSS: Main: 376582144 16:20:52 INFO - PROCESS | 2248 | 16:20:52 INFO - PROCESS | 2248 | __start_tp_report 16:20:52 INFO - PROCESS | 2248 | _x_x_mozilla_page_load 16:20:52 INFO - PROCESS | 2248 | _x_x_mozilla_page_load_details 16:20:52 INFO - PROCESS | 2248 | |i|pagename|runs| 16:20:52 INFO - PROCESS | 2248 | |0;dojo.html;3294.5387645729593;3319.5684561007065;3337.699607035366;3341.298327351257;3353.463445848663;5551.8889622207535;5699.886002279952;5720.11338242573;5809.099589563619;5810.825675229739;5623.297087864027;5706.857328566788;5841.3574506804325;5851.648901065928;5877.735501902414;5024.522670346311;5104.3619547556555;5105.132127538327;5115.2071428928575;5167.612429067816;5187.896242075156;5193.922091168617;5202.505761952609;5203.609729270301;5239.842804715846;5083.313752643383;5144.485551444863;5154.948450515503;5159.6646217996085;5233.4243233244315;5182.844514664566;5187.377514698261;5196.8960620787375;5197.220416937452;5228.529432351069;5090;5111.667741596784;5146.253793200002;5177.275181474596;5184.144616138333;5603.859903502404;5610.831675049755;5743.971280143573;5747.367789543188;5773.220615216934;6132.58950441401;6179.907301390438;6186.288576813679;6192.28788689302;6206.069089636546;6097.69511524422;6192.566520343577;6233.844153896143;6263.997760358321;6267.185265915402;5656.858578535528;5726.513246374105;5729.999999999958;5791.652500849962;5800.332961709458;6200.689965501707;6201.286852012076;6204.968975155186;6205.81382558524;6206.658633775095;5914.674692891932;5963.940360596339;6091.695415229309;6096.306872073336;6120.827106377172;6114.723190878623;6144.815655530341;6150.815475535741;6154.323024467305;6158.1686472326555;6107.704691583745;6115.96942015296;6122.846539228136;6125.1797411726975;6138;5356.705381203993;5964.254468191476;6007.459328660441;6018.21763170785;6138.877222455526;4800.159972004885;4861.611071114302;4867.756612169377;4869.878253043702;4934.753262336941;4326.264535029052;4377.255866502702;4378.781060947003;4389.626881715026;4418.270985287391;5534.501894829485;5689.061304884731;5706.857328566736;5759.424057594207;5759.625624334405;6126.181801870944;6158.983767678373;6212.875742485125;6212.9068063979075;6269.78055768055;5689.630174038674;5712.457316554921;5716.114002329646;5727.0550359190265;5789.88420231593;5350.759215835296;5363.088274993261;5385;5385.488378604026;5397.298351214396;6113.749968914014;6152.630842149485;6158.815235542941;6169.167162433014;6182.319944806158;6168.444839964424;6210.534209934182;6216.471599914058;6237.875242495215;6238.656873871981;6215.223097112861;6219.844503887439;6221.595596286408;6224.533160012927;6226.813195604139;4554.271316589462;5924.6149000314845;6032.306284777307;6110.7861224856915;6114.419130182626;5215.973920130375;6202.472789812917;6208.2860471044605;6218.5647004711045;6227.283862355887;6007.098935159761;6043.395660434097;6048.36492168316;6061.212042434456;6063.757449701962;5893.970530147321;5941.91087133702;5994.400559943971;6029.969850150721;6034.577579569388;5898.764049437975;5905.409459054232;5933.643981361132;5943.405659434022;5957.255343082114;5992.970035149797;6017.3681763414215;6020.337762845996;6034.879302414015;6057.939860524479;5060.3927528696795;5600.523955463831;5806.68063256525;5861.179434879035;5861.296644402699 16:20:52 INFO - PROCESS | 2248 | |1;ext.html;468.37238100944705;486.98052077916884;520.1807153732871;539.8677324055607;552.1662289942187;31031.379372412568;40797.79601101994;41795.37306940395;41893;41981.16037679252;42956;43562.782186089105;43578.346324805156;43618.56381436185;43683.78158109205;8693.217610415062;9578.994205608398;9708.029197080306;9749.610015599368;9757.512124393787;8787.516686582236;11783.823242651368;12041.759164816698;12062.57780977666;12280.754384912296;12085.697857553543;12163.209391389575;12169.93915030428;12187.695307617292;12188.695282617917;12027.45876435558;12077.818832717516;12101.213421127643;12103.273803571814;12103.394830258452;11932.22440541362;11953.103517236243;11967.1622986391;11971.162018658697;12025;41079.589204108175;41387;41395.37906931398;41450.1709965799;41644;3351.8994430167136;3412.9146771330666;3533.134382076382;3603.981980090109;3841.9423708644395;31205.531917021264;32001.359972800645;32522.674773252438;32539.34921301561;32631.184220394443;2898.6231789867393;3267;3314.9005529834144;3390.937704139212;3391.6777906098882;513091;520407;521557;523149.3842530763;525176;61707.6914615424;61905.59519226486;61958.38041619616;61975.6901215491;62010.37989620136;64627.35372646307;64659.67670161713;64831.675841621436;64851;64902;16454.588635284214;16651.500454986126;16668.6666266674;16714.749278760828;16737.665246694996;11445.370504622158;11685.532578696926;12734.68163295925;12843.293618851052;12944.352782360844;16708.6658266834;16874.83125168757;17016.149192540324;17133.742993855103;17174.570635734206;6735.158105236846;6840;6845.398748475547;6869.725210991504;6928.965355173192;845.7843249971341;932.7481579973369;965.888922773876;992.9751756206008;1034.1726618705006;29304.85347573291;30716.53925191124;31284.687153128634;31599.52600710991;31955.360892782013;800.3877034068942;978.6134476881592;995.4574756757585;1035.9378437293788;1036.3574583758118;15286.235688215545;15723.21383930799;15757.842421575866;15769.842301577068;16231.918840405722;662018;662700;665524;665674;665711;58586;58722.70638646779;58796.706016469645;59004.704976474844;59007.40992590105;1257.7673130470891;1383.6817531967502;1419.1414194412232;1446.9855301447271;1454.4691187716408;30332.54501182528;31403.685963140077;31419.214519637193;32014.83992580022;32324.676753233107;50617;51162;51199;51301.48698513117;51342.48657513377;463583;465275;466173;468870;468892;26211.34471638148;26388.868055659597;26946.59580106261;26986.730132698423;28062.43875122527;48034.51965480301;49004;49362;49882.50117498779;49948.75025624994;38274.42588361121;38451.42322865103;39694.007649807834;40043.1991360177;41256;39611;40562.79718601388;40897.77306680804;41232.1753564921;41762.791186043876;38243.808780955915;39365;39434.014149646486;39651.60348396595;39807.2038559233;30366.392672146874;35035.12412189614;35280.470792937616;35949;36942.891713248646;24310.63534047026;24469.127409421966;24474.26577202687;25749;25777.355566110997 16:20:52 INFO - PROCESS | 2248 | |2;jquery.html;349.1533032396438;369.3259800863424;369.39972544615;376.3639449330631;401.52619908507967;15386.69226615467;16978.915105424472;21428.357149285508;21755.782442175572;21887.67168492472;19766.604667906635;21041.053152608132;21867;22381;22789.834965678234;5894.469497745202;5978.760849566012;6125.785597504088;6217.471514921237;6219.222597175353;4224.894377640569;4461.196984542781;6038.667873266991;6148.415900489446;6170.598911070789;6451.096846441502;6775.62734049627;6811.693473793666;6845.14435695538;6877.970998555413;6474.805755827309;6732.83167920801;6788.660566971632;6795.082663840368;6816;5902.793402230901;5903.734331955073;5924.911126333109;5925.703714814242;7323.7070517179245;28448.431031379256;29495.55756663652;29508.409831803245;29617.703822961925;29969.10092697201;2178.498945242587;2298.804601608865;2480.2559232230437;2730.604062410939;2788.442311537701;16404.26180821866;17220.913895430567;17370.82629173705;17400.042997635126;17413;2043.3563427520282;2058.835293176557;2111.405888554147;2136.8824714640687;2143.8928053597415;90383.54808225982;90400;119268.21097683541;132986.6701332984;136149.3192534041;21531.461713457287;21626.783732162476;21735.347939561838;21770.129194832345;21817.781822181892;35382.646173537934;35633.46549801755;35671.4649280261;35685.46471802925;35733;10353.844692329621;10596.31123976939;10685.09176719972;10805.621803236849;10949.726256843483;7206.56760594366;8765.824683506295;8866.113388661212;8903.421277616933;9013.504257265782;7636.312731854048;9759.487011504589;9809.509524523744;9818.410895346302;9951.900480995242;3212.3414699986447;3358;3734.2344819311975;3744.9438258426144;3754.1177823211524;880.8018195905998;920.8388532006873;962.5471702803678;967.4582233949011;988.4761076629397;19300;20413.795862041487;21596.78403215979;21661.67507487389;21663.35009949704;781.9452638315263;813.2964985287801;889.857622780352;897.3225214962662;911.1526280559011;10876.129909607213;11233.66299011031;11259.718507037225;11316.71708207285;11350.716232094099;560210;566857;567724;576717;577670;102510.97489025015;120469.39765301117;125553;129671;134960;936.3679516326453;972.2659392159017;974.45917515778;977.359829311802;1040.458961340114;19666.213351466355;20145.496362591053;20313.289034883706;20326.186952521733;20382.898085509478;54823;57501;58280.12579811391;58325;58569;79270.81093783483;81838;85555.5722221385;85744.56703778042;85847;7916.208379162038;8014.278714915686;8212;8254.83490330202;8435.156484351515;12203.816942746043;12245.387730613611;12431.564895228623;12938.547150849676;13232.206067635972;9751.951240243754;11433.59982400612;11461.140414468782;11582.07343412508;11624.186306958432;11071.5571377144;11186.216964812385;11251.549938002716;11523.654290371302;11546.653600392001;9362.95318523403;11293.548258069584;11539.249948753304;11700.648980530597;11768.58809941648;7751.92248077512;8029.638666259915;8080.757577272691;8130.39022073335;8181.977252843394;9084.63661453561;11224.7755044897;11338.716532086433;11539.134564907834;11539.480723367637 16:20:52 INFO - PROCESS | 2248 | |3;mootools.html;1385.4042761612507;1476.667749756305;1634.9100799456032;1827.9086045697711;1843.8801477903944;8347.207015333548;8916.420432671872;8925.955370223157;8926.330525210618;8942.19520243178;7690.923090769091;7928;8382.161783821615;8578.270846978;8694.869576956351;2093.3824521766114;2343.8124950003958;2359.469119448119;2375.9049638014544;2383.189715496731;2346.671465994762;2366.7278262999716;2404.843685160459;2407.6388541718793;2416.565018296706;2371.0752806405517;2377.8454400464;2392.5334559760854;2398.928032159038;2402.267308470916;2342.4612339161918;2360.881955902198;2364.8935797889135;2378.988105059481;2387.5821731197143;2334.4280651240383;2362.1850461590725;2367.0413482539598;2370.2889133260123;2373.774491423312;8756.518391488466;8829.028806831244;8848.424852384574;8862.0251772305;8864.778380540474;4693.6949098308505;5206.349206349207;5381.381141168777;5381.784728610851;5389;3610.6930910872607;3618.149734812317;3687.059799751073;3688.8155592220282;3691.9261614767556;1076.3649446826332;1083.8807731149568;1085.5006696919347;1098.5660664037741;1108.51225460797;9813;11551.826722599168;11621.360825154614;11632.709182270512;11649.708757281136;4502.752348620857;4585.95414045862;4772.260299653559;4783.378160839069;4790.257510085942;9857.40855548655;9857.507124643738;9883.555240014217;9885.45629990343;9898.30711850178;4682.578567928903;5346.4386239445075;5356.357237131491;5356.437574054746;5365.3293338332705;4395.164918665443;4557.749323787158;4753.073150735642;4880.633952453581;4896.608271338285;3848.615138486185;3902.726809123391;3982.4026396040827;3997.5402828675074;4014.157027024299;1286.298967062953;1288.6778305423645;1294.1975974895624;1301.342821874947;1312.934353282332;790.4703848421572;901.1889299630385;914.5061666700041;927.7263207353847;970.6020531582018;5671.914921276184;5787.536997040227;5932.525397968152;6008.008678567988;6141.7543298267565;438.4169055156634;438.95829896159813;447.17942575374553;757.8635845547855;1003.1673710820002;3397.43942249531;3774.0176087584996;4035.7982100894837;4070.5725898780793;4074.2055299216368;9885.308028437941;10277.9486102569;10837.02466777978;10838.620648277272;10839.458027098613;10446.320989135682;10836.729081773019;10866.022058014816;10875.238733288752;10882;8039.7990050248045;8293.792655183668;8299.2530672241;8300.252977232078;8328.417010809306;5682.772689092555;5724;5848.385919478394;5868.589198756046;5928.318243402064;10078.697639070839;10114.848277275994;10147;10155.492225388552;10205.438700871524;9872.851907221253;9960.153386962189;10128.34165779222;10319.48402579883;10357.17142628603;6483.578567393104;6684.4318232950745;6685.2646208916;6990.860182796214;7209.35115839577;7904.802379940317;8001.959990200012;8013.278804907353;8139.633716482654;8428.747137585882;7472.589007604416;7554.206808285051;7582.734604288823;7601.88597171054;7734.187910269566;7371.594562299125;7410.888836667346;7427.294407031323;7433.851322973618;7577.962110189414;6867.3476019778045;7016.649167541705;7025.789226323219;7032.26161253061;7046.577205367695;4830.468648448738;5017.724025178504;5119.667221630582;5181.870453238699;5259.027079990214;4900.926486102782;5064.569511591556;5182.32629758129;5225.712585807817;5264.9210261846865 16:20:52 INFO - PROCESS | 2248 | |4;prototype.html;275.5549787093844;282.1676055635874;289.370618903884;289.4023840768812;291.4360712022237;5221.216817477381;5236.2407450919645;5237.476252374765;5258.579313654908;5260.500252476008;4850.5877000455;4889.168841296979;5095.745212739357;5183.352080989876;5270.600462701555;3361.6302206757236;3758.2107757370986;3953.4069889516586;4009.178118485718;4013.9598604014022;3526.4710293455933;3556.039869235305;3687.3362794696945;3718.590954994962;3922;3708.629137086283;3770.396736522157;3849.711271654623;3991;4002.919941601166;3725.217704282104;3913.2802702122035;3959.4456776051375;3960.683145348365;3966.5522084439262;3270.051685011344;3464.5149679044953;3472.7048200902955;3481.860725570974;3485.825708714554;4583.9083218335445;4810.254410566367;5104.668196567211;5110.565601923804;5135;4483.932741008887;4497.910041799146;4528.43394575678;4530.275155975061;4553.225951588238;5423.7830486780485;5460.26286451328;5474.275603185024;5477.877035207773;5519.641223320512;3222.6455089940087;3234.2399536108996;3789.2232092420986;3793.3740932746064;3848.807559622008;5414.729263536808;5473.200689018133;5665.943340566583;5730.7421166047625;5736.541076713853;3833.201762713235;3839.6578087366684;3849.2301539692176;3855.383138697795;3859.7684138951754;3691.5201023866725;3902.434147048719;3928.489296391508;3937.625925537069;3942.4821443744363;4284.892877678083;4529.705569137996;4557.883318586967;4586.472555656075;4593.104344652827;5480.629842539365;5512.614117011771;5515.283013208257;5528.281323428009;5539.667619942817;1119.2780656476527;1233.1614502138452;1331.4873773597235;1333.3533236380347;1333.5599252246736;726.4733068525277;796.1560745609672;822.7984143884787;871.7123349294718;890.9287257019423;4052.1085361220485;4765.9030026788105;4984.903321269315;5133.871653208625;5134.435212126663;4939.542834863724;5166.948330516722;5192.532672059533;5532.695701736443;5573.303337082864;4451.821927122944;4755.096531659043;4836.7339796310835;4851.539103785135;5121.334226550524;2715.456908618284;2848.4364072335065;2951.4539810134766;2976.702329767006;2979.836109014025;4661.813527458864;5211.299977016361;5569.27599412082;5670.262865827416;5905.734241959122;5480.534154596824;5814.94185058144;5816.709164541756;5828.737706803204;5884.352721200665;5845.7369418376275;5858.94141058584;5860.501857342174;5887.4406931341455;5894.557908156819;5261.736913154404;5339.092354299701;5485.476680726751;5663.263775709132;5690.658560486384;5131.4862115676715;5637.718114094197;5649.858753531194;5652.4064973178865;5663.8389130229025;5894.764209431746;5900.940990590039;5931.228940237742;5943.729365519419;5997.220361353171;3625.4410603440774;3730.981345093257;3747.437884317374;3773.2264885698914;3811.371123764548;4298.720583162084;4754.45323787755;5654.575906806924;5684.237886255274;5747.798827041033;5544.61187716856;5550.639208451437;5556.943930745489;5574.9163762542785;5584.464272325143;4583.449986001701;5078.695278283315;5114.902889815158;5550.750216240198;5597.216389705363;4943.3573635427165;5021.6735912165595;5232.895342093061;5472.343318801769;5480.3971563127225;4761.500042495627;5022.547970682656;5304.336957880265;5404.8108316208745;5502.752376143003;4768.66619336657;5442.619016668795;5446.128619421032;5455.672659640434;5470.647621522783 16:20:52 INFO - PROCESS | 2248 | |5;yui.html;706.9328413800689;726.5096060159391;764.4610549562556;809.3606051219537;815.6451943404619;16163.595910102238;16763.748543771853;16772.329106835743;16797.83202167978;16799.328026878924;14918.925405372986;15096.6980660387;15119.24403779812;15125.924370378132;15137.24313784312;683.8803209438353;699.1679900917917;835.3150416658312;847.0936098374741;848.516345682961;834.5034704350901;858.6307887608326;859.3726579596898;859.8151397449548;860.9655613775442;915.0849150849151;917.47703808829;918.8759517465123;919.2416256588308;919.4437365393941;706.6678661029308;876.0976194519654;904.4708845325426;911.1344222988155;913.3743385780762;675.1729131813538;676.651524464901;676.7394553097093;677.1434135818145;680.7787469072571;14846.70306593862;14875.553733388017;14890.851091489163;14900.701985960328;14918.477853275192;11819;13728.931355343258;14014.92992535041;14047.929760351235;14152.71694566103;12726;12875.549355772597;13029.54396596124;13264.903276746894;13400.195988240737;3394.100563350714;3394.9490757638655;3423.9315213695836;3439.9656003440145;3583.964160358389;94340;115495;115699.42150289279;115902.25075241717;116872;11002.394868282321;11024.889751102386;11062.502187401426;11146.164037697205;11477.655670329903;21681.34955951324;21682.56634867294;21695.891520542613;21730.674039889414;21774.346769596938;8002;8213.589320533949;8246.587670616445;8247.175282471704;8280.875786863202;4352.542982986804;4451.77741112943;4489.169503641837;4516.277395616686;4553.271476563734;3420.452727563578;3536.91157721059;3540.504329393887;3544.8404821782565;3547.6452354764833;639.3606393606393;643.5784561112479;660.2638058564665;666.1706175811049;669.5045666206972;538.8895276468393;541.2882060090968;558.2700594094879;629.4052120745852;635.8251480842805;8634.78413039679;9119.680811171576;9132.863007054899;9268;9293.395929264576;578.8552861784553;612.5803824380314;613.8864310102646;630.3412933484519;644.0628884972353;4240.533541310453;4428.778561071934;4442.800073996678;4461.7992190351515;4484.786957690079;166478;168632;168708.474643502;169974;171731;111564;146866.26566867097;147635;150872.73690894645;151986;94388.58417123748;99274.5036274814;107195.92804072016;107622.46188769162;111806.96868640931;8321.16788321163;8914.777130571787;10060.4305049194;10102.69691909244;10145.64490242838;63318.36681633309;63370.41573960688;64026.03960940683;64063;64194.35805641884;83934;92790;96690;98025.50987245019;99911;11615.419229038684;12993.101667221443;13275.66810829737;13383.39774710121;13484.86515134836;14649.780253295996;14784.966222076646;15081.24593770329;15141.621459463602;15150.469733559272;13578.86421135776;13728.679095597065;14077.859221407654;14181.361838717075;14207.147571145764;13997.790033149715;14107.788383174055;14120.50578229757;14175.291235438393;14368;13172.73654526885;13372.465101395836;13494.055416120776;13876.236806975714;14292.28538573088;10742.516586753458;11404.714882128013;11465.770684586094;11658.242214256046;11728.472218750347;10785.784284314426;11220.719482013015;11300.152488563555;11509;11581.942090289494 16:20:52 INFO - PROCESS | 2248 | __end_tp_report 16:20:52 INFO - PROCESS | 2248 | __start_cc_report 16:20:52 INFO - PROCESS | 2248 | _x_x_mozilla_cycle_collect,1266 16:20:52 INFO - PROCESS | 2248 | __end_cc_report 16:20:52 INFO - PROCESS | 2248 | __startTimestamp1471562452737__endTimestamp 16:20:52 INFO - PROCESS | 2248 | 16:20:52 INFO - PROCESS | 2248 | ------- Summary: start ------- 16:20:52 INFO - PROCESS | 2248 | Number of tests: 6 16:20:52 INFO - PROCESS | 2248 | 16:20:52 INFO - PROCESS | 2248 | [#0] dojo.html Cycles:165 Average:5668.68 Median:5877.74 stddev:631.47 (10.7%) stddev-sans-first:605.32 16:20:52 INFO - PROCESS | 2248 | Values: 3294.5 3319.6 3337.7 3341.3 3353.5 5551.9 5699.9 5720.1 5809.1 5810.8 5623.3 5706.9 5841.4 5851.6 5877.7 5024.5 5104.4 5105.1 5115.2 5167.6 5187.9 5193.9 5202.5 5203.6 5239.8 5083.3 5144.5 5154.9 5159.7 5233.4 5182.8 5187.4 5196.9 5197.2 5228.5 5090.0 5111.7 5146.3 5177.3 5184.1 5603.9 5610.8 5744.0 5747.4 5773.2 6132.6 6179.9 6186.3 6192.3 6206.1 6097.7 6192.6 6233.8 6264.0 6267.2 5656.9 5726.5 5730.0 5791.7 5800.3 6200.7 6201.3 6205.0 6205.8 6206.7 5914.7 5963.9 6091.7 6096.3 6120.8 6114.7 6144.8 6150.8 6154.3 6158.2 6107.7 6116.0 6122.8 6125.2 6138.0 5356.7 5964.3 6007.5 6018.2 6138.9 4800.2 4861.6 4867.8 4869.9 4934.8 4326.3 4377.3 4378.8 4389.6 4418.3 5534.5 5689.1 5706.9 5759.4 5759.6 6126.2 6159.0 6212.9 6212.9 6269.8 5689.6 5712.5 5716.1 5727.1 5789.9 5350.8 5363.1 5385.0 5385.5 5397.3 6113.7 6152.6 6158.8 6169.2 6182.3 6168.4 6210.5 6216.5 6237.9 6238.7 6215.2 6219.8 6221.6 6224.5 6226.8 4554.3 5924.6 6032.3 6110.8 6114.4 5216.0 6202.5 6208.3 6218.6 6227.3 6007.1 6043.4 6048.4 6061.2 6063.8 5894.0 5941.9 5994.4 6030.0 6034.6 5898.8 5905.4 5933.6 5943.4 5957.3 5993.0 6017.4 6020.3 6034.9 6057.9 5060.4 5600.5 5806.7 5861.2 5861.3 16:20:52 INFO - PROCESS | 2248 | 16:20:52 INFO - PROCESS | 2248 | [#1] ext.html Cycles:180 Average:69416.56 Median:38908.21 stddev:148586.83 (381.9%) stddev-sans-first:148913.48 16:20:52 INFO - PROCESS | 2248 | Values: 468.4 487.0 520.2 539.9 552.2 31031.4 40797.8 41795.4 41893.0 41981.2 42956.0 43562.8 43578.3 43618.6 43683.8 8693.2 9579.0 9708.0 9749.6 9757.5 8787.5 11783.8 12041.8 12062.6 12280.8 12085.7 12163.2 12169.9 12187.7 12188.7 12027.5 12077.8 12101.2 12103.3 12103.4 11932.2 11953.1 11967.2 11971.2 12025.0 41079.6 41387.0 41395.4 41450.2 41644.0 3351.9 3412.9 3533.1 3604.0 3841.9 31205.5 32001.4 32522.7 32539.3 32631.2 2898.6 3267.0 3314.9 3390.9 3391.7 513091.0 520407.0 521557.0 523149.4 525176.0 61707.7 61905.6 61958.4 61975.7 62010.4 64627.4 64659.7 64831.7 64851.0 64902.0 16454.6 16651.5 16668.7 16714.7 16737.7 11445.4 11685.5 12734.7 12843.3 12944.4 16708.7 16874.8 17016.1 17133.7 17174.6 6735.2 6840.0 6845.4 6869.7 6929.0 845.8 932.7 965.9 993.0 1034.2 29304.9 30716.5 31284.7 31599.5 31955.4 800.4 978.6 995.5 1035.9 1036.4 15286.2 15723.2 15757.8 15769.8 16231.9 662018.0 662700.0 665524.0 665674.0 665711.0 58586.0 58722.7 58796.7 59004.7 59007.4 1257.8 1383.7 1419.1 1447.0 1454.5 30332.5 31403.7 31419.2 32014.8 32324.7 50617.0 51162.0 51199.0 51301.5 51342.5 463583.0 465275.0 466173.0 468870.0 468892.0 26211.3 26388.9 26946.6 26986.7 28062.4 48034.5 49004.0 49362.0 49882.5 49948.8 38274.4 38451.4 39694.0 40043.2 41256.0 39611.0 40562.8 40897.8 41232.2 41762.8 38243.8 39365.0 39434.0 39651.6 39807.2 30366.4 35035.1 35280.5 35949.0 36942.9 24310.6 24469.1 24474.3 25749.0 25777.4 16:20:52 INFO - PROCESS | 2248 | 16:20:52 INFO - PROCESS | 2248 | [#2] jquery.html Cycles:180 Average:35810.55 Median:35652.47 stddev:95050.05 (266.6%) stddev-sans-first:95279.40 16:20:52 INFO - PROCESS | 2248 | Values: 349.2 369.3 369.4 376.4 401.5 15386.7 16978.9 21428.4 21755.8 21887.7 19766.6 21041.1 21867.0 22381.0 22789.8 5894.5 5978.8 6125.8 6217.5 6219.2 4224.9 4461.2 6038.7 6148.4 6170.6 6451.1 6775.6 6811.7 6845.1 6878.0 6474.8 6732.8 6788.7 6795.1 6816.0 5902.8 5903.7 5924.9 5925.7 7323.7 28448.4 29495.6 29508.4 29617.7 29969.1 2178.5 2298.8 2480.3 2730.6 2788.4 16404.3 17220.9 17370.8 17400.0 17413.0 2043.4 2058.8 2111.4 2136.9 2143.9 90383.5 90400.0 119268.2 132986.7 136149.3 21531.5 21626.8 21735.3 21770.1 21817.8 35382.6 35633.5 35671.5 35685.5 35733.0 10353.8 10596.3 10685.1 10805.6 10949.7 7206.6 8765.8 8866.1 8903.4 9013.5 7636.3 9759.5 9809.5 9818.4 9951.9 3212.3 3358.0 3734.2 3744.9 3754.1 880.8 920.8 962.5 967.5 988.5 19300.0 20413.8 21596.8 21661.7 21663.4 781.9 813.3 889.9 897.3 911.2 10876.1 11233.7 11259.7 11316.7 11350.7 560210.0 566857.0 567724.0 576717.0 577670.0 102511.0 120469.4 125553.0 129671.0 134960.0 936.4 972.3 974.5 977.4 1040.5 19666.2 20145.5 20313.3 20326.2 20382.9 54823.0 57501.0 58280.1 58325.0 58569.0 79270.8 81838.0 85555.6 85744.6 85847.0 7916.2 8014.3 8212.0 8254.8 8435.2 12203.8 12245.4 12431.6 12938.5 13232.2 9752.0 11433.6 11461.1 11582.1 11624.2 11071.6 11186.2 11251.5 11523.7 11546.7 9363.0 11293.5 11539.2 11700.6 11768.6 7751.9 8029.6 8080.8 8130.4 8182.0 9084.6 11224.8 11338.7 11539.1 11539.5 16:20:52 INFO - PROCESS | 2248 | 16:20:52 INFO - PROCESS | 2248 | [#3] mootools.html Cycles:180 Average:5677.32 Median:4855.55 stddev:3178.46 (65.5%) stddev-sans-first:3171.01 16:20:52 INFO - PROCESS | 2248 | Values: 1385.4 1476.7 1634.9 1827.9 1843.9 8347.2 8916.4 8926.0 8926.3 8942.2 7690.9 7928.0 8382.2 8578.3 8694.9 2093.4 2343.8 2359.5 2375.9 2383.2 2346.7 2366.7 2404.8 2407.6 2416.6 2371.1 2377.8 2392.5 2398.9 2402.3 2342.5 2360.9 2364.9 2379.0 2387.6 2334.4 2362.2 2367.0 2370.3 2373.8 8756.5 8829.0 8848.4 8862.0 8864.8 4693.7 5206.3 5381.4 5381.8 5389.0 3610.7 3618.1 3687.1 3688.8 3691.9 1076.4 1083.9 1085.5 1098.6 1108.5 9813.0 11551.8 11621.4 11632.7 11649.7 4502.8 4586.0 4772.3 4783.4 4790.3 9857.4 9857.5 9883.6 9885.5 9898.3 4682.6 5346.4 5356.4 5356.4 5365.3 4395.2 4557.7 4753.1 4880.6 4896.6 3848.6 3902.7 3982.4 3997.5 4014.2 1286.3 1288.7 1294.2 1301.3 1312.9 790.5 901.2 914.5 927.7 970.6 5671.9 5787.5 5932.5 6008.0 6141.8 438.4 439.0 447.2 757.9 1003.2 3397.4 3774.0 4035.8 4070.6 4074.2 9885.3 10277.9 10837.0 10838.6 10839.5 10446.3 10836.7 10866.0 10875.2 10882.0 8039.8 8293.8 8299.3 8300.3 8328.4 5682.8 5724.0 5848.4 5868.6 5928.3 10078.7 10114.8 10147.0 10155.5 10205.4 9872.9 9960.2 10128.3 10319.5 10357.2 6483.6 6684.4 6685.3 6990.9 7209.4 7904.8 8002.0 8013.3 8139.6 8428.7 7472.6 7554.2 7582.7 7601.9 7734.2 7371.6 7410.9 7427.3 7433.9 7578.0 6867.3 7016.6 7025.8 7032.3 7046.6 4830.5 5017.7 5119.7 5181.9 5259.0 4900.9 5064.6 5182.3 5225.7 5264.9 16:20:52 INFO - PROCESS | 2248 | 16:20:52 INFO - PROCESS | 2248 | [#4] prototype.html Cycles:180 Average:4458.81 Median:5050.62 stddev:1381.94 (27.4%) stddev-sans-first:1349.67 16:20:52 INFO - PROCESS | 2248 | Values: 275.6 282.2 289.4 289.4 291.4 5221.2 5236.2 5237.5 5258.6 5260.5 4850.6 4889.2 5095.7 5183.4 5270.6 3361.6 3758.2 3953.4 4009.2 4014.0 3526.5 3556.0 3687.3 3718.6 3922.0 3708.6 3770.4 3849.7 3991.0 4002.9 3725.2 3913.3 3959.4 3960.7 3966.6 3270.1 3464.5 3472.7 3481.9 3485.8 4583.9 4810.3 5104.7 5110.6 5135.0 4483.9 4497.9 4528.4 4530.3 4553.2 5423.8 5460.3 5474.3 5477.9 5519.6 3222.6 3234.2 3789.2 3793.4 3848.8 5414.7 5473.2 5665.9 5730.7 5736.5 3833.2 3839.7 3849.2 3855.4 3859.8 3691.5 3902.4 3928.5 3937.6 3942.5 4284.9 4529.7 4557.9 4586.5 4593.1 5480.6 5512.6 5515.3 5528.3 5539.7 1119.3 1233.2 1331.5 1333.4 1333.6 726.5 796.2 822.8 871.7 890.9 4052.1 4765.9 4984.9 5133.9 5134.4 4939.5 5166.9 5192.5 5532.7 5573.3 4451.8 4755.1 4836.7 4851.5 5121.3 2715.5 2848.4 2951.5 2976.7 2979.8 4661.8 5211.3 5569.3 5670.3 5905.7 5480.5 5814.9 5816.7 5828.7 5884.4 5845.7 5858.9 5860.5 5887.4 5894.6 5261.7 5339.1 5485.5 5663.3 5690.7 5131.5 5637.7 5649.9 5652.4 5663.8 5894.8 5900.9 5931.2 5943.7 5997.2 3625.4 3731.0 3747.4 3773.2 3811.4 4298.7 4754.5 5654.6 5684.2 5747.8 5544.6 5550.6 5556.9 5574.9 5584.5 4583.4 5078.7 5114.9 5550.8 5597.2 4943.4 5021.7 5232.9 5472.3 5480.4 4761.5 5022.5 5304.3 5404.8 5502.8 4768.7 5442.6 5446.1 5455.7 5470.6 16:20:52 INFO - PROCESS | 2248 | 16:20:52 INFO - PROCESS | 2248 | [#5] yui.html Cycles:180 Average:25901.86 Median:3394.52 stddev:42413.20 (1249.5%) stddev-sans-first:42489.99 16:20:52 INFO - PROCESS | 2248 | Values: 706.9 726.5 764.5 809.4 815.6 16163.6 16763.7 16772.3 16797.8 16799.3 14918.9 15096.7 15119.2 15125.9 15137.2 683.9 699.2 835.3 847.1 848.5 834.5 858.6 859.4 859.8 861.0 915.1 917.5 918.9 919.2 919.4 706.7 876.1 904.5 911.1 913.4 675.2 676.7 676.7 677.1 680.8 14846.7 14875.6 14890.9 14900.7 14918.5 11819.0 13728.9 14014.9 14047.9 14152.7 12726.0 12875.5 13029.5 13264.9 13400.2 3394.1 3394.9 3423.9 3440.0 3584.0 94340.0 115495.0 115699.4 115902.3 116872.0 11002.4 11024.9 11062.5 11146.2 11477.7 21681.3 21682.6 21695.9 21730.7 21774.3 8002.0 8213.6 8246.6 8247.2 8280.9 4352.5 4451.8 4489.2 4516.3 4553.3 3420.5 3536.9 3540.5 3544.8 3547.6 639.4 643.6 660.3 666.2 669.5 538.9 541.3 558.3 629.4 635.8 8634.8 9119.7 9132.9 9268.0 9293.4 578.9 612.6 613.9 630.3 644.1 4240.5 4428.8 4442.8 4461.8 4484.8 166478.0 168632.0 168708.5 169974.0 171731.0 111564.0 146866.3 147635.0 150872.7 151986.0 94388.6 99274.5 107195.9 107622.5 111807.0 8321.2 8914.8 10060.4 10102.7 10145.6 63318.4 63370.4 64026.0 64063.0 64194.4 83934.0 92790.0 96690.0 98025.5 99911.0 11615.4 12993.1 13275.7 13383.4 13484.9 14649.8 14785.0 15081.2 15141.6 15150.5 13578.9 13728.7 14077.9 14181.4 14207.1 13997.8 14107.8 14120.5 14175.3 14368.0 13172.7 13372.5 13494.1 13876.2 14292.3 10742.5 11404.7 11465.8 11658.2 11728.5 10785.8 11220.7 11300.2 11509.0 11581.9 16:20:52 INFO - PROCESS | 2248 | -------- Summary: end -------- 16:20:52 INFO - PROCESS | 2248 | 16:20:53 INFO - TEST-INFO | 2248: exit 0 16:20:53 INFO - TEST-OK | dromaeo_css | took 1117982ms 16:20:53 INFO - TEST-START | kraken 16:20:53 INFO - operating with platform_type : mac_ 16:20:53 INFO - Initialising browser for kraken test... 16:20:53 INFO - TEST-INFO | started process 2250 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/tmpJ7DAip/profile http://localhost:54682/getInfo.html) 16:20:55 INFO - PROCESS | 2250 | __metrics Screen width/height:1600/1200 16:20:55 INFO - PROCESS | 2250 | colorDepth:24 16:20:55 INFO - PROCESS | 2250 | Browser inner width/height: 1024/689 16:20:55 INFO - PROCESS | 2250 | __metrics 16:20:55 INFO - PROCESS | 2250 | 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" 16:20:55 INFO - PROCESS | 2250 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:20:56 INFO - TEST-INFO | 2250: exit 0 16:20:56 INFO - Browser initialized. 16:20:56 INFO - Running cycle 1/1 for kraken test... 16:20:56 INFO - TEST-INFO | started process 2251 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/tmpJ7DAip/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:21:08 INFO - PROCESS | 2251 | RSS: Main: 228380672 16:21:08 INFO - PROCESS | 2251 | 16:21:11 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54682/tests/kraken/driver.html?testName=audio-beat-detection) 16:21:12 INFO - PROCESS | 2251 | RSS: Main: 286269440 16:21:12 INFO - PROCESS | 2251 | 16:21:14 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54682/tests/kraken/driver.html?testName=audio-dft) 16:21:15 INFO - PROCESS | 2251 | RSS: Main: 294273024 16:21:15 INFO - PROCESS | 2251 | 16:21:18 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54682/tests/kraken/driver.html?testName=audio-fft) 16:21:19 INFO - PROCESS | 2251 | RSS: Main: 386797568 16:21:19 INFO - PROCESS | 2251 | 16:21:21 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54682/tests/kraken/driver.html?testName=audio-oscillator) 16:21:22 INFO - PROCESS | 2251 | RSS: Main: 288612352 16:21:22 INFO - PROCESS | 2251 | 16:21:24 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54682/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:21:24 INFO - PROCESS | 2251 | RSS: Main: 293183488 16:21:24 INFO - PROCESS | 2251 | 16:21:27 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54682/tests/kraken/driver.html?testName=imaging-darkroom) 16:21:28 INFO - PROCESS | 2251 | RSS: Main: 327614464 16:21:28 INFO - PROCESS | 2251 | 16:21:31 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54682/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:31 INFO - PROCESS | 2251 | RSS: Main: 331198464 16:21:31 INFO - PROCESS | 2251 | 16:21:34 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54682/tests/kraken/driver.html?testName=json-parse-financial) 16:21:35 INFO - PROCESS | 2251 | RSS: Main: 328945664 16:21:35 INFO - PROCESS | 2251 | 16:21:36 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54682/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:37 INFO - PROCESS | 2251 | RSS: Main: 302125056 16:21:37 INFO - PROCESS | 2251 | 16:21:39 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54682/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:39 INFO - PROCESS | 2251 | RSS: Main: 309456896 16:21:39 INFO - PROCESS | 2251 | 16:21:41 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54682/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:42 INFO - PROCESS | 2251 | RSS: Main: 301412352 16:21:42 INFO - PROCESS | 2251 | 16:21:45 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:46 INFO - PROCESS | 2251 | RSS: Main: 314925056 16:21:46 INFO - PROCESS | 2251 | 16:21:49 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:21:49 INFO - PROCESS | 2251 | RSS: Main: 317808640 16:21:49 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | Cycle 1(1): loaded http://localhost:54682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54682/tests/kraken/driver.html?testName=ai-astar) 16:21:51 INFO - PROCESS | 2251 | RSS: Main: 360906752 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | __start_tp_report 16:21:51 INFO - PROCESS | 2251 | _x_x_mozilla_page_load 16:21:51 INFO - PROCESS | 2251 | _x_x_mozilla_page_load_details 16:21:51 INFO - PROCESS | 2251 | |i|pagename|runs| 16:21:51 INFO - PROCESS | 2251 | |0;ai-astar;102;112;113;112;108;112;113;114;111;112 16:21:51 INFO - PROCESS | 2251 | |1;audio-beat-detection;142;108;107;166;107;106;131;107;140;106 16:21:51 INFO - PROCESS | 2251 | |2;audio-dft;184;183;184;185;183;180;182;185;184;182 16:21:51 INFO - PROCESS | 2251 | |3;audio-fft;80;74;58;86;58;57;59;68;85;76 16:21:51 INFO - PROCESS | 2251 | |4;audio-oscillator;85;90;96;73;70;67;98;71;68;104 16:21:51 INFO - PROCESS | 2251 | |5;imaging-gaussian-blur;87;85;88;85;87;84;87;86;87;84 16:21:51 INFO - PROCESS | 2251 | |6;imaging-darkroom;92;92;93;97;93;93;92;95;92;100 16:21:51 INFO - PROCESS | 2251 | |7;imaging-desaturate;84;85;84;86;84;84;85;84;85;84 16:21:51 INFO - PROCESS | 2251 | |8;json-parse-financial;54;51;54;55;52;54;55;53;54;53 16:21:51 INFO - PROCESS | 2251 | |9;json-stringify-tinderbox;73;79;64;72;73;70;67;70;69;69 16:21:51 INFO - PROCESS | 2251 | |10;stanford-crypto-aes;86;86;84;85;82;85;85;84;88;86 16:21:51 INFO - PROCESS | 2251 | |11;stanford-crypto-ccm;139;138;141;144;138;137;142;149;225;150 16:21:51 INFO - PROCESS | 2251 | |12;stanford-crypto-pbkdf2;171;172;171;168;167;168;167;167;178;230 16:21:51 INFO - PROCESS | 2251 | |13;stanford-crypto-sha256-iterative;66;67;68;68;66;67;67;66;66;67 16:21:51 INFO - PROCESS | 2251 | __end_tp_report 16:21:51 INFO - PROCESS | 2251 | __start_cc_report 16:21:51 INFO - PROCESS | 2251 | _x_x_mozilla_cycle_collect,2025 16:21:51 INFO - PROCESS | 2251 | __end_cc_report 16:21:51 INFO - PROCESS | 2251 | __startTimestamp1471562511824__endTimestamp 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | ------- Summary: start ------- 16:21:51 INFO - PROCESS | 2251 | Number of tests: 14 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:3.51 (3.1%) stddev-sans-first:1.69 16:21:51 INFO - PROCESS | 2251 | Values: 102.0 112.0 113.0 112.0 108.0 112.0 113.0 114.0 111.0 112.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:119.50 stddev:21.41 (17.9%) stddev-sans-first:21.45 16:21:51 INFO - PROCESS | 2251 | Values: 142.0 108.0 107.0 166.0 107.0 106.0 131.0 107.0 140.0 106.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:1.55 (0.8%) stddev-sans-first:1.62 16:21:51 INFO - PROCESS | 2251 | Values: 184.0 183.0 184.0 185.0 183.0 180.0 182.0 185.0 184.0 182.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#3] audio-fft Cycles:10 Average:70.10 Median:75.00 stddev:11.62 (15.5%) stddev-sans-first:11.76 16:21:51 INFO - PROCESS | 2251 | Values: 80.0 74.0 58.0 86.0 58.0 57.0 59.0 68.0 85.0 76.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#4] audio-oscillator Cycles:10 Average:82.20 Median:79.00 stddev:14.05 (17.8%) stddev-sans-first:14.86 16:21:51 INFO - PROCESS | 2251 | Values: 85.0 90.0 96.0 73.0 70.0 67.0 98.0 71.0 68.0 104.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.41 (1.6%) stddev-sans-first:1.45 16:21:51 INFO - PROCESS | 2251 | Values: 87.0 85.0 88.0 85.0 87.0 84.0 87.0 86.0 87.0 84.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:93.00 stddev:2.69 (2.9%) stddev-sans-first:2.76 16:21:51 INFO - PROCESS | 2251 | Values: 92.0 92.0 93.0 97.0 93.0 93.0 92.0 95.0 92.0 100.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:84.50 stddev:0.71 (0.8%) stddev-sans-first:0.73 16:21:51 INFO - PROCESS | 2251 | Values: 84.0 85.0 84.0 86.0 84.0 84.0 85.0 84.0 85.0 84.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.27 (2.4%) stddev-sans-first:1.33 16:21:51 INFO - PROCESS | 2251 | Values: 54.0 51.0 54.0 55.0 52.0 54.0 55.0 53.0 54.0 53.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.00 stddev:4.03 (5.7%) stddev-sans-first:4.18 16:21:51 INFO - PROCESS | 2251 | Values: 73.0 79.0 64.0 72.0 73.0 70.0 67.0 70.0 69.0 69.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:85.50 stddev:1.60 (1.9%) stddev-sans-first:1.66 16:21:51 INFO - PROCESS | 2251 | Values: 86.0 86.0 84.0 85.0 82.0 85.0 85.0 84.0 88.0 86.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:143.00 stddev:26.63 (18.6%) stddev-sans-first:27.93 16:21:51 INFO - PROCESS | 2251 | Values: 139.0 138.0 141.0 144.0 138.0 137.0 142.0 149.0 225.0 150.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:171.00 stddev:19.31 (11.3%) stddev-sans-first:20.40 16:21:51 INFO - PROCESS | 2251 | Values: 171.0 172.0 171.0 168.0 167.0 168.0 167.0 167.0 178.0 230.0 16:21:51 INFO - PROCESS | 2251 | 16:21:51 INFO - PROCESS | 2251 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 16:21:51 INFO - PROCESS | 2251 | Values: 66.0 67.0 68.0 68.0 66.0 67.0 67.0 66.0 66.0 67.0 16:21:51 INFO - PROCESS | 2251 | -------- Summary: end -------- 16:21:51 INFO - PROCESS | 2251 | 16:21:52 INFO - TEST-INFO | 2251: exit 0 16:21:52 INFO - TEST-OK | kraken | took 59333ms 16:21:52 INFO - SUITE-END | took 1177s 16:21:52 INFO - Completed test suite (00:19:37) 16:21:52 INFO - javascript benchmark 16:21:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3294.5387645729593, 3319.5684561007065, 3337.699607035366, 3341.298327351257, 3353.463445848663, 5551.8889622207535, 5699.886002279952, 5720.11338242573, 5809.099589563619, 5810.825675229739, 5623.297087864027, 5706.857328566788, 5841.3574506804325, 5851.648901065928, 5877.735501902414, 5024.522670346311, 5104.3619547556555, 5105.132127538327, 5115.2071428928575, 5167.612429067816, 5187.896242075156, 5193.922091168617, 5202.505761952609, 5203.609729270301, 5239.842804715846, 5083.313752643383, 5144.485551444863, 5154.948450515503, 5159.6646217996085, 5233.4243233244315, 5182.844514664566, 5187.377514698261, 5196.8960620787375, 5197.220416937452, 5228.529432351069, 5090.0, 5111.667741596784, 5146.253793200002, 5177.275181474596, 5184.144616138333, 5603.859903502404, 5610.831675049755, 5743.971280143573, 5747.367789543188, 5773.220615216934, 6132.58950441401, 6179.907301390438, 6186.288576813679, 6192.28788689302, 6206.069089636546, 6097.69511524422, 6192.566520343577, 6233.844153896143, 6263.997760358321, 6267.185265915402, 5656.858578535528, 5726.513246374105, 5729.999999999958, 5791.652500849962, 5800.332961709458, 6200.689965501707, 6201.286852012076, 6204.968975155186, 6205.81382558524, 6206.658633775095, 5914.674692891932, 5963.940360596339, 6091.695415229309, 6096.306872073336, 6120.827106377172, 6114.723190878623, 6144.815655530341, 6150.815475535741, 6154.323024467305, 6158.1686472326555, 6107.704691583745, 6115.96942015296, 6122.846539228136, 6125.1797411726975, 6138.0, 5356.705381203993, 5964.254468191476, 6007.459328660441, 6018.21763170785, 6138.877222455526, 4800.159972004885, 4861.611071114302, 4867.756612169377, 4869.878253043702, 4934.753262336941, 4326.264535029052, 4377.255866502702, 4378.781060947003, 4389.626881715026, 4418.270985287391, 5534.501894829485, 5689.061304884731, 5706.857328566736, 5759.424057594207, 5759.625624334405, 6126.181801870944, 6158.983767678373, 6212.875742485125, 6212.9068063979075, 6269.78055768055, 5689.630174038674, 5712.457316554921, 5716.114002329646, 5727.0550359190265, 5789.88420231593, 5350.759215835296, 5363.088274993261, 5385.0, 5385.488378604026, 5397.298351214396, 6113.749968914014, 6152.630842149485, 6158.815235542941, 6169.167162433014, 6182.319944806158, 6168.444839964424, 6210.534209934182, 6216.471599914058, 6237.875242495215, 6238.656873871981, 6215.223097112861, 6219.844503887439, 6221.595596286408, 6224.533160012927, 6226.813195604139, 4554.271316589462, 5924.6149000314845, 6032.306284777307, 6110.7861224856915, 6114.419130182626, 5215.973920130375, 6202.472789812917, 6208.2860471044605, 6218.5647004711045, 6227.283862355887, 6007.098935159761, 6043.395660434097, 6048.36492168316, 6061.212042434456, 6063.757449701962, 5893.970530147321, 5941.91087133702, 5994.400559943971, 6029.969850150721, 6034.577579569388, 5898.764049437975, 5905.409459054232, 5933.643981361132, 5943.405659434022, 5957.255343082114, 5992.970035149797, 6017.3681763414215, 6020.337762845996, 6034.879302414015, 6057.939860524479, 5060.3927528696795, 5600.523955463831, 5806.68063256525, 5861.179434879035, 5861.296644402699], "value": 5629.184899997893, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.37238100944705, 486.98052077916884, 520.1807153732871, 539.8677324055607, 552.1662289942187, 31031.379372412568, 40797.79601101994, 41795.37306940395, 41893.0, 41981.16037679252, 42956.0, 43562.782186089105, 43578.346324805156, 43618.56381436185, 43683.78158109205, 8693.217610415062, 9578.994205608398, 9708.029197080306, 9749.610015599368, 9757.512124393787, 8787.516686582236, 11783.823242651368, 12041.759164816698, 12062.57780977666, 12280.754384912296, 12085.697857553543, 12163.209391389575, 12169.93915030428, 12187.695307617292, 12188.695282617917, 12027.45876435558, 12077.818832717516, 12101.213421127643, 12103.273803571814, 12103.394830258452, 11932.22440541362, 11953.103517236243, 11967.1622986391, 11971.162018658697, 12025.0, 41079.589204108175, 41387.0, 41395.37906931398, 41450.1709965799, 41644.0, 3351.8994430167136, 3412.9146771330666, 3533.134382076382, 3603.981980090109, 3841.9423708644395, 31205.531917021264, 32001.359972800645, 32522.674773252438, 32539.34921301561, 32631.184220394443, 2898.6231789867393, 3267.0, 3314.9005529834144, 3390.937704139212, 3391.6777906098882, 513091.0, 520407.0, 521557.0, 523149.3842530763, 525176.0, 61707.6914615424, 61905.59519226486, 61958.38041619616, 61975.6901215491, 62010.37989620136, 64627.35372646307, 64659.67670161713, 64831.675841621436, 64851.0, 64902.0, 16454.588635284214, 16651.500454986126, 16668.6666266674, 16714.749278760828, 16737.665246694996, 11445.370504622158, 11685.532578696926, 12734.68163295925, 12843.293618851052, 12944.352782360844, 16708.6658266834, 16874.83125168757, 17016.149192540324, 17133.742993855103, 17174.570635734206, 6735.158105236846, 6840.0, 6845.398748475547, 6869.725210991504, 6928.965355173192, 845.7843249971341, 932.7481579973369, 965.888922773876, 992.9751756206008, 1034.1726618705006, 29304.85347573291, 30716.53925191124, 31284.687153128634, 31599.52600710991, 31955.360892782013, 800.3877034068942, 978.6134476881592, 995.4574756757585, 1035.9378437293788, 1036.3574583758118, 15286.235688215545, 15723.21383930799, 15757.842421575866, 15769.842301577068, 16231.918840405722, 662018.0, 662700.0, 665524.0, 665674.0, 665711.0, 58586.0, 58722.70638646779, 58796.706016469645, 59004.704976474844, 59007.40992590105, 1257.7673130470891, 1383.6817531967502, 1419.1414194412232, 1446.9855301447271, 1454.4691187716408, 30332.54501182528, 31403.685963140077, 31419.214519637193, 32014.83992580022, 32324.676753233107, 50617.0, 51162.0, 51199.0, 51301.48698513117, 51342.48657513377, 463583.0, 465275.0, 466173.0, 468870.0, 468892.0, 26211.34471638148, 26388.868055659597, 26946.59580106261, 26986.730132698423, 28062.43875122527, 48034.51965480301, 49004.0, 49362.0, 49882.50117498779, 49948.75025624994, 38274.42588361121, 38451.42322865103, 39694.007649807834, 40043.1991360177, 41256.0, 39611.0, 40562.79718601388, 40897.77306680804, 41232.1753564921, 41762.791186043876, 38243.808780955915, 39365.0, 39434.014149646486, 39651.60348396595, 39807.2038559233, 30366.392672146874, 35035.12412189614, 35280.470792937616, 35949.0, 36942.891713248646, 24310.63534047026, 24469.127409421966, 24474.26577202687, 25749.0, 25777.355566110997], "value": 20521.39608776433, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.1533032396438, 369.3259800863424, 369.39972544615, 376.3639449330631, 401.52619908507967, 15386.69226615467, 16978.915105424472, 21428.357149285508, 21755.782442175572, 21887.67168492472, 19766.604667906635, 21041.053152608132, 21867.0, 22381.0, 22789.834965678234, 5894.469497745202, 5978.760849566012, 6125.785597504088, 6217.471514921237, 6219.222597175353, 4224.894377640569, 4461.196984542781, 6038.667873266991, 6148.415900489446, 6170.598911070789, 6451.096846441502, 6775.62734049627, 6811.693473793666, 6845.14435695538, 6877.970998555413, 6474.805755827309, 6732.83167920801, 6788.660566971632, 6795.082663840368, 6816.0, 5902.793402230901, 5903.734331955073, 5924.911126333109, 5925.703714814242, 7323.7070517179245, 28448.431031379256, 29495.55756663652, 29508.409831803245, 29617.703822961925, 29969.10092697201, 2178.498945242587, 2298.804601608865, 2480.2559232230437, 2730.604062410939, 2788.442311537701, 16404.26180821866, 17220.913895430567, 17370.82629173705, 17400.042997635126, 17413.0, 2043.3563427520282, 2058.835293176557, 2111.405888554147, 2136.8824714640687, 2143.8928053597415, 90383.54808225982, 90400.0, 119268.21097683541, 132986.6701332984, 136149.3192534041, 21531.461713457287, 21626.783732162476, 21735.347939561838, 21770.129194832345, 21817.781822181892, 35382.646173537934, 35633.46549801755, 35671.4649280261, 35685.46471802925, 35733.0, 10353.844692329621, 10596.31123976939, 10685.09176719972, 10805.621803236849, 10949.726256843483, 7206.56760594366, 8765.824683506295, 8866.113388661212, 8903.421277616933, 9013.504257265782, 7636.312731854048, 9759.487011504589, 9809.509524523744, 9818.410895346302, 9951.900480995242, 3212.3414699986447, 3358.0, 3734.2344819311975, 3744.9438258426144, 3754.1177823211524, 880.8018195905998, 920.8388532006873, 962.5471702803678, 967.4582233949011, 988.4761076629397, 19300.0, 20413.795862041487, 21596.78403215979, 21661.67507487389, 21663.35009949704, 781.9452638315263, 813.2964985287801, 889.857622780352, 897.3225214962662, 911.1526280559011, 10876.129909607213, 11233.66299011031, 11259.718507037225, 11316.71708207285, 11350.716232094099, 560210.0, 566857.0, 567724.0, 576717.0, 577670.0, 102510.97489025015, 120469.39765301117, 125553.0, 129671.0, 134960.0, 936.3679516326453, 972.2659392159017, 974.45917515778, 977.359829311802, 1040.458961340114, 19666.213351466355, 20145.496362591053, 20313.289034883706, 20326.186952521733, 20382.898085509478, 54823.0, 57501.0, 58280.12579811391, 58325.0, 58569.0, 79270.81093783483, 81838.0, 85555.5722221385, 85744.56703778042, 85847.0, 7916.208379162038, 8014.278714915686, 8212.0, 8254.83490330202, 8435.156484351515, 12203.816942746043, 12245.387730613611, 12431.564895228623, 12938.547150849676, 13232.206067635972, 9751.951240243754, 11433.59982400612, 11461.140414468782, 11582.07343412508, 11624.186306958432, 11071.5571377144, 11186.216964812385, 11251.549938002716, 11523.654290371302, 11546.653600392001, 9362.95318523403, 11293.548258069584, 11539.249948753304, 11700.648980530597, 11768.58809941648, 7751.92248077512, 8029.638666259915, 8080.757577272691, 8130.39022073335, 8181.977252843394, 9084.63661453561, 11224.7755044897, 11338.716532086433, 11539.134564907834, 11539.480723367637], "value": 10813.626144627275, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.4042761612507, 1476.667749756305, 1634.9100799456032, 1827.9086045697711, 1843.8801477903944, 8347.207015333548, 8916.420432671872, 8925.955370223157, 8926.330525210618, 8942.19520243178, 7690.923090769091, 7928.0, 8382.161783821615, 8578.270846978, 8694.869576956351, 2093.3824521766114, 2343.8124950003958, 2359.469119448119, 2375.9049638014544, 2383.189715496731, 2346.671465994762, 2366.7278262999716, 2404.843685160459, 2407.6388541718793, 2416.565018296706, 2371.0752806405517, 2377.8454400464, 2392.5334559760854, 2398.928032159038, 2402.267308470916, 2342.4612339161918, 2360.881955902198, 2364.8935797889135, 2378.988105059481, 2387.5821731197143, 2334.4280651240383, 2362.1850461590725, 2367.0413482539598, 2370.2889133260123, 2373.774491423312, 8756.518391488466, 8829.028806831244, 8848.424852384574, 8862.0251772305, 8864.778380540474, 4693.6949098308505, 5206.349206349207, 5381.381141168777, 5381.784728610851, 5389.0, 3610.6930910872607, 3618.149734812317, 3687.059799751073, 3688.8155592220282, 3691.9261614767556, 1076.3649446826332, 1083.8807731149568, 1085.5006696919347, 1098.5660664037741, 1108.51225460797, 9813.0, 11551.826722599168, 11621.360825154614, 11632.709182270512, 11649.708757281136, 4502.752348620857, 4585.95414045862, 4772.260299653559, 4783.378160839069, 4790.257510085942, 9857.40855548655, 9857.507124643738, 9883.555240014217, 9885.45629990343, 9898.30711850178, 4682.578567928903, 5346.4386239445075, 5356.357237131491, 5356.437574054746, 5365.3293338332705, 4395.164918665443, 4557.749323787158, 4753.073150735642, 4880.633952453581, 4896.608271338285, 3848.615138486185, 3902.726809123391, 3982.4026396040827, 3997.5402828675074, 4014.157027024299, 1286.298967062953, 1288.6778305423645, 1294.1975974895624, 1301.342821874947, 1312.934353282332, 790.4703848421572, 901.1889299630385, 914.5061666700041, 927.7263207353847, 970.6020531582018, 5671.914921276184, 5787.536997040227, 5932.525397968152, 6008.008678567988, 6141.7543298267565, 438.4169055156634, 438.95829896159813, 447.17942575374553, 757.8635845547855, 1003.1673710820002, 3397.43942249531, 3774.0176087584996, 4035.7982100894837, 4070.5725898780793, 4074.2055299216368, 9885.308028437941, 10277.9486102569, 10837.02466777978, 10838.620648277272, 10839.458027098613, 10446.320989135682, 10836.729081773019, 10866.022058014816, 10875.238733288752, 10882.0, 8039.7990050248045, 8293.792655183668, 8299.2530672241, 8300.252977232078, 8328.417010809306, 5682.772689092555, 5724.0, 5848.385919478394, 5868.589198756046, 5928.318243402064, 10078.697639070839, 10114.848277275994, 10147.0, 10155.492225388552, 10205.438700871524, 9872.851907221253, 9960.153386962189, 10128.34165779222, 10319.48402579883, 10357.17142628603, 6483.578567393104, 6684.4318232950745, 6685.2646208916, 6990.860182796214, 7209.35115839577, 7904.802379940317, 8001.959990200012, 8013.278804907353, 8139.633716482654, 8428.747137585882, 7472.589007604416, 7554.206808285051, 7582.734604288823, 7601.88597171054, 7734.187910269566, 7371.594562299125, 7410.888836667346, 7427.294407031323, 7433.851322973618, 7577.962110189414, 6867.3476019778045, 7016.649167541705, 7025.789226323219, 7032.26161253061, 7046.577205367695, 4830.468648448738, 5017.724025178504, 5119.667221630582, 5181.870453238699, 5259.027079990214, 4900.926486102782, 5064.569511591556, 5182.32629758129, 5225.712585807817, 5264.9210261846865], "value": 4533.0102912620605, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.5549787093844, 282.1676055635874, 289.370618903884, 289.4023840768812, 291.4360712022237, 5221.216817477381, 5236.2407450919645, 5237.476252374765, 5258.579313654908, 5260.500252476008, 4850.5877000455, 4889.168841296979, 5095.745212739357, 5183.352080989876, 5270.600462701555, 3361.6302206757236, 3758.2107757370986, 3953.4069889516586, 4009.178118485718, 4013.9598604014022, 3526.4710293455933, 3556.039869235305, 3687.3362794696945, 3718.590954994962, 3922.0, 3708.629137086283, 3770.396736522157, 3849.711271654623, 3991.0, 4002.919941601166, 3725.217704282104, 3913.2802702122035, 3959.4456776051375, 3960.683145348365, 3966.5522084439262, 3270.051685011344, 3464.5149679044953, 3472.7048200902955, 3481.860725570974, 3485.825708714554, 4583.9083218335445, 4810.254410566367, 5104.668196567211, 5110.565601923804, 5135.0, 4483.932741008887, 4497.910041799146, 4528.43394575678, 4530.275155975061, 4553.225951588238, 5423.7830486780485, 5460.26286451328, 5474.275603185024, 5477.877035207773, 5519.641223320512, 3222.6455089940087, 3234.2399536108996, 3789.2232092420986, 3793.3740932746064, 3848.807559622008, 5414.729263536808, 5473.200689018133, 5665.943340566583, 5730.7421166047625, 5736.541076713853, 3833.201762713235, 3839.6578087366684, 3849.2301539692176, 3855.383138697795, 3859.7684138951754, 3691.5201023866725, 3902.434147048719, 3928.489296391508, 3937.625925537069, 3942.4821443744363, 4284.892877678083, 4529.705569137996, 4557.883318586967, 4586.472555656075, 4593.104344652827, 5480.629842539365, 5512.614117011771, 5515.283013208257, 5528.281323428009, 5539.667619942817, 1119.2780656476527, 1233.1614502138452, 1331.4873773597235, 1333.3533236380347, 1333.5599252246736, 726.4733068525277, 796.1560745609672, 822.7984143884787, 871.7123349294718, 890.9287257019423, 4052.1085361220485, 4765.9030026788105, 4984.903321269315, 5133.871653208625, 5134.435212126663, 4939.542834863724, 5166.948330516722, 5192.532672059533, 5532.695701736443, 5573.303337082864, 4451.821927122944, 4755.096531659043, 4836.7339796310835, 4851.539103785135, 5121.334226550524, 2715.456908618284, 2848.4364072335065, 2951.4539810134766, 2976.702329767006, 2979.836109014025, 4661.813527458864, 5211.299977016361, 5569.27599412082, 5670.262865827416, 5905.734241959122, 5480.534154596824, 5814.94185058144, 5816.709164541756, 5828.737706803204, 5884.352721200665, 5845.7369418376275, 5858.94141058584, 5860.501857342174, 5887.4406931341455, 5894.557908156819, 5261.736913154404, 5339.092354299701, 5485.476680726751, 5663.263775709132, 5690.658560486384, 5131.4862115676715, 5637.718114094197, 5649.858753531194, 5652.4064973178865, 5663.8389130229025, 5894.764209431746, 5900.940990590039, 5931.228940237742, 5943.729365519419, 5997.220361353171, 3625.4410603440774, 3730.981345093257, 3747.437884317374, 3773.2264885698914, 3811.371123764548, 4298.720583162084, 4754.45323787755, 5654.575906806924, 5684.237886255274, 5747.798827041033, 5544.61187716856, 5550.639208451437, 5556.943930745489, 5574.9163762542785, 5584.464272325143, 4583.449986001701, 5078.695278283315, 5114.902889815158, 5550.750216240198, 5597.216389705363, 4943.3573635427165, 5021.6735912165595, 5232.895342093061, 5472.343318801769, 5480.3971563127225, 4761.500042495627, 5022.547970682656, 5304.336957880265, 5404.8108316208745, 5502.752376143003, 4768.66619336657, 5442.619016668795, 5446.128619421032, 5455.672659640434, 5470.647621522783], "value": 4006.598905904226, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.9328413800689, 726.5096060159391, 764.4610549562556, 809.3606051219537, 815.6451943404619, 16163.595910102238, 16763.748543771853, 16772.329106835743, 16797.83202167978, 16799.328026878924, 14918.925405372986, 15096.6980660387, 15119.24403779812, 15125.924370378132, 15137.24313784312, 683.8803209438353, 699.1679900917917, 835.3150416658312, 847.0936098374741, 848.516345682961, 834.5034704350901, 858.6307887608326, 859.3726579596898, 859.8151397449548, 860.9655613775442, 915.0849150849151, 917.47703808829, 918.8759517465123, 919.2416256588308, 919.4437365393941, 706.6678661029308, 876.0976194519654, 904.4708845325426, 911.1344222988155, 913.3743385780762, 675.1729131813538, 676.651524464901, 676.7394553097093, 677.1434135818145, 680.7787469072571, 14846.70306593862, 14875.553733388017, 14890.851091489163, 14900.701985960328, 14918.477853275192, 11819.0, 13728.931355343258, 14014.92992535041, 14047.929760351235, 14152.71694566103, 12726.0, 12875.549355772597, 13029.54396596124, 13264.903276746894, 13400.195988240737, 3394.100563350714, 3394.9490757638655, 3423.9315213695836, 3439.9656003440145, 3583.964160358389, 94340.0, 115495.0, 115699.42150289279, 115902.25075241717, 116872.0, 11002.394868282321, 11024.889751102386, 11062.502187401426, 11146.164037697205, 11477.655670329903, 21681.34955951324, 21682.56634867294, 21695.891520542613, 21730.674039889414, 21774.346769596938, 8002.0, 8213.589320533949, 8246.587670616445, 8247.175282471704, 8280.875786863202, 4352.542982986804, 4451.77741112943, 4489.169503641837, 4516.277395616686, 4553.271476563734, 3420.452727563578, 3536.91157721059, 3540.504329393887, 3544.8404821782565, 3547.6452354764833, 639.3606393606393, 643.5784561112479, 660.2638058564665, 666.1706175811049, 669.5045666206972, 538.8895276468393, 541.2882060090968, 558.2700594094879, 629.4052120745852, 635.8251480842805, 8634.78413039679, 9119.680811171576, 9132.863007054899, 9268.0, 9293.395929264576, 578.8552861784553, 612.5803824380314, 613.8864310102646, 630.3412933484519, 644.0628884972353, 4240.533541310453, 4428.778561071934, 4442.800073996678, 4461.7992190351515, 4484.786957690079, 166478.0, 168632.0, 168708.474643502, 169974.0, 171731.0, 111564.0, 146866.26566867097, 147635.0, 150872.73690894645, 151986.0, 94388.58417123748, 99274.5036274814, 107195.92804072016, 107622.46188769162, 111806.96868640931, 8321.16788321163, 8914.777130571787, 10060.4305049194, 10102.69691909244, 10145.64490242838, 63318.36681633309, 63370.41573960688, 64026.03960940683, 64063.0, 64194.35805641884, 83934.0, 92790.0, 96690.0, 98025.50987245019, 99911.0, 11615.419229038684, 12993.101667221443, 13275.66810829737, 13383.39774710121, 13484.86515134836, 14649.780253295996, 14784.966222076646, 15081.24593770329, 15141.621459463602, 15150.469733559272, 13578.86421135776, 13728.679095597065, 14077.859221407654, 14181.361838717075, 14207.147571145764, 13997.790033149715, 14107.788383174055, 14120.50578229757, 14175.291235438393, 14368.0, 13172.73654526885, 13372.465101395836, 13494.055416120776, 13876.236806975714, 14292.28538573088, 10742.516586753458, 11404.714882128013, 11465.770684586094, 11658.242214256046, 11728.472218750347, 10785.784284314426, 11220.719482013015, 11300.152488563555, 11509.0, 11581.942090289494], "value": 7891.521782818723, "unit": "score"}], "name": "dromaeo_css", "value": 7507.573948877898, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 112.0, 108.0, 112.0, 113.0, 114.0, 111.0, 112.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 107.0, 166.0, 107.0, 106.0, 131.0, 107.0, 140.0, 106.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 184.0, 185.0, 183.0, 180.0, 182.0, 185.0, 184.0, 182.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 58.0, 86.0, 58.0, 57.0, 59.0, 68.0, 85.0, 76.0], "value": 70.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 90.0, 96.0, 73.0, 70.0, 67.0, 98.0, 71.0, 68.0, 104.0], "value": 82.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 87.0, 84.0, 87.0, 86.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 97.0, 93.0, 93.0, 92.0, 95.0, 92.0, 100.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 86.0, 84.0, 84.0, 85.0, 84.0, 85.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 54.0, 55.0, 52.0, 54.0, 55.0, 53.0, 54.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 79.0, 64.0, 72.0, 73.0, 70.0, 67.0, 70.0, 69.0, 69.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 85.0, 82.0, 85.0, 85.0, 84.0, 88.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 141.0, 144.0, 138.0, 137.0, 142.0, 149.0, 225.0, 150.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 171.0, 168.0, 167.0, 168.0, 167.0, 167.0, 178.0, 230.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 68.0, 66.0, 67.0, 67.0, 66.0, 66.0, 67.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1435.0, "alertThreshold": 2.0}]} 16:21:53 INFO - Return code: 0 16:21:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:21:53 INFO - # TBPL SUCCESS # 16:21:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:21:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:21:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:21:53 INFO - 2016-08-18 16:21:53.172 screenresolution[2255:24164] starting screenresolution argv=screenresolution get 16:21:53 INFO - 2016-08-18 16:21:53.174 screenresolution[2255:24164] Display 0: 1600x1200x32@60 16:21:53 INFO - 2016-08-18 16:21:53.181 screenresolution[2256:24167] starting screenresolution argv=screenresolution list 16:21:53 INFO - Available Modes on Display 0 16:21:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:21:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:21:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:21:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:21:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:21:53 INFO - Intel Iris: 16:21:53 INFO - Chipset Model: Intel Iris 16:21:53 INFO - Type: GPU 16:21:53 INFO - Bus: Built-In 16:21:53 INFO - VRAM (Dynamic, Max): 1536 MB 16:21:53 INFO - Vendor: Intel (0x8086) 16:21:53 INFO - Device ID: 0x0a2e 16:21:53 INFO - Revision ID: 0x0009 16:21:53 INFO - Displays: 16:21:53 INFO - Display: 16:21:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:21:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:21:53 INFO - Display Serial Number: 1600x1200 60 16:21:53 INFO - Main Display: Yes 16:21:53 INFO - Mirror: Off 16:21:53 INFO - Online: Yes 16:21:53 INFO - Rotation: Supported 16:21:53 INFO - Return code: 0 16:21:53 INFO - Running post-action listener: _resource_record_post_action 16:21:53 INFO - [mozharness: 2016-08-18 23:21:53.415626Z] Finished run-tests step (success) 16:21:53 INFO - Running post-run listener: _resource_record_post_run 16:21:53 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 602112; Write bytes: 705017856; Read time: 5; Write time: 2031 16:21:53 INFO - TinderboxPrint: CPU usage
53.7% 16:21:53 INFO - TinderboxPrint: I/O read bytes / time
602,112 / 5 16:21:53 INFO - TinderboxPrint: I/O write bytes / time
705,017,856 / 2,031 16:21:53 INFO - TinderboxPrint: CPU idle
2,249.4 (46.3%) 16:21:53 INFO - TinderboxPrint: CPU system
972.6 (20.0%) 16:21:53 INFO - TinderboxPrint: CPU user
1,631.6 (33.6%) 16:21:53 INFO - TinderboxPrint: Swap in / out
405,315,584 / 0 16:21:53 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 388174336; Write bytes: 382900224; Read time: 29428; Write time: 765 16:21:53 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 602112; Write bytes: 296652800; Read time: 5; Write time: 1216 16:21:53 INFO - Running post-run listener: _upload_blobber_files 16:21:53 INFO - Blob upload gear active. 16:21:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:21:53 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 16:21:53 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:21:53 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:21:54 INFO - (blobuploader) - INFO - Open directory for files ... 16:21:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:21:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:21:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:55 INFO - (blobuploader) - INFO - Done attempting. 16:21:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:21:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:21:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:55 INFO - (blobuploader) - INFO - Done attempting. 16:21:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:21:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:21:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:58 INFO - (blobuploader) - INFO - Done attempting. 16:21:58 INFO - (blobuploader) - INFO - Iteration through files over. 16:21:58 INFO - Return code: 0 16:21:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:21:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:21:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6525435da2d398fc9521dc30888161aa4610aae999a3517e19e8a4c45912ef3c229e15b5e1d919fec53e195d9e51d18250449b17a172cb92fc081c4d982dd56d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9c79bfe620006117bd82f42c361953cc43ed0920a85b0859d4eb1a10e606f6cf910b17f25f809c058767abcf3de63c537a877e759977930734a1c26e80a34fd5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56f9ca6aa1f318299e0cee828a06873d8e7c2a059bfe4c83382b73ae9cb0978173d5935645e0aaf0e4e0569a080ea9e38f8566d4a04a0b543618a43bfabe454b"} 16:21:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:21:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:21:58 INFO - Contents: 16:21:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6525435da2d398fc9521dc30888161aa4610aae999a3517e19e8a4c45912ef3c229e15b5e1d919fec53e195d9e51d18250449b17a172cb92fc081c4d982dd56d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9c79bfe620006117bd82f42c361953cc43ed0920a85b0859d4eb1a10e606f6cf910b17f25f809c058767abcf3de63c537a877e759977930734a1c26e80a34fd5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56f9ca6aa1f318299e0cee828a06873d8e7c2a059bfe4c83382b73ae9cb0978173d5935645e0aaf0e4e0569a080ea9e38f8566d4a04a0b543618a43bfabe454b"} 16:21:58 INFO - Running post-run listener: copy_logs_to_upload_dir 16:21:58 INFO - Copying logs to upload dir... 16:21:58 INFO - mkdir: /builds/slave/test/build/upload/logs 16:21:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.052816 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-18 16:21:58.358202) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 16:21:58.361343) ========= 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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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/Autoland/sha512/6525435da2d398fc9521dc30888161aa4610aae999a3517e19e8a4c45912ef3c229e15b5e1d919fec53e195d9e51d18250449b17a172cb92fc081c4d982dd56d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9c79bfe620006117bd82f42c361953cc43ed0920a85b0859d4eb1a10e606f6cf910b17f25f809c058767abcf3de63c537a877e759977930734a1c26e80a34fd5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56f9ca6aa1f318299e0cee828a06873d8e7c2a059bfe4c83382b73ae9cb0978173d5935645e0aaf0e4e0569a080ea9e38f8566d4a04a0b543618a43bfabe454b"} build_url:https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011205 build_url: 'https://queue.taskcluster.net/v1/task/TEUlbDkaQZmZJlzT37bCxQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6525435da2d398fc9521dc30888161aa4610aae999a3517e19e8a4c45912ef3c229e15b5e1d919fec53e195d9e51d18250449b17a172cb92fc081c4d982dd56d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9c79bfe620006117bd82f42c361953cc43ed0920a85b0859d4eb1a10e606f6cf910b17f25f809c058767abcf3de63c537a877e759977930734a1c26e80a34fd5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56f9ca6aa1f318299e0cee828a06873d8e7c2a059bfe4c83382b73ae9cb0978173d5935645e0aaf0e4e0569a080ea9e38f8566d4a04a0b543618a43bfabe454b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 16:21:58.394608) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:21:58.394953) ========= 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.Cdhun6QU6Y/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.AEoQpPfVP9/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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.005295 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 16:21:58.444067) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 16:21:58.444373) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 16:21:58.444694) ========= ========= Total master_lag: 0.18 =========