builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0013 starttime: 1472206120.37 results: success (0) buildid: 20160826011616 builduid: 0dafb86a80254ab18463a086c084e674 revision: fd166af53b59fa5cead6c1d9671385c5478c4839 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:40.368010) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:40.368899) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:40.369530) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:40.395519) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:40.396259) ========= 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.GTUirmZjCf/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.wE0mq78cmZ/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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-26 03:08:40-- 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% 8.28M=0.001s 2016-08-26 03:08:40 (8.28 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136725 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:40.559034) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:40.559819) ========= 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.GTUirmZjCf/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.wE0mq78cmZ/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.020277 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:40.610158) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:40.610522) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev fd166af53b59fa5cead6c1d9671385c5478c4839 --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 fd166af53b59fa5cead6c1d9671385c5478c4839 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GTUirmZjCf/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.wE0mq78cmZ/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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-26 03:08:40,687 truncating revision to first 12 chars 2016-08-26 03:08:40,687 Setting DEBUG logging. 2016-08-26 03:08:40,687 attempt 1/10 2016-08-26 03:08:40,687 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/fd166af53b59?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 03:08:41,391 unpacking tar archive at: autoland-fd166af53b59/testing/mozharness/ program finished with exit code 0 elapsedTime=0.949356 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:41.582583) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:41.582914) ========= script_repo_revision: fd166af53b59fa5cead6c1d9671385c5478c4839 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:41.583361) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:41.583639) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 03:08:41.602580) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-08-26 03:08:41.602892) ========= /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.GTUirmZjCf/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.wE0mq78cmZ/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 03:08:41 INFO - MultiFileLogger online at 20160826 03:08:41 in /builds/slave/test 03:08:41 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 03:08:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:08:41 INFO - {'append_to_log': False, 03:08:41 INFO - 'base_work_dir': '/builds/slave/test', 03:08:41 INFO - 'blob_upload_branch': 'Autoland', 03:08:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:08:41 INFO - 'branch': 'Autoland', 03:08:41 INFO - 'buildbot_json_path': 'buildprops.json', 03:08:41 INFO - 'config_files': ('talos/mac_config.py',), 03:08:41 INFO - 'default_actions': ('clobber', 03:08:41 INFO - 'read-buildbot-config', 03:08:41 INFO - 'download-and-extract', 03:08:41 INFO - 'populate-webroot', 03:08:41 INFO - 'create-virtualenv', 03:08:41 INFO - 'install', 03:08:41 INFO - 'run-tests'), 03:08:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:08:41 INFO - 'download_minidump_stackwalk': True, 03:08:41 INFO - 'download_symbols': 'ondemand', 03:08:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:08:41 INFO - 'tooltool.py': '/tools/tooltool.py', 03:08:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:08:41 INFO - '/tools/misc-python/virtualenv.py')}, 03:08:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:08:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:08:41 INFO - 'installer_path': 'installer.exe', 03:08:41 INFO - 'log_level': 'info', 03:08:41 INFO - 'log_name': 'talos', 03:08:41 INFO - 'log_to_console': True, 03:08:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:08:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:08:41 INFO - 'opt_config_files': (), 03:08:41 INFO - 'pip_index': False, 03:08:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:08:41 INFO - 'cmd': ('bash', 03:08:41 INFO - '-c', 03:08:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:08:41 INFO - 'enabled': True, 03:08:41 INFO - 'halt_on_failure': False, 03:08:41 INFO - 'name': 'check_screen_resolution'},), 03:08:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:08:41 INFO - 'cmd': ('bash', 03:08:41 INFO - '-c', 03:08:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:08:41 INFO - 'enabled': True, 03:08:41 INFO - 'halt_on_failure': False, 03:08:41 INFO - 'name': 'check_screen_resolution'},), 03:08:41 INFO - 'run_cmd_checks_enabled': True, 03:08:41 INFO - 'sps_profile': False, 03:08:41 INFO - 'sps_profile_interval': 0, 03:08:41 INFO - 'suite': 'dromaeojs', 03:08:41 INFO - 'system_bits': '32', 03:08:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:08:41 INFO - 'title': 't-yosemite-r7-0013', 03:08:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:08:41 INFO - 'use_talos_json': True, 03:08:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:08:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:08:41 INFO - 'work_dir': 'build'} 03:08:41 INFO - [mozharness: 2016-08-26 10:08:41.796990Z] Running clobber step. 03:08:41 INFO - Running pre-action listener: _resource_record_pre_action 03:08:41 INFO - Running main action method: clobber 03:08:41 INFO - rmtree: /builds/slave/test/build 03:08:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:08:43 INFO - Running post-action listener: _resource_record_post_action 03:08:43 INFO - [mozharness: 2016-08-26 10:08:43.362802Z] Finished clobber step (success) 03:08:43 INFO - [mozharness: 2016-08-26 10:08:43.362967Z] Running read-buildbot-config step. 03:08:43 INFO - Running pre-action listener: _resource_record_pre_action 03:08:43 INFO - Running main action method: read_buildbot_config 03:08:43 INFO - Using buildbot properties: 03:08:43 INFO - { 03:08:43 INFO - "project": "", 03:08:43 INFO - "product": "firefox", 03:08:43 INFO - "script_repo_revision": "production", 03:08:43 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 03:08:43 INFO - "repository": "", 03:08:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 03:08:43 INFO - "buildid": "20160826011616", 03:08:43 INFO - "pgo_build": "False", 03:08:43 INFO - "basedir": "/builds/slave/test", 03:08:43 INFO - "buildnumber": 169, 03:08:43 INFO - "slavename": "t-yosemite-r7-0013", 03:08:43 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:08:43 INFO - "platform": "yosemite_r7", 03:08:43 INFO - "scheduler": "tests-autoland-macosx64-talos", 03:08:43 INFO - "branch": "autoland", 03:08:43 INFO - "repo_path": "integration/autoland", 03:08:43 INFO - "revision": "fd166af53b59fa5cead6c1d9671385c5478c4839", 03:08:43 INFO - "stage_platform": "macosx64", 03:08:43 INFO - "builduid": "0dafb86a80254ab18463a086c084e674", 03:08:43 INFO - "slavebuilddir": "test" 03:08:43 INFO - } 03:08:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 03:08:43 INFO - Running post-action listener: _resource_record_post_action 03:08:43 INFO - [mozharness: 2016-08-26 10:08:43.366443Z] Finished read-buildbot-config step (success) 03:08:43 INFO - [mozharness: 2016-08-26 10:08:43.366585Z] Running download-and-extract step. 03:08:43 INFO - Running pre-action listener: _resource_record_pre_action 03:08:43 INFO - Running main action method: download_and_extract 03:08:43 INFO - mkdir: /builds/slave/test/build/tests 03:08:43 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')]} 03:08:43 INFO - trying https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 03:08:43 INFO - Downloading https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/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 03:08:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/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 03:08:44 INFO - Downloaded 1199 bytes. 03:08:44 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:08:44 INFO - Using the following test package requirements: 03:08:44 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:08:44 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:08:44 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 03:08:44 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:08:44 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 03:08:44 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:08:44 INFO - u'jsshell-mac.zip'], 03:08:44 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:08:44 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 03:08:44 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:08:44 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:08:44 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 03:08:44 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:08:44 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 03:08:44 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:08:44 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 03:08:44 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:08:44 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 03:08:44 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:08:44 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')]} 03:08:44 INFO - trying https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:08:44 INFO - Downloading https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/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 03:08:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/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 03:08:46 INFO - Downloaded 16367215 bytes. 03:08:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:08:53 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 03:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:08:53 INFO - trying https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:08:53 INFO - Downloading https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/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 03:08:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/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 03:08:54 INFO - Downloaded 16367215 bytes. 03:08:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:00 INFO - trying https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 03:09:00 INFO - Downloading https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/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 03:09:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/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 03:09:02 INFO - Downloaded 14413998 bytes. 03:09:02 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 03:09: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')]} 03:09:03 INFO - trying https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:09:03 INFO - Downloading https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 03:09:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/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 03:09:06 INFO - Downloaded 111831980 bytes. 03:09:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:09:06 INFO - mkdir: /builds/slave/test/properties 03:09:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:09:06 INFO - Writing to file /builds/slave/test/properties/build_url 03:09:06 INFO - Contents: 03:09:06 INFO - build_url:https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:09:07 INFO - Running post-action listener: _resource_record_post_action 03:09:07 INFO - Running post-action listener: set_extra_try_arguments 03:09:07 INFO - [mozharness: 2016-08-26 10:09:07.345233Z] Finished download-and-extract step (success) 03:09:07 INFO - [mozharness: 2016-08-26 10:09:07.345350Z] Running populate-webroot step. 03:09:07 INFO - Running pre-action listener: _resource_record_pre_action 03:09:07 INFO - Running main action method: populate_webroot 03:09:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:09:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:09:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:09:07 INFO - '--tppagecycles', 03:09:07 INFO - '7'], 03:09:07 INFO - 'tests': ['tsvgm']}}, 03:09:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:09:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:09:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:09:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:09:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:09:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:09:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:07 INFO - 'talos_options': ['--disable-e10s'], 03:09:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:09:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:09:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:07 INFO - 'talos_options': ['--disable-e10s'], 03:09:07 INFO - 'tests': ['damp', 'tps']}, 03:09:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:07 INFO - 'tests': ['damp', 'tps']}, 03:09:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:09:07 INFO - 'tests': ['dromaeo_dom']}, 03:09:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:09:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:09:07 INFO - 'tests': ['basic_compositor_video']}, 03:09:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:09:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:09:07 INFO - 'tests': ['a11yr', 03:09:07 INFO - 'ts_paint', 03:09:07 INFO - 'tpaint', 03:09:07 INFO - 'sessionrestore', 03:09:07 INFO - 'sessionrestore_no_auto_restore', 03:09:07 INFO - 'tabpaint']}, 03:09:07 INFO - 'other-e10s': {'tests': ['a11yr', 03:09:07 INFO - 'ts_paint', 03:09:07 INFO - 'tpaint', 03:09:07 INFO - 'sessionrestore', 03:09:07 INFO - 'sessionrestore_no_auto_restore', 03:09:07 INFO - 'tabpaint']}, 03:09:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:09:07 INFO - 'ts_paint', 03:09:07 INFO - 'tpaint', 03:09:07 INFO - 'sessionrestore', 03:09:07 INFO - 'sessionrestore_no_auto_restore', 03:09:07 INFO - 'tabpaint']}, 03:09:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:09:07 INFO - 'ts_paint', 03:09:07 INFO - 'tpaint', 03:09:07 INFO - 'sessionrestore', 03:09:07 INFO - 'sessionrestore_no_auto_restore', 03:09:07 INFO - 'tabpaint']}, 03:09:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:09:07 INFO - 'tests': ['a11yr', 03:09:07 INFO - 'ts_paint', 03:09:07 INFO - 'tpaint', 03:09:07 INFO - 'sessionrestore', 03:09:07 INFO - 'sessionrestore_no_auto_restore', 03:09:07 INFO - 'tabpaint']}, 03:09:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:09:07 INFO - 'tests': ['a11yr', 03:09:07 INFO - 'ts_paint', 03:09:07 INFO - 'tpaint', 03:09:07 INFO - 'sessionrestore', 03:09:07 INFO - 'sessionrestore_no_auto_restore', 03:09:07 INFO - 'tabpaint']}, 03:09:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:09:07 INFO - 'tests': ['tsvgx', 03:09:07 INFO - 'tsvgr_opacity', 03:09:07 INFO - 'tart', 03:09:07 INFO - 'tscrollx', 03:09:07 INFO - 'cart']}, 03:09:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:09:07 INFO - 'tsvgr_opacity', 03:09:07 INFO - 'tart', 03:09:07 INFO - 'tscrollx', 03:09:07 INFO - 'cart']}, 03:09:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:07 INFO - 'talos_options': ['--disable-e10s'], 03:09:07 INFO - 'tests': ['tp5o']}, 03:09:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:07 INFO - 'tests': ['tp5o']}, 03:09:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:07 INFO - 'talos_options': ['--disable-e10s', 03:09:07 INFO - '--xperf_path', 03:09:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:09:07 INFO - 'tests': ['tp5n']}, 03:09:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:07 INFO - 'talos_options': ['--xperf_path', 03:09:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:09:07 INFO - 'tests': ['tp5n']}}, 03:09:07 INFO - 'talos.zip': {'path': '', 03:09:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:09:07 INFO - Running post-action listener: _resource_record_post_action 03:09:07 INFO - [mozharness: 2016-08-26 10:09:07.364491Z] Finished populate-webroot step (success) 03:09:07 INFO - [mozharness: 2016-08-26 10:09:07.364620Z] Running create-virtualenv step. 03:09:07 INFO - Running pre-action listener: _resource_record_pre_action 03:09:07 INFO - Running main action method: create_virtualenv 03:09:07 INFO - Creating virtualenv /builds/slave/test/build/venv 03:09:07 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 03:09:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:09:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:09:07 INFO - Using real prefix '/tools/python27' 03:09:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:09:08 INFO - Installing distribute.............................................................................................................................................................................................done. 03:09:11 INFO - Installing pip.................done. 03:09:11 INFO - Return code: 0 03:09:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:11 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 0x1081d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108265618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9934b81d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108134d30>, '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 0x107e99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10812cb20>, '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.GTUirmZjCf/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.wE0mq78cmZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 03:09:11 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 03:09:11 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 03:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GTUirmZjCf/Render', 03:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:11 INFO - 'HOME': '/Users/cltbld', 03:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:11 INFO - 'LOGNAME': 'cltbld', 03:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:11 INFO - 'MOZ_NO_REMOTE': '1', 03:09:11 INFO - 'NO_EM_RESTART': '1', 03:09:11 INFO - 'PAGER': '/bin/cat', 03:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:11 INFO - 'PWD': '/builds/slave/test', 03:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:11 INFO - 'SHELL': '/bin/bash', 03:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wE0mq78cmZ/Listeners', 03:09:11 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 03:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:11 INFO - 'USER': 'cltbld', 03:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:11 INFO - 'XPC_FLAGS': '0x0', 03:09:11 INFO - 'XPC_SERVICE_NAME': '0', 03:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:11 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 03:09:11 INFO - Cleaning up... 03:09:11 INFO - Return code: 0 03:09:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:11 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 0x1081d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108265618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9934b81d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108134d30>, '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 0x107e99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10812cb20>, '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.GTUirmZjCf/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.wE0mq78cmZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 03:09:11 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 03:09:11 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 03:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GTUirmZjCf/Render', 03:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:11 INFO - 'HOME': '/Users/cltbld', 03:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:11 INFO - 'LOGNAME': 'cltbld', 03:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:11 INFO - 'MOZ_NO_REMOTE': '1', 03:09:11 INFO - 'NO_EM_RESTART': '1', 03:09:11 INFO - 'PAGER': '/bin/cat', 03:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:11 INFO - 'PWD': '/builds/slave/test', 03:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:11 INFO - 'SHELL': '/bin/bash', 03:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wE0mq78cmZ/Listeners', 03:09:11 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 03:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:11 INFO - 'USER': 'cltbld', 03:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:11 INFO - 'XPC_FLAGS': '0x0', 03:09:11 INFO - 'XPC_SERVICE_NAME': '0', 03:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:11 INFO - Downloading/unpacking psutil>=3.1.1 03:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:09:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:14 INFO - Installing collected packages: psutil 03:09:14 INFO - Running setup.py install for psutil 03:09:14 INFO - building 'psutil._psutil_osx' extension 03:09:14 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 03:09:14 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 03:09:14 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 03:09:15 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 03:09:15 INFO - building 'psutil._psutil_posix' extension 03:09:15 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 03:09:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:09:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:09:15 INFO - ^ 03:09:15 INFO - 1 warning generated. 03:09:15 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 03:09:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:15 INFO - Successfully installed psutil 03:09:15 INFO - Cleaning up... 03:09:15 INFO - Return code: 0 03:09:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:15 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 0x1081d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108265618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9934b81d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108134d30>, '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 0x107e99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10812cb20>, '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.GTUirmZjCf/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.wE0mq78cmZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 03:09:15 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 03:09:15 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 03:09:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GTUirmZjCf/Render', 03:09:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:15 INFO - 'HOME': '/Users/cltbld', 03:09:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:15 INFO - 'LOGNAME': 'cltbld', 03:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:15 INFO - 'MOZ_NO_REMOTE': '1', 03:09:15 INFO - 'NO_EM_RESTART': '1', 03:09:15 INFO - 'PAGER': '/bin/cat', 03:09:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:15 INFO - 'PWD': '/builds/slave/test', 03:09:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:15 INFO - 'SHELL': '/bin/bash', 03:09:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wE0mq78cmZ/Listeners', 03:09:15 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 03:09:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:15 INFO - 'USER': 'cltbld', 03:09:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:15 INFO - 'XPC_FLAGS': '0x0', 03:09:15 INFO - 'XPC_SERVICE_NAME': '0', 03:09:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:09:15 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 03:09:15 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 03:09:15 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 03:09:15 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 03:09:15 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 03:09:15 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 03:09:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:09:19 INFO - Installing collected packages: mozsystemmonitor 03:09:19 INFO - Running setup.py install for mozsystemmonitor 03:09:19 INFO - Successfully installed mozsystemmonitor 03:09:19 INFO - Cleaning up... 03:09:19 INFO - Return code: 0 03:09:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:09:19 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')]} 03:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:19 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')]} 03:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:19 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 0x1081d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108265618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9934b81d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108134d30>, '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 0x107e99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10812cb20>, '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.GTUirmZjCf/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.wE0mq78cmZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 03:09:19 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 03:09:19 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 03:09:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GTUirmZjCf/Render', 03:09:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:19 INFO - 'HOME': '/Users/cltbld', 03:09:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:19 INFO - 'LOGNAME': 'cltbld', 03:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:19 INFO - 'MOZ_NO_REMOTE': '1', 03:09:19 INFO - 'NO_EM_RESTART': '1', 03:09:19 INFO - 'PAGER': '/bin/cat', 03:09:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:19 INFO - 'PWD': '/builds/slave/test', 03:09:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:19 INFO - 'SHELL': '/bin/bash', 03:09:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wE0mq78cmZ/Listeners', 03:09:19 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 03:09:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:19 INFO - 'USER': 'cltbld', 03:09:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:19 INFO - 'XPC_FLAGS': '0x0', 03:09:19 INFO - 'XPC_SERVICE_NAME': '0', 03:09:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:19 INFO - Downloading/unpacking jsonschema==2.5.1 03:09:19 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 03:09:19 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 03:09:19 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 03:09:19 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 03:09:19 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 03:09:19 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 03:09:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:09:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:09:22 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 03:09:22 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 03:09:22 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 03:09:22 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 03:09:22 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 03:09:22 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 03:09:22 INFO - Downloading functools32-3.2.3-2.tar.gz 03:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:09:22 INFO - warning: no files found matching '*.txt' 03:09:22 INFO - no previously-included directories found matching 'build' 03:09:22 INFO - no previously-included directories found matching 'dist' 03:09:22 INFO - no previously-included directories found matching '.git*' 03:09:22 INFO - Installing collected packages: jsonschema, functools32 03:09:22 INFO - Running setup.py install for functools32 03:09:22 INFO - warning: no files found matching '*.txt' 03:09:22 INFO - no previously-included directories found matching 'build' 03:09:22 INFO - no previously-included directories found matching 'dist' 03:09:22 INFO - no previously-included directories found matching '.git*' 03:09:22 INFO - Successfully installed jsonschema functools32 03:09:22 INFO - Cleaning up... 03:09:22 INFO - Return code: 0 03:09:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:22 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 0x1081d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108265618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9934b81d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108134d30>, '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 0x107e99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10812cb20>, '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.GTUirmZjCf/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.wE0mq78cmZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 03:09:22 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 03:09:22 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 03:09:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GTUirmZjCf/Render', 03:09:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:22 INFO - 'HOME': '/Users/cltbld', 03:09:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:22 INFO - 'LOGNAME': 'cltbld', 03:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:22 INFO - 'MOZ_NO_REMOTE': '1', 03:09:22 INFO - 'NO_EM_RESTART': '1', 03:09:22 INFO - 'PAGER': '/bin/cat', 03:09:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:22 INFO - 'PWD': '/builds/slave/test', 03:09:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:22 INFO - 'SHELL': '/bin/bash', 03:09:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wE0mq78cmZ/Listeners', 03:09:22 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 03:09:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:22 INFO - 'USER': 'cltbld', 03:09:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:22 INFO - 'XPC_FLAGS': '0x0', 03:09:22 INFO - 'XPC_SERVICE_NAME': '0', 03:09:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:09:23 INFO - Cleaning up... 03:09:23 INFO - Return code: 0 03:09:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:23 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 0x1081d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108265618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9934b81d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108134d30>, '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 0x107e99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10812cb20>, '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.GTUirmZjCf/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.wE0mq78cmZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 03:09:23 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 03:09:23 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 03:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GTUirmZjCf/Render', 03:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:23 INFO - 'HOME': '/Users/cltbld', 03:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:23 INFO - 'LOGNAME': 'cltbld', 03:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:23 INFO - 'MOZ_NO_REMOTE': '1', 03:09:23 INFO - 'NO_EM_RESTART': '1', 03:09:23 INFO - 'PAGER': '/bin/cat', 03:09:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:23 INFO - 'PWD': '/builds/slave/test', 03:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:23 INFO - 'SHELL': '/bin/bash', 03:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wE0mq78cmZ/Listeners', 03:09:23 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 03:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:23 INFO - 'USER': 'cltbld', 03:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:23 INFO - 'XPC_FLAGS': '0x0', 03:09:23 INFO - 'XPC_SERVICE_NAME': '0', 03:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:23 INFO - Downloading/unpacking blobuploader==1.2.4 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:26 INFO - Downloading blobuploader-1.2.4.tar.gz 03:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:09:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:09:26 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 03:09:26 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 03:09:26 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 03:09:26 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 03:09:26 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 03:09:26 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 03:09:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:09:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:28 INFO - Downloading docopt-0.6.1.tar.gz 03:09:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:09:28 INFO - Installing collected packages: blobuploader, requests, docopt 03:09:28 INFO - Running setup.py install for blobuploader 03:09:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:09:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:09:28 INFO - Running setup.py install for requests 03:09:28 INFO - Running setup.py install for docopt 03:09:28 INFO - Successfully installed blobuploader requests docopt 03:09:28 INFO - Cleaning up... 03:09:28 INFO - Return code: 0 03:09:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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 0x1081d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108265618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9934b81d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108134d30>, '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 0x107e99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10812cb20>, '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.GTUirmZjCf/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.wE0mq78cmZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 03:09: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 03:09: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 03:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GTUirmZjCf/Render', 03:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:29 INFO - 'HOME': '/Users/cltbld', 03:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:29 INFO - 'LOGNAME': 'cltbld', 03:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:29 INFO - 'MOZ_NO_REMOTE': '1', 03:09:29 INFO - 'NO_EM_RESTART': '1', 03:09:29 INFO - 'PAGER': '/bin/cat', 03:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:29 INFO - 'PWD': '/builds/slave/test', 03:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:29 INFO - 'SHELL': '/bin/bash', 03:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wE0mq78cmZ/Listeners', 03:09:29 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 03:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:29 INFO - 'USER': 'cltbld', 03:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:29 INFO - 'XPC_FLAGS': '0x0', 03:09:29 INFO - 'XPC_SERVICE_NAME': '0', 03:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:29 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-62GgvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:29 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-r6InfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:29 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-ggYByo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:29 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-IusTk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:29 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-ENPcGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:29 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-3KrhcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:29 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-PhHOsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:29 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-9yRK4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:30 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-HfSgar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:30 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-ynnZ9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:30 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-cWZX2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:30 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-6nx1rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:30 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-UrHYIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:30 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-uws4TE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:30 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-q9_IcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:30 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-qcdTc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:30 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-lg7DNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:09:31 INFO - Running setup.py install for manifestparser 03:09:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:09:31 INFO - Running setup.py install for mozcrash 03:09:31 INFO - Running setup.py install for mozdebug 03:09:31 INFO - Running setup.py install for mozdevice 03:09:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:09:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:09:31 INFO - Running setup.py install for mozfile 03:09:31 INFO - Running setup.py install for mozhttpd 03:09:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:09:31 INFO - Running setup.py install for mozinfo 03:09:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:09:32 INFO - Running setup.py install for mozInstall 03:09:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:09:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:09:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:09:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:09:32 INFO - Running setup.py install for mozleak 03:09:32 INFO - Running setup.py install for mozlog 03:09:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:09:32 INFO - Running setup.py install for moznetwork 03:09:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:09:32 INFO - Running setup.py install for mozprocess 03:09:32 INFO - Running setup.py install for mozprofile 03:09:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:09:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:09:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:09:32 INFO - Running setup.py install for mozrunner 03:09:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:09:33 INFO - Running setup.py install for mozscreenshot 03:09:33 INFO - Running setup.py install for moztest 03:09:33 INFO - Running setup.py install for mozversion 03:09:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:09:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:09:33 INFO - Cleaning up... 03:09:33 INFO - Return code: 0 03:09:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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 0x1081d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108265618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9934b81d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108134d30>, '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 0x107e99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10812cb20>, '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.GTUirmZjCf/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.wE0mq78cmZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 03:09: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 03:09: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 03:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GTUirmZjCf/Render', 03:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:33 INFO - 'HOME': '/Users/cltbld', 03:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:33 INFO - 'LOGNAME': 'cltbld', 03:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:33 INFO - 'MOZ_NO_REMOTE': '1', 03:09:33 INFO - 'NO_EM_RESTART': '1', 03:09:33 INFO - 'PAGER': '/bin/cat', 03:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:33 INFO - 'PWD': '/builds/slave/test', 03:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:33 INFO - 'SHELL': '/bin/bash', 03:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wE0mq78cmZ/Listeners', 03:09:33 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 03:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:33 INFO - 'USER': 'cltbld', 03:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:33 INFO - 'XPC_FLAGS': '0x0', 03:09:33 INFO - 'XPC_SERVICE_NAME': '0', 03:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:33 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-WEcYS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:33 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)) 03:09:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:33 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-DXNPIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09: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)) 03:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-AUxDRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09: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)) 03:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-kMa4V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09: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)) 03:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-LpTJJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09: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)) 03:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-KLimnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09: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)) 03:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-277UzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09: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)) 03:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-IZZNje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09: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)) 03:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-MaWi_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:34 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)) 03:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-URAo3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:34 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)) 03:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:34 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-JwagzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09: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)) 03:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-SIEmvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09: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)) 03:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-oJVbss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09: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)) 03:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-2QzZT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09: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)) 03:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Q3xQia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09: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)) 03:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Ks7iLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09: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)) 03:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:35 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Ma1A8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09: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)) 03:09: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)) 03:09: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)) 03:09: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)) 03:09: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)) 03:09: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)) 03:09:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:38 INFO - Downloading blessings-1.6.tar.gz 03:09:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:09:38 INFO - Installing collected packages: blessings 03:09:38 INFO - Running setup.py install for blessings 03:09:38 INFO - Successfully installed blessings 03:09:38 INFO - Cleaning up... 03:09:38 INFO - Return code: 0 03:09:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:09:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:38 INFO - Reading from file tmpfile_stdout 03:09:38 INFO - Current package versions: 03:09:38 INFO - blessings == 1.6 03:09:38 INFO - blobuploader == 1.2.4 03:09:38 INFO - docopt == 0.6.1 03:09:38 INFO - functools32 == 3.2.3-2 03:09:38 INFO - jsonschema == 2.5.1 03:09:38 INFO - manifestparser == 1.1 03:09:38 INFO - mozInstall == 1.12 03:09:38 INFO - mozcrash == 0.17 03:09:38 INFO - mozdebug == 0.1 03:09:38 INFO - mozdevice == 0.48 03:09:38 INFO - mozfile == 1.2 03:09:38 INFO - mozhttpd == 0.7 03:09:38 INFO - mozinfo == 0.9 03:09:38 INFO - mozleak == 0.1 03:09:38 INFO - mozlog == 3.3 03:09:38 INFO - moznetwork == 0.27 03:09:38 INFO - mozprocess == 0.23 03:09:38 INFO - mozprofile == 0.28 03:09:38 INFO - mozrunner == 6.12 03:09:38 INFO - mozscreenshot == 0.1 03:09:38 INFO - mozsystemmonitor == 0.3 03:09:38 INFO - moztest == 0.7 03:09:38 INFO - mozversion == 1.4 03:09:38 INFO - psutil == 3.1.1 03:09:38 INFO - requests == 1.2.3 03:09:38 INFO - wsgiref == 0.1.2 03:09:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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 0x1081d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108265618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9934b81d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108134d30>, '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 0x107e99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10812cb20>, '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.GTUirmZjCf/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.wE0mq78cmZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 03:09: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 03:09: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 03:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GTUirmZjCf/Render', 03:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:39 INFO - 'HOME': '/Users/cltbld', 03:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:39 INFO - 'LOGNAME': 'cltbld', 03:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:39 INFO - 'MOZ_NO_REMOTE': '1', 03:09:39 INFO - 'NO_EM_RESTART': '1', 03:09:39 INFO - 'PAGER': '/bin/cat', 03:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:39 INFO - 'PWD': '/builds/slave/test', 03:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:39 INFO - 'SHELL': '/bin/bash', 03:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wE0mq78cmZ/Listeners', 03:09:39 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 03:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:39 INFO - 'USER': 'cltbld', 03:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:39 INFO - 'XPC_FLAGS': '0x0', 03:09:39 INFO - 'XPC_SERVICE_NAME': '0', 03:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09: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)) 03:09: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)) 03:09: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)) 03:09: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)) 03:09: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)) 03:09: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)) 03:09: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)) 03:09: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)) 03:09: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)) 03:09: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)) 03:09: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)) 03:09:39 INFO - Cleaning up... 03:09:39 INFO - Return code: 0 03:09:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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 0x1081d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108265618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9934b81d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108134d30>, '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 0x107e99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10812cb20>, '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.GTUirmZjCf/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.wE0mq78cmZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 03:09: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 03:09: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 03:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GTUirmZjCf/Render', 03:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:39 INFO - 'HOME': '/Users/cltbld', 03:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:39 INFO - 'LOGNAME': 'cltbld', 03:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:39 INFO - 'MOZ_NO_REMOTE': '1', 03:09:39 INFO - 'NO_EM_RESTART': '1', 03:09:39 INFO - 'PAGER': '/bin/cat', 03:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:39 INFO - 'PWD': '/builds/slave/test', 03:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:39 INFO - 'SHELL': '/bin/bash', 03:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wE0mq78cmZ/Listeners', 03:09:39 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 03:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:39 INFO - 'USER': 'cltbld', 03:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:39 INFO - 'XPC_FLAGS': '0x0', 03:09:39 INFO - 'XPC_SERVICE_NAME': '0', 03:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:09:39 INFO - Cleaning up... 03:09:39 INFO - Return code: 0 03:09:39 INFO - Running post-action listener: _resource_record_post_action 03:09:39 INFO - Running post-action listener: _start_resource_monitoring 03:09:39 INFO - Starting resource monitoring. 03:09:39 INFO - [mozharness: 2016-08-26 10:09:39.657364Z] Finished create-virtualenv step (success) 03:09:39 INFO - [mozharness: 2016-08-26 10:09:39.658465Z] Running install step. 03:09:39 INFO - Running pre-action listener: _resource_record_pre_action 03:09:39 INFO - Running main action method: install 03:09:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:39 INFO - Reading from file tmpfile_stdout 03:09:39 INFO - Detecting whether we're running mozinstall >=1.0... 03:09:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:09:40 INFO - Reading from file tmpfile_stdout 03:09:40 INFO - Output received: 03:09:40 INFO - Usage: mozinstall [options] installer 03:09:40 INFO - Options: 03:09:40 INFO - -h, --help show this help message and exit 03:09:40 INFO - -d DEST, --destination=DEST 03:09:40 INFO - Directory to install application into. [default: 03:09:40 INFO - "/builds/slave/test"] 03:09:40 INFO - --app=APP Application being installed. [default: firefox] 03:09:40 INFO - mkdir: /builds/slave/test/build/application 03:09: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'] 03:09: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 03:10:14 INFO - Reading from file tmpfile_stdout 03:10:14 INFO - Output received: 03:10:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:10:14 INFO - Running post-action listener: _resource_record_post_action 03:10:14 INFO - [mozharness: 2016-08-26 10:10:14.528107Z] Finished install step (success) 03:10:14 INFO - [mozharness: 2016-08-26 10:10:14.528217Z] Running run-tests step. 03:10:14 INFO - Running pre-action listener: _resource_record_pre_action 03:10:14 INFO - Running main action method: run_tests 03:10:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:10:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:10:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:10:14 INFO - 2016-08-26 03:10:14.542 screenresolution[1911:15781] starting screenresolution argv=screenresolution get 03:10:14 INFO - 2016-08-26 03:10:14.545 screenresolution[1911:15781] Display 0: 1600x1200x32@60 03:10:14 INFO - 2016-08-26 03:10:14.551 screenresolution[1912:15783] starting screenresolution argv=screenresolution list 03:10:14 INFO - Available Modes on Display 0 03:10:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:10:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:10:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:10:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:10:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:10:14 INFO - Intel Iris: 03:10:14 INFO - Chipset Model: Intel Iris 03:10:14 INFO - Type: GPU 03:10:14 INFO - Bus: Built-In 03:10:14 INFO - VRAM (Dynamic, Max): 1536 MB 03:10:14 INFO - Vendor: Intel (0x8086) 03:10:14 INFO - Device ID: 0x0a2e 03:10:14 INFO - Revision ID: 0x0009 03:10:14 INFO - Displays: 03:10:14 INFO - Display: 03:10:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:10:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:10:14 INFO - Display Serial Number: 1600x1200 60 03:10:14 INFO - Main Display: Yes 03:10:14 INFO - Mirror: Off 03:10:14 INFO - Online: Yes 03:10:14 INFO - Rotation: Supported 03:10:14 INFO - Return code: 0 03:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:10:14 INFO - Python 2.7.3 03:10:14 INFO - Return code: 0 03:10:14 INFO - grabbing minidump binary from tooltool 03:10:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:14 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 0x108134d30>, '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 0x107e99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10812cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:10:14 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 03:10:14 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 03:10:14 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 03:10:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:10:14 INFO - Return code: 0 03:10:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:10:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:10:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:10:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:10:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:10:14 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/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--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 03:10:14 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/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0013 --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 03:10:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GTUirmZjCf/Render', 03:10:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:14 INFO - 'HOME': '/Users/cltbld', 03:10:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:14 INFO - 'LOGNAME': 'cltbld', 03:10:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:10:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:14 INFO - 'MOZ_NO_REMOTE': '1', 03:10:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:10:14 INFO - 'NO_EM_RESTART': '1', 03:10:14 INFO - 'PAGER': '/bin/cat', 03:10:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:14 INFO - 'PWD': '/builds/slave/test', 03:10:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:10:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:14 INFO - 'SHELL': '/bin/bash', 03:10:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wE0mq78cmZ/Listeners', 03:10:14 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 03:10:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:14 INFO - 'USER': 'cltbld', 03:10:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:14 INFO - 'XPC_FLAGS': '0x0', 03:10:14 INFO - 'XPC_SERVICE_NAME': '0', 03:10:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:14 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/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--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 03:10:15 INFO - mozversion application_buildid: 20160826011616 03:10:15 INFO - mozversion application_changeset: fd166af53b59fa5cead6c1d9671385c5478c4839 03:10:15 INFO - mozversion application_display_name: Nightly 03:10:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:10:15 INFO - mozversion application_name: Firefox 03:10:15 INFO - mozversion application_remotingname: firefox 03:10:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:10:15 INFO - mozversion application_vendor: Mozilla 03:10:15 INFO - mozversion application_version: 51.0a1 03:10:15 INFO - mozversion platform_buildid: 20160826011616 03:10:15 INFO - mozversion platform_changeset: fd166af53b59fa5cead6c1d9671385c5478c4839 03:10:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:10:15 INFO - mozversion platform_version: 51.0a1 03:10:15 INFO - using testdate: 1472206215 03:10:15 INFO - actual date: 1472206215 03:10:15 INFO - starting webserver on 'localhost:54512' 03:10:15 INFO - SUITE-START | Running 2 tests 03:10:15 INFO - TEST-START | dromaeo_css 03:10:15 INFO - operating with platform_type : mac_ 03:10:15 INFO - Initialising browser for dromaeo_css test... 03:10:15 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpxRf5Pl/profile http://localhost:54512/getInfo.html) 03:10:17 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 03:10:17 INFO - PROCESS | 1922 | colorDepth:24 03:10:17 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 03:10:17 INFO - PROCESS | 1922 | __metrics 03:10:17 INFO - PROCESS | 1922 | 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" 03:10:17 INFO - PROCESS | 1922 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:10:18 INFO - TEST-INFO | 1922: exit 0 03:10:18 INFO - Browser initialized. 03:10:18 INFO - Running cycle 1/1 for dromaeo_css test... 03:10:18 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpxRf5Pl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:10:30 INFO - PROCESS | 1925 | RSS: Main: 233771008 03:10:30 INFO - PROCESS | 1925 | 03:11:21 INFO - PROCESS | 1925 | 1472206281592 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] 03:13:33 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:54512/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54512/tests/dromaeo/cssquery-ext.html) 03:13:34 INFO - PROCESS | 1925 | RSS: Main: 361472000 03:13:34 INFO - PROCESS | 1925 | 03:16:37 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:54512/tests/dromaeo/cssquery-ext.html (next: http://localhost:54512/tests/dromaeo/cssquery-jquery.html) 03:16:38 INFO - PROCESS | 1925 | RSS: Main: 369909760 03:16:38 INFO - PROCESS | 1925 | 03:19:41 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:54512/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54512/tests/dromaeo/cssquery-mootools.html) 03:19:41 INFO - PROCESS | 1925 | RSS: Main: 343986176 03:19:41 INFO - PROCESS | 1925 | 03:22:44 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:54512/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54512/tests/dromaeo/cssquery-prototype.html) 03:22:44 INFO - PROCESS | 1925 | RSS: Main: 340791296 03:22:44 INFO - PROCESS | 1925 | 03:25:48 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:54512/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54512/tests/dromaeo/cssquery-yui.html) 03:25:48 INFO - PROCESS | 1925 | RSS: Main: 332636160 03:25:48 INFO - PROCESS | 1925 | 03:28:52 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:54512/tests/dromaeo/cssquery-yui.html (next: http://localhost:54512/tests/dromaeo/cssquery-dojo.html) 03:28:52 INFO - PROCESS | 1925 | RSS: Main: 444424192 03:28:52 INFO - PROCESS | 1925 | 03:28:52 INFO - PROCESS | 1925 | __start_tp_report 03:28:52 INFO - PROCESS | 1925 | _x_x_mozilla_page_load 03:28:52 INFO - PROCESS | 1925 | _x_x_mozilla_page_load_details 03:28:52 INFO - PROCESS | 1925 | |i|pagename|runs| 03:28:52 INFO - PROCESS | 1925 | |0;dojo.html;3340.8496617652204;3368.1074515253454;3387.3056023515182;3413.573303337083;3472.8263586820667;5647.209390685307;5701.657900525971;5794.275715535558;5797.884042319156;5838.854028649281;5713.371529131792;5774.711264436772;5827.796027139051;5850.005499065159;5854.853628659275;5179.87050323743;5198.064348417283;5252;5267.631265811395;5369.812056578021;5305.257263983026;5358.571314294866;5371.892562148755;5372.54333381663;5414;4332.631726303268;5147.562457191143;5177.19753438214;5210.322658054467;5222.999999999962;5231.790728370861;5234.8691282717855;5240.135377662681;5273.156294992821;5290.841274761763;5239.607029472805;5260.421353651095;5271.367435907716;5275.657082289639;5277.12927366984;5317.803494213809;5400.162974738922;5676.489115979582;5709.143628455723;5714.885702285931;6265.248170219557;6292.465140463066;6315.873682526324;6333.619982801047;6335.588186767892;5488.56091512678;6274.435300822948;6280;6292.748290068392;6298.622082675054;5724.198612194296;5794.101914203306;5809.419058094157;5889.617174883661;5894.97052514739;6225.782097626652;6258.468030217391;6283;6297.1813664223355;6303.401176888188;5174.844754657366;6105.550726437353;6122.448979591858;6200.503959683215;6231.501479881598;6291.402316779899;6314.368563143741;6333.334999824952;6342.5243106767175;6343.5876668016435;6291.465225455796;6298.748050077946;6302.873942521124;6303.338149494329;6306.905396419057;6109.374301065143;6179.876402471926;6184.319724830265;6274.843128921722;6331.873362532816;4677.95322046782;5007.749612519359;5045.596352291808;5046.369203849519;5061.7975280989085;4425.137098265807;4468.039371535136;4517.051419202005;4590.334401511829;4628.629709623222;5837;5839.182514447981;5844.707764611752;5854.882902341929;5857.179994800731;5178.81874134411;6174.783882564088;6277.089821975788;6288.5283603728985;6306.085617585424;4847.321375007502;4851.223804191383;5868.2077919480325;5869.7945571904775;5870.882582348329;5517.834464966058;5520;5520.696361700145;5523.392426833045;5523.585731070106;5370.543503802064;6314.55798094129;6324.873502530016;6378.330275321025;6413.999999999906;6171.1360409543595;6187.84530386744;6238.75044998195;6239.0953311770445;6268.467180289544;6347.270063942521;6377.936220637735;6384.936150638434;6391.6804159792755;6400.295967443484;6171.13604095418;6217.347178546188;6250.468710159687;6271;6277.748890044347;6165.229346331708;6234.75060997555;6270.059491076375;6287.71705273273;6296.496280297473;5219.739013049409;6098.634081955097;6209.627422354673;6224.719887604978;6231.657258850807;6025.096235564701;6205.689715514297;6210.254769427697;6213.254409470893;6213.658248796179;5745.195672605755;5883.911741323969;5940.970295148497;5991.071383935497;6001.669908155093;5167.974160129175;6024.608400453956;6086.239220097488;6163.229596300463;6181.81454556364;6021.307549631849;6021.849453763516;6025.307089684742;6027.819165425044;6030.849228769316 03:28:52 INFO - PROCESS | 1925 | |1;ext.html;463.6870112673946;489.82197814256705;514.4032921810699;535.1811728056075;548.0546058049864;30233.697663023362;31159.75360985561;32126.518102228427;32192.839035804845;32221.51667724986;42224.78887605565;42938.78530607351;42962.78518607411;44756.433524826636;44833.55166448335;9851.950740246306;10013.749656258578;10016.599336026551;10017.69946901594;10044.447555384451;8919.10808919106;9778.462184579845;9894.40633561985;9895.653652122177;9928.503574821267;8917.955410222972;12264.448099835528;12531.812022819666;12542.372881355941;12551.560695375663;10322.277440579162;12245.755084898297;12312.199707019015;12340.691482712915;12370.814437783485;12038;12138.45376958039;12153;12155;12163.209391389531;40189.196216075805;40491.59508404908;40505.59494405048;40534.39198412026;40571.78284651465;3483.89548313551;3614.0784100054316;3659.79871107089;3719.512195121973;4173.499180098377;31746.206344841565;31833;32175;32235.516467253008;32269.45036046033;3250.593675790526;3335.1495368681076;3369.9831500842583;3439.002689220123;3440.1571614954246;488654;491114;491679;491806.54096729646;492275;54915;55143;55199;55228.723856381264;55263.447365526634;63435.68282158559;63485.68257158777;63511;63548.68225658842;63569.682151588946;13534.59396218115;16273.59316017109;16444;16466.01203927768;16501;12865.549705760253;12920.289384083777;12930.741385172243;12937.482500700055;12943.288119153536;11977.700557485958;14183.71632567343;15197.6200594986;16690.666186676197;17092.914535427244;5416.187571864252;5963.522918166536;6293.1504246927;6431.967840160769;6478.6760661967655;809.4900212865974;1035.9223058270659;1053.657561292583;1078.245228340165;1108.1245815805585;27055.594166087525;30309;30849.766009359824;31324.843375782973;32365.838170809468;892.433304851424;1000.2198285337448;1062.718379629399;1098.8406681031206;1105.789899919013;15569.299381527631;15628.140452275235;15724.606884827743;15846.445374412071;15904.204789760697;641024;645855;646052.7697361483;647828;647842.4549667774;50809.49190508122;51437;57445;58079.12881306784;58394.70802645959;1351.5067000544927;1383.3982217735318;1406.268740255082;1407.9577612671637;1410.1961881727316;29580.704192958656;30112.462703695623;30906.53640195444;31585.684143158272;31762;50177.49822501728;51881.14653694083;51941.48058519366;51986.96026079533;52018.47981520136;559733;561354;561707;561775;563189;25705.485890281714;26138.954441822876;26204.475910481302;26210.60684089702;26421;44295.77852110719;45762.54237457583;46623.834404140165;48737.26623832975;49427;32761.67238327586;38115.80942095272;39283.607163928;40611.98470038272;40844.97887552835;38819;38916.41625375678;39002.219955600165;39036.21927561376;39349;37898;38546.421803672405;38763;38798.80600596979;38884.8334549964;33767.83116084404;33832;34698.78554250589;34932.47601286034;35080.649193507736;24865.751342486343;25550.744492554837;25588.232353029438;25686.614700779875;26080 03:28:52 INFO - PROCESS | 1925 | |2;jquery.html;303.50528638319537;366.23274240466225;370.5849448617549;380.2091649369313;402.2156794251211;14802.114870991878;15347.84652153478;19728.506787330323;20219.797802021974;20835;20118.497037574067;20330;20399.490012749688;21124.260650877222;21339.786602133972;5808.709564521778;6133.17202177707;6145.047517634774;6158.907616385758;6170;4192.706510544263;5689.9189154060805;5945.85135371617;6707.1616047994;6713.832154196135;6662.400383965442;6663.533552651316;6667;6675;6691.297413771557;6386.457151142135;6548.476121910236;6595.835104122388;6598.175228096488;6604.339566043357;5516.365617953907;5667.064934285838;5710.657360558338;5724.656520608777;5725.971370143165;25893.611595826078;25937.481250375076;25957.610635840476;26189.73810261892;26225.082122125827;2229.264342766899;2377.8335516513853;2449.64480150379;2685.3957859481657;2848.430313937221;14951.626209344744;15281.61795955099;15380.615484612863;15980.680386392323;16453.83546164535;1826.6072794349145;1843.3640394064028;1888.0937150167997;1895.4882181810833;1911.1399870058528;107920.46039769829;109190.45404772925;114312;114425.4278728601;119043.4047829764;7329.2670732927345;8394.874076888851;9407.529623518796;9842.852357214648;10013.949930250303;12725.872741272655;13285.402156902963;13386;14155.150690958575;14161.716765664834;8356.289715374262;8459.788505287417;8519.488830670056;8619.439736417113;8686.174813392718;6963.4777391694815;8515.063343032261;8524.488530688179;8526.78683032929;8713.302935765123;3994.242533285345;4180.28935081037;4388.692791504564;4539.59143677064;4586.362841366228;1760.1111438723362;1832.3128826689992;1848.8336049755317;1890.1116475256608;1891.9243230270636;821.3142026408016;884.91150884911;897.6005677473462;927.6299798103998;975.2295686407658;18057;18619.975901325288;20544.38366848997;20564.485887852923;20788.792112078987;843.0535279199238;889.7063968890251;906.4833045164324;990.4255531791543;1002.1832206751474;9822.624832523452;10001.949990250003;10565.260431769702;11028.44857757109;11041.668749937515;565700;582787;583799;608765;611104;117500;120835;121699;126026.36986815007;131084.3445782765;952.6944793599506;958.942463452195;986.3736527305213;988.2143695761887;988.8961659025699;17982.91008544949;18026;18147.818521814614;18150;18307.908460457613;52342.95314093621;52714;53753;53961;54718.45281547133;79842.4031519378;80900.19099809162;81245;82031.17968820235;82526;6509.902351464826;7843.490173138728;7996.120426753168;8017.8396432072195;8061.959690201512;10214.846777298199;11831;12321.753564928473;12531.310777907303;12613.684657883627;10712.250142489951;11076.507785748465;11199.72000699989;11367.943160284145;11417.429128543707;10666.520006599876;10725.946370268099;11002.394868282321;11288.9435552825;11333.320000799979;11323.660290191307;11458.885411145782;11500.94249528747;11538.538458461568;11614.941925290319;7839.05931288249;8039.598020099089;8140.145284745254;8188.631511582113;8308.833823323379;10651.733706657396;11244.425780390588;11249.783410480393;11286.717832054264;11459.4270286487 03:28:52 INFO - PROCESS | 1925 | |3;mootools.html;1365.3924003818292;1532.800735904332;1550.054466775267;1753.5002524280583;1905.9047047647623;7711.5758633275145;8471;8731.519766412861;8745.91254087459;8885.489466790645;8648.048714641385;8707.477551346923;8723.38936274461;8726.258268047224;8746.344024198195;2371.2174982255815;2397.5564520563753;2399.5800734871414;2402.411409204747;2412.445137618349;2170.967435488469;2219.911203551864;2406.3984003999;2407.1214006887535;2409.795167410772;2172.5546263016045;2205.018766648842;2381.154690085021;2408.8675122868235;2421.2736179146273;2350.2361732436857;2375.964360534593;2379.155399833069;2386.5465561543338;2392.6650268962358;2224.89987950541;2234.3520379090046;2235.854669446497;2238.876861772602;2238.9104435822546;7806.985091938069;8497.532635705034;8619.65521379144;8808.64765409383;8817.735467935972;5191.402988656315;5371.409144994048;5383.031054410205;5389.245505629215;5389.487998640123;3629.274145170976;3641.089727568108;3702.6667599916136;3712.3688972874675;3727.1613886875507;1116.1573012375686;1118.457548089176;1119.6529075986389;1126.2735535579504;1141.2809929744292;11614.535418583246;11632.302061876315;11693.181477296592;11707.765844683145;11708.297502149899;3687.8340474678703;4339.804708788112;4603.49361570227;4619.145458090264;4629.467611224685;9020.917489901123;9043.23132533742;9050.909490905138;9064.501452420049;9082.45505269686;5246.790128394898;5247.973760131251;5250.212468129735;5254.264402983586;5255.76349064293;4421.270490369117;4583.472900616405;4768.451628062748;4928.654994150446;4943.579795717333;3666.871659491905;3725.4598083277774;3765.3034188675183;3820.7898565578603;3832.693384529231;963.7686955130719;981.7825895889047;989.3964681544252;1173.2256710570982;1182.0838849891231;772.2432016623741;802.5786462107324;934.8364036293622;938.2306508662831;944.1266828183902;5565.833025009256;5869.765209391662;5964.403559644001;5996.580239383201;6066.726997285133;418.53542567749776;423.88978865495193;452.4977275224498;453.97503137327766;454.3593533118287;3922.37242041272;4061.045654271244;4064.6545043671026;4075.4294398784195;4127.484064491939;10465.581376744845;10724.678259652223;10802.243842931077;10856.077233435246;10866.511007004703;10108.241881858743;10611.363318200933;10621.84067238992;10777.946110269399;10780.460976951277;7984.560849153236;8217.876731849026;8223.465474744122;8226.753197403968;8228.50628962264;5922.703864806656;5995.850103747267;6033.396660333931;6123.050927106306;6172;9695.466749328854;10000.24998125129;10153.746156346151;10198.592056317664;10203.28576999603;9942.806863176465;10133.847992279974;10255.846162307422;10285.74285642865;10297.897021029694;6922.06552115458;7161.928380716126;7315.487915846053;7507.662155203138;7635.083789945242;8102.554359510284;8431.747047588582;8450.577471126542;8476.49141051539;8493.957530212556;7420.740274090381;7474.813129671802;7548.849023019399;7588.62056897164;7683.193264707125;7183;7306.59813710251;7332.23011583776;7332.230115837973;7386.002889609834;6905.240423553305;7066.364027237573;7069.434445244471;7080.752173673896;7095.148582170173;4824.18278802635;4973.055119527278;4995.600351971906;4999.850004499871;5053.974730126326;4868.756562171948;4987.1521841287795;5048.167052436454;5129.563987061141;5205 03:28:52 INFO - PROCESS | 1925 | |4;prototype.html;240.45896732352207;288.92199106241316;300.98194108353493;304.5827216713102;305.79053348456296;4279.465066866638;5168.6381953263235;5190.662606930547;5217.217417387394;5218.712970786605;4877.292792545079;4891.3641226640575;5035.773390197441;5077.9844031193725;5132.331992102563;2929.2570920302437;3883.475730776337;3898.8498392973997;3920.157525962793;3938.1532970411354;3405.625381208065;3920.2159568086354;3928.8821335359985;3942.684585233189;3964.3458829293277;3017.803842750225;3256.4436917020143;3598.820058997053;3727.155058246172;3849.8845034648866;3848.8845334639727;3867.903302417434;3897.8635747748694;3901.51231096113;3917.98041009796;2681.1554360376417;3116.3299890523645;3416.180116771982;3423;3456.7753096048928;4277.272863613193;4595.494495605481;4814.277858321245;4886.877828054292;5022.936009034486;3704.740388268002;3893.785841778701;4534.659900507442;4536.977315113436;4537.251353526664;5438.510534051954;5470.617056806025;5470.9452905470835;5475.972620136914;5491.176323551499;3043.432632194422;3199.9520007199912;3821.560520540118;3827.5215598050245;3830.4062870255157;4915.5330243626795;4952.331435256267;5226.3989641191365;5277.788888444457;5663.688497132697;3274.3287626036604;3701.056230661164;3836.4245363195187;3843.135294558758;3854.7879866607605;3724.776513409204;3793.5447746270625;3929.862454814068;3945.743526670757;3945.8421663133727;4115.34154535273;4434.423524941737;4486.214912390318;4526.637868970474;4528.207563676344;5385.165299378603;5433.347998240237;5434.782608695607;5452.972735136378;5456.317960254968;1141.646089712192;1293.1788314420223;1299.4152631315947;1315.9210447373189;1319.7492476429447;694.8054544727485;776.3013288040809;859.2524503681836;883.2271762208068;886.189136939693;4189.287821070365;4591.862244132682;5125.974370128126;5172.663776854492;5195.506426889439;5614.129809879475;5650.208970744099;5650.915236271459;5658.443927919855;5668.319801623831;4162.83348666056;4963.751812409365;5051.444341122474;5081.695098294114;5145.716985565755;2934.2224310557717;2937.9412411751646;2944.5435957426635;2945.558166275033;2968.5992391026934;4924.778384972685;5524.171374293807;5704.686242256633;5740.512056475246;5820.729915333023;4584.426946631671;4895.461499235082;5467.589930755209;5838;5896.970515147483;5876.676782776988;5905.43898329658;5910.822675319747;5934.406559344117;5943.889919078665;5243.977424402205;5449.2371068049715;5561.490918894098;5767.36655906776;5782.392848750821;5670.716464176857;5674.574406919415;5680.857978550569;5699.7435115419075;5707.543396528351;5899.852503687443;5926.525877929842;5929.636183677692;5930.436608522184;5962;3650.872219472306;3773.2076263984873;3813.8093095345675;3845.3270677630744;3856.5757766646207;5148.099082660594;5356.946430535801;5707.315122185364;5714.971425142848;5721;5625.381208067028;5644.463775941279;5644.548436125018;5654.151877218285;5663.886722265449;4832.685875418087;5005.77474013663;5086.981284784978;5565.777368905198;5594.174941654438;5154.8969020620125;5442.319710036246;5456.972715136399;5461.876283406003;5471.343438787371;5113.667611605234;5230.959925628932;5419.458054194707;5492.3522943117705;5541.833744987657;4649;4905.877353066202;5369.5167434931045;5400.378956419905;5410.567154627542 03:28:52 INFO - PROCESS | 1925 | |5;yui.html;718.802329359426;733.842223921857;770.0489894979703;803.4375936844203;815.1970309245394;12950.611481655542;16443.671126577476;16460.0946947918;16461.34154633813;16494.752578711297;14384.640383990405;14491.913106517028;14492.42030318786;14495.420183192686;14932;601.515779797263;677.0859339891157;697.8394969157072;698.4447364345358;841.3984001438985;690.3510699942063;833.6040380819123;862.2627653356384;865.7056600755742;866.2377108144855;898.2768871058795;906.6554709210491;912.6303846941966;912.6714382822179;915.2403505090792;906.9591868365874;907.4101833808012;908.3006085314337;908.418612088264;908.6819613135416;528.5586535243077;655.0632595388593;671.8924972004505;673.2358772792867;674.2616834566192;14806.925965370212;14905.403783848742;14922.477713280092;14926.776098358534;14927.104373737611;13637.454501819915;13896;14039.64900877476;14069.226192559405;14447.060941038799;11363.034142097931;12387.75224495505;12745.745085098339;12765.489380424771;12951.222926624432;4564.4522657281;4571.337156112379;4681.695689780154;4710.811567537295;4717.669763116583;112447;113705;115624;115994.42002790017;117043;10805.459727013618;10828.512716927757;10894.836577451344;10973.890261097287;11271.774564508663;21198.894005530183;21424.678629820566;21426.25008124739;21428.78571214268;21476.89261553682;7987.121416644164;8095.5142691438705;8256.298214651822;8298.419110662317;8555.529445880537;4351.630111440563;4386.366805442105;4392.253316936129;4409.690554151798;4555.703879247838;3548.538689970287;3681.7054635629083;3708.610595887447;3711.684506816951;3715.7213209009433;642.8167972127922;646.1728986896779;651.4365074210868;659.960402375859;665.6838001949036;520.5783315514445;565.3300838506383;587.3920492290545;670.165643773505;682.8497730499282;9434.764130896783;9467.881471559893;10064.597416103421;10420.06219440254;10549.261551691308;665.4210836572213;667.4593579200863;675.3651567526509;677.2617846547287;677.3598948993155;4352.888848908277;4671.743054131987;4676.158291507493;4699.224627936353;4745.833895813699;126919;140572.29713851569;143249;149277;152757;109782;132637;134219.3289033568;134686.65313346742;139749;89178;98390.5080474593;104439;105808.47095764472;108328.45835770771;9550.37922535033;9715.805683886425;9959;9997.55011024491;10005.549750261402;61468.692656536434;61578.69210653918;62483.75032499416;63268.36731632625;64567.677161613894;75167;89019.55490222508;93188;95425;97607;10985.285956412808;13209.735805284034;13411.664708382368;13597.660058498617;13649.471963854654;14786.556403307917;15155.393784248947;15168.620784480476;15277.847221528087;15396.769048464057;13548.458061677422;13799.051745197798;13812.37844296989;13966.58100256994;14216;11365.943170284427;13782.862171378158;13963;14039.806616437718;14048.578542643738;13372.732545348845;13453.394597243343;13743.587692369245;14107.365168567234;14209.934254931128;11205.60780372683;11216.495257713259;11463.369514676773;11465.19743617972;12239.755204895675;10543.367397956146;11301.71745706364;11463.254888432226;11574.247673901169;11588.072954163477 03:28:52 INFO - PROCESS | 1925 | __end_tp_report 03:28:52 INFO - PROCESS | 1925 | __start_cc_report 03:28:52 INFO - PROCESS | 1925 | _x_x_mozilla_cycle_collect,1174 03:28:52 INFO - PROCESS | 1925 | __end_cc_report 03:28:52 INFO - PROCESS | 1925 | __startTimestamp1472207332321__endTimestamp 03:28:52 INFO - PROCESS | 1925 | 03:28:52 INFO - PROCESS | 1925 | ------- Summary: start ------- 03:28:52 INFO - PROCESS | 1925 | Number of tests: 6 03:28:52 INFO - PROCESS | 1925 | 03:28:52 INFO - PROCESS | 1925 | [#0] dojo.html Cycles:165 Average:5747.49 Median:5894.97 stddev:653.12 (11.1%) stddev-sans-first:627.24 03:28:52 INFO - PROCESS | 1925 | Values: 3340.8 3368.1 3387.3 3413.6 3472.8 5647.2 5701.7 5794.3 5797.9 5838.9 5713.4 5774.7 5827.8 5850.0 5854.9 5179.9 5198.1 5252.0 5267.6 5369.8 5305.3 5358.6 5371.9 5372.5 5414.0 4332.6 5147.6 5177.2 5210.3 5223.0 5231.8 5234.9 5240.1 5273.2 5290.8 5239.6 5260.4 5271.4 5275.7 5277.1 5317.8 5400.2 5676.5 5709.1 5714.9 6265.2 6292.5 6315.9 6333.6 6335.6 5488.6 6274.4 6280.0 6292.7 6298.6 5724.2 5794.1 5809.4 5889.6 5895.0 6225.8 6258.5 6283.0 6297.2 6303.4 5174.8 6105.6 6122.4 6200.5 6231.5 6291.4 6314.4 6333.3 6342.5 6343.6 6291.5 6298.7 6302.9 6303.3 6306.9 6109.4 6179.9 6184.3 6274.8 6331.9 4678.0 5007.7 5045.6 5046.4 5061.8 4425.1 4468.0 4517.1 4590.3 4628.6 5837.0 5839.2 5844.7 5854.9 5857.2 5178.8 6174.8 6277.1 6288.5 6306.1 4847.3 4851.2 5868.2 5869.8 5870.9 5517.8 5520.0 5520.7 5523.4 5523.6 5370.5 6314.6 6324.9 6378.3 6414.0 6171.1 6187.8 6238.8 6239.1 6268.5 6347.3 6377.9 6384.9 6391.7 6400.3 6171.1 6217.3 6250.5 6271.0 6277.7 6165.2 6234.8 6270.1 6287.7 6296.5 5219.7 6098.6 6209.6 6224.7 6231.7 6025.1 6205.7 6210.3 6213.3 6213.7 5745.2 5883.9 5941.0 5991.1 6001.7 5168.0 6024.6 6086.2 6163.2 6181.8 6021.3 6021.8 6025.3 6027.8 6030.8 03:28:52 INFO - PROCESS | 1925 | 03:28:52 INFO - PROCESS | 1925 | [#1] ext.html Cycles:180 Average:69830.67 Median:19208.19 stddev:152171.10 (792.2%) stddev-sans-first:152508.85 03:28:52 INFO - PROCESS | 1925 | Values: 463.7 489.8 514.4 535.2 548.1 30233.7 31159.8 32126.5 32192.8 32221.5 42224.8 42938.8 42962.8 44756.4 44833.6 9852.0 10013.7 10016.6 10017.7 10044.4 8919.1 9778.5 9894.4 9895.7 9928.5 8918.0 12264.4 12531.8 12542.4 12551.6 10322.3 12245.8 12312.2 12340.7 12370.8 12038.0 12138.5 12153.0 12155.0 12163.2 40189.2 40491.6 40505.6 40534.4 40571.8 3483.9 3614.1 3659.8 3719.5 4173.5 31746.2 31833.0 32175.0 32235.5 32269.5 3250.6 3335.1 3370.0 3439.0 3440.2 488654.0 491114.0 491679.0 491806.5 492275.0 54915.0 55143.0 55199.0 55228.7 55263.4 63435.7 63485.7 63511.0 63548.7 63569.7 13534.6 16273.6 16444.0 16466.0 16501.0 12865.5 12920.3 12930.7 12937.5 12943.3 11977.7 14183.7 15197.6 16690.7 17092.9 5416.2 5963.5 6293.2 6432.0 6478.7 809.5 1035.9 1053.7 1078.2 1108.1 27055.6 30309.0 30849.8 31324.8 32365.8 892.4 1000.2 1062.7 1098.8 1105.8 15569.3 15628.1 15724.6 15846.4 15904.2 641024.0 645855.0 646052.8 647828.0 647842.5 50809.5 51437.0 57445.0 58079.1 58394.7 1351.5 1383.4 1406.3 1408.0 1410.2 29580.7 30112.5 30906.5 31585.7 31762.0 50177.5 51881.1 51941.5 51987.0 52018.5 559733.0 561354.0 561707.0 561775.0 563189.0 25705.5 26139.0 26204.5 26210.6 26421.0 44295.8 45762.5 46623.8 48737.3 49427.0 32761.7 38115.8 39283.6 40612.0 40845.0 38819.0 38916.4 39002.2 39036.2 39349.0 37898.0 38546.4 38763.0 38798.8 38884.8 33767.8 33832.0 34698.8 34932.5 35080.6 24865.8 25550.7 25588.2 25686.6 26080.0 03:28:52 INFO - PROCESS | 1925 | 03:28:52 INFO - PROCESS | 1925 | [#2] jquery.html Cycles:180 Average:34635.24 Median:1575.97 stddev:98472.90 (6248.4%) stddev-sans-first:98715.40 03:28:52 INFO - PROCESS | 1925 | Values: 303.5 366.2 370.6 380.2 402.2 14802.1 15347.8 19728.5 20219.8 20835.0 20118.5 20330.0 20399.5 21124.3 21339.8 5808.7 6133.2 6145.0 6158.9 6170.0 4192.7 5689.9 5945.9 6707.2 6713.8 6662.4 6663.5 6667.0 6675.0 6691.3 6386.5 6548.5 6595.8 6598.2 6604.3 5516.4 5667.1 5710.7 5724.7 5726.0 25893.6 25937.5 25957.6 26189.7 26225.1 2229.3 2377.8 2449.6 2685.4 2848.4 14951.6 15281.6 15380.6 15980.7 16453.8 1826.6 1843.4 1888.1 1895.5 1911.1 107920.5 109190.5 114312.0 114425.4 119043.4 7329.3 8394.9 9407.5 9842.9 10013.9 12725.9 13285.4 13386.0 14155.2 14161.7 8356.3 8459.8 8519.5 8619.4 8686.2 6963.5 8515.1 8524.5 8526.8 8713.3 3994.2 4180.3 4388.7 4539.6 4586.4 1760.1 1832.3 1848.8 1890.1 1891.9 821.3 884.9 897.6 927.6 975.2 18057.0 18620.0 20544.4 20564.5 20788.8 843.1 889.7 906.5 990.4 1002.2 9822.6 10001.9 10565.3 11028.4 11041.7 565700.0 582787.0 583799.0 608765.0 611104.0 117500.0 120835.0 121699.0 126026.4 131084.3 952.7 958.9 986.4 988.2 988.9 17982.9 18026.0 18147.8 18150.0 18307.9 52343.0 52714.0 53753.0 53961.0 54718.5 79842.4 80900.2 81245.0 82031.2 82526.0 6509.9 7843.5 7996.1 8017.8 8062.0 10214.8 11831.0 12321.8 12531.3 12613.7 10712.3 11076.5 11199.7 11367.9 11417.4 10666.5 10725.9 11002.4 11288.9 11333.3 11323.7 11458.9 11500.9 11538.5 11614.9 7839.1 8039.6 8140.1 8188.6 8308.8 10651.7 11244.4 11249.8 11286.7 11459.4 03:28:52 INFO - PROCESS | 1925 | 03:28:52 INFO - PROCESS | 1925 | [#3] mootools.html Cycles:180 Average:5658.52 Median:4936.12 stddev:3197.90 (64.8%) stddev-sans-first:3190.59 03:28:52 INFO - PROCESS | 1925 | Values: 1365.4 1532.8 1550.1 1753.5 1905.9 7711.6 8471.0 8731.5 8745.9 8885.5 8648.0 8707.5 8723.4 8726.3 8746.3 2371.2 2397.6 2399.6 2402.4 2412.4 2171.0 2219.9 2406.4 2407.1 2409.8 2172.6 2205.0 2381.2 2408.9 2421.3 2350.2 2376.0 2379.2 2386.5 2392.7 2224.9 2234.4 2235.9 2238.9 2238.9 7807.0 8497.5 8619.7 8808.6 8817.7 5191.4 5371.4 5383.0 5389.2 5389.5 3629.3 3641.1 3702.7 3712.4 3727.2 1116.2 1118.5 1119.7 1126.3 1141.3 11614.5 11632.3 11693.2 11707.8 11708.3 3687.8 4339.8 4603.5 4619.1 4629.5 9020.9 9043.2 9050.9 9064.5 9082.5 5246.8 5248.0 5250.2 5254.3 5255.8 4421.3 4583.5 4768.5 4928.7 4943.6 3666.9 3725.5 3765.3 3820.8 3832.7 963.8 981.8 989.4 1173.2 1182.1 772.2 802.6 934.8 938.2 944.1 5565.8 5869.8 5964.4 5996.6 6066.7 418.5 423.9 452.5 454.0 454.4 3922.4 4061.0 4064.7 4075.4 4127.5 10465.6 10724.7 10802.2 10856.1 10866.5 10108.2 10611.4 10621.8 10777.9 10780.5 7984.6 8217.9 8223.5 8226.8 8228.5 5922.7 5995.9 6033.4 6123.1 6172.0 9695.5 10000.2 10153.7 10198.6 10203.3 9942.8 10133.8 10255.8 10285.7 10297.9 6922.1 7161.9 7315.5 7507.7 7635.1 8102.6 8431.7 8450.6 8476.5 8494.0 7420.7 7474.8 7548.8 7588.6 7683.2 7183.0 7306.6 7332.2 7332.2 7386.0 6905.2 7066.4 7069.4 7080.8 7095.1 4824.2 4973.1 4995.6 4999.9 5054.0 4868.8 4987.2 5048.2 5129.6 5205.0 03:28:52 INFO - PROCESS | 1925 | 03:28:52 INFO - PROCESS | 1925 | [#4] prototype.html Cycles:180 Average:4431.43 Median:5029.35 stddev:1401.03 (27.9%) stddev-sans-first:1369.19 03:28:52 INFO - PROCESS | 1925 | Values: 240.5 288.9 301.0 304.6 305.8 4279.5 5168.6 5190.7 5217.2 5218.7 4877.3 4891.4 5035.8 5078.0 5132.3 2929.3 3883.5 3898.8 3920.2 3938.2 3405.6 3920.2 3928.9 3942.7 3964.3 3017.8 3256.4 3598.8 3727.2 3849.9 3848.9 3867.9 3897.9 3901.5 3918.0 2681.2 3116.3 3416.2 3423.0 3456.8 4277.3 4595.5 4814.3 4886.9 5022.9 3704.7 3893.8 4534.7 4537.0 4537.3 5438.5 5470.6 5470.9 5476.0 5491.2 3043.4 3200.0 3821.6 3827.5 3830.4 4915.5 4952.3 5226.4 5277.8 5663.7 3274.3 3701.1 3836.4 3843.1 3854.8 3724.8 3793.5 3929.9 3945.7 3945.8 4115.3 4434.4 4486.2 4526.6 4528.2 5385.2 5433.3 5434.8 5453.0 5456.3 1141.6 1293.2 1299.4 1315.9 1319.7 694.8 776.3 859.3 883.2 886.2 4189.3 4591.9 5126.0 5172.7 5195.5 5614.1 5650.2 5650.9 5658.4 5668.3 4162.8 4963.8 5051.4 5081.7 5145.7 2934.2 2937.9 2944.5 2945.6 2968.6 4924.8 5524.2 5704.7 5740.5 5820.7 4584.4 4895.5 5467.6 5838.0 5897.0 5876.7 5905.4 5910.8 5934.4 5943.9 5244.0 5449.2 5561.5 5767.4 5782.4 5670.7 5674.6 5680.9 5699.7 5707.5 5899.9 5926.5 5929.6 5930.4 5962.0 3650.9 3773.2 3813.8 3845.3 3856.6 5148.1 5356.9 5707.3 5715.0 5721.0 5625.4 5644.5 5644.5 5654.2 5663.9 4832.7 5005.8 5087.0 5565.8 5594.2 5154.9 5442.3 5457.0 5461.9 5471.3 5113.7 5231.0 5419.5 5492.4 5541.8 4649.0 4905.9 5369.5 5400.4 5410.6 03:28:52 INFO - PROCESS | 1925 | 03:28:52 INFO - PROCESS | 1925 | [#5] yui.html Cycles:180 Average:24719.27 Median:21452.84 stddev:38936.39 (181.5%) stddev-sans-first:39003.92 03:28:52 INFO - PROCESS | 1925 | Values: 718.8 733.8 770.0 803.4 815.2 12950.6 16443.7 16460.1 16461.3 16494.8 14384.6 14491.9 14492.4 14495.4 14932.0 601.5 677.1 697.8 698.4 841.4 690.4 833.6 862.3 865.7 866.2 898.3 906.7 912.6 912.7 915.2 907.0 907.4 908.3 908.4 908.7 528.6 655.1 671.9 673.2 674.3 14806.9 14905.4 14922.5 14926.8 14927.1 13637.5 13896.0 14039.6 14069.2 14447.1 11363.0 12387.8 12745.7 12765.5 12951.2 4564.5 4571.3 4681.7 4710.8 4717.7 112447.0 113705.0 115624.0 115994.4 117043.0 10805.5 10828.5 10894.8 10973.9 11271.8 21198.9 21424.7 21426.3 21428.8 21476.9 7987.1 8095.5 8256.3 8298.4 8555.5 4351.6 4386.4 4392.3 4409.7 4555.7 3548.5 3681.7 3708.6 3711.7 3715.7 642.8 646.2 651.4 660.0 665.7 520.6 565.3 587.4 670.2 682.8 9434.8 9467.9 10064.6 10420.1 10549.3 665.4 667.5 675.4 677.3 677.4 4352.9 4671.7 4676.2 4699.2 4745.8 126919.0 140572.3 143249.0 149277.0 152757.0 109782.0 132637.0 134219.3 134686.7 139749.0 89178.0 98390.5 104439.0 105808.5 108328.5 9550.4 9715.8 9959.0 9997.6 10005.5 61468.7 61578.7 62483.8 63268.4 64567.7 75167.0 89019.6 93188.0 95425.0 97607.0 10985.3 13209.7 13411.7 13597.7 13649.5 14786.6 15155.4 15168.6 15277.8 15396.8 13548.5 13799.1 13812.4 13966.6 14216.0 11365.9 13782.9 13963.0 14039.8 14048.6 13372.7 13453.4 13743.6 14107.4 14209.9 11205.6 11216.5 11463.4 11465.2 12239.8 10543.4 11301.7 11463.3 11574.2 11588.1 03:28:52 INFO - PROCESS | 1925 | -------- Summary: end -------- 03:28:52 INFO - PROCESS | 1925 | 03:28:52 INFO - TEST-INFO | 1925: exit 0 03:28:52 INFO - TEST-OK | dromaeo_css | took 1117824ms 03:28:52 INFO - TEST-START | kraken 03:28:52 INFO - operating with platform_type : mac_ 03:28:52 INFO - Initialising browser for kraken test... 03:28:52 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpnOcQrc/profile http://localhost:54512/getInfo.html) 03:28:55 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 03:28:55 INFO - PROCESS | 1930 | colorDepth:24 03:28:55 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 03:28:55 INFO - PROCESS | 1930 | __metrics 03:28:55 INFO - PROCESS | 1930 | 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" 03:28:55 INFO - PROCESS | 1930 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:28:56 INFO - TEST-INFO | 1930: exit 0 03:28:56 INFO - Browser initialized. 03:28:56 INFO - Running cycle 1/1 for kraken test... 03:28:56 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpnOcQrc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:29:08 INFO - PROCESS | 1931 | RSS: Main: 233738240 03:29:08 INFO - PROCESS | 1931 | 03:29:11 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54512/tests/kraken/driver.html?testName=audio-beat-detection) 03:29:11 INFO - PROCESS | 1931 | RSS: Main: 292110336 03:29:11 INFO - PROCESS | 1931 | 03:29:14 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54512/tests/kraken/driver.html?testName=audio-dft) 03:29:15 INFO - PROCESS | 1931 | RSS: Main: 288276480 03:29:15 INFO - PROCESS | 1931 | 03:29:18 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54512/tests/kraken/driver.html?testName=audio-fft) 03:29:19 INFO - PROCESS | 1931 | RSS: Main: 377741312 03:29:19 INFO - PROCESS | 1931 | 03:29:21 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54512/tests/kraken/driver.html?testName=audio-oscillator) 03:29:22 INFO - PROCESS | 1931 | RSS: Main: 279035904 03:29:22 INFO - PROCESS | 1931 | 03:29:24 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54512/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:29:24 INFO - PROCESS | 1931 | RSS: Main: 282574848 03:29:24 INFO - PROCESS | 1931 | 03:29:27 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54512/tests/kraken/driver.html?testName=imaging-darkroom) 03:29:28 INFO - PROCESS | 1931 | RSS: Main: 321601536 03:29:28 INFO - PROCESS | 1931 | 03:29:31 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54512/tests/kraken/driver.html?testName=imaging-desaturate) 03:29:31 INFO - PROCESS | 1931 | RSS: Main: 322523136 03:29:31 INFO - PROCESS | 1931 | 03:29:34 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54512/tests/kraken/driver.html?testName=json-parse-financial) 03:29:35 INFO - PROCESS | 1931 | RSS: Main: 322527232 03:29:35 INFO - PROCESS | 1931 | 03:29:36 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54512/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:29:37 INFO - PROCESS | 1931 | RSS: Main: 298106880 03:29:37 INFO - PROCESS | 1931 | 03:29:39 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54512/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:29:39 INFO - PROCESS | 1931 | RSS: Main: 304893952 03:29:39 INFO - PROCESS | 1931 | 03:29:42 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54512/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:29:42 INFO - PROCESS | 1931 | RSS: Main: 301285376 03:29:42 INFO - PROCESS | 1931 | 03:29:45 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54512/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:29:46 INFO - PROCESS | 1931 | RSS: Main: 319111168 03:29:46 INFO - PROCESS | 1931 | 03:29:49 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54512/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:29:49 INFO - PROCESS | 1931 | RSS: Main: 315334656 03:29:49 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54512/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54512/tests/kraken/driver.html?testName=ai-astar) 03:29:51 INFO - PROCESS | 1931 | RSS: Main: 358600704 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | __start_tp_report 03:29:51 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 03:29:51 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 03:29:51 INFO - PROCESS | 1931 | |i|pagename|runs| 03:29:51 INFO - PROCESS | 1931 | |0;ai-astar;113;112;130;114;119;164;163;113;112;114 03:29:51 INFO - PROCESS | 1931 | |1;audio-beat-detection;152;137;121;192;135;163;148;108;161;108 03:29:51 INFO - PROCESS | 1931 | |2;audio-dft;185;184;186;183;183;183;183;185;183;181 03:29:51 INFO - PROCESS | 1931 | |3;audio-fft;78;79;75;156;75;82;80;88;135;57 03:29:51 INFO - PROCESS | 1931 | |4;audio-oscillator;82;83;130;70;71;79;133;53;64;126 03:29:51 INFO - PROCESS | 1931 | |5;imaging-gaussian-blur;90;87;89;88;89;86;90;88;88;85 03:29:51 INFO - PROCESS | 1931 | |6;imaging-darkroom;95;98;95;90;130;150;94;95;95;95 03:29:51 INFO - PROCESS | 1931 | |7;imaging-desaturate;84;84;86;86;83;84;82;88;84;85 03:29:51 INFO - PROCESS | 1931 | |8;json-parse-financial;54;53;54;54;54;53;54;55;53;53 03:29:51 INFO - PROCESS | 1931 | |9;json-stringify-tinderbox;66;68;66;70;78;79;75;72;71;71 03:29:51 INFO - PROCESS | 1931 | |10;stanford-crypto-aes;96;99;112;80;86;86;85;84;86;88 03:29:51 INFO - PROCESS | 1931 | |11;stanford-crypto-ccm;139;138;163;124;145;150;150;139;187;134 03:29:51 INFO - PROCESS | 1931 | |12;stanford-crypto-pbkdf2;170;169;172;170;172;168;169;169;172;169 03:29:51 INFO - PROCESS | 1931 | |13;stanford-crypto-sha256-iterative;66;68;68;67;70;82;86;88;86;66 03:29:51 INFO - PROCESS | 1931 | __end_tp_report 03:29:51 INFO - PROCESS | 1931 | __start_cc_report 03:29:51 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,2075 03:29:51 INFO - PROCESS | 1931 | __end_cc_report 03:29:51 INFO - PROCESS | 1931 | __startTimestamp1472207391952__endTimestamp 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | ------- Summary: start ------- 03:29:51 INFO - PROCESS | 1931 | Number of tests: 14 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#0] ai-astar Cycles:10 Average:125.40 Median:116.50 stddev:20.80 (17.9%) stddev-sans-first:21.57 03:29:51 INFO - PROCESS | 1931 | Values: 113.0 112.0 130.0 114.0 119.0 164.0 163.0 113.0 112.0 114.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#1] audio-beat-detection Cycles:10 Average:142.50 Median:150.00 stddev:26.38 (17.6%) stddev-sans-first:27.75 03:29:51 INFO - PROCESS | 1931 | Values: 152.0 137.0 121.0 192.0 135.0 163.0 148.0 108.0 161.0 108.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#2] audio-dft Cycles:10 Average:183.60 Median:183.50 stddev:1.43 (0.8%) stddev-sans-first:1.42 03:29:51 INFO - PROCESS | 1931 | Values: 185.0 184.0 186.0 183.0 183.0 183.0 183.0 185.0 183.0 181.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#3] audio-fft Cycles:10 Average:90.50 Median:78.50 stddev:30.46 (38.8%) stddev-sans-first:31.97 03:29:51 INFO - PROCESS | 1931 | Values: 78.0 79.0 75.0 156.0 75.0 82.0 80.0 88.0 135.0 57.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:70.50 stddev:29.40 (41.7%) stddev-sans-first:31.07 03:29:51 INFO - PROCESS | 1931 | Values: 82.0 83.0 130.0 70.0 71.0 79.0 133.0 53.0 64.0 126.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:88.50 stddev:1.63 (1.8%) stddev-sans-first:1.56 03:29:51 INFO - PROCESS | 1931 | Values: 90.0 87.0 89.0 88.0 89.0 86.0 90.0 88.0 88.0 85.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#6] imaging-darkroom Cycles:10 Average:103.70 Median:95.00 stddev:19.80 (20.8%) stddev-sans-first:20.75 03:29:51 INFO - PROCESS | 1931 | Values: 95.0 98.0 95.0 90.0 130.0 150.0 94.0 95.0 95.0 95.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:84.50 stddev:1.71 (2.0%) stddev-sans-first:1.80 03:29:51 INFO - PROCESS | 1931 | Values: 84.0 84.0 86.0 86.0 83.0 84.0 82.0 88.0 84.0 85.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 03:29:51 INFO - PROCESS | 1931 | Values: 54.0 53.0 54.0 54.0 54.0 53.0 54.0 55.0 53.0 53.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.50 stddev:4.55 (6.4%) stddev-sans-first:4.35 03:29:51 INFO - PROCESS | 1931 | Values: 66.0 68.0 66.0 70.0 78.0 79.0 75.0 72.0 71.0 71.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:86.00 stddev:9.51 (11.1%) stddev-sans-first:9.85 03:29:51 INFO - PROCESS | 1931 | Values: 96.0 99.0 112.0 80.0 86.0 86.0 85.0 84.0 86.0 88.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#11] stanford-crypto-ccm Cycles:10 Average:146.90 Median:147.50 stddev:17.59 (11.9%) stddev-sans-first:18.42 03:29:51 INFO - PROCESS | 1931 | Values: 139.0 138.0 163.0 124.0 145.0 150.0 150.0 139.0 187.0 134.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:170.00 stddev:1.49 (0.9%) stddev-sans-first:1.58 03:29:51 INFO - PROCESS | 1931 | Values: 170.0 169.0 172.0 170.0 172.0 168.0 169.0 169.0 172.0 169.0 03:29:51 INFO - PROCESS | 1931 | 03:29:51 INFO - PROCESS | 1931 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:76.00 stddev:9.48 (12.5%) stddev-sans-first:9.51 03:29:51 INFO - PROCESS | 1931 | Values: 66.0 68.0 68.0 67.0 70.0 82.0 86.0 88.0 86.0 66.0 03:29:51 INFO - PROCESS | 1931 | -------- Summary: end -------- 03:29:51 INFO - PROCESS | 1931 | 03:29:52 INFO - TEST-INFO | 1931: exit 0 03:29:52 INFO - TEST-OK | kraken | took 59880ms 03:29:52 INFO - SUITE-END | took 1177s 03:29:53 INFO - Completed test suite (00:19:37) 03:29:53 INFO - javascript benchmark 03:29:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.8496617652204, 3368.1074515253454, 3387.3056023515182, 3413.573303337083, 3472.8263586820667, 5647.209390685307, 5701.657900525971, 5794.275715535558, 5797.884042319156, 5838.854028649281, 5713.371529131792, 5774.711264436772, 5827.796027139051, 5850.005499065159, 5854.853628659275, 5179.87050323743, 5198.064348417283, 5252.0, 5267.631265811395, 5369.812056578021, 5305.257263983026, 5358.571314294866, 5371.892562148755, 5372.54333381663, 5414.0, 4332.631726303268, 5147.562457191143, 5177.19753438214, 5210.322658054467, 5222.999999999962, 5231.790728370861, 5234.8691282717855, 5240.135377662681, 5273.156294992821, 5290.841274761763, 5239.607029472805, 5260.421353651095, 5271.367435907716, 5275.657082289639, 5277.12927366984, 5317.803494213809, 5400.162974738922, 5676.489115979582, 5709.143628455723, 5714.885702285931, 6265.248170219557, 6292.465140463066, 6315.873682526324, 6333.619982801047, 6335.588186767892, 5488.56091512678, 6274.435300822948, 6280.0, 6292.748290068392, 6298.622082675054, 5724.198612194296, 5794.101914203306, 5809.419058094157, 5889.617174883661, 5894.97052514739, 6225.782097626652, 6258.468030217391, 6283.0, 6297.1813664223355, 6303.401176888188, 5174.844754657366, 6105.550726437353, 6122.448979591858, 6200.503959683215, 6231.501479881598, 6291.402316779899, 6314.368563143741, 6333.334999824952, 6342.5243106767175, 6343.5876668016435, 6291.465225455796, 6298.748050077946, 6302.873942521124, 6303.338149494329, 6306.905396419057, 6109.374301065143, 6179.876402471926, 6184.319724830265, 6274.843128921722, 6331.873362532816, 4677.95322046782, 5007.749612519359, 5045.596352291808, 5046.369203849519, 5061.7975280989085, 4425.137098265807, 4468.039371535136, 4517.051419202005, 4590.334401511829, 4628.629709623222, 5837.0, 5839.182514447981, 5844.707764611752, 5854.882902341929, 5857.179994800731, 5178.81874134411, 6174.783882564088, 6277.089821975788, 6288.5283603728985, 6306.085617585424, 4847.321375007502, 4851.223804191383, 5868.2077919480325, 5869.7945571904775, 5870.882582348329, 5517.834464966058, 5520.0, 5520.696361700145, 5523.392426833045, 5523.585731070106, 5370.543503802064, 6314.55798094129, 6324.873502530016, 6378.330275321025, 6413.999999999906, 6171.1360409543595, 6187.84530386744, 6238.75044998195, 6239.0953311770445, 6268.467180289544, 6347.270063942521, 6377.936220637735, 6384.936150638434, 6391.6804159792755, 6400.295967443484, 6171.13604095418, 6217.347178546188, 6250.468710159687, 6271.0, 6277.748890044347, 6165.229346331708, 6234.75060997555, 6270.059491076375, 6287.71705273273, 6296.496280297473, 5219.739013049409, 6098.634081955097, 6209.627422354673, 6224.719887604978, 6231.657258850807, 6025.096235564701, 6205.689715514297, 6210.254769427697, 6213.254409470893, 6213.658248796179, 5745.195672605755, 5883.911741323969, 5940.970295148497, 5991.071383935497, 6001.669908155093, 5167.974160129175, 6024.608400453956, 6086.239220097488, 6163.229596300463, 6181.81454556364, 6021.307549631849, 6021.849453763516, 6025.307089684742, 6027.819165425044, 6030.849228769316], "value": 5707.963514365076, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.6870112673946, 489.82197814256705, 514.4032921810699, 535.1811728056075, 548.0546058049864, 30233.697663023362, 31159.75360985561, 32126.518102228427, 32192.839035804845, 32221.51667724986, 42224.78887605565, 42938.78530607351, 42962.78518607411, 44756.433524826636, 44833.55166448335, 9851.950740246306, 10013.749656258578, 10016.599336026551, 10017.69946901594, 10044.447555384451, 8919.10808919106, 9778.462184579845, 9894.40633561985, 9895.653652122177, 9928.503574821267, 8917.955410222972, 12264.448099835528, 12531.812022819666, 12542.372881355941, 12551.560695375663, 10322.277440579162, 12245.755084898297, 12312.199707019015, 12340.691482712915, 12370.814437783485, 12038.0, 12138.45376958039, 12153.0, 12155.0, 12163.209391389531, 40189.196216075805, 40491.59508404908, 40505.59494405048, 40534.39198412026, 40571.78284651465, 3483.89548313551, 3614.0784100054316, 3659.79871107089, 3719.512195121973, 4173.499180098377, 31746.206344841565, 31833.0, 32175.0, 32235.516467253008, 32269.45036046033, 3250.593675790526, 3335.1495368681076, 3369.9831500842583, 3439.002689220123, 3440.1571614954246, 488654.0, 491114.0, 491679.0, 491806.54096729646, 492275.0, 54915.0, 55143.0, 55199.0, 55228.723856381264, 55263.447365526634, 63435.68282158559, 63485.68257158777, 63511.0, 63548.68225658842, 63569.682151588946, 13534.59396218115, 16273.59316017109, 16444.0, 16466.01203927768, 16501.0, 12865.549705760253, 12920.289384083777, 12930.741385172243, 12937.482500700055, 12943.288119153536, 11977.700557485958, 14183.71632567343, 15197.6200594986, 16690.666186676197, 17092.914535427244, 5416.187571864252, 5963.522918166536, 6293.1504246927, 6431.967840160769, 6478.6760661967655, 809.4900212865974, 1035.9223058270659, 1053.657561292583, 1078.245228340165, 1108.1245815805585, 27055.594166087525, 30309.0, 30849.766009359824, 31324.843375782973, 32365.838170809468, 892.433304851424, 1000.2198285337448, 1062.718379629399, 1098.8406681031206, 1105.789899919013, 15569.299381527631, 15628.140452275235, 15724.606884827743, 15846.445374412071, 15904.204789760697, 641024.0, 645855.0, 646052.7697361483, 647828.0, 647842.4549667774, 50809.49190508122, 51437.0, 57445.0, 58079.12881306784, 58394.70802645959, 1351.5067000544927, 1383.3982217735318, 1406.268740255082, 1407.9577612671637, 1410.1961881727316, 29580.704192958656, 30112.462703695623, 30906.53640195444, 31585.684143158272, 31762.0, 50177.49822501728, 51881.14653694083, 51941.48058519366, 51986.96026079533, 52018.47981520136, 559733.0, 561354.0, 561707.0, 561775.0, 563189.0, 25705.485890281714, 26138.954441822876, 26204.475910481302, 26210.60684089702, 26421.0, 44295.77852110719, 45762.54237457583, 46623.834404140165, 48737.26623832975, 49427.0, 32761.67238327586, 38115.80942095272, 39283.607163928, 40611.98470038272, 40844.97887552835, 38819.0, 38916.41625375678, 39002.219955600165, 39036.21927561376, 39349.0, 37898.0, 38546.421803672405, 38763.0, 38798.80600596979, 38884.8334549964, 33767.83116084404, 33832.0, 34698.78554250589, 34932.47601286034, 35080.649193507736, 24865.751342486343, 25550.744492554837, 25588.232353029438, 25686.614700779875, 26080.0], "value": 20116.70744991022, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.50528638319537, 366.23274240466225, 370.5849448617549, 380.2091649369313, 402.2156794251211, 14802.114870991878, 15347.84652153478, 19728.506787330323, 20219.797802021974, 20835.0, 20118.497037574067, 20330.0, 20399.490012749688, 21124.260650877222, 21339.786602133972, 5808.709564521778, 6133.17202177707, 6145.047517634774, 6158.907616385758, 6170.0, 4192.706510544263, 5689.9189154060805, 5945.85135371617, 6707.1616047994, 6713.832154196135, 6662.400383965442, 6663.533552651316, 6667.0, 6675.0, 6691.297413771557, 6386.457151142135, 6548.476121910236, 6595.835104122388, 6598.175228096488, 6604.339566043357, 5516.365617953907, 5667.064934285838, 5710.657360558338, 5724.656520608777, 5725.971370143165, 25893.611595826078, 25937.481250375076, 25957.610635840476, 26189.73810261892, 26225.082122125827, 2229.264342766899, 2377.8335516513853, 2449.64480150379, 2685.3957859481657, 2848.430313937221, 14951.626209344744, 15281.61795955099, 15380.615484612863, 15980.680386392323, 16453.83546164535, 1826.6072794349145, 1843.3640394064028, 1888.0937150167997, 1895.4882181810833, 1911.1399870058528, 107920.46039769829, 109190.45404772925, 114312.0, 114425.4278728601, 119043.4047829764, 7329.2670732927345, 8394.874076888851, 9407.529623518796, 9842.852357214648, 10013.949930250303, 12725.872741272655, 13285.402156902963, 13386.0, 14155.150690958575, 14161.716765664834, 8356.289715374262, 8459.788505287417, 8519.488830670056, 8619.439736417113, 8686.174813392718, 6963.4777391694815, 8515.063343032261, 8524.488530688179, 8526.78683032929, 8713.302935765123, 3994.242533285345, 4180.28935081037, 4388.692791504564, 4539.59143677064, 4586.362841366228, 1760.1111438723362, 1832.3128826689992, 1848.8336049755317, 1890.1116475256608, 1891.9243230270636, 821.3142026408016, 884.91150884911, 897.6005677473462, 927.6299798103998, 975.2295686407658, 18057.0, 18619.975901325288, 20544.38366848997, 20564.485887852923, 20788.792112078987, 843.0535279199238, 889.7063968890251, 906.4833045164324, 990.4255531791543, 1002.1832206751474, 9822.624832523452, 10001.949990250003, 10565.260431769702, 11028.44857757109, 11041.668749937515, 565700.0, 582787.0, 583799.0, 608765.0, 611104.0, 117500.0, 120835.0, 121699.0, 126026.36986815007, 131084.3445782765, 952.6944793599506, 958.942463452195, 986.3736527305213, 988.2143695761887, 988.8961659025699, 17982.91008544949, 18026.0, 18147.818521814614, 18150.0, 18307.908460457613, 52342.95314093621, 52714.0, 53753.0, 53961.0, 54718.45281547133, 79842.4031519378, 80900.19099809162, 81245.0, 82031.17968820235, 82526.0, 6509.902351464826, 7843.490173138728, 7996.120426753168, 8017.8396432072195, 8061.959690201512, 10214.846777298199, 11831.0, 12321.753564928473, 12531.310777907303, 12613.684657883627, 10712.250142489951, 11076.507785748465, 11199.72000699989, 11367.943160284145, 11417.429128543707, 10666.520006599876, 10725.946370268099, 11002.394868282321, 11288.9435552825, 11333.320000799979, 11323.660290191307, 11458.885411145782, 11500.94249528747, 11538.538458461568, 11614.941925290319, 7839.05931288249, 8039.598020099089, 8140.145284745254, 8188.631511582113, 8308.833823323379, 10651.733706657396, 11244.425780390588, 11249.783410480393, 11286.717832054264, 11459.4270286487], "value": 9568.01493648202, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.3924003818292, 1532.800735904332, 1550.054466775267, 1753.5002524280583, 1905.9047047647623, 7711.5758633275145, 8471.0, 8731.519766412861, 8745.91254087459, 8885.489466790645, 8648.048714641385, 8707.477551346923, 8723.38936274461, 8726.258268047224, 8746.344024198195, 2371.2174982255815, 2397.5564520563753, 2399.5800734871414, 2402.411409204747, 2412.445137618349, 2170.967435488469, 2219.911203551864, 2406.3984003999, 2407.1214006887535, 2409.795167410772, 2172.5546263016045, 2205.018766648842, 2381.154690085021, 2408.8675122868235, 2421.2736179146273, 2350.2361732436857, 2375.964360534593, 2379.155399833069, 2386.5465561543338, 2392.6650268962358, 2224.89987950541, 2234.3520379090046, 2235.854669446497, 2238.876861772602, 2238.9104435822546, 7806.985091938069, 8497.532635705034, 8619.65521379144, 8808.64765409383, 8817.735467935972, 5191.402988656315, 5371.409144994048, 5383.031054410205, 5389.245505629215, 5389.487998640123, 3629.274145170976, 3641.089727568108, 3702.6667599916136, 3712.3688972874675, 3727.1613886875507, 1116.1573012375686, 1118.457548089176, 1119.6529075986389, 1126.2735535579504, 1141.2809929744292, 11614.535418583246, 11632.302061876315, 11693.181477296592, 11707.765844683145, 11708.297502149899, 3687.8340474678703, 4339.804708788112, 4603.49361570227, 4619.145458090264, 4629.467611224685, 9020.917489901123, 9043.23132533742, 9050.909490905138, 9064.501452420049, 9082.45505269686, 5246.790128394898, 5247.973760131251, 5250.212468129735, 5254.264402983586, 5255.76349064293, 4421.270490369117, 4583.472900616405, 4768.451628062748, 4928.654994150446, 4943.579795717333, 3666.871659491905, 3725.4598083277774, 3765.3034188675183, 3820.7898565578603, 3832.693384529231, 963.7686955130719, 981.7825895889047, 989.3964681544252, 1173.2256710570982, 1182.0838849891231, 772.2432016623741, 802.5786462107324, 934.8364036293622, 938.2306508662831, 944.1266828183902, 5565.833025009256, 5869.765209391662, 5964.403559644001, 5996.580239383201, 6066.726997285133, 418.53542567749776, 423.88978865495193, 452.4977275224498, 453.97503137327766, 454.3593533118287, 3922.37242041272, 4061.045654271244, 4064.6545043671026, 4075.4294398784195, 4127.484064491939, 10465.581376744845, 10724.678259652223, 10802.243842931077, 10856.077233435246, 10866.511007004703, 10108.241881858743, 10611.363318200933, 10621.84067238992, 10777.946110269399, 10780.460976951277, 7984.560849153236, 8217.876731849026, 8223.465474744122, 8226.753197403968, 8228.50628962264, 5922.703864806656, 5995.850103747267, 6033.396660333931, 6123.050927106306, 6172.0, 9695.466749328854, 10000.24998125129, 10153.746156346151, 10198.592056317664, 10203.28576999603, 9942.806863176465, 10133.847992279974, 10255.846162307422, 10285.74285642865, 10297.897021029694, 6922.06552115458, 7161.928380716126, 7315.487915846053, 7507.662155203138, 7635.083789945242, 8102.554359510284, 8431.747047588582, 8450.577471126542, 8476.49141051539, 8493.957530212556, 7420.740274090381, 7474.813129671802, 7548.849023019399, 7588.62056897164, 7683.193264707125, 7183.0, 7306.59813710251, 7332.23011583776, 7332.230115837973, 7386.002889609834, 6905.240423553305, 7066.364027237573, 7069.434445244471, 7080.752173673896, 7095.148582170173, 4824.18278802635, 4973.055119527278, 4995.600351971906, 4999.850004499871, 5053.974730126326, 4868.756562171948, 4987.1521841287795, 5048.167052436454, 5129.563987061141, 5205.0], "value": 4453.716627138247, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [240.45896732352207, 288.92199106241316, 300.98194108353493, 304.5827216713102, 305.79053348456296, 4279.465066866638, 5168.6381953263235, 5190.662606930547, 5217.217417387394, 5218.712970786605, 4877.292792545079, 4891.3641226640575, 5035.773390197441, 5077.9844031193725, 5132.331992102563, 2929.2570920302437, 3883.475730776337, 3898.8498392973997, 3920.157525962793, 3938.1532970411354, 3405.625381208065, 3920.2159568086354, 3928.8821335359985, 3942.684585233189, 3964.3458829293277, 3017.803842750225, 3256.4436917020143, 3598.820058997053, 3727.155058246172, 3849.8845034648866, 3848.8845334639727, 3867.903302417434, 3897.8635747748694, 3901.51231096113, 3917.98041009796, 2681.1554360376417, 3116.3299890523645, 3416.180116771982, 3423.0, 3456.7753096048928, 4277.272863613193, 4595.494495605481, 4814.277858321245, 4886.877828054292, 5022.936009034486, 3704.740388268002, 3893.785841778701, 4534.659900507442, 4536.977315113436, 4537.251353526664, 5438.510534051954, 5470.617056806025, 5470.9452905470835, 5475.972620136914, 5491.176323551499, 3043.432632194422, 3199.9520007199912, 3821.560520540118, 3827.5215598050245, 3830.4062870255157, 4915.5330243626795, 4952.331435256267, 5226.3989641191365, 5277.788888444457, 5663.688497132697, 3274.3287626036604, 3701.056230661164, 3836.4245363195187, 3843.135294558758, 3854.7879866607605, 3724.776513409204, 3793.5447746270625, 3929.862454814068, 3945.743526670757, 3945.8421663133727, 4115.34154535273, 4434.423524941737, 4486.214912390318, 4526.637868970474, 4528.207563676344, 5385.165299378603, 5433.347998240237, 5434.782608695607, 5452.972735136378, 5456.317960254968, 1141.646089712192, 1293.1788314420223, 1299.4152631315947, 1315.9210447373189, 1319.7492476429447, 694.8054544727485, 776.3013288040809, 859.2524503681836, 883.2271762208068, 886.189136939693, 4189.287821070365, 4591.862244132682, 5125.974370128126, 5172.663776854492, 5195.506426889439, 5614.129809879475, 5650.208970744099, 5650.915236271459, 5658.443927919855, 5668.319801623831, 4162.83348666056, 4963.751812409365, 5051.444341122474, 5081.695098294114, 5145.716985565755, 2934.2224310557717, 2937.9412411751646, 2944.5435957426635, 2945.558166275033, 2968.5992391026934, 4924.778384972685, 5524.171374293807, 5704.686242256633, 5740.512056475246, 5820.729915333023, 4584.426946631671, 4895.461499235082, 5467.589930755209, 5838.0, 5896.970515147483, 5876.676782776988, 5905.43898329658, 5910.822675319747, 5934.406559344117, 5943.889919078665, 5243.977424402205, 5449.2371068049715, 5561.490918894098, 5767.36655906776, 5782.392848750821, 5670.716464176857, 5674.574406919415, 5680.857978550569, 5699.7435115419075, 5707.543396528351, 5899.852503687443, 5926.525877929842, 5929.636183677692, 5930.436608522184, 5962.0, 3650.872219472306, 3773.2076263984873, 3813.8093095345675, 3845.3270677630744, 3856.5757766646207, 5148.099082660594, 5356.946430535801, 5707.315122185364, 5714.971425142848, 5721.0, 5625.381208067028, 5644.463775941279, 5644.548436125018, 5654.151877218285, 5663.886722265449, 4832.685875418087, 5005.77474013663, 5086.981284784978, 5565.777368905198, 5594.174941654438, 5154.8969020620125, 5442.319710036246, 5456.972715136399, 5461.876283406003, 5471.343438787371, 5113.667611605234, 5230.959925628932, 5419.458054194707, 5492.3522943117705, 5541.833744987657, 4649.0, 4905.877353066202, 5369.5167434931045, 5400.378956419905, 5410.567154627542], "value": 3978.456823116603, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.802329359426, 733.842223921857, 770.0489894979703, 803.4375936844203, 815.1970309245394, 12950.611481655542, 16443.671126577476, 16460.0946947918, 16461.34154633813, 16494.752578711297, 14384.640383990405, 14491.913106517028, 14492.42030318786, 14495.420183192686, 14932.0, 601.515779797263, 677.0859339891157, 697.8394969157072, 698.4447364345358, 841.3984001438985, 690.3510699942063, 833.6040380819123, 862.2627653356384, 865.7056600755742, 866.2377108144855, 898.2768871058795, 906.6554709210491, 912.6303846941966, 912.6714382822179, 915.2403505090792, 906.9591868365874, 907.4101833808012, 908.3006085314337, 908.418612088264, 908.6819613135416, 528.5586535243077, 655.0632595388593, 671.8924972004505, 673.2358772792867, 674.2616834566192, 14806.925965370212, 14905.403783848742, 14922.477713280092, 14926.776098358534, 14927.104373737611, 13637.454501819915, 13896.0, 14039.64900877476, 14069.226192559405, 14447.060941038799, 11363.034142097931, 12387.75224495505, 12745.745085098339, 12765.489380424771, 12951.222926624432, 4564.4522657281, 4571.337156112379, 4681.695689780154, 4710.811567537295, 4717.669763116583, 112447.0, 113705.0, 115624.0, 115994.42002790017, 117043.0, 10805.459727013618, 10828.512716927757, 10894.836577451344, 10973.890261097287, 11271.774564508663, 21198.894005530183, 21424.678629820566, 21426.25008124739, 21428.78571214268, 21476.89261553682, 7987.121416644164, 8095.5142691438705, 8256.298214651822, 8298.419110662317, 8555.529445880537, 4351.630111440563, 4386.366805442105, 4392.253316936129, 4409.690554151798, 4555.703879247838, 3548.538689970287, 3681.7054635629083, 3708.610595887447, 3711.684506816951, 3715.7213209009433, 642.8167972127922, 646.1728986896779, 651.4365074210868, 659.960402375859, 665.6838001949036, 520.5783315514445, 565.3300838506383, 587.3920492290545, 670.165643773505, 682.8497730499282, 9434.764130896783, 9467.881471559893, 10064.597416103421, 10420.06219440254, 10549.261551691308, 665.4210836572213, 667.4593579200863, 675.3651567526509, 677.2617846547287, 677.3598948993155, 4352.888848908277, 4671.743054131987, 4676.158291507493, 4699.224627936353, 4745.833895813699, 126919.0, 140572.29713851569, 143249.0, 149277.0, 152757.0, 109782.0, 132637.0, 134219.3289033568, 134686.65313346742, 139749.0, 89178.0, 98390.5080474593, 104439.0, 105808.47095764472, 108328.45835770771, 9550.37922535033, 9715.805683886425, 9959.0, 9997.55011024491, 10005.549750261402, 61468.692656536434, 61578.69210653918, 62483.75032499416, 63268.36731632625, 64567.677161613894, 75167.0, 89019.55490222508, 93188.0, 95425.0, 97607.0, 10985.285956412808, 13209.735805284034, 13411.664708382368, 13597.660058498617, 13649.471963854654, 14786.556403307917, 15155.393784248947, 15168.620784480476, 15277.847221528087, 15396.769048464057, 13548.458061677422, 13799.051745197798, 13812.37844296989, 13966.58100256994, 14216.0, 11365.943170284427, 13782.862171378158, 13963.0, 14039.806616437718, 14048.578542643738, 13372.732545348845, 13453.394597243343, 13743.587692369245, 14107.365168567234, 14209.934254931128, 11205.60780372683, 11216.495257713259, 11463.369514676773, 11465.19743617972, 12239.755204895675, 10543.367397956146, 11301.71745706364, 11463.254888432226, 11574.247673901169, 11588.072954163477], "value": 7893.406112314953, "unit": "score"}], "name": "dromaeo_css", "value": 7318.718657434471, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 130.0, 114.0, 119.0, 164.0, 163.0, 113.0, 112.0, 114.0], "value": 125.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 137.0, 121.0, 192.0, 135.0, 163.0, 148.0, 108.0, 161.0, 108.0], "value": 142.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 186.0, 183.0, 183.0, 183.0, 183.0, 185.0, 183.0, 181.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 75.0, 156.0, 75.0, 82.0, 80.0, 88.0, 135.0, 57.0], "value": 90.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 130.0, 70.0, 71.0, 79.0, 133.0, 53.0, 64.0, 126.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 88.0, 89.0, 86.0, 90.0, 88.0, 88.0, 85.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 95.0, 90.0, 130.0, 150.0, 94.0, 95.0, 95.0, 95.0], "value": 103.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 86.0, 83.0, 84.0, 82.0, 88.0, 84.0, 85.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 54.0, 54.0, 53.0, 54.0, 55.0, 53.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 70.0, 78.0, 79.0, 75.0, 72.0, 71.0, 71.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 99.0, 112.0, 80.0, 86.0, 86.0, 85.0, 84.0, 86.0, 88.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 163.0, 124.0, 145.0, 150.0, 150.0, 139.0, 187.0, 134.0], "value": 146.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 172.0, 170.0, 172.0, 168.0, 169.0, 169.0, 172.0, 169.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 67.0, 70.0, 82.0, 86.0, 88.0, 86.0, 66.0], "value": 74.7, "unit": "score"}], "name": "kraken", "value": 1514.5000000000002, "alertThreshold": 2.0}]} 03:29:53 INFO - Return code: 0 03:29:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:29:53 INFO - # TBPL SUCCESS # 03:29:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:29:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:29:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:29:53 INFO - 2016-08-26 03:29:53.286 screenresolution[1935:17908] starting screenresolution argv=screenresolution get 03:29:53 INFO - 2016-08-26 03:29:53.288 screenresolution[1935:17908] Display 0: 1600x1200x32@60 03:29:53 INFO - 2016-08-26 03:29:53.294 screenresolution[1936:17911] starting screenresolution argv=screenresolution list 03:29:53 INFO - Available Modes on Display 0 03:29:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:29:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:29:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:29:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:29:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:29:53 INFO - Intel Iris: 03:29:53 INFO - Chipset Model: Intel Iris 03:29:53 INFO - Type: GPU 03:29:53 INFO - Bus: Built-In 03:29:53 INFO - VRAM (Dynamic, Max): 1536 MB 03:29:53 INFO - Vendor: Intel (0x8086) 03:29:53 INFO - Device ID: 0x0a2e 03:29:53 INFO - Revision ID: 0x0009 03:29:53 INFO - Displays: 03:29:53 INFO - Display: 03:29:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:29:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:29:53 INFO - Display Serial Number: 1600x1200 60 03:29:53 INFO - Main Display: Yes 03:29:53 INFO - Mirror: Off 03:29:53 INFO - Online: Yes 03:29:53 INFO - Rotation: Supported 03:29:53 INFO - Return code: 0 03:29:53 INFO - Running post-action listener: _resource_record_post_action 03:29:53 INFO - [mozharness: 2016-08-26 10:29:53.530295Z] Finished run-tests step (success) 03:29:53 INFO - Running post-run listener: _resource_record_post_run 03:29:53 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 700416; Write bytes: 709253632; Read time: 11; Write time: 1888 03:29:53 INFO - TinderboxPrint: CPU usage
53.4% 03:29:53 INFO - TinderboxPrint: I/O read bytes / time
700,416 / 11 03:29:53 INFO - TinderboxPrint: I/O write bytes / time
709,253,632 / 1,888 03:29:53 INFO - TinderboxPrint: CPU idle
2,261.8 (46.6%) 03:29:53 INFO - TinderboxPrint: CPU system
971.6 (20.0%) 03:29:53 INFO - TinderboxPrint: CPU user
1,620.3 (33.4%) 03:29:53 INFO - TinderboxPrint: Swap in / out
407,769,088 / 0 03:29:53 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389028352; Write bytes: 383924224; Read time: 29143; Write time: 708 03:29:53 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 700416; Write bytes: 299115008; Read time: 11; Write time: 1129 03:29:54 INFO - Running post-run listener: _upload_blobber_files 03:29:54 INFO - Blob upload gear active. 03:29:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:29:54 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 03:29:54 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'] 03:29:54 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 03:29:54 INFO - (blobuploader) - INFO - Open directory for files ... 03:29:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:29:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:29:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:55 INFO - (blobuploader) - INFO - Done attempting. 03:29:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:29:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:29:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:56 INFO - (blobuploader) - INFO - Done attempting. 03:29:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:29:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:29:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:58 INFO - (blobuploader) - INFO - Done attempting. 03:29:58 INFO - (blobuploader) - INFO - Iteration through files over. 03:29:58 INFO - Return code: 0 03:29:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:29:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:29:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8e5e5f8878a1f6f655d0658ce7e0039701c502548b41a54b81887e638cdd00fdba39b5d97354017d0adf9404bd4eec9a44cc089f94de500c8e0631fc83323e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cb10c000600ad1b7ad665d098281f07fae206f1c192119f469bf60f157541dc0098caf5bfdaa10ce06fe2f8394c6b423c9e4038db187238c6d93c599e19c0f67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2c7093cf4c92bcd91b076e3b9b7557f348e830c56d0734bdd3345c7b10f48de8aab7bca2eec7fc756ec331501b075c6ae75a2d8093ddce5fb4edfe62c0f7dab7"} 03:29:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:29:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:29:58 INFO - Contents: 03:29:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8e5e5f8878a1f6f655d0658ce7e0039701c502548b41a54b81887e638cdd00fdba39b5d97354017d0adf9404bd4eec9a44cc089f94de500c8e0631fc83323e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cb10c000600ad1b7ad665d098281f07fae206f1c192119f469bf60f157541dc0098caf5bfdaa10ce06fe2f8394c6b423c9e4038db187238c6d93c599e19c0f67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2c7093cf4c92bcd91b076e3b9b7557f348e830c56d0734bdd3345c7b10f48de8aab7bca2eec7fc756ec331501b075c6ae75a2d8093ddce5fb4edfe62c0f7dab7"} 03:29:58 INFO - Running post-run listener: copy_logs_to_upload_dir 03:29:58 INFO - Copying logs to upload dir... 03:29:58 INFO - mkdir: /builds/slave/test/build/upload/logs 03:29:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.989797 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-08-26 03:29:58.634479) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 03:29:58.638427) ========= 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.GTUirmZjCf/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.wE0mq78cmZ/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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/8e5e5f8878a1f6f655d0658ce7e0039701c502548b41a54b81887e638cdd00fdba39b5d97354017d0adf9404bd4eec9a44cc089f94de500c8e0631fc83323e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cb10c000600ad1b7ad665d098281f07fae206f1c192119f469bf60f157541dc0098caf5bfdaa10ce06fe2f8394c6b423c9e4038db187238c6d93c599e19c0f67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2c7093cf4c92bcd91b076e3b9b7557f348e830c56d0734bdd3345c7b10f48de8aab7bca2eec7fc756ec331501b075c6ae75a2d8093ddce5fb4edfe62c0f7dab7"} build_url:https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010865 build_url: 'https://queue.taskcluster.net/v1/task/NZ9jTLkdQzKN0JyBZsp_fQ/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/8e5e5f8878a1f6f655d0658ce7e0039701c502548b41a54b81887e638cdd00fdba39b5d97354017d0adf9404bd4eec9a44cc089f94de500c8e0631fc83323e65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cb10c000600ad1b7ad665d098281f07fae206f1c192119f469bf60f157541dc0098caf5bfdaa10ce06fe2f8394c6b423c9e4038db187238c6d93c599e19c0f67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2c7093cf4c92bcd91b076e3b9b7557f348e830c56d0734bdd3345c7b10f48de8aab7bca2eec7fc756ec331501b075c6ae75a2d8093ddce5fb4edfe62c0f7dab7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 03:29:58.671113) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:29:58.671483) ========= 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.GTUirmZjCf/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.wE0mq78cmZ/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.005176 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:29:58.720575) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 03:29:58.720932) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 03:29:58.721315) ========= ========= Total master_lag: 0.19 =========