builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0314 starttime: 1472611263.72 results: success (0) buildid: 20160830173020 builduid: dc80a276e1a74807bd5dea0659f6ecd0 revision: b0cad82d939e46950bcc1fc9a6bd6ea7b6c56310 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:03.721462) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:03.721961) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:03.722290) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:03.750581) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:03.750961) ========= 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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-30 19:41:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-08-30 19:41:03 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096960 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:03.870296) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:03.870649) ========= 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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.019870 ========= master_lag: 0.26 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:04.153593) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 19:41:04.153947) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev b0cad82d939e46950bcc1fc9a6bd6ea7b6c56310 --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 b0cad82d939e46950bcc1fc9a6bd6ea7b6c56310 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Vo1l4tdOcU/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.wzpyP687mJ/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-30 19:41:04,238 truncating revision to first 12 chars 2016-08-30 19:41:04,238 Setting DEBUG logging. 2016-08-30 19:41:04,238 attempt 1/10 2016-08-30 19:41:04,238 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/b0cad82d939e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 19:41:04,998 unpacking tar archive at: autoland-b0cad82d939e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.014522 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 19:41:05.190806) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:05.191132) ========= script_repo_revision: b0cad82d939e46950bcc1fc9a6bd6ea7b6c56310 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:05.191543) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:05.191815) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 19:41:05.210168) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-08-30 19:41:05.210492) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 19:41:05 INFO - MultiFileLogger online at 20160830 19:41:05 in /builds/slave/test 19:41:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 19:41:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:41:05 INFO - {'append_to_log': False, 19:41:05 INFO - 'base_work_dir': '/builds/slave/test', 19:41:05 INFO - 'blob_upload_branch': 'Autoland', 19:41:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:41:05 INFO - 'branch': 'Autoland', 19:41:05 INFO - 'buildbot_json_path': 'buildprops.json', 19:41:05 INFO - 'config_files': ('talos/mac_config.py',), 19:41:05 INFO - 'default_actions': ('clobber', 19:41:05 INFO - 'read-buildbot-config', 19:41:05 INFO - 'download-and-extract', 19:41:05 INFO - 'populate-webroot', 19:41:05 INFO - 'create-virtualenv', 19:41:05 INFO - 'install', 19:41:05 INFO - 'run-tests'), 19:41:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:41:05 INFO - 'download_minidump_stackwalk': True, 19:41:05 INFO - 'download_symbols': 'ondemand', 19:41:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:41:05 INFO - 'tooltool.py': '/tools/tooltool.py', 19:41:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:41:05 INFO - '/tools/misc-python/virtualenv.py')}, 19:41:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:41:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:41:05 INFO - 'installer_path': 'installer.exe', 19:41:05 INFO - 'log_level': 'info', 19:41:05 INFO - 'log_name': 'talos', 19:41:05 INFO - 'log_to_console': True, 19:41:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:41:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:41:05 INFO - 'opt_config_files': (), 19:41:05 INFO - 'pip_index': False, 19:41:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:41:05 INFO - 'cmd': ('bash', 19:41:05 INFO - '-c', 19:41:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:41:05 INFO - 'enabled': True, 19:41:05 INFO - 'halt_on_failure': False, 19:41:05 INFO - 'name': 'check_screen_resolution'},), 19:41:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:41:05 INFO - 'cmd': ('bash', 19:41:05 INFO - '-c', 19:41:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:41:05 INFO - 'enabled': True, 19:41:05 INFO - 'halt_on_failure': False, 19:41:05 INFO - 'name': 'check_screen_resolution'},), 19:41:05 INFO - 'run_cmd_checks_enabled': True, 19:41:05 INFO - 'sps_profile': False, 19:41:05 INFO - 'sps_profile_interval': 0, 19:41:05 INFO - 'suite': 'dromaeojs-e10s', 19:41:05 INFO - 'system_bits': '32', 19:41:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:41:05 INFO - 'title': 't-yosemite-r7-0314', 19:41:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:41:05 INFO - 'use_talos_json': True, 19:41:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:41:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:41:05 INFO - 'work_dir': 'build'} 19:41:05 INFO - [mozharness: 2016-08-31 02:41:05.397560Z] Running clobber step. 19:41:05 INFO - Running pre-action listener: _resource_record_pre_action 19:41:05 INFO - Running main action method: clobber 19:41:05 INFO - rmtree: /builds/slave/test/build 19:41:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:41:05 INFO - Running post-action listener: _resource_record_post_action 19:41:05 INFO - [mozharness: 2016-08-31 02:41:05.594559Z] Finished clobber step (success) 19:41:05 INFO - [mozharness: 2016-08-31 02:41:05.594725Z] Running read-buildbot-config step. 19:41:05 INFO - Running pre-action listener: _resource_record_pre_action 19:41:05 INFO - Running main action method: read_buildbot_config 19:41:05 INFO - Using buildbot properties: 19:41:05 INFO - { 19:41:05 INFO - "project": "", 19:41:05 INFO - "product": "firefox", 19:41:05 INFO - "script_repo_revision": "production", 19:41:05 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 19:41:05 INFO - "repository": "", 19:41:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 19:41:05 INFO - "buildid": "20160830173020", 19:41:05 INFO - "pgo_build": "False", 19:41:05 INFO - "basedir": "/builds/slave/test", 19:41:05 INFO - "buildnumber": 164, 19:41:05 INFO - "slavename": "t-yosemite-r7-0314", 19:41:05 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 19:41:05 INFO - "platform": "yosemite_r7", 19:41:05 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 19:41:05 INFO - "branch": "autoland", 19:41:05 INFO - "repo_path": "integration/autoland", 19:41:05 INFO - "revision": "b0cad82d939e46950bcc1fc9a6bd6ea7b6c56310", 19:41:05 INFO - "stage_platform": "macosx64", 19:41:05 INFO - "builduid": "dc80a276e1a74807bd5dea0659f6ecd0", 19:41:05 INFO - "slavebuilddir": "test" 19:41:05 INFO - } 19:41:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:41:05 INFO - Running post-action listener: _resource_record_post_action 19:41:05 INFO - [mozharness: 2016-08-31 02:41:05.598156Z] Finished read-buildbot-config step (success) 19:41:05 INFO - [mozharness: 2016-08-31 02:41:05.598291Z] Running download-and-extract step. 19:41:05 INFO - Running pre-action listener: _resource_record_pre_action 19:41:05 INFO - Running main action method: download_and_extract 19:41:05 INFO - mkdir: /builds/slave/test/build/tests 19:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:05 INFO - trying https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:41:05 INFO - Downloading https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/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 19:41:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/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 19:41:06 INFO - Downloaded 1199 bytes. 19:41:06 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:41:06 INFO - Using the following test package requirements: 19:41:06 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:41:06 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:41:06 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:41:06 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:41:06 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:41:06 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:41:06 INFO - u'jsshell-mac.zip'], 19:41:06 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:41:06 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:41:06 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:41:06 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:41:06 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:41:06 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:41:06 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:41:06 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:41:06 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:41:06 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:41:06 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:41:06 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:41:06 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')]} 19:41:06 INFO - trying https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:41:06 INFO - Downloading https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/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 19:41:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/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 19:41:07 INFO - Downloaded 16380358 bytes. 19:41:07 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:41:14 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 19:41: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')]} 19:41:14 INFO - trying https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:41:14 INFO - Downloading https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/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 19:41:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/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 19:41:15 INFO - Downloaded 16380358 bytes. 19:41:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:41: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')]} 19:41:22 INFO - trying https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:41:22 INFO - Downloading https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/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 19:41:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/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 19:41:23 INFO - Downloaded 14413998 bytes. 19:41:23 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:41: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')]} 19:41:23 INFO - trying https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:41:23 INFO - Downloading https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:41:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/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 19:41:26 INFO - Downloaded 111569904 bytes. 19:41:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:41:26 INFO - mkdir: /builds/slave/test/properties 19:41:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:41:26 INFO - Writing to file /builds/slave/test/properties/build_url 19:41:26 INFO - Contents: 19:41:26 INFO - build_url:https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:41:27 INFO - Running post-action listener: _resource_record_post_action 19:41:27 INFO - Running post-action listener: set_extra_try_arguments 19:41:27 INFO - [mozharness: 2016-08-31 02:41:27.417167Z] Finished download-and-extract step (success) 19:41:27 INFO - [mozharness: 2016-08-31 02:41:27.417321Z] Running populate-webroot step. 19:41:27 INFO - Running pre-action listener: _resource_record_pre_action 19:41:27 INFO - Running main action method: populate_webroot 19:41:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:41:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:41:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:41:27 INFO - '--tppagecycles', 19:41:27 INFO - '7'], 19:41:27 INFO - 'tests': ['tsvgm']}}, 19:41:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:41:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:41:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:41:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:41:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:41:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:41:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:27 INFO - 'talos_options': ['--disable-e10s'], 19:41:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:41:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:41:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:27 INFO - 'talos_options': ['--disable-e10s'], 19:41:27 INFO - 'tests': ['damp', 'tps']}, 19:41:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:27 INFO - 'tests': ['damp', 'tps']}, 19:41:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:41:27 INFO - 'tests': ['dromaeo_dom']}, 19:41:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:41:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:41:27 INFO - 'tests': ['basic_compositor_video']}, 19:41:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:41:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:41:27 INFO - 'tests': ['a11yr', 19:41:27 INFO - 'ts_paint', 19:41:27 INFO - 'tpaint', 19:41:27 INFO - 'sessionrestore', 19:41:27 INFO - 'sessionrestore_no_auto_restore', 19:41:27 INFO - 'tabpaint']}, 19:41:27 INFO - 'other-e10s': {'tests': ['a11yr', 19:41:27 INFO - 'ts_paint', 19:41:27 INFO - 'tpaint', 19:41:27 INFO - 'sessionrestore', 19:41:27 INFO - 'sessionrestore_no_auto_restore', 19:41:27 INFO - 'tabpaint']}, 19:41:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:41:27 INFO - 'ts_paint', 19:41:27 INFO - 'tpaint', 19:41:27 INFO - 'sessionrestore', 19:41:27 INFO - 'sessionrestore_no_auto_restore', 19:41:27 INFO - 'tabpaint']}, 19:41:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:41:27 INFO - 'ts_paint', 19:41:27 INFO - 'tpaint', 19:41:27 INFO - 'sessionrestore', 19:41:27 INFO - 'sessionrestore_no_auto_restore', 19:41:27 INFO - 'tabpaint']}, 19:41:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:41:27 INFO - 'tests': ['a11yr', 19:41:27 INFO - 'ts_paint', 19:41:27 INFO - 'tpaint', 19:41:27 INFO - 'sessionrestore', 19:41:27 INFO - 'sessionrestore_no_auto_restore', 19:41:27 INFO - 'tabpaint']}, 19:41:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:41:27 INFO - 'tests': ['a11yr', 19:41:27 INFO - 'ts_paint', 19:41:27 INFO - 'tpaint', 19:41:27 INFO - 'sessionrestore', 19:41:27 INFO - 'sessionrestore_no_auto_restore', 19:41:27 INFO - 'tabpaint']}, 19:41:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:41:27 INFO - 'tests': ['tsvgx', 19:41:27 INFO - 'tsvgr_opacity', 19:41:27 INFO - 'tart', 19:41:27 INFO - 'tscrollx', 19:41:27 INFO - 'cart']}, 19:41:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:41:27 INFO - 'tsvgr_opacity', 19:41:27 INFO - 'tart', 19:41:27 INFO - 'tscrollx', 19:41:27 INFO - 'cart']}, 19:41:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:27 INFO - 'talos_options': ['--disable-e10s'], 19:41:27 INFO - 'tests': ['tp5o']}, 19:41:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:27 INFO - 'tests': ['tp5o']}, 19:41:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:27 INFO - 'talos_options': ['--disable-e10s', 19:41:27 INFO - '--xperf_path', 19:41:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:41:27 INFO - 'tests': ['tp5n']}, 19:41:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:41:27 INFO - 'talos_options': ['--xperf_path', 19:41:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:41:27 INFO - 'tests': ['tp5n']}}, 19:41:27 INFO - 'talos.zip': {'path': '', 19:41:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:41:27 INFO - Running post-action listener: _resource_record_post_action 19:41:27 INFO - [mozharness: 2016-08-31 02:41:27.435766Z] Finished populate-webroot step (success) 19:41:27 INFO - [mozharness: 2016-08-31 02:41:27.435899Z] Running create-virtualenv step. 19:41:27 INFO - Running pre-action listener: _resource_record_pre_action 19:41:27 INFO - Running main action method: create_virtualenv 19:41:27 INFO - Creating virtualenv /builds/slave/test/build/venv 19:41:27 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 19:41:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:41:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:41:27 INFO - Using real prefix '/tools/python27' 19:41:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:41:28 INFO - Installing distribute.............................................................................................................................................................................................done. 19:41:31 INFO - Installing pip.................done. 19:41:31 INFO - Return code: 0 19:41:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:31 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 0x1091caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa322488d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10912ed30>, '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 0x108e93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109126b20>, '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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 19:41:31 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 19:41:31 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 19:41:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vo1l4tdOcU/Render', 19:41:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:31 INFO - 'HOME': '/Users/cltbld', 19:41:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:31 INFO - 'LOGNAME': 'cltbld', 19:41:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:31 INFO - 'MOZ_NO_REMOTE': '1', 19:41:31 INFO - 'NO_EM_RESTART': '1', 19:41:31 INFO - 'PAGER': '/bin/cat', 19:41:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:31 INFO - 'PWD': '/builds/slave/test', 19:41:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:31 INFO - 'SHELL': '/bin/bash', 19:41:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wzpyP687mJ/Listeners', 19:41:31 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 19:41:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:31 INFO - 'USER': 'cltbld', 19:41:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:31 INFO - 'XPC_FLAGS': '0x0', 19:41:31 INFO - 'XPC_SERVICE_NAME': '0', 19:41:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:31 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 19:41:31 INFO - Cleaning up... 19:41:31 INFO - Return code: 0 19:41:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:31 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 0x1091caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa322488d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10912ed30>, '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 0x108e93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109126b20>, '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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 19:41:31 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 19:41:31 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 19:41:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vo1l4tdOcU/Render', 19:41:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:31 INFO - 'HOME': '/Users/cltbld', 19:41:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:31 INFO - 'LOGNAME': 'cltbld', 19:41:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:31 INFO - 'MOZ_NO_REMOTE': '1', 19:41:31 INFO - 'NO_EM_RESTART': '1', 19:41:31 INFO - 'PAGER': '/bin/cat', 19:41:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:31 INFO - 'PWD': '/builds/slave/test', 19:41:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:31 INFO - 'SHELL': '/bin/bash', 19:41:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wzpyP687mJ/Listeners', 19:41:31 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 19:41:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:31 INFO - 'USER': 'cltbld', 19:41:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:31 INFO - 'XPC_FLAGS': '0x0', 19:41:31 INFO - 'XPC_SERVICE_NAME': '0', 19:41:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:31 INFO - Downloading/unpacking psutil>=3.1.1 19:41:31 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 19:41:31 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 19:41:31 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 19:41:31 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 19:41:31 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 19:41:31 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 19:41:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:41:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:41:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:41:34 INFO - Installing collected packages: psutil 19:41:34 INFO - Running setup.py install for psutil 19:41:34 INFO - building 'psutil._psutil_osx' extension 19:41:34 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 19:41:35 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 19:41:35 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 19:41:35 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 19:41:35 INFO - building 'psutil._psutil_posix' extension 19:41:35 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 19:41:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:41:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:41:35 INFO - ^ 19:41:35 INFO - 1 warning generated. 19:41:35 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 19:41:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:41:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:41:35 INFO - Successfully installed psutil 19:41:35 INFO - Cleaning up... 19:41:35 INFO - Return code: 0 19:41:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:35 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 0x1091caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa322488d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10912ed30>, '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 0x108e93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109126b20>, '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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 19:41:35 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 19:41:35 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 19:41:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vo1l4tdOcU/Render', 19:41:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:35 INFO - 'HOME': '/Users/cltbld', 19:41:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:35 INFO - 'LOGNAME': 'cltbld', 19:41:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:35 INFO - 'MOZ_NO_REMOTE': '1', 19:41:35 INFO - 'NO_EM_RESTART': '1', 19:41:35 INFO - 'PAGER': '/bin/cat', 19:41:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:35 INFO - 'PWD': '/builds/slave/test', 19:41:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:35 INFO - 'SHELL': '/bin/bash', 19:41:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wzpyP687mJ/Listeners', 19:41:35 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 19:41:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:35 INFO - 'USER': 'cltbld', 19:41:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:35 INFO - 'XPC_FLAGS': '0x0', 19:41:35 INFO - 'XPC_SERVICE_NAME': '0', 19:41:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:41: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 19:41: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 19:41: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 19:41: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 19:41: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 19:41: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 19:41:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:41:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:41:38 INFO - Installing collected packages: mozsystemmonitor 19:41:38 INFO - Running setup.py install for mozsystemmonitor 19:41:38 INFO - Successfully installed mozsystemmonitor 19:41:38 INFO - Cleaning up... 19:41:38 INFO - Return code: 0 19:41:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:41: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')]} 19:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41: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')]} 19:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1091caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa322488d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10912ed30>, '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 0x108e93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109126b20>, '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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 19:41:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:41:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:41:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vo1l4tdOcU/Render', 19:41:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:38 INFO - 'HOME': '/Users/cltbld', 19:41:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:38 INFO - 'LOGNAME': 'cltbld', 19:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:38 INFO - 'MOZ_NO_REMOTE': '1', 19:41:38 INFO - 'NO_EM_RESTART': '1', 19:41:38 INFO - 'PAGER': '/bin/cat', 19:41:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:38 INFO - 'PWD': '/builds/slave/test', 19:41:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:38 INFO - 'SHELL': '/bin/bash', 19:41:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wzpyP687mJ/Listeners', 19:41:38 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 19:41:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:38 INFO - 'USER': 'cltbld', 19:41:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:38 INFO - 'XPC_FLAGS': '0x0', 19:41:38 INFO - 'XPC_SERVICE_NAME': '0', 19:41:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:38 INFO - Downloading/unpacking jsonschema==2.5.1 19:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:41:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:41 INFO - Downloading functools32-3.2.3-2.tar.gz 19:41:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:41:41 INFO - warning: no files found matching '*.txt' 19:41:41 INFO - no previously-included directories found matching 'build' 19:41:41 INFO - no previously-included directories found matching 'dist' 19:41:41 INFO - no previously-included directories found matching '.git*' 19:41:41 INFO - Installing collected packages: jsonschema, functools32 19:41:41 INFO - Running setup.py install for functools32 19:41:42 INFO - warning: no files found matching '*.txt' 19:41:42 INFO - no previously-included directories found matching 'build' 19:41:42 INFO - no previously-included directories found matching 'dist' 19:41:42 INFO - no previously-included directories found matching '.git*' 19:41:42 INFO - Successfully installed jsonschema functools32 19:41:42 INFO - Cleaning up... 19:41:42 INFO - Return code: 0 19:41:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1091caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa322488d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10912ed30>, '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 0x108e93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109126b20>, '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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 19:41:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vo1l4tdOcU/Render', 19:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:42 INFO - 'HOME': '/Users/cltbld', 19:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:42 INFO - 'LOGNAME': 'cltbld', 19:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:42 INFO - 'MOZ_NO_REMOTE': '1', 19:41:42 INFO - 'NO_EM_RESTART': '1', 19:41:42 INFO - 'PAGER': '/bin/cat', 19:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:42 INFO - 'PWD': '/builds/slave/test', 19:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:42 INFO - 'SHELL': '/bin/bash', 19:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wzpyP687mJ/Listeners', 19:41:42 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 19:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:42 INFO - 'USER': 'cltbld', 19:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:42 INFO - 'XPC_FLAGS': '0x0', 19:41:42 INFO - 'XPC_SERVICE_NAME': '0', 19:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:41:42 INFO - Cleaning up... 19:41:42 INFO - Return code: 0 19:41:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1091caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa322488d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10912ed30>, '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 0x108e93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109126b20>, '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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 19:41:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vo1l4tdOcU/Render', 19:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:42 INFO - 'HOME': '/Users/cltbld', 19:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:42 INFO - 'LOGNAME': 'cltbld', 19:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:42 INFO - 'MOZ_NO_REMOTE': '1', 19:41:42 INFO - 'NO_EM_RESTART': '1', 19:41:42 INFO - 'PAGER': '/bin/cat', 19:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:42 INFO - 'PWD': '/builds/slave/test', 19:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:42 INFO - 'SHELL': '/bin/bash', 19:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wzpyP687mJ/Listeners', 19:41:42 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 19:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:42 INFO - 'USER': 'cltbld', 19:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:42 INFO - 'XPC_FLAGS': '0x0', 19:41:42 INFO - 'XPC_SERVICE_NAME': '0', 19:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:42 INFO - Downloading/unpacking blobuploader==1.2.4 19:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:45 INFO - Downloading blobuploader-1.2.4.tar.gz 19:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:41:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:41:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:41:46 INFO - Downloading docopt-0.6.1.tar.gz 19:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:41:46 INFO - Installing collected packages: blobuploader, requests, docopt 19:41:46 INFO - Running setup.py install for blobuploader 19:41:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:41:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:41:46 INFO - Running setup.py install for requests 19:41:47 INFO - Running setup.py install for docopt 19:41:47 INFO - Successfully installed blobuploader requests docopt 19:41:47 INFO - Cleaning up... 19:41:47 INFO - Return code: 0 19:41:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:41:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:47 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 0x1091caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa322488d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10912ed30>, '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 0x108e93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109126b20>, '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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 19:41:47 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 19:41:47 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 19:41:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vo1l4tdOcU/Render', 19:41:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:47 INFO - 'HOME': '/Users/cltbld', 19:41:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:47 INFO - 'LOGNAME': 'cltbld', 19:41:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:47 INFO - 'MOZ_NO_REMOTE': '1', 19:41:47 INFO - 'NO_EM_RESTART': '1', 19:41:47 INFO - 'PAGER': '/bin/cat', 19:41:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:47 INFO - 'PWD': '/builds/slave/test', 19:41:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:47 INFO - 'SHELL': '/bin/bash', 19:41:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wzpyP687mJ/Listeners', 19:41:47 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 19:41:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:47 INFO - 'USER': 'cltbld', 19:41:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:47 INFO - 'XPC_FLAGS': '0x0', 19:41:47 INFO - 'XPC_SERVICE_NAME': '0', 19:41:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:41:47 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-oahS7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:41:47 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-R9clDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:41:47 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-2cb7LC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:41:47 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-164SOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:41:47 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-k23J6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:41:48 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-KadqWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:41:48 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-XvtFKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:41:48 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-y63Gn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:41:48 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-5IR3oG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:41:48 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-hUrtWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:41:48 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-FQUmhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:41:48 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-pKICdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:41:48 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-w2kbXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:41:48 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Ev4nyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:41:49 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-PJI9Lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:41:49 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-6xxln_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:41:49 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-1FjgGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:41:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:41:49 INFO - Running setup.py install for manifestparser 19:41:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:41:49 INFO - Running setup.py install for mozcrash 19:41:49 INFO - Running setup.py install for mozdebug 19:41:49 INFO - Running setup.py install for mozdevice 19:41:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:41:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:41:49 INFO - Running setup.py install for mozfile 19:41:50 INFO - Running setup.py install for mozhttpd 19:41:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:41:50 INFO - Running setup.py install for mozinfo 19:41:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:41:50 INFO - Running setup.py install for mozInstall 19:41:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:41:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:41:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:41:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:41:50 INFO - Running setup.py install for mozleak 19:41:50 INFO - Running setup.py install for mozlog 19:41:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:41:50 INFO - Running setup.py install for moznetwork 19:41:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:41:50 INFO - Running setup.py install for mozprocess 19:41:51 INFO - Running setup.py install for mozprofile 19:41:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:41:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:41:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:41:51 INFO - Running setup.py install for mozrunner 19:41:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:41:51 INFO - Running setup.py install for mozscreenshot 19:41:51 INFO - Running setup.py install for moztest 19:41:51 INFO - Running setup.py install for mozversion 19:41:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:41:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:41:51 INFO - Cleaning up... 19:41:51 INFO - Return code: 0 19:41:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:41:51 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')]} 19:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:51 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')]} 19:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:51 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 0x1091caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa322488d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10912ed30>, '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 0x108e93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109126b20>, '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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 19:41:51 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 19:41:51 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 19:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vo1l4tdOcU/Render', 19:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:51 INFO - 'HOME': '/Users/cltbld', 19:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:51 INFO - 'LOGNAME': 'cltbld', 19:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:51 INFO - 'MOZ_NO_REMOTE': '1', 19:41:51 INFO - 'NO_EM_RESTART': '1', 19:41:51 INFO - 'PAGER': '/bin/cat', 19:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:51 INFO - 'PWD': '/builds/slave/test', 19:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:51 INFO - 'SHELL': '/bin/bash', 19:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wzpyP687mJ/Listeners', 19:41:51 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 19:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:51 INFO - 'USER': 'cltbld', 19:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:51 INFO - 'XPC_FLAGS': '0x0', 19:41:51 INFO - 'XPC_SERVICE_NAME': '0', 19:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:41:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-XR8gQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:41:52 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)) 19:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:41:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-XG21lz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:41:52 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)) 19:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:41:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Km3w58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:41:52 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)) 19:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:41:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-YJM5ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:41:52 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)) 19:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:41:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-mcTx4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:41:52 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)) 19:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:41:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-bx2XH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:41:52 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)) 19:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:41:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-1st321-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:41:52 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)) 19:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:41:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Pbxk8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:41:53 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)) 19:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:41:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-TIaLMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:41:53 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)) 19:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:41:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-4ey4Oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:41:53 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)) 19:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:41:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-MtQwfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:41:53 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)) 19:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:41:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-6FSop2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:41:53 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)) 19:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:41:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-k4zzEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:41:53 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)) 19:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:41:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-C19K6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:41:53 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)) 19:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:41:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-qnHaQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:41:53 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)) 19:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:41:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-yYy6FA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:41:53 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)) 19:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:41:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-qzo84F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:41:54 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)) 19:41:54 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)) 19:41:54 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)) 19:41:54 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)) 19:41:54 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)) 19:41:54 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)) 19:41:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:41:54 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 19:41:54 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 19:41:54 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 19:41:54 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 19:41:54 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 19:41:54 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 19:41:56 INFO - Downloading blessings-1.6.tar.gz 19:41:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:41:56 INFO - Installing collected packages: blessings 19:41:56 INFO - Running setup.py install for blessings 19:41:56 INFO - Successfully installed blessings 19:41:56 INFO - Cleaning up... 19:41:56 INFO - Return code: 0 19:41:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:41:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:41:57 INFO - Reading from file tmpfile_stdout 19:41:57 INFO - Current package versions: 19:41:57 INFO - blessings == 1.6 19:41:57 INFO - blobuploader == 1.2.4 19:41:57 INFO - docopt == 0.6.1 19:41:57 INFO - functools32 == 3.2.3-2 19:41:57 INFO - jsonschema == 2.5.1 19:41:57 INFO - manifestparser == 1.1 19:41:57 INFO - mozInstall == 1.12 19:41:57 INFO - mozcrash == 0.17 19:41:57 INFO - mozdebug == 0.1 19:41:57 INFO - mozdevice == 0.48 19:41:57 INFO - mozfile == 1.2 19:41:57 INFO - mozhttpd == 0.7 19:41:57 INFO - mozinfo == 0.9 19:41:57 INFO - mozleak == 0.1 19:41:57 INFO - mozlog == 3.3 19:41:57 INFO - moznetwork == 0.27 19:41:57 INFO - mozprocess == 0.23 19:41:57 INFO - mozprofile == 0.28 19:41:57 INFO - mozrunner == 6.12 19:41:57 INFO - mozscreenshot == 0.1 19:41:57 INFO - mozsystemmonitor == 0.3 19:41:57 INFO - moztest == 0.7 19:41:57 INFO - mozversion == 1.4 19:41:57 INFO - psutil == 3.1.1 19:41:57 INFO - requests == 1.2.3 19:41:57 INFO - wsgiref == 0.1.2 19:41:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:41:57 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')]} 19:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:57 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')]} 19:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:57 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 0x1091caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa322488d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10912ed30>, '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 0x108e93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109126b20>, '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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 19:41:57 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 19:41:57 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 19:41:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vo1l4tdOcU/Render', 19:41:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:57 INFO - 'HOME': '/Users/cltbld', 19:41:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:57 INFO - 'LOGNAME': 'cltbld', 19:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:57 INFO - 'MOZ_NO_REMOTE': '1', 19:41:57 INFO - 'NO_EM_RESTART': '1', 19:41:57 INFO - 'PAGER': '/bin/cat', 19:41:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:57 INFO - 'PWD': '/builds/slave/test', 19:41:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:57 INFO - 'SHELL': '/bin/bash', 19:41:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wzpyP687mJ/Listeners', 19:41:57 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 19:41:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:57 INFO - 'USER': 'cltbld', 19:41:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:57 INFO - 'XPC_FLAGS': '0x0', 19:41:57 INFO - 'XPC_SERVICE_NAME': '0', 19:41:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:57 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)) 19:41:57 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)) 19:41:57 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)) 19:41:57 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)) 19:41:57 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)) 19:41:57 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)) 19:41:57 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)) 19:41:57 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)) 19:41:57 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)) 19:41:57 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)) 19:41:57 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)) 19:41:57 INFO - Cleaning up... 19:41:57 INFO - Return code: 0 19:41:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:41:57 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')]} 19:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:57 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')]} 19:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:41:57 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 0x1091caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10925f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa322488d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10912ed30>, '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 0x108e93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109126b20>, '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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 19:41:57 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 19:41:57 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 19:41:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vo1l4tdOcU/Render', 19:41:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:41:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:41:57 INFO - 'HOME': '/Users/cltbld', 19:41:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:41:57 INFO - 'LOGNAME': 'cltbld', 19:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:41:57 INFO - 'MOZ_NO_REMOTE': '1', 19:41:57 INFO - 'NO_EM_RESTART': '1', 19:41:57 INFO - 'PAGER': '/bin/cat', 19:41:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:41:57 INFO - 'PWD': '/builds/slave/test', 19:41:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:41:57 INFO - 'SHELL': '/bin/bash', 19:41:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wzpyP687mJ/Listeners', 19:41:57 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 19:41:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:41:57 INFO - 'USER': 'cltbld', 19:41:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:41:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:41:57 INFO - 'XPC_FLAGS': '0x0', 19:41:57 INFO - 'XPC_SERVICE_NAME': '0', 19:41:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:41:57 INFO - Cleaning up... 19:41:57 INFO - Return code: 0 19:41:57 INFO - Running post-action listener: _resource_record_post_action 19:41:57 INFO - Running post-action listener: _start_resource_monitoring 19:41:57 INFO - Starting resource monitoring. 19:41:57 INFO - [mozharness: 2016-08-31 02:41:57.637045Z] Finished create-virtualenv step (success) 19:41:57 INFO - [mozharness: 2016-08-31 02:41:57.638110Z] Running install step. 19:41:57 INFO - Running pre-action listener: _resource_record_pre_action 19:41:57 INFO - Running main action method: install 19:41:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:41:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:41:57 INFO - Reading from file tmpfile_stdout 19:41:57 INFO - Detecting whether we're running mozinstall >=1.0... 19:41:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:41:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:41:57 INFO - Reading from file tmpfile_stdout 19:41:57 INFO - Output received: 19:41:57 INFO - Usage: mozinstall [options] installer 19:41:57 INFO - Options: 19:41:57 INFO - -h, --help show this help message and exit 19:41:57 INFO - -d DEST, --destination=DEST 19:41:57 INFO - Directory to install application into. [default: 19:41:57 INFO - "/builds/slave/test"] 19:41:57 INFO - --app=APP Application being installed. [default: firefox] 19:41:58 INFO - mkdir: /builds/slave/test/build/application 19:41:58 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'] 19:41:58 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 19:42:33 INFO - Reading from file tmpfile_stdout 19:42:33 INFO - Output received: 19:42:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:42:33 INFO - Running post-action listener: _resource_record_post_action 19:42:33 INFO - [mozharness: 2016-08-31 02:42:33.085456Z] Finished install step (success) 19:42:33 INFO - [mozharness: 2016-08-31 02:42:33.085595Z] Running run-tests step. 19:42:33 INFO - Running pre-action listener: _resource_record_pre_action 19:42:33 INFO - Running main action method: run_tests 19:42:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:42:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:42:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:42:33 INFO - 2016-08-30 19:42:33.100 screenresolution[2982:33976] starting screenresolution argv=screenresolution get 19:42:33 INFO - 2016-08-30 19:42:33.103 screenresolution[2982:33976] Display 0: 1600x1200x32@60 19:42:33 INFO - 2016-08-30 19:42:33.108 screenresolution[2983:33978] starting screenresolution argv=screenresolution list 19:42:33 INFO - Available Modes on Display 0 19:42:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:42:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:42:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:42:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:42:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:42:33 INFO - Intel Iris: 19:42:33 INFO - Chipset Model: Intel Iris 19:42:33 INFO - Type: GPU 19:42:33 INFO - Bus: Built-In 19:42:33 INFO - VRAM (Dynamic, Max): 1536 MB 19:42:33 INFO - Vendor: Intel (0x8086) 19:42:33 INFO - Device ID: 0x0a2e 19:42:33 INFO - Revision ID: 0x0009 19:42:33 INFO - Displays: 19:42:33 INFO - Display: 19:42:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:42:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:42:33 INFO - Display Serial Number: 1600x1200 60 19:42:33 INFO - Main Display: Yes 19:42:33 INFO - Mirror: Off 19:42:33 INFO - Online: Yes 19:42:33 INFO - Rotation: Supported 19:42:33 INFO - Return code: 0 19:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:42:33 INFO - Python 2.7.3 19:42:33 INFO - Return code: 0 19:42:33 INFO - grabbing minidump binary from tooltool 19:42: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')]} 19:42:33 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 0x10912ed30>, '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 0x108e93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109126b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:42:33 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 19:42:33 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 19:42:33 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 19:42:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:42:33 INFO - Return code: 0 19:42:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:42:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:42:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:42:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:42:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0314 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:42:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vo1l4tdOcU/Render', 19:42:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:42:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:42:33 INFO - 'HOME': '/Users/cltbld', 19:42:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:42:33 INFO - 'LOGNAME': 'cltbld', 19:42:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:42:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:42:33 INFO - 'MOZ_NO_REMOTE': '1', 19:42:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:42:33 INFO - 'NO_EM_RESTART': '1', 19:42:33 INFO - 'PAGER': '/bin/cat', 19:42:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:42:33 INFO - 'PWD': '/builds/slave/test', 19:42:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:42:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:42:33 INFO - 'SHELL': '/bin/bash', 19:42:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wzpyP687mJ/Listeners', 19:42:33 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 19:42:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:42:33 INFO - 'USER': 'cltbld', 19:42:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:42:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:42:33 INFO - 'XPC_FLAGS': '0x0', 19:42:33 INFO - 'XPC_SERVICE_NAME': '0', 19:42:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:42:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:42:33 INFO - mozversion application_buildid: 20160830173020 19:42:33 INFO - mozversion application_changeset: b0cad82d939e46950bcc1fc9a6bd6ea7b6c56310 19:42:33 INFO - mozversion application_display_name: Nightly 19:42:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:42:33 INFO - mozversion application_name: Firefox 19:42:33 INFO - mozversion application_remotingname: firefox 19:42:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:42:33 INFO - mozversion application_vendor: Mozilla 19:42:33 INFO - mozversion application_version: 51.0a1 19:42:33 INFO - mozversion platform_buildid: 20160830173020 19:42:33 INFO - mozversion platform_changeset: b0cad82d939e46950bcc1fc9a6bd6ea7b6c56310 19:42:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:42:33 INFO - mozversion platform_version: 51.0a1 19:42:33 INFO - using testdate: 1472611353 19:42:33 INFO - actual date: 1472611353 19:42:33 INFO - starting webserver on 'localhost:56338' 19:42:33 INFO - SUITE-START | Running 2 tests 19:42:33 INFO - TEST-START | dromaeo_css 19:42:33 INFO - operating with platform_type : mac_ 19:42:33 INFO - Initialising browser for dromaeo_css test... 19:42:33 INFO - TEST-INFO | started process 2993 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpcUE9Z7/profile http://localhost:56338/getInfo.html) 19:42:36 INFO - PROCESS | 2993 | __metrics Screen width/height:1600/1200 19:42:36 INFO - PROCESS | 2993 | colorDepth:24 19:42:36 INFO - PROCESS | 2993 | Browser inner width/height: 1024/689 19:42:36 INFO - PROCESS | 2993 | __metrics 19:42:36 ERROR - PROCESS | 2993 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 19:42:37 INFO - TEST-INFO | 2993: exit 0 19:42:37 INFO - Browser initialized. 19:42:37 INFO - Running cycle 1/1 for dromaeo_css test... 19:42:37 INFO - TEST-INFO | started process 2996 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpcUE9Z7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:42:49 INFO - PROCESS | 2996 | RSS: Main: 244297728 19:42:49 INFO - PROCESS | 2996 | 19:43:38 INFO - PROCESS | 2996 | 1472611418919 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] 19:45:51 INFO - PROCESS | 2996 | Cycle 1(1): loaded http://localhost:56338/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56338/tests/dromaeo/cssquery-ext.html) 19:45:52 INFO - PROCESS | 2996 | RSS: Main: 264892416 19:45:52 INFO - PROCESS | 2996 | 19:48:55 INFO - PROCESS | 2996 | Cycle 1(1): loaded http://localhost:56338/tests/dromaeo/cssquery-ext.html (next: http://localhost:56338/tests/dromaeo/cssquery-jquery.html) 19:48:55 INFO - PROCESS | 2996 | RSS: Main: 274804736 19:48:55 INFO - PROCESS | 2996 | 19:51:57 INFO - PROCESS | 2996 | Cycle 1(1): loaded http://localhost:56338/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56338/tests/dromaeo/cssquery-mootools.html) 19:51:58 INFO - PROCESS | 2996 | RSS: Main: 276520960 19:51:58 INFO - PROCESS | 2996 | 19:55:00 INFO - PROCESS | 2996 | Cycle 1(1): loaded http://localhost:56338/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56338/tests/dromaeo/cssquery-prototype.html) 19:55:00 INFO - PROCESS | 2996 | RSS: Main: 272531456 19:55:00 INFO - PROCESS | 2996 | 19:58:03 INFO - PROCESS | 2996 | Cycle 1(1): loaded http://localhost:56338/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56338/tests/dromaeo/cssquery-yui.html) 19:58:04 INFO - PROCESS | 2996 | RSS: Main: 278016000 19:58:04 INFO - PROCESS | 2996 | 20:01:06 INFO - PROCESS | 2996 | Cycle 1(1): loaded http://localhost:56338/tests/dromaeo/cssquery-yui.html (next: http://localhost:56338/tests/dromaeo/cssquery-dojo.html) 20:01:07 INFO - PROCESS | 2996 | RSS: Main: 280768512 20:01:07 INFO - PROCESS | 2996 | 20:01:07 INFO - PROCESS | 2996 | __start_tp_report 20:01:07 INFO - PROCESS | 2996 | _x_x_mozilla_page_load 20:01:07 INFO - PROCESS | 2996 | _x_x_mozilla_page_load_details 20:01:07 INFO - PROCESS | 2996 | |i|pagename|runs| 20:01:07 INFO - PROCESS | 2996 | |0;dojo.html;3398.4732366483204;3413.402654535456;3416.1288871337806;3451.4650229214467;3453.6201017888034;5814.534837213024;5841.474267315941;5843;5881.67650779207;5916.940830591693;5829.562782791287;5883.0881213411885;5917.319508256552;5919.408059194078;5964.761409543624;5233.1103712368795;5316.867078323035;5358.517733403993;5386.380566234894;5387.164989426626;5193.221016847466;5259.86850328741;5297.682139071649;5392.460753924595;5399.1091469907415;5286.392064912527;5311.097113490678;5324.387695415038;5332.386775520807;5383.811566595189;5312.60155488336;5318.627696061278;5325.3875804282225;5341.305630268079;5391.487808658171;5130.281760553526;5218.13900706383;5270.604704647167;5324.5740340772645;5326.866828329284;5782.190493330937;5851.32709738377;5888.440598143169;5908.20239267702;5934.347221805641;6162.260528736536;6224.937750622526;6256.124142620037;6259.499240060784;6315.905261421082;6095.287210409396;6138.508919286446;6169.475594574466;6208.068789681539;6287.937120628781;5887.882242355129;6009.489193418521;6010.218671572755;6043.395660434009;6084.330723620398;6324;6361.650109243943;6375.681215939185;6379.808605741835;6415.615063096229;6101.298350689639;6114.814446323802;6235.625862448268;6249.531285153632;6269.62382257066;6179.567430279927;6264.686765661699;6280.780172693934;6281.591696539802;6288.591241569284;5626.943730562642;5871.735771890189;6207.534434917399;6207.596506227081;6265.028920517327;6129.601575897642;6141.539384546177;6174.289956654952;6181.783637572664;6259.467945224706;4212.3681447782465;4341.891452713644;4781.019890922353;5015.1975683890405;5059.038782631288;4452.688311818207;4464.776761161929;4508.977455112769;4528.932066019012;4552.2488789350045;5635.943640563624;5916.585838991229;6019.307779605401;6066.75732970685;6101.908471372933;6198.938010619926;6242.063690446469;6311.779087731999;6316.084167795644;6329.303776584572;6030.849228769228;6048.60684055535;6054.788082417094;6073.544484163705;6086.908696369558;5384.488473595002;5468.316460442445;5540.279763630702;5560.694161821139;5595.608307418442;6338;6446.387593178641;6457.644829534421;6466.0947467355545;6491.253505846699;6255;6263.029230469285;6297.874042519215;6304.3380445054445;6358.109864618865;5344.9732751336;6191.84520386994;6328.46208072319;6442.291347951816;6446.806595802133;6162.784302549389;6249.562530622995;6260.718267677874;6281.623102613858;6283.622982621057;6235.158253635701;6266.592671476339;6266.905996409966;6271.874562508633;6273.74905003813;6115.327313995368;6164.445199932027;6227.283862355887;6237.126802247598;6243.594166379171;4867;6107.511399088151;6134.754609815736;6152.569320147547;6206.10011548332;6023.66869822164;6066.393360663898;6133;6139.416755408229;6149.692515374303;6096.207493026056;6150.708351246289;6219.937800621936;6220.160278362363;6221.595596286227;6127.754889804358;6137.162486020116;6143.72682107623;6147.877042459215;6151.8926593213655 20:01:07 INFO - PROCESS | 2996 | |1;ext.html;467.2104143996647;517.1699422427005;522.0967725834306;524.8871492629085;529.9903683482964;32339.838300808493;39479.407808882854;39509.60490395095;39595.81212563621;39629.40555891664;32816;40908.70458601849;40959.180816383654;40959.38560921581;41098.58901410985;9428.71713848584;9640.855387169198;9642.13220810127;9647.71056868295;9686.806263874718;9542.713718588453;9606.039396060372;9638.37350572214;9672.22622190223;9680.370775899579;9632.566534505964;9657.13085822276;9694.903050969506;9695.127438530531;9699.224062075016;9629.133377995979;9672;9673.419594824332;9687.273454490942;9688.951555242249;9496;9566.66516671913;9582.281328900292;9584.329096963216;9602.183814375787;39004.804975975225;39224.41163382552;39227.01932451712;39428;39528.60471395307;3005.4740420426556;3332.3168750406107;3387.5596172497417;3513.8067406292644;3575.964240357589;29480.705192948226;31145.688543114506;31196.908108216332;31261.531077033862;31299.84350078258;3157.715805577509;3233.547303377529;3326.4012477753995;3386.0857568456627;3390.69483746464;484242;484596;486950;490131;500090.99999999633;41550.79224603918;58369.4163058364;59237.70381148153;59348.81302374015;59836;59691.104633430536;63455;64217.14697118188;64379.67810161013;64530.70938581202;15961;16093.597660058593;16136;16155.949863259086;16215.35138594443;12334.876651233373;12385.256884586954;12413.75172496545;12429.37853107341;12712.364381780873;18538.073096345128;18772.249110035445;18791.906040469985;18824.435266942015;18829.34097306588;7993.160718124629;8103.10865804761;8122.7157049502985;8200.917990820135;8229.83540329202;858.9828203436022;974.3277138774223;984.4683870709737;996.3175224970918;1014.5028935821542;27606.861965690445;28425.00512482053;28680.71319286822;28881.277968050967;29469.410611787644;1021.5198856537415;1037.901399367059;1045.2892033417197;1048.580567772897;1101.5043230546125;15206;15436.228188590525;15468.149251791034;15730.134842583539;15827.52517424479;627941;635475;647496;653952;841770;57603.135952960736;57637;57668.8466230673;57762;57870.421295787346;1357.2738584857054;1373.478078330228;1397.832260128791;1398.8041674165624;1412.6468382904275;30121.925646325963;30858.845705771328;30954.0713778587;31356.843215783774;31489.8425507871;49695.751521242164;50138;50218;50224.497755021985;50279.245811312125;500351.49824250647;548217;562564;567592;569040;26857.194284171506;26911.461770764865;27088.1873543794;27197.86401067982;27212;44187.89530261769;46265.76867115643;46305.53694463012;46492;46714.29928551142;36705.63294367129;37514.62485375111;37738;38834.0291492715;39009.6099039006;37970.62029379671;38057.238855223295;38431;39039;39140.80429597948;31948.52077218797;36357.45463818098;36846.26307473889;36890.81554592317;37372.06569835667;30004.549931751477;30536.694633053386;34131.82934085314;34152.48771268383;34226.315473690884;24669.876650616632;25100.874495627406;25307.367315816515;25725.3568660785;25765.3558661035 20:01:07 INFO - PROCESS | 2996 | |2;jquery.html;328.0830079926603;337.94086034943876;340.6391466092959;345.8080765175328;369.91307042844926;20054.89972550137;20169.394918152444;20688.27591034314;20869.26957556488;21075.73545587266;20432.387028389134;20478.18087276507;21088.789112108876;21237.893810530924;21380.037898294577;5223.373195216579;5862.384449632791;5983.222181116449;6060.151578778975;6070.392960703916;5916.852078698024;5985.700714964256;5991.820245392645;6015.127806468059;6015.248093988252;5838.503727183194;5911.645301281916;5930.169776231331;5940.435658612447;5957.136215248786;5732.5987180897355;5898.675572843492;5912.793052243193;5918.201042859224;5938.940610593903;4904.950950490521;5143.588512918957;5148.099082660519;5199.610029247784;5212.530872221519;25411.237662870142;25665;25876.87061564699;25888.223353299432;26059.478810423687;2006.7290915726483;2213.3359992002333;2616.607508873665;2792.287966568512;2858.299716569433;8171.836563268701;8371.623276952552;9059.229965452945;10066.647667331681;10177.89822101777;1812.9728054079067;1852.2868695552174;1870.3827736846808;1956.4130760771711;1971.822535971755;116633.41683291529;119725;120761;121617.56764864741;122602;11277.887221127683;11460.484278207501;11536.557930258718;11622.907876582127;11641.301521908712;14366;14377.281135943162;14385.928070359581;14396.496122635866;14399.78400323996;6178.752849885954;7271.927280727231;8506.829863402822;8513.531755753493;8638.870416943751;7315.4147668186415;8190.959045204855;8383.371247156487;8646.870296945552;8648.610812513452;4387.100644367897;4426.22541055314;4469.597736203757;4490.169318676056;4566.13243483737;1895.8198971097725;1936.7966363531907;1940.4857712706144;1949.32356678376;1969.4190213886939;831.4595512916532;889.9911000889908;895.4940458640847;912.2975309012023;975.3465178330536;20129.295474658316;20792.792072079388;20879.060442280133;20949.162033518794;21235.256766013423;780.4380845791021;850.6937502499095;903.2864037410503;952.7522844060455;996.5117092624562;8460.238578527962;8954.731358059138;11199.160062995308;11202.49588768491;11208.775824483628;534191;538279.3086034545;538776;554143;558231;62706.68646656738;73740.63129684317;76523;128101.71898281084;131182;891.5497673674882;944.5371767833849;991.861139440479;1009.6869970309227;1016.8627235323285;18069.909650451664;18082.457526274233;18641.44075677732;18992.430227092656;19174;37518.06204844901;50518.98962020665;51360.229596555335;52365.47634523606;52516.94966100733;79837.6008119979;80583;80737;81117.59441202757;82735.586322068;7007.614581198083;7366.594837284001;7421.035265415678;7440.479166458296;7650.273224043707;11719.941400292944;13232.625680451423;13236.073474856668;13426.925845932106;13469.326533673082;11392.658220253406;11471.959930618226;11475.942620287178;11476.598319058792;11507.367094809866;10359.94820025895;11129.054030407506;11499.252548584314;11644.123746034427;11725.824112638487;11448.313101213955;11626.941865290957;11840.05279577649;11954.880451195377;11986.820197697216;7284.064619264609;7471.551706897603;7516.210797866145;7598.12621548529;7665.578393188428;11137.387443690675;11299.265547739371;11435.59975400857;11574.6527604172;11638.941805290919 20:01:07 INFO - PROCESS | 2996 | |3;mootools.html;1475.3877140986488;1515.9393624255026;1547.7291473992063;1715.9399421020266;1868.3180639066736;6607;8509.361797865155;8689.869651955225;9083.27333813295;9105.772355691102;8700.608472618731;8888.377813553037;8900.955495222515;8925.598348074336;8933.821323573524;2369.9881500592473;2397.61638137898;2402.843815152018;2409.963850542243;2422.551827911842;2377.8454400464;2419.588669926117;2423.200343886522;2428.599281118622;2437.1591800828774;2384.5707772600927;2394.6288325309606;2399.688040554734;2399.940001499968;2423.491066875958;1954.7067939809;2371.395294199985;2392.7846493815555;2396.1190465484683;2401.4236583220077;2298.0118549023914;2373.715154181509;2381.7141942966955;2401.399650087478;2477.9504409911883;7198;7644.388448924128;8862.734117976472;8980.8652870207;8984.191422771983;5292.338457692788;5455.49973757216;5466.098093814516;5476.479734425223;5482.259894914176;3627.764195327322;3678.4666223397726;3695.8521659133603;3722.8324725387424;3727.0682329417646;1085.625459216569;1095.6603452929617;1096.8080585897435;1098.2477003252798;1112.104755671684;11742.647720568397;11756.05951523876;11773.529058837636;11903.107266955012;11948.581799637059;4733.569101168246;4744.169770290186;4797.880052998703;4887;4970.850874473772;9397.731306273703;10015.899841001643;10116.140128089048;10134.949325253474;10150.746231344276;4912.4350699669285;5426.457354264542;5450.83647490568;5469.261649677323;5501.614886957955;4239.766812825263;4494.303382975579;4811.326414302001;4894.853154405374;5026.1204289249235;3984.940225896614;4011.839526418969;4114.526829414655;4122.958770412318;4127.690423218271;1315.1122991980376;1325.5493132335052;1327.4889167670517;1336.6190635668788;1338.1569611144917;757.8934755257276;911.7902882337099;914.5655813488539;916.6516723645105;932.0080311330369;5705.5435565154685;5840.269966254218;5974.103884417372;5990.700464976734;6123.9387606124255;439.89882327064953;448.26484565312916;454.2595569222147;959.5681943125621;984.2765567308023;3922.8626998054933;4009.7970608817236;4029.234445455354;4042.413849991735;4047.0894048839364;8795;10146.137578305927;10808.62169824068;10924.180686448548;11049.834252486218;10335.689929302132;10756.838647420294;10830.241883068267;10842.295250808673;10846.620368287073;8050.5169689818795;8098.975256185953;8334.083250842403;8350.582470876554;8359.749207523662;5873.2658417697785;5874.265716785402;5999.130126131774;6037.577369584263;6178.9382106180165;10059.647912323033;10251.28241023151;10325.690229293134;10341.172706183637;10392.168626510012;10132.848007280045;10154.593816247267;10332.638357657446;10418.635347762793;10492;6984.511084224056;7426.108866936002;7500.1374841893885;7671;7732.845343092994;8532.573371331533;8552.187542183732;8577.013643431086;8603.268722158688;8603.39776215684;6501.544891857525;6603.877340851971;7451.3293803557945;7512.32389084985;7700;6728.865422691617;7453.962730186315;7502.812429689301;7508.549487030796;7508.887366689613;6997.300269972962;7020.262872398324;7092.361687448154;7108.9289107110335;7111;4408;5025.572826309804;5073.898522029613;5118.309028281284;5126.615503837153;4598.241290187082;4819.445763737215;4878.707277563358;4955.950440495693;5156.664816786897 20:01:07 INFO - PROCESS | 2996 | |4;prototype.html;239.40866060829757;294.9542820862767;313.6001597962598;318.39663837028843;321.45673811258956;4596.931046034308;4864.318995340646;5518.9724051379735;5666.008448521513;5782.421757824215;4746.406699162605;5394.1908713692965;5425.2910333245;5506.366767821702;5611.971940140304;3057.2420858006626;3669.2754405429405;4045.4336392905016;4132.855350062749;4232.407462955189;3874.341361968472;4247.704450142705;4269.338252570857;4277.096691972074;4299.914001719963;3677.926441471169;4283.91432171358;4288.742675439484;4291.648130838791;4318.892027699301;4093.9385909211533;4242.342436922282;4262.531121576624;4272.636825869805;4347.369631403445;3069.938601227985;3369.427197376452;3607.494950706903;3616.963830361689;3670.6696397324104;5424.05079111154;5456.317960254968;5535.169724541311;5537.58468114889;5588.546977785764;4435.004248088341;4559.589636932659;4735.881602959919;4763.761811909425;4965.255211718235;5395.811146609889;5901.645901245939;5981.641101533878;5994.910076348859;6024.21685180928;3208.5347624594538;3804.7444343366624;4062.7156099073077;4069.2878746219294;4081.9387709184384;5920.566071678416;5978.192943952554;6212.627242365473;6280.937190628127;6337.302896681362;3417.4190387633666;3492.161881148557;4054.9862534366407;4122.175564887035;4137.648299894482;4185.497740271187;4210.873673789792;4210.915596779552;4210.957890421118;4236.046889449849;4001.4598029265676;4662.463816661059;4730.314104454835;4941.802327906844;4977.271591987626;5242.7378631068295;5500.724963751796;5967.791127310524;5989.9101513477335;6050;1382.4262930883572;1431.4560467022466;1453.4985430026625;1454.7454195515743;1456.2791418248034;801.8837268596021;816.8856360109589;850.9361797865184;862.5213006781171;905.456725964416;5490.780368785204;5775.537956963432;5788.716349901127;5823.708814559255;5846.6784326862435;5670.7731690732735;6012.699365031731;6054.576179667469;6271.43557079865;6389.233292004989;5544.500994910478;5785.623934444247;5798.971005144947;5832.50423713989;5842.678652674143;2537.6320433537035;2796.783399221345;2834.773218142585;3286.917827054343;3311.791196213365;5387.919181212285;6205.441510264098;6385.45723613498;6520.250171230275;6562.571848416116;6316.147150238351;6376.968115159488;6405.327440618668;6412.749513844756;6442.903356449657;5978.133170690226;6181.258249010148;6501.34986501365;6527.608343499405;6575.178102737158;5392.406835248198;5848.64908105515;6107.267127944675;6198.783042593487;6250.968745156245;5178.8964220716125;5716.5998380113;5786.826395208151;5826.912596310974;6184.093476066421;5799.82600521985;6062.347363884493;6249.437910325579;6400.967995159995;6503.6097834130105;3876.7673939563715;4079.9196606561854;4182.2681030820095;4203.411522386929;4208.284591619493;5720.456556627114;6141.631502109888;6156.122382673328;6186.979148440637;6198;5101.744912754273;5378;5505;6046.879062418639;6049.425304596057;4872.976674898169;5078.619103567174;5203.479652034766;6066.787662431793;6146.262448506208;5604.383517812956;5632.49307562305;5970.492508136857;5977;5986.820395388146;5298.417174110922;5962.52299816005;6019.668918209365;6127.448529632176;6144.525313924715;5325.2810870531985;5824.301083869963;5955.3498693081165;5972.402759723993;5996.8800623988145 20:01:07 INFO - PROCESS | 2996 | |5;yui.html;639.0829160155178;703.9084918960535;745.9067616547932;767.5855038279329;789.565738843636;15075.457267542119;15583.909126361144;15862.52412427629;15872.365105395771;15939.521814345573;14409;14505.564833055;14570.854291457083;14589.999999999973;14612.707745845077;662.1623646087703;806.330745481252;823.7734622978669;836.0260296754274;837.204655577201;850.6512329944724;853.9274161696263;854.3464249848871;856.3448961544424;856.8928883889514;880.6697488441835;905.5608030105394;908.8000639859221;908.9728606674448;910.0171814440389;901.4951627088851;906.7007887397143;907.768519027647;908.454927043775;913.3515204205022;673.0509980926919;673.192169396726;674.6053558668185;675.7769935921183;677.072410797211;14568.562943111723;14582.052166609243;14597.343119559646;14605.050671706305;14611.63470913225;11602.709932251677;14265.144091354448;14276.714465710731;14345.569632911029;14418.134911905214;11804.527818887234;11883.94058029713;11896.583619573274;11904.702382440508;11965.581204657883;3782.0733920189728;3785.7160712946365;3798.164403831153;3806.4290356446477;3953.920921581581;108420;108578;108652.4567377166;109452;111410.32884506739;9918.760154980628;10491.527881245362;10823.67528974132;10911.618093366853;11216.775664486664;22232.888835555717;22372.888135559217;22375.88812055962;22377.55244895093;22398.32805015852;8041.8793718094275;8063;8133.471324363897;8216.233509795422;8376.329893608498;4520.028193938253;4526.502084770673;4531.8187272509385;4609.792559334838;4674.719516829001;3507.631698671654;3593.443016332473;3599.7840129591787;3619.49327094207;3637.181634132352;625.671522450717;630.782380078872;661.3386613386614;671.828683685657;719.7552832037028;575.2033433694363;577.2841676321415;595.5830918357167;621.8010236724296;644.8387903024244;7189.96405017982;8388.203120703523;8955.910440895508;9184.678536251198;9376.812463750686;508.5143687778163;526.7103093298671;597.978831549369;629.2920464477463;668.5654324688991;4129.236091323115;4287.6998610097;4344.761742903321;4541.523140070245;4605.838795642137;89482.55258723664;115919.84080159091;118309;137552;151160;117972.41013794875;119494.40252798681;119581;120146.19049761737;120305;65825.34174658288;65968;80661.9503537237;85806;101476.98523014675;8629.525376104124;9430.95284523573;9476.241900647794;9525.476098814355;9663.130318271109;47789;53308.93382132258;55261.44738552563;57891.13163302638;57941.710291448275;76691;77485.61257193678;78004.60997694975;81621.18378816136;82810.5859470723;13029.47882084706;13111.344432778133;13151.473941042252;13308.268045257604;13731.038827281995;14475.348609312818;14559.566391344004;14763.557093287218;14781.704365912836;15011;12694.365281736062;13672.316384180553;13738.313084345542;13819.309034548434;14162.50431234943;13573.932130339284;13829.308534573433;13863.930680346533;13948.372323245276;14029.088109272865;13522.594322170351;13818;13818;14023.298835058002;14164.220967846868;10888.965548272901;11137.579217043605;11419.0864730823;11631.47658355359;11668.183227174355;8423.536705481258;9493.765810444598;10931.83602245983;11552.884471155181;11591 20:01:07 INFO - PROCESS | 2996 | __end_tp_report 20:01:07 INFO - PROCESS | 2996 | __start_cc_report 20:01:07 INFO - PROCESS | 2996 | _x_x_mozilla_cycle_collect,380 20:01:07 INFO - PROCESS | 2996 | __end_cc_report 20:01:07 INFO - PROCESS | 2996 | __startTimestamp1472612467202__endTimestamp 20:01:07 INFO - PROCESS | 2996 | 20:01:07 INFO - PROCESS | 2996 | ------- Summary: start ------- 20:01:07 INFO - PROCESS | 2996 | Number of tests: 6 20:01:07 INFO - PROCESS | 2996 | 20:01:07 INFO - PROCESS | 2996 | [#0] dojo.html Cycles:165 Average:5817.84 Median:6101.30 stddev:650.36 (10.7%) stddev-sans-first:624.05 20:01:07 INFO - PROCESS | 2996 | Values: 3398.5 3413.4 3416.1 3451.5 3453.6 5814.5 5841.5 5843.0 5881.7 5916.9 5829.6 5883.1 5917.3 5919.4 5964.8 5233.1 5316.9 5358.5 5386.4 5387.2 5193.2 5259.9 5297.7 5392.5 5399.1 5286.4 5311.1 5324.4 5332.4 5383.8 5312.6 5318.6 5325.4 5341.3 5391.5 5130.3 5218.1 5270.6 5324.6 5326.9 5782.2 5851.3 5888.4 5908.2 5934.3 6162.3 6224.9 6256.1 6259.5 6315.9 6095.3 6138.5 6169.5 6208.1 6287.9 5887.9 6009.5 6010.2 6043.4 6084.3 6324.0 6361.7 6375.7 6379.8 6415.6 6101.3 6114.8 6235.6 6249.5 6269.6 6179.6 6264.7 6280.8 6281.6 6288.6 5626.9 5871.7 6207.5 6207.6 6265.0 6129.6 6141.5 6174.3 6181.8 6259.5 4212.4 4341.9 4781.0 5015.2 5059.0 4452.7 4464.8 4509.0 4528.9 4552.2 5635.9 5916.6 6019.3 6066.8 6101.9 6198.9 6242.1 6311.8 6316.1 6329.3 6030.8 6048.6 6054.8 6073.5 6086.9 5384.5 5468.3 5540.3 5560.7 5595.6 6338.0 6446.4 6457.6 6466.1 6491.3 6255.0 6263.0 6297.9 6304.3 6358.1 5345.0 6191.8 6328.5 6442.3 6446.8 6162.8 6249.6 6260.7 6281.6 6283.6 6235.2 6266.6 6266.9 6271.9 6273.7 6115.3 6164.4 6227.3 6237.1 6243.6 4867.0 6107.5 6134.8 6152.6 6206.1 6023.7 6066.4 6133.0 6139.4 6149.7 6096.2 6150.7 6219.9 6220.2 6221.6 6127.8 6137.2 6143.7 6147.9 6151.9 20:01:07 INFO - PROCESS | 2996 | 20:01:07 INFO - PROCESS | 2996 | [#1] ext.html Cycles:180 Average:70196.23 Median:39327.51 stddev:155529.49 (395.5%) stddev-sans-first:155877.67 20:01:07 INFO - PROCESS | 2996 | Values: 467.2 517.2 522.1 524.9 530.0 32339.8 39479.4 39509.6 39595.8 39629.4 32816.0 40908.7 40959.2 40959.4 41098.6 9428.7 9640.9 9642.1 9647.7 9686.8 9542.7 9606.0 9638.4 9672.2 9680.4 9632.6 9657.1 9694.9 9695.1 9699.2 9629.1 9672.0 9673.4 9687.3 9689.0 9496.0 9566.7 9582.3 9584.3 9602.2 39004.8 39224.4 39227.0 39428.0 39528.6 3005.5 3332.3 3387.6 3513.8 3576.0 29480.7 31145.7 31196.9 31261.5 31299.8 3157.7 3233.5 3326.4 3386.1 3390.7 484242.0 484596.0 486950.0 490131.0 500091.0 41550.8 58369.4 59237.7 59348.8 59836.0 59691.1 63455.0 64217.1 64379.7 64530.7 15961.0 16093.6 16136.0 16155.9 16215.4 12334.9 12385.3 12413.8 12429.4 12712.4 18538.1 18772.2 18791.9 18824.4 18829.3 7993.2 8103.1 8122.7 8200.9 8229.8 859.0 974.3 984.5 996.3 1014.5 27606.9 28425.0 28680.7 28881.3 29469.4 1021.5 1037.9 1045.3 1048.6 1101.5 15206.0 15436.2 15468.1 15730.1 15827.5 627941.0 635475.0 647496.0 653952.0 841770.0 57603.1 57637.0 57668.8 57762.0 57870.4 1357.3 1373.5 1397.8 1398.8 1412.6 30121.9 30858.8 30954.1 31356.8 31489.8 49695.8 50138.0 50218.0 50224.5 50279.2 500351.5 548217.0 562564.0 567592.0 569040.0 26857.2 26911.5 27088.2 27197.9 27212.0 44187.9 46265.8 46305.5 46492.0 46714.3 36705.6 37514.6 37738.0 38834.0 39009.6 37970.6 38057.2 38431.0 39039.0 39140.8 31948.5 36357.5 36846.3 36890.8 37372.1 30004.5 30536.7 34131.8 34152.5 34226.3 24669.9 25100.9 25307.4 25725.4 25765.4 20:01:07 INFO - PROCESS | 2996 | 20:01:07 INFO - PROCESS | 2996 | [#2] jquery.html Cycles:180 Average:32604.72 Median:339.29 stddev:90889.47 (26788.1%) stddev-sans-first:91112.13 20:01:07 INFO - PROCESS | 2996 | Values: 328.1 337.9 340.6 345.8 369.9 20054.9 20169.4 20688.3 20869.3 21075.7 20432.4 20478.2 21088.8 21237.9 21380.0 5223.4 5862.4 5983.2 6060.2 6070.4 5916.9 5985.7 5991.8 6015.1 6015.2 5838.5 5911.6 5930.2 5940.4 5957.1 5732.6 5898.7 5912.8 5918.2 5938.9 4905.0 5143.6 5148.1 5199.6 5212.5 25411.2 25665.0 25876.9 25888.2 26059.5 2006.7 2213.3 2616.6 2792.3 2858.3 8171.8 8371.6 9059.2 10066.6 10177.9 1813.0 1852.3 1870.4 1956.4 1971.8 116633.4 119725.0 120761.0 121617.6 122602.0 11277.9 11460.5 11536.6 11622.9 11641.3 14366.0 14377.3 14385.9 14396.5 14399.8 6178.8 7271.9 8506.8 8513.5 8638.9 7315.4 8191.0 8383.4 8646.9 8648.6 4387.1 4426.2 4469.6 4490.2 4566.1 1895.8 1936.8 1940.5 1949.3 1969.4 831.5 890.0 895.5 912.3 975.3 20129.3 20792.8 20879.1 20949.2 21235.3 780.4 850.7 903.3 952.8 996.5 8460.2 8954.7 11199.2 11202.5 11208.8 534191.0 538279.3 538776.0 554143.0 558231.0 62706.7 73740.6 76523.0 128101.7 131182.0 891.5 944.5 991.9 1009.7 1016.9 18069.9 18082.5 18641.4 18992.4 19174.0 37518.1 50519.0 51360.2 52365.5 52516.9 79837.6 80583.0 80737.0 81117.6 82735.6 7007.6 7366.6 7421.0 7440.5 7650.3 11719.9 13232.6 13236.1 13426.9 13469.3 11392.7 11472.0 11475.9 11476.6 11507.4 10359.9 11129.1 11499.3 11644.1 11725.8 11448.3 11626.9 11840.1 11954.9 11986.8 7284.1 7471.6 7516.2 7598.1 7665.6 11137.4 11299.3 11435.6 11574.7 11638.9 20:01:07 INFO - PROCESS | 2996 | 20:01:07 INFO - PROCESS | 2996 | [#3] mootools.html Cycles:180 Average:5728.01 Median:4738.87 stddev:3214.59 (67.8%) stddev-sans-first:3207.72 20:01:07 INFO - PROCESS | 2996 | Values: 1475.4 1515.9 1547.7 1715.9 1868.3 6607.0 8509.4 8689.9 9083.3 9105.8 8700.6 8888.4 8901.0 8925.6 8933.8 2370.0 2397.6 2402.8 2410.0 2422.6 2377.8 2419.6 2423.2 2428.6 2437.2 2384.6 2394.6 2399.7 2399.9 2423.5 1954.7 2371.4 2392.8 2396.1 2401.4 2298.0 2373.7 2381.7 2401.4 2478.0 7198.0 7644.4 8862.7 8980.9 8984.2 5292.3 5455.5 5466.1 5476.5 5482.3 3627.8 3678.5 3695.9 3722.8 3727.1 1085.6 1095.7 1096.8 1098.2 1112.1 11742.6 11756.1 11773.5 11903.1 11948.6 4733.6 4744.2 4797.9 4887.0 4970.9 9397.7 10015.9 10116.1 10134.9 10150.7 4912.4 5426.5 5450.8 5469.3 5501.6 4239.8 4494.3 4811.3 4894.9 5026.1 3984.9 4011.8 4114.5 4123.0 4127.7 1315.1 1325.5 1327.5 1336.6 1338.2 757.9 911.8 914.6 916.7 932.0 5705.5 5840.3 5974.1 5990.7 6123.9 439.9 448.3 454.3 959.6 984.3 3922.9 4009.8 4029.2 4042.4 4047.1 8795.0 10146.1 10808.6 10924.2 11049.8 10335.7 10756.8 10830.2 10842.3 10846.6 8050.5 8099.0 8334.1 8350.6 8359.7 5873.3 5874.3 5999.1 6037.6 6178.9 10059.6 10251.3 10325.7 10341.2 10392.2 10132.8 10154.6 10332.6 10418.6 10492.0 6984.5 7426.1 7500.1 7671.0 7732.8 8532.6 8552.2 8577.0 8603.3 8603.4 6501.5 6603.9 7451.3 7512.3 7700.0 6728.9 7454.0 7502.8 7508.5 7508.9 6997.3 7020.3 7092.4 7108.9 7111.0 4408.0 5025.6 5073.9 5118.3 5126.6 4598.2 4819.4 4878.7 4956.0 5156.7 20:01:07 INFO - PROCESS | 2996 | 20:01:07 INFO - PROCESS | 2996 | [#4] prototype.html Cycles:180 Average:4829.56 Median:5502.86 stddev:1542.31 (28.0%) stddev-sans-first:1507.67 20:01:07 INFO - PROCESS | 2996 | Values: 239.4 295.0 313.6 318.4 321.5 4596.9 4864.3 5519.0 5666.0 5782.4 4746.4 5394.2 5425.3 5506.4 5612.0 3057.2 3669.3 4045.4 4132.9 4232.4 3874.3 4247.7 4269.3 4277.1 4299.9 3677.9 4283.9 4288.7 4291.6 4318.9 4093.9 4242.3 4262.5 4272.6 4347.4 3069.9 3369.4 3607.5 3617.0 3670.7 5424.1 5456.3 5535.2 5537.6 5588.5 4435.0 4559.6 4735.9 4763.8 4965.3 5395.8 5901.6 5981.6 5994.9 6024.2 3208.5 3804.7 4062.7 4069.3 4081.9 5920.6 5978.2 6212.6 6280.9 6337.3 3417.4 3492.2 4055.0 4122.2 4137.6 4185.5 4210.9 4210.9 4211.0 4236.0 4001.5 4662.5 4730.3 4941.8 4977.3 5242.7 5500.7 5967.8 5989.9 6050.0 1382.4 1431.5 1453.5 1454.7 1456.3 801.9 816.9 850.9 862.5 905.5 5490.8 5775.5 5788.7 5823.7 5846.7 5670.8 6012.7 6054.6 6271.4 6389.2 5544.5 5785.6 5799.0 5832.5 5842.7 2537.6 2796.8 2834.8 3286.9 3311.8 5387.9 6205.4 6385.5 6520.3 6562.6 6316.1 6377.0 6405.3 6412.7 6442.9 5978.1 6181.3 6501.3 6527.6 6575.2 5392.4 5848.6 6107.3 6198.8 6251.0 5178.9 5716.6 5786.8 5826.9 6184.1 5799.8 6062.3 6249.4 6401.0 6503.6 3876.8 4079.9 4182.3 4203.4 4208.3 5720.5 6141.6 6156.1 6187.0 6198.0 5101.7 5378.0 5505.0 6046.9 6049.4 4873.0 5078.6 5203.5 6066.8 6146.3 5604.4 5632.5 5970.5 5977.0 5986.8 5298.4 5962.5 6019.7 6127.4 6144.5 5325.3 5824.3 5955.3 5972.4 5996.9 20:01:07 INFO - PROCESS | 2996 | 20:01:07 INFO - PROCESS | 2996 | [#5] yui.html Cycles:180 Average:22465.88 Median:3880.17 stddev:34441.55 (887.6%) stddev-sans-first:34499.18 20:01:07 INFO - PROCESS | 2996 | Values: 639.1 703.9 745.9 767.6 789.6 15075.5 15583.9 15862.5 15872.4 15939.5 14409.0 14505.6 14570.9 14590.0 14612.7 662.2 806.3 823.8 836.0 837.2 850.7 853.9 854.3 856.3 856.9 880.7 905.6 908.8 909.0 910.0 901.5 906.7 907.8 908.5 913.4 673.1 673.2 674.6 675.8 677.1 14568.6 14582.1 14597.3 14605.1 14611.6 11602.7 14265.1 14276.7 14345.6 14418.1 11804.5 11883.9 11896.6 11904.7 11965.6 3782.1 3785.7 3798.2 3806.4 3953.9 108420.0 108578.0 108652.5 109452.0 111410.3 9918.8 10491.5 10823.7 10911.6 11216.8 22232.9 22372.9 22375.9 22377.6 22398.3 8041.9 8063.0 8133.5 8216.2 8376.3 4520.0 4526.5 4531.8 4609.8 4674.7 3507.6 3593.4 3599.8 3619.5 3637.2 625.7 630.8 661.3 671.8 719.8 575.2 577.3 595.6 621.8 644.8 7190.0 8388.2 8955.9 9184.7 9376.8 508.5 526.7 598.0 629.3 668.6 4129.2 4287.7 4344.8 4541.5 4605.8 89482.6 115919.8 118309.0 137552.0 151160.0 117972.4 119494.4 119581.0 120146.2 120305.0 65825.3 65968.0 80662.0 85806.0 101477.0 8629.5 9431.0 9476.2 9525.5 9663.1 47789.0 53308.9 55261.4 57891.1 57941.7 76691.0 77485.6 78004.6 81621.2 82810.6 13029.5 13111.3 13151.5 13308.3 13731.0 14475.3 14559.6 14763.6 14781.7 15011.0 12694.4 13672.3 13738.3 13819.3 14162.5 13573.9 13829.3 13863.9 13948.4 14029.1 13522.6 13818.0 13818.0 14023.3 14164.2 10889.0 11137.6 11419.1 11631.5 11668.2 8423.5 9493.8 10931.8 11552.9 11591.0 20:01:07 INFO - PROCESS | 2996 | -------- Summary: end -------- 20:01:07 INFO - PROCESS | 2996 | 20:01:07 INFO - TEST-INFO | 2996: exit 0 20:01:07 INFO - TEST-OK | dromaeo_css | took 1114126ms 20:01:07 INFO - TEST-START | kraken 20:01:07 INFO - operating with platform_type : mac_ 20:01:07 INFO - Initialising browser for kraken test... 20:01:07 INFO - TEST-INFO | started process 3111 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmp569Xlm/profile http://localhost:56338/getInfo.html) 20:01:10 INFO - PROCESS | 3111 | __metrics Screen width/height:1600/1200 20:01:10 INFO - PROCESS | 3111 | colorDepth:24 20:01:10 INFO - PROCESS | 3111 | Browser inner width/height: 1024/689 20:01:10 INFO - PROCESS | 3111 | __metrics 20:01:10 ERROR - PROCESS | 3111 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 20:01:10 INFO - PROCESS | 3111 | 20:01:10 INFO - PROCESS | 3111 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x10C0011,name=PVRManager::Msg_NotifyVSync) Channel error: cannot send/recv 20:01:10 INFO - PROCESS | 3111 | 20:01:11 INFO - TEST-INFO | 3111: exit 0 20:01:11 INFO - Browser initialized. 20:01:11 INFO - Running cycle 1/1 for kraken test... 20:01:11 INFO - TEST-INFO | started process 3113 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmp569Xlm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:01:23 INFO - PROCESS | 3113 | RSS: Main: 244350976 20:01:23 INFO - PROCESS | 3113 | 20:01:26 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56338/tests/kraken/driver.html?testName=audio-beat-detection) 20:01:27 INFO - PROCESS | 3113 | RSS: Main: 256991232 20:01:27 INFO - PROCESS | 3113 | 20:01:29 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56338/tests/kraken/driver.html?testName=audio-dft) 20:01:30 INFO - PROCESS | 3113 | RSS: Main: 265650176 20:01:30 INFO - PROCESS | 3113 | 20:01:33 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56338/tests/kraken/driver.html?testName=audio-fft) 20:01:34 INFO - PROCESS | 3113 | RSS: Main: 263860224 20:01:34 INFO - PROCESS | 3113 | 20:01:36 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56338/tests/kraken/driver.html?testName=audio-oscillator) 20:01:36 INFO - PROCESS | 3113 | RSS: Main: 265195520 20:01:36 INFO - PROCESS | 3113 | 20:01:38 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56338/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:01:39 INFO - PROCESS | 3113 | RSS: Main: 265699328 20:01:39 INFO - PROCESS | 3113 | 20:01:42 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56338/tests/kraken/driver.html?testName=imaging-darkroom) 20:01:43 INFO - PROCESS | 3113 | RSS: Main: 266297344 20:01:43 INFO - PROCESS | 3113 | 20:01:46 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56338/tests/kraken/driver.html?testName=imaging-desaturate) 20:01:46 INFO - PROCESS | 3113 | RSS: Main: 267796480 20:01:46 INFO - PROCESS | 3113 | 20:01:49 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56338/tests/kraken/driver.html?testName=json-parse-financial) 20:01:50 INFO - PROCESS | 3113 | RSS: Main: 267968512 20:01:50 INFO - PROCESS | 3113 | 20:01:51 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56338/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:01:52 INFO - PROCESS | 3113 | RSS: Main: 267460608 20:01:52 INFO - PROCESS | 3113 | 20:01:54 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56338/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:01:55 INFO - PROCESS | 3113 | RSS: Main: 267464704 20:01:55 INFO - PROCESS | 3113 | 20:01:57 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56338/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:01:58 INFO - PROCESS | 3113 | RSS: Main: 267771904 20:01:58 INFO - PROCESS | 3113 | 20:02:01 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56338/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:02:01 INFO - PROCESS | 3113 | RSS: Main: 268214272 20:02:01 INFO - PROCESS | 3113 | 20:02:04 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56338/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:02:05 INFO - PROCESS | 3113 | RSS: Main: 268189696 20:02:05 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | Cycle 1(1): loaded http://localhost:56338/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56338/tests/kraken/driver.html?testName=ai-astar) 20:02:07 INFO - PROCESS | 3113 | RSS: Main: 269963264 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | __start_tp_report 20:02:07 INFO - PROCESS | 3113 | _x_x_mozilla_page_load 20:02:07 INFO - PROCESS | 3113 | _x_x_mozilla_page_load_details 20:02:07 INFO - PROCESS | 3113 | |i|pagename|runs| 20:02:07 INFO - PROCESS | 3113 | |0;ai-astar;109;115;117;114;116;115;117;118;115;133 20:02:07 INFO - PROCESS | 3113 | |1;audio-beat-detection;142;112;166;158;140;163;167;114;163;110 20:02:07 INFO - PROCESS | 3113 | |2;audio-dft;192;201;226;256;203;202;202;197;199;193 20:02:07 INFO - PROCESS | 3113 | |3;audio-fft;71;92;94;177;84;81;60;64;133;58 20:02:07 INFO - PROCESS | 3113 | |4;audio-oscillator;69;73;122;71;70;77;111;69;72;111 20:02:07 INFO - PROCESS | 3113 | |5;imaging-gaussian-blur;91;87;89;87;90;104;120;87;88;86 20:02:07 INFO - PROCESS | 3113 | |6;imaging-darkroom;99;99;100;99;101;113;170;116;168;165 20:02:07 INFO - PROCESS | 3113 | |7;imaging-desaturate;91;89;145;124;91;91;93;99;92;92 20:02:07 INFO - PROCESS | 3113 | |8;json-parse-financial;56;59;58;58;59;59;58;58;59;58 20:02:07 INFO - PROCESS | 3113 | |9;json-stringify-tinderbox;68;70;74;79;79;77;71;91;74;73 20:02:07 INFO - PROCESS | 3113 | |10;stanford-crypto-aes;85;88;89;87;88;90;87;83;92;110 20:02:07 INFO - PROCESS | 3113 | |11;stanford-crypto-ccm;124;149;154;160;157;155;168;176;178;134 20:02:07 INFO - PROCESS | 3113 | |12;stanford-crypto-pbkdf2;186;172;171;199;237;184;173;175;171;172 20:02:07 INFO - PROCESS | 3113 | |13;stanford-crypto-sha256-iterative;64;85;92;66;66;79;82;65;62;78 20:02:07 INFO - PROCESS | 3113 | __end_tp_report 20:02:07 INFO - PROCESS | 3113 | __start_cc_report 20:02:07 INFO - PROCESS | 3113 | _x_x_mozilla_cycle_collect,972 20:02:07 INFO - PROCESS | 3113 | __end_cc_report 20:02:07 INFO - PROCESS | 3113 | __startTimestamp1472612527476__endTimestamp 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | ------- Summary: start ------- 20:02:07 INFO - PROCESS | 3113 | Number of tests: 14 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#0] ai-astar Cycles:10 Average:116.90 Median:116.50 stddev:6.17 (5.3%) stddev-sans-first:5.85 20:02:07 INFO - PROCESS | 3113 | Values: 109.0 115.0 117.0 114.0 116.0 115.0 117.0 118.0 115.0 133.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:160.50 stddev:23.64 (14.7%) stddev-sans-first:25.06 20:02:07 INFO - PROCESS | 3113 | Values: 142.0 112.0 166.0 158.0 140.0 163.0 167.0 114.0 163.0 110.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#2] audio-dft Cycles:10 Average:207.10 Median:202.00 stddev:19.58 (9.7%) stddev-sans-first:19.99 20:02:07 INFO - PROCESS | 3113 | Values: 192.0 201.0 226.0 256.0 203.0 202.0 202.0 197.0 199.0 193.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#3] audio-fft Cycles:10 Average:91.40 Median:76.00 stddev:37.26 (49.0%) stddev-sans-first:38.78 20:02:07 INFO - PROCESS | 3113 | Values: 71.0 92.0 94.0 177.0 84.0 81.0 60.0 64.0 133.0 58.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#4] audio-oscillator Cycles:10 Average:84.50 Median:70.50 stddev:21.16 (30.0%) stddev-sans-first:21.68 20:02:07 INFO - PROCESS | 3113 | Values: 69.0 73.0 122.0 71.0 70.0 77.0 111.0 69.0 72.0 111.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:87.50 stddev:10.86 (12.4%) stddev-sans-first:11.49 20:02:07 INFO - PROCESS | 3113 | Values: 91.0 87.0 89.0 87.0 90.0 104.0 120.0 87.0 88.0 86.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#6] imaging-darkroom Cycles:10 Average:123.00 Median:169.00 stddev:31.43 (18.6%) stddev-sans-first:32.11 20:02:07 INFO - PROCESS | 3113 | Values: 99.0 99.0 100.0 99.0 101.0 113.0 170.0 116.0 168.0 165.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#7] imaging-desaturate Cycles:10 Average:100.70 Median:91.50 stddev:18.67 (20.4%) stddev-sans-first:19.47 20:02:07 INFO - PROCESS | 3113 | Values: 91.0 89.0 145.0 124.0 91.0 91.0 93.0 99.0 92.0 92.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:58.50 stddev:0.92 (1.6%) stddev-sans-first:0.53 20:02:07 INFO - PROCESS | 3113 | Values: 56.0 59.0 58.0 58.0 59.0 59.0 58.0 58.0 59.0 58.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#9] json-stringify-tinderbox Cycles:10 Average:75.60 Median:75.50 stddev:6.54 (8.7%) stddev-sans-first:6.33 20:02:07 INFO - PROCESS | 3113 | Values: 68.0 70.0 74.0 79.0 79.0 77.0 71.0 91.0 74.0 73.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:88.00 stddev:7.49 (8.5%) stddev-sans-first:7.73 20:02:07 INFO - PROCESS | 3113 | Values: 85.0 88.0 89.0 87.0 88.0 90.0 87.0 83.0 92.0 110.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#11] stanford-crypto-ccm Cycles:10 Average:155.50 Median:158.50 stddev:17.01 (10.7%) stddev-sans-first:13.70 20:02:07 INFO - PROCESS | 3113 | Values: 124.0 149.0 154.0 160.0 157.0 155.0 168.0 176.0 178.0 134.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.00 Median:179.50 stddev:20.73 (11.5%) stddev-sans-first:21.97 20:02:07 INFO - PROCESS | 3113 | Values: 186.0 172.0 171.0 199.0 237.0 184.0 173.0 175.0 171.0 172.0 20:02:07 INFO - PROCESS | 3113 | 20:02:07 INFO - PROCESS | 3113 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:78.50 stddev:10.56 (13.4%) stddev-sans-first:10.57 20:02:07 INFO - PROCESS | 3113 | Values: 64.0 85.0 92.0 66.0 66.0 79.0 82.0 65.0 62.0 78.0 20:02:07 INFO - PROCESS | 3113 | -------- Summary: end -------- 20:02:07 INFO - PROCESS | 3113 | 20:02:08 INFO - TEST-INFO | 3113: exit 0 20:02:08 INFO - TEST-OK | kraken | took 60470ms 20:02:08 INFO - SUITE-END | took 1174s 20:02:08 INFO - Completed test suite (00:19:34) 20:02:08 INFO - javascript benchmark 20:02:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7377.134754111213, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3398.4732366483204, 3413.402654535456, 3416.1288871337806, 3451.4650229214467, 3453.6201017888034, 5814.534837213024, 5841.474267315941, 5843.0, 5881.67650779207, 5916.940830591693, 5829.562782791287, 5883.0881213411885, 5917.319508256552, 5919.408059194078, 5964.761409543624, 5233.1103712368795, 5316.867078323035, 5358.517733403993, 5386.380566234894, 5387.164989426626, 5193.221016847466, 5259.86850328741, 5297.682139071649, 5392.460753924595, 5399.1091469907415, 5286.392064912527, 5311.097113490678, 5324.387695415038, 5332.386775520807, 5383.811566595189, 5312.60155488336, 5318.627696061278, 5325.3875804282225, 5341.305630268079, 5391.487808658171, 5130.281760553526, 5218.13900706383, 5270.604704647167, 5324.5740340772645, 5326.866828329284, 5782.190493330937, 5851.32709738377, 5888.440598143169, 5908.20239267702, 5934.347221805641, 6162.260528736536, 6224.937750622526, 6256.124142620037, 6259.499240060784, 6315.905261421082, 6095.287210409396, 6138.508919286446, 6169.475594574466, 6208.068789681539, 6287.937120628781, 5887.882242355129, 6009.489193418521, 6010.218671572755, 6043.395660434009, 6084.330723620398, 6324.0, 6361.650109243943, 6375.681215939185, 6379.808605741835, 6415.615063096229, 6101.298350689639, 6114.814446323802, 6235.625862448268, 6249.531285153632, 6269.62382257066, 6179.567430279927, 6264.686765661699, 6280.780172693934, 6281.591696539802, 6288.591241569284, 5626.943730562642, 5871.735771890189, 6207.534434917399, 6207.596506227081, 6265.028920517327, 6129.601575897642, 6141.539384546177, 6174.289956654952, 6181.783637572664, 6259.467945224706, 4212.3681447782465, 4341.891452713644, 4781.019890922353, 5015.1975683890405, 5059.038782631288, 4452.688311818207, 4464.776761161929, 4508.977455112769, 4528.932066019012, 4552.2488789350045, 5635.943640563624, 5916.585838991229, 6019.307779605401, 6066.75732970685, 6101.908471372933, 6198.938010619926, 6242.063690446469, 6311.779087731999, 6316.084167795644, 6329.303776584572, 6030.849228769228, 6048.60684055535, 6054.788082417094, 6073.544484163705, 6086.908696369558, 5384.488473595002, 5468.316460442445, 5540.279763630702, 5560.694161821139, 5595.608307418442, 6338.0, 6446.387593178641, 6457.644829534421, 6466.0947467355545, 6491.253505846699, 6255.0, 6263.029230469285, 6297.874042519215, 6304.3380445054445, 6358.109864618865, 5344.9732751336, 6191.84520386994, 6328.46208072319, 6442.291347951816, 6446.806595802133, 6162.784302549389, 6249.562530622995, 6260.718267677874, 6281.623102613858, 6283.622982621057, 6235.158253635701, 6266.592671476339, 6266.905996409966, 6271.874562508633, 6273.74905003813, 6115.327313995368, 6164.445199932027, 6227.283862355887, 6237.126802247598, 6243.594166379171, 4867.0, 6107.511399088151, 6134.754609815736, 6152.569320147547, 6206.10011548332, 6023.66869822164, 6066.393360663898, 6133.0, 6139.416755408229, 6149.692515374303, 6096.207493026056, 6150.708351246289, 6219.937800621936, 6220.160278362363, 6221.595596286227, 6127.754889804358, 6137.162486020116, 6143.72682107623, 6147.877042459215, 6151.8926593213655], "value": 5776.566695505203, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.2104143996647, 517.1699422427005, 522.0967725834306, 524.8871492629085, 529.9903683482964, 32339.838300808493, 39479.407808882854, 39509.60490395095, 39595.81212563621, 39629.40555891664, 32816.0, 40908.70458601849, 40959.180816383654, 40959.38560921581, 41098.58901410985, 9428.71713848584, 9640.855387169198, 9642.13220810127, 9647.71056868295, 9686.806263874718, 9542.713718588453, 9606.039396060372, 9638.37350572214, 9672.22622190223, 9680.370775899579, 9632.566534505964, 9657.13085822276, 9694.903050969506, 9695.127438530531, 9699.224062075016, 9629.133377995979, 9672.0, 9673.419594824332, 9687.273454490942, 9688.951555242249, 9496.0, 9566.66516671913, 9582.281328900292, 9584.329096963216, 9602.183814375787, 39004.804975975225, 39224.41163382552, 39227.01932451712, 39428.0, 39528.60471395307, 3005.4740420426556, 3332.3168750406107, 3387.5596172497417, 3513.8067406292644, 3575.964240357589, 29480.705192948226, 31145.688543114506, 31196.908108216332, 31261.531077033862, 31299.84350078258, 3157.715805577509, 3233.547303377529, 3326.4012477753995, 3386.0857568456627, 3390.69483746464, 484242.0, 484596.0, 486950.0, 490131.0, 500090.99999999633, 41550.79224603918, 58369.4163058364, 59237.70381148153, 59348.81302374015, 59836.0, 59691.104633430536, 63455.0, 64217.14697118188, 64379.67810161013, 64530.70938581202, 15961.0, 16093.597660058593, 16136.0, 16155.949863259086, 16215.35138594443, 12334.876651233373, 12385.256884586954, 12413.75172496545, 12429.37853107341, 12712.364381780873, 18538.073096345128, 18772.249110035445, 18791.906040469985, 18824.435266942015, 18829.34097306588, 7993.160718124629, 8103.10865804761, 8122.7157049502985, 8200.917990820135, 8229.83540329202, 858.9828203436022, 974.3277138774223, 984.4683870709737, 996.3175224970918, 1014.5028935821542, 27606.861965690445, 28425.00512482053, 28680.71319286822, 28881.277968050967, 29469.410611787644, 1021.5198856537415, 1037.901399367059, 1045.2892033417197, 1048.580567772897, 1101.5043230546125, 15206.0, 15436.228188590525, 15468.149251791034, 15730.134842583539, 15827.52517424479, 627941.0, 635475.0, 647496.0, 653952.0, 841770.0, 57603.135952960736, 57637.0, 57668.8466230673, 57762.0, 57870.421295787346, 1357.2738584857054, 1373.478078330228, 1397.832260128791, 1398.8041674165624, 1412.6468382904275, 30121.925646325963, 30858.845705771328, 30954.0713778587, 31356.843215783774, 31489.8425507871, 49695.751521242164, 50138.0, 50218.0, 50224.497755021985, 50279.245811312125, 500351.49824250647, 548217.0, 562564.0, 567592.0, 569040.0, 26857.194284171506, 26911.461770764865, 27088.1873543794, 27197.86401067982, 27212.0, 44187.89530261769, 46265.76867115643, 46305.53694463012, 46492.0, 46714.29928551142, 36705.63294367129, 37514.62485375111, 37738.0, 38834.0291492715, 39009.6099039006, 37970.62029379671, 38057.238855223295, 38431.0, 39039.0, 39140.80429597948, 31948.52077218797, 36357.45463818098, 36846.26307473889, 36890.81554592317, 37372.06569835667, 30004.549931751477, 30536.694633053386, 34131.82934085314, 34152.48771268383, 34226.315473690884, 24669.876650616632, 25100.874495627406, 25307.367315816515, 25725.3568660785, 25765.3558661035], "value": 19838.046440149665, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.0830079926603, 337.94086034943876, 340.6391466092959, 345.8080765175328, 369.91307042844926, 20054.89972550137, 20169.394918152444, 20688.27591034314, 20869.26957556488, 21075.73545587266, 20432.387028389134, 20478.18087276507, 21088.789112108876, 21237.893810530924, 21380.037898294577, 5223.373195216579, 5862.384449632791, 5983.222181116449, 6060.151578778975, 6070.392960703916, 5916.852078698024, 5985.700714964256, 5991.820245392645, 6015.127806468059, 6015.248093988252, 5838.503727183194, 5911.645301281916, 5930.169776231331, 5940.435658612447, 5957.136215248786, 5732.5987180897355, 5898.675572843492, 5912.793052243193, 5918.201042859224, 5938.940610593903, 4904.950950490521, 5143.588512918957, 5148.099082660519, 5199.610029247784, 5212.530872221519, 25411.237662870142, 25665.0, 25876.87061564699, 25888.223353299432, 26059.478810423687, 2006.7290915726483, 2213.3359992002333, 2616.607508873665, 2792.287966568512, 2858.299716569433, 8171.836563268701, 8371.623276952552, 9059.229965452945, 10066.647667331681, 10177.89822101777, 1812.9728054079067, 1852.2868695552174, 1870.3827736846808, 1956.4130760771711, 1971.822535971755, 116633.41683291529, 119725.0, 120761.0, 121617.56764864741, 122602.0, 11277.887221127683, 11460.484278207501, 11536.557930258718, 11622.907876582127, 11641.301521908712, 14366.0, 14377.281135943162, 14385.928070359581, 14396.496122635866, 14399.78400323996, 6178.752849885954, 7271.927280727231, 8506.829863402822, 8513.531755753493, 8638.870416943751, 7315.4147668186415, 8190.959045204855, 8383.371247156487, 8646.870296945552, 8648.610812513452, 4387.100644367897, 4426.22541055314, 4469.597736203757, 4490.169318676056, 4566.13243483737, 1895.8198971097725, 1936.7966363531907, 1940.4857712706144, 1949.32356678376, 1969.4190213886939, 831.4595512916532, 889.9911000889908, 895.4940458640847, 912.2975309012023, 975.3465178330536, 20129.295474658316, 20792.792072079388, 20879.060442280133, 20949.162033518794, 21235.256766013423, 780.4380845791021, 850.6937502499095, 903.2864037410503, 952.7522844060455, 996.5117092624562, 8460.238578527962, 8954.731358059138, 11199.160062995308, 11202.49588768491, 11208.775824483628, 534191.0, 538279.3086034545, 538776.0, 554143.0, 558231.0, 62706.68646656738, 73740.63129684317, 76523.0, 128101.71898281084, 131182.0, 891.5497673674882, 944.5371767833849, 991.861139440479, 1009.6869970309227, 1016.8627235323285, 18069.909650451664, 18082.457526274233, 18641.44075677732, 18992.430227092656, 19174.0, 37518.06204844901, 50518.98962020665, 51360.229596555335, 52365.47634523606, 52516.94966100733, 79837.6008119979, 80583.0, 80737.0, 81117.59441202757, 82735.586322068, 7007.614581198083, 7366.594837284001, 7421.035265415678, 7440.479166458296, 7650.273224043707, 11719.941400292944, 13232.625680451423, 13236.073474856668, 13426.925845932106, 13469.326533673082, 11392.658220253406, 11471.959930618226, 11475.942620287178, 11476.598319058792, 11507.367094809866, 10359.94820025895, 11129.054030407506, 11499.252548584314, 11644.123746034427, 11725.824112638487, 11448.313101213955, 11626.941865290957, 11840.05279577649, 11954.880451195377, 11986.820197697216, 7284.064619264609, 7471.551706897603, 7516.210797866145, 7598.12621548529, 7665.578393188428, 11137.387443690675, 11299.265547739371, 11435.59975400857, 11574.6527604172, 11638.941805290919], "value": 9355.926210478268, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1475.3877140986488, 1515.9393624255026, 1547.7291473992063, 1715.9399421020266, 1868.3180639066736, 6607.0, 8509.361797865155, 8689.869651955225, 9083.27333813295, 9105.772355691102, 8700.608472618731, 8888.377813553037, 8900.955495222515, 8925.598348074336, 8933.821323573524, 2369.9881500592473, 2397.61638137898, 2402.843815152018, 2409.963850542243, 2422.551827911842, 2377.8454400464, 2419.588669926117, 2423.200343886522, 2428.599281118622, 2437.1591800828774, 2384.5707772600927, 2394.6288325309606, 2399.688040554734, 2399.940001499968, 2423.491066875958, 1954.7067939809, 2371.395294199985, 2392.7846493815555, 2396.1190465484683, 2401.4236583220077, 2298.0118549023914, 2373.715154181509, 2381.7141942966955, 2401.399650087478, 2477.9504409911883, 7198.0, 7644.388448924128, 8862.734117976472, 8980.8652870207, 8984.191422771983, 5292.338457692788, 5455.49973757216, 5466.098093814516, 5476.479734425223, 5482.259894914176, 3627.764195327322, 3678.4666223397726, 3695.8521659133603, 3722.8324725387424, 3727.0682329417646, 1085.625459216569, 1095.6603452929617, 1096.8080585897435, 1098.2477003252798, 1112.104755671684, 11742.647720568397, 11756.05951523876, 11773.529058837636, 11903.107266955012, 11948.581799637059, 4733.569101168246, 4744.169770290186, 4797.880052998703, 4887.0, 4970.850874473772, 9397.731306273703, 10015.899841001643, 10116.140128089048, 10134.949325253474, 10150.746231344276, 4912.4350699669285, 5426.457354264542, 5450.83647490568, 5469.261649677323, 5501.614886957955, 4239.766812825263, 4494.303382975579, 4811.326414302001, 4894.853154405374, 5026.1204289249235, 3984.940225896614, 4011.839526418969, 4114.526829414655, 4122.958770412318, 4127.690423218271, 1315.1122991980376, 1325.5493132335052, 1327.4889167670517, 1336.6190635668788, 1338.1569611144917, 757.8934755257276, 911.7902882337099, 914.5655813488539, 916.6516723645105, 932.0080311330369, 5705.5435565154685, 5840.269966254218, 5974.103884417372, 5990.700464976734, 6123.9387606124255, 439.89882327064953, 448.26484565312916, 454.2595569222147, 959.5681943125621, 984.2765567308023, 3922.8626998054933, 4009.7970608817236, 4029.234445455354, 4042.413849991735, 4047.0894048839364, 8795.0, 10146.137578305927, 10808.62169824068, 10924.180686448548, 11049.834252486218, 10335.689929302132, 10756.838647420294, 10830.241883068267, 10842.295250808673, 10846.620368287073, 8050.5169689818795, 8098.975256185953, 8334.083250842403, 8350.582470876554, 8359.749207523662, 5873.2658417697785, 5874.265716785402, 5999.130126131774, 6037.577369584263, 6178.9382106180165, 10059.647912323033, 10251.28241023151, 10325.690229293134, 10341.172706183637, 10392.168626510012, 10132.848007280045, 10154.593816247267, 10332.638357657446, 10418.635347762793, 10492.0, 6984.511084224056, 7426.108866936002, 7500.1374841893885, 7671.0, 7732.845343092994, 8532.573371331533, 8552.187542183732, 8577.013643431086, 8603.268722158688, 8603.39776215684, 6501.544891857525, 6603.877340851971, 7451.3293803557945, 7512.32389084985, 7700.0, 6728.865422691617, 7453.962730186315, 7502.812429689301, 7508.549487030796, 7508.887366689613, 6997.300269972962, 7020.262872398324, 7092.361687448154, 7108.9289107110335, 7111.0, 4408.0, 5025.572826309804, 5073.898522029613, 5118.309028281284, 5126.615503837153, 4598.241290187082, 4819.445763737215, 4878.707277563358, 4955.950440495693, 5156.664816786897], "value": 4577.764408295682, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [239.40866060829757, 294.9542820862767, 313.6001597962598, 318.39663837028843, 321.45673811258956, 4596.931046034308, 4864.318995340646, 5518.9724051379735, 5666.008448521513, 5782.421757824215, 4746.406699162605, 5394.1908713692965, 5425.2910333245, 5506.366767821702, 5611.971940140304, 3057.2420858006626, 3669.2754405429405, 4045.4336392905016, 4132.855350062749, 4232.407462955189, 3874.341361968472, 4247.704450142705, 4269.338252570857, 4277.096691972074, 4299.914001719963, 3677.926441471169, 4283.91432171358, 4288.742675439484, 4291.648130838791, 4318.892027699301, 4093.9385909211533, 4242.342436922282, 4262.531121576624, 4272.636825869805, 4347.369631403445, 3069.938601227985, 3369.427197376452, 3607.494950706903, 3616.963830361689, 3670.6696397324104, 5424.05079111154, 5456.317960254968, 5535.169724541311, 5537.58468114889, 5588.546977785764, 4435.004248088341, 4559.589636932659, 4735.881602959919, 4763.761811909425, 4965.255211718235, 5395.811146609889, 5901.645901245939, 5981.641101533878, 5994.910076348859, 6024.21685180928, 3208.5347624594538, 3804.7444343366624, 4062.7156099073077, 4069.2878746219294, 4081.9387709184384, 5920.566071678416, 5978.192943952554, 6212.627242365473, 6280.937190628127, 6337.302896681362, 3417.4190387633666, 3492.161881148557, 4054.9862534366407, 4122.175564887035, 4137.648299894482, 4185.497740271187, 4210.873673789792, 4210.915596779552, 4210.957890421118, 4236.046889449849, 4001.4598029265676, 4662.463816661059, 4730.314104454835, 4941.802327906844, 4977.271591987626, 5242.7378631068295, 5500.724963751796, 5967.791127310524, 5989.9101513477335, 6050.0, 1382.4262930883572, 1431.4560467022466, 1453.4985430026625, 1454.7454195515743, 1456.2791418248034, 801.8837268596021, 816.8856360109589, 850.9361797865184, 862.5213006781171, 905.456725964416, 5490.780368785204, 5775.537956963432, 5788.716349901127, 5823.708814559255, 5846.6784326862435, 5670.7731690732735, 6012.699365031731, 6054.576179667469, 6271.43557079865, 6389.233292004989, 5544.500994910478, 5785.623934444247, 5798.971005144947, 5832.50423713989, 5842.678652674143, 2537.6320433537035, 2796.783399221345, 2834.773218142585, 3286.917827054343, 3311.791196213365, 5387.919181212285, 6205.441510264098, 6385.45723613498, 6520.250171230275, 6562.571848416116, 6316.147150238351, 6376.968115159488, 6405.327440618668, 6412.749513844756, 6442.903356449657, 5978.133170690226, 6181.258249010148, 6501.34986501365, 6527.608343499405, 6575.178102737158, 5392.406835248198, 5848.64908105515, 6107.267127944675, 6198.783042593487, 6250.968745156245, 5178.8964220716125, 5716.5998380113, 5786.826395208151, 5826.912596310974, 6184.093476066421, 5799.82600521985, 6062.347363884493, 6249.437910325579, 6400.967995159995, 6503.6097834130105, 3876.7673939563715, 4079.9196606561854, 4182.2681030820095, 4203.411522386929, 4208.284591619493, 5720.456556627114, 6141.631502109888, 6156.122382673328, 6186.979148440637, 6198.0, 5101.744912754273, 5378.0, 5505.0, 6046.879062418639, 6049.425304596057, 4872.976674898169, 5078.619103567174, 5203.479652034766, 6066.787662431793, 6146.262448506208, 5604.383517812956, 5632.49307562305, 5970.492508136857, 5977.0, 5986.820395388146, 5298.417174110922, 5962.52299816005, 6019.668918209365, 6127.448529632176, 6144.525313924715, 5325.2810870531985, 5824.301083869963, 5955.3498693081165, 5972.402759723993, 5996.8800623988145], "value": 4322.298853395643, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [639.0829160155178, 703.9084918960535, 745.9067616547932, 767.5855038279329, 789.565738843636, 15075.457267542119, 15583.909126361144, 15862.52412427629, 15872.365105395771, 15939.521814345573, 14409.0, 14505.564833055, 14570.854291457083, 14589.999999999973, 14612.707745845077, 662.1623646087703, 806.330745481252, 823.7734622978669, 836.0260296754274, 837.204655577201, 850.6512329944724, 853.9274161696263, 854.3464249848871, 856.3448961544424, 856.8928883889514, 880.6697488441835, 905.5608030105394, 908.8000639859221, 908.9728606674448, 910.0171814440389, 901.4951627088851, 906.7007887397143, 907.768519027647, 908.454927043775, 913.3515204205022, 673.0509980926919, 673.192169396726, 674.6053558668185, 675.7769935921183, 677.072410797211, 14568.562943111723, 14582.052166609243, 14597.343119559646, 14605.050671706305, 14611.63470913225, 11602.709932251677, 14265.144091354448, 14276.714465710731, 14345.569632911029, 14418.134911905214, 11804.527818887234, 11883.94058029713, 11896.583619573274, 11904.702382440508, 11965.581204657883, 3782.0733920189728, 3785.7160712946365, 3798.164403831153, 3806.4290356446477, 3953.920921581581, 108420.0, 108578.0, 108652.4567377166, 109452.0, 111410.32884506739, 9918.760154980628, 10491.527881245362, 10823.67528974132, 10911.618093366853, 11216.775664486664, 22232.888835555717, 22372.888135559217, 22375.88812055962, 22377.55244895093, 22398.32805015852, 8041.8793718094275, 8063.0, 8133.471324363897, 8216.233509795422, 8376.329893608498, 4520.028193938253, 4526.502084770673, 4531.8187272509385, 4609.792559334838, 4674.719516829001, 3507.631698671654, 3593.443016332473, 3599.7840129591787, 3619.49327094207, 3637.181634132352, 625.671522450717, 630.782380078872, 661.3386613386614, 671.828683685657, 719.7552832037028, 575.2033433694363, 577.2841676321415, 595.5830918357167, 621.8010236724296, 644.8387903024244, 7189.96405017982, 8388.203120703523, 8955.910440895508, 9184.678536251198, 9376.812463750686, 508.5143687778163, 526.7103093298671, 597.978831549369, 629.2920464477463, 668.5654324688991, 4129.236091323115, 4287.6998610097, 4344.761742903321, 4541.523140070245, 4605.838795642137, 89482.55258723664, 115919.84080159091, 118309.0, 137552.0, 151160.0, 117972.41013794875, 119494.40252798681, 119581.0, 120146.19049761737, 120305.0, 65825.34174658288, 65968.0, 80661.9503537237, 85806.0, 101476.98523014675, 8629.525376104124, 9430.95284523573, 9476.241900647794, 9525.476098814355, 9663.130318271109, 47789.0, 53308.93382132258, 55261.44738552563, 57891.13163302638, 57941.710291448275, 76691.0, 77485.61257193678, 78004.60997694975, 81621.18378816136, 82810.5859470723, 13029.47882084706, 13111.344432778133, 13151.473941042252, 13308.268045257604, 13731.038827281995, 14475.348609312818, 14559.566391344004, 14763.557093287218, 14781.704365912836, 15011.0, 12694.365281736062, 13672.316384180553, 13738.313084345542, 13819.309034548434, 14162.50431234943, 13573.932130339284, 13829.308534573433, 13863.930680346533, 13948.372323245276, 14029.088109272865, 13522.594322170351, 13818.0, 13818.0, 14023.298835058002, 14164.220967846868, 10888.965548272901, 11137.579217043605, 11419.0864730823, 11631.47658355359, 11668.183227174355, 8423.536705481258, 9493.765810444598, 10931.83602245983, 11552.884471155181, 11591.0], "value": 7597.3094228053815, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1597.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 115.0, 117.0, 114.0, 116.0, 115.0, 117.0, 118.0, 115.0, 133.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 166.0, 158.0, 140.0, 163.0, 167.0, 114.0, 163.0, 110.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 201.0, 226.0, 256.0, 203.0, 202.0, 202.0, 197.0, 199.0, 193.0], "value": 207.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 92.0, 94.0, 177.0, 84.0, 81.0, 60.0, 64.0, 133.0, 58.0], "value": 91.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 122.0, 71.0, 70.0, 77.0, 111.0, 69.0, 72.0, 111.0], "value": 84.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 89.0, 87.0, 90.0, 104.0, 120.0, 87.0, 88.0, 86.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 100.0, 99.0, 101.0, 113.0, 170.0, 116.0, 168.0, 165.0], "value": 123.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 145.0, 124.0, 91.0, 91.0, 93.0, 99.0, 92.0, 92.0], "value": 100.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 58.0, 58.0, 59.0, 59.0, 58.0, 58.0, 59.0, 58.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 74.0, 79.0, 79.0, 77.0, 71.0, 91.0, 74.0, 73.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 89.0, 87.0, 88.0, 90.0, 87.0, 83.0, 92.0, 110.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 149.0, 154.0, 160.0, 157.0, 155.0, 168.0, 176.0, 178.0, 134.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 172.0, 171.0, 199.0, 237.0, 184.0, 173.0, 175.0, 171.0, 172.0], "value": 184.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 85.0, 92.0, 66.0, 66.0, 79.0, 82.0, 65.0, 62.0, 78.0], "value": 73.9, "unit": "score"}]}]} 20:02:08 INFO - Return code: 0 20:02:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:02:08 ERROR - # TBPL SUCCESS # 20:02:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:02:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:02:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:02:08 INFO - 2016-08-30 20:02:08.779 screenresolution[3124:36769] starting screenresolution argv=screenresolution get 20:02:08 INFO - 2016-08-30 20:02:08.781 screenresolution[3124:36769] Display 0: 1600x1200x32@60 20:02:08 INFO - 2016-08-30 20:02:08.787 screenresolution[3125:36772] starting screenresolution argv=screenresolution list 20:02:08 INFO - Available Modes on Display 0 20:02:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:02:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:02:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:02:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:02:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:02:09 INFO - Intel Iris: 20:02:09 INFO - Chipset Model: Intel Iris 20:02:09 INFO - Type: GPU 20:02:09 INFO - Bus: Built-In 20:02:09 INFO - VRAM (Dynamic, Max): 1536 MB 20:02:09 INFO - Vendor: Intel (0x8086) 20:02:09 INFO - Device ID: 0x0a2e 20:02:09 INFO - Revision ID: 0x0009 20:02:09 INFO - Displays: 20:02:09 INFO - Display: 20:02:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:02:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:02:09 INFO - Display Serial Number: 1600x1200 60 20:02:09 INFO - Main Display: Yes 20:02:09 INFO - Mirror: Off 20:02:09 INFO - Online: Yes 20:02:09 INFO - Rotation: Supported 20:02:09 INFO - Return code: 0 20:02:09 INFO - Running post-action listener: _resource_record_post_action 20:02:09 INFO - [mozharness: 2016-08-31 03:02:09.024170Z] Finished run-tests step (success) 20:02:09 INFO - Running post-run listener: _resource_record_post_run 20:02:09 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 0; Write bytes: 709463040; Read time: 0; Write time: 2025 20:02:09 INFO - TinderboxPrint: CPU usage
54.2% 20:02:09 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 20:02:09 INFO - TinderboxPrint: I/O write bytes / time
709,463,040 / 2,025 20:02:09 INFO - TinderboxPrint: CPU idle
2,217.3 (45.8%) 20:02:09 INFO - TinderboxPrint: CPU system
971.4 (20.1%) 20:02:09 INFO - TinderboxPrint: CPU user
1,652.8 (34.1%) 20:02:09 INFO - TinderboxPrint: Swap in / out
405,315,584 / 0 20:02:09 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 398500352; Write bytes: 393926656; Read time: 31058; Write time: 817 20:02:09 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 0; Write bytes: 298882048; Read time: 0; Write time: 1151 20:02:09 INFO - Running post-run listener: _upload_blobber_files 20:02:09 INFO - Blob upload gear active. 20:02:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:02:09 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 20:02:09 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'] 20:02:09 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 20:02:09 INFO - (blobuploader) - INFO - Open directory for files ... 20:02:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:02:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:02:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:02:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:02:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:02:10 INFO - (blobuploader) - INFO - Done attempting. 20:02:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:02:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:02:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:02:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:02:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:02:11 INFO - (blobuploader) - INFO - Done attempting. 20:02:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:02:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:02:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:02:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:02:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:02:14 INFO - (blobuploader) - INFO - Done attempting. 20:02:14 INFO - (blobuploader) - INFO - Iteration through files over. 20:02:14 INFO - Return code: 0 20:02:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:02:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:02:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f79c150add400e3c0b6720dd45ee3f9da1e1b3f2a3a97700f7c4288e0cfd176c06e50149384357a7589c0e869efbda888c20e63671a408a75d70abe3860c9ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ae6665db1ba1bd2a587bea842f83e3c1eb2cd9b3373c8b5986d911120505d8054d1314782a1d9acecbaf1e8dc51dae229fc22b870c963495559df8130f6a8f76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/666d26b9848c0a929bec1e91522721c1e70c6d022b928c75566287f0cdb17e9c9bc7a73b09331a65d3a3fd0f5d22a0179fdc7039f4081f5edbd2f80222abeee2"} 20:02:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:02:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:02:14 INFO - Contents: 20:02:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f79c150add400e3c0b6720dd45ee3f9da1e1b3f2a3a97700f7c4288e0cfd176c06e50149384357a7589c0e869efbda888c20e63671a408a75d70abe3860c9ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ae6665db1ba1bd2a587bea842f83e3c1eb2cd9b3373c8b5986d911120505d8054d1314782a1d9acecbaf1e8dc51dae229fc22b870c963495559df8130f6a8f76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/666d26b9848c0a929bec1e91522721c1e70c6d022b928c75566287f0cdb17e9c9bc7a73b09331a65d3a3fd0f5d22a0179fdc7039f4081f5edbd2f80222abeee2"} 20:02:14 INFO - Running post-run listener: copy_logs_to_upload_dir 20:02:14 INFO - Copying logs to upload dir... 20:02:14 INFO - mkdir: /builds/slave/test/build/upload/logs 20:02:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.837732 ========= master_lag: 11.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-08-30 20:02:25.184677) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 20:02:25.188949) ========= 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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f79c150add400e3c0b6720dd45ee3f9da1e1b3f2a3a97700f7c4288e0cfd176c06e50149384357a7589c0e869efbda888c20e63671a408a75d70abe3860c9ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ae6665db1ba1bd2a587bea842f83e3c1eb2cd9b3373c8b5986d911120505d8054d1314782a1d9acecbaf1e8dc51dae229fc22b870c963495559df8130f6a8f76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/666d26b9848c0a929bec1e91522721c1e70c6d022b928c75566287f0cdb17e9c9bc7a73b09331a65d3a3fd0f5d22a0179fdc7039f4081f5edbd2f80222abeee2"} build_url:https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/PEGG-r2JQ9u5JryjWgt2UA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f79c150add400e3c0b6720dd45ee3f9da1e1b3f2a3a97700f7c4288e0cfd176c06e50149384357a7589c0e869efbda888c20e63671a408a75d70abe3860c9ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ae6665db1ba1bd2a587bea842f83e3c1eb2cd9b3373c8b5986d911120505d8054d1314782a1d9acecbaf1e8dc51dae229fc22b870c963495559df8130f6a8f76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/666d26b9848c0a929bec1e91522721c1e70c6d022b928c75566287f0cdb17e9c9bc7a73b09331a65d3a3fd0f5d22a0179fdc7039f4081f5edbd2f80222abeee2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 20:02:25.228421) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:02:25.228778) ========= 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.Vo1l4tdOcU/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.wzpyP687mJ/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.005394 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:02:25.279573) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 20:02:25.280034) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 20:02:25.280627) ========= ========= Total master_lag: 11.52 =========