builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0081 starttime: 1471524291.52 results: success (0) buildid: 20160816194715 builduid: 5e3a1b1e4c74453bb8c5864110661cfb revision: f0067001c059ff57d6927c6da5a1605f1d29a449 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:51.518691) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:51.519171) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:51.519473) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:51.547594) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:51.547986) ========= 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.reir3PrfXt/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.sQunNfDJDn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-18 05:44:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.99M=0.001s 2016-08-18 05:44:51 (9.99 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098668 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:51.669203) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:51.669576) ========= 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.reir3PrfXt/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.sQunNfDJDn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.021312 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:51.718467) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 05:44:51.718855) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev f0067001c059ff57d6927c6da5a1605f1d29a449 --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 f0067001c059ff57d6927c6da5a1605f1d29a449 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.reir3PrfXt/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.sQunNfDJDn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-18 05:44:51,797 truncating revision to first 12 chars 2016-08-18 05:44:51,797 Setting DEBUG logging. 2016-08-18 05:44:51,797 attempt 1/10 2016-08-18 05:44:51,797 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/f0067001c059?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 05:44:52,604 unpacking tar archive at: autoland-f0067001c059/testing/mozharness/ program finished with exit code 0 elapsedTime=1.085050 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 05:44:52.826525) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:52.826924) ========= script_repo_revision: f0067001c059ff57d6927c6da5a1605f1d29a449 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:52.827371) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:52.827669) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:44:52.846604) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-18 05:44:52.847028) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.reir3PrfXt/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.sQunNfDJDn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:44:53 INFO - MultiFileLogger online at 20160818 05:44:53 in /builds/slave/test 05:44:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 05:44:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:44:53 INFO - {'append_to_log': False, 05:44:53 INFO - 'base_work_dir': '/builds/slave/test', 05:44:53 INFO - 'blob_upload_branch': 'Autoland', 05:44:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:44:53 INFO - 'branch': 'Autoland', 05:44:53 INFO - 'buildbot_json_path': 'buildprops.json', 05:44:53 INFO - 'config_files': ('talos/mac_config.py',), 05:44:53 INFO - 'default_actions': ('clobber', 05:44:53 INFO - 'read-buildbot-config', 05:44:53 INFO - 'download-and-extract', 05:44:53 INFO - 'populate-webroot', 05:44:53 INFO - 'create-virtualenv', 05:44:53 INFO - 'install', 05:44:53 INFO - 'run-tests'), 05:44:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:44:53 INFO - 'download_minidump_stackwalk': True, 05:44:53 INFO - 'download_symbols': 'ondemand', 05:44:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:44:53 INFO - 'tooltool.py': '/tools/tooltool.py', 05:44:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:44:53 INFO - '/tools/misc-python/virtualenv.py')}, 05:44:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:44:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:44:53 INFO - 'installer_path': 'installer.exe', 05:44:53 INFO - 'log_level': 'info', 05:44:53 INFO - 'log_name': 'talos', 05:44:53 INFO - 'log_to_console': True, 05:44:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:44:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:44:53 INFO - 'opt_config_files': (), 05:44:53 INFO - 'pip_index': False, 05:44:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:44:53 INFO - 'cmd': ('bash', 05:44:53 INFO - '-c', 05:44:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:44:53 INFO - 'enabled': True, 05:44:53 INFO - 'halt_on_failure': False, 05:44:53 INFO - 'name': 'check_screen_resolution'},), 05:44:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:44:53 INFO - 'cmd': ('bash', 05:44:53 INFO - '-c', 05:44:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:44:53 INFO - 'enabled': True, 05:44:53 INFO - 'halt_on_failure': False, 05:44:53 INFO - 'name': 'check_screen_resolution'},), 05:44:53 INFO - 'run_cmd_checks_enabled': True, 05:44:53 INFO - 'sps_profile': False, 05:44:53 INFO - 'sps_profile_interval': 0, 05:44:53 INFO - 'suite': 'dromaeojs', 05:44:53 INFO - 'system_bits': '32', 05:44:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:44:53 INFO - 'title': 't-yosemite-r7-0081', 05:44:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:44:53 INFO - 'use_talos_json': True, 05:44:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:44:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:44:53 INFO - 'work_dir': 'build'} 05:44:53 INFO - [mozharness: 2016-08-18 12:44:53.043890Z] Running clobber step. 05:44:53 INFO - Running pre-action listener: _resource_record_pre_action 05:44:53 INFO - Running main action method: clobber 05:44:53 INFO - rmtree: /builds/slave/test/build 05:44:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:44:53 INFO - Running post-action listener: _resource_record_post_action 05:44:53 INFO - [mozharness: 2016-08-18 12:44:53.624415Z] Finished clobber step (success) 05:44:53 INFO - [mozharness: 2016-08-18 12:44:53.624655Z] Running read-buildbot-config step. 05:44:53 INFO - Running pre-action listener: _resource_record_pre_action 05:44:53 INFO - Running main action method: read_buildbot_config 05:44:53 INFO - Using buildbot properties: 05:44:53 INFO - { 05:44:53 INFO - "project": "", 05:44:53 INFO - "product": "firefox", 05:44:53 INFO - "script_repo_revision": "production", 05:44:53 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 05:44:53 INFO - "repository": "", 05:44:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 05:44:53 INFO - "buildid": "20160816194715", 05:44:53 INFO - "pgo_build": "False", 05:44:53 INFO - "basedir": "/builds/slave/test", 05:44:53 INFO - "buildnumber": 163, 05:44:53 INFO - "slavename": "t-yosemite-r7-0081", 05:44:53 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:44:53 INFO - "platform": "yosemite_r7", 05:44:53 INFO - "scheduler": "tests-autoland-macosx64-talos", 05:44:53 INFO - "branch": "autoland", 05:44:53 INFO - "repo_path": "integration/autoland", 05:44:53 INFO - "revision": "f0067001c059ff57d6927c6da5a1605f1d29a449", 05:44:53 INFO - "stage_platform": "macosx64", 05:44:53 INFO - "builduid": "5e3a1b1e4c74453bb8c5864110661cfb", 05:44:53 INFO - "slavebuilddir": "test" 05:44:53 INFO - } 05:44:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:44:53 INFO - Running post-action listener: _resource_record_post_action 05:44:53 INFO - [mozharness: 2016-08-18 12:44:53.630318Z] Finished read-buildbot-config step (success) 05:44:53 INFO - [mozharness: 2016-08-18 12:44:53.630558Z] Running download-and-extract step. 05:44:53 INFO - Running pre-action listener: _resource_record_pre_action 05:44:53 INFO - Running main action method: download_and_extract 05:44:53 INFO - mkdir: /builds/slave/test/build/tests 05:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:53 INFO - trying https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:44:53 INFO - Downloading https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:44:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 05:44:54 INFO - Downloaded 1199 bytes. 05:44:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:44:54 INFO - Using the following test package requirements: 05:44:54 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:44:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:44:54 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:44:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:44:54 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:44:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:44:54 INFO - u'jsshell-mac.zip'], 05:44:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:44:54 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:44:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:44:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:44:54 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:44:54 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:44:54 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:44:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:44:54 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:44:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:44:54 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:44:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:54 INFO - trying https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:44:54 INFO - Downloading https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:44:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:44:55 INFO - Downloaded 16333857 bytes. 05:44:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:45:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:02 INFO - trying https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:45:02 INFO - Downloading https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:45:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:45:03 INFO - Downloaded 16333857 bytes. 05:45:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:10 INFO - trying https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:45:10 INFO - Downloading https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:45:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:45:10 INFO - Downloaded 14413719 bytes. 05:45:10 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:11 INFO - trying https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:45:11 INFO - Downloading https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:45:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 05:45:14 INFO - Downloaded 111146416 bytes. 05:45:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:45:14 INFO - mkdir: /builds/slave/test/properties 05:45:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:45:14 INFO - Writing to file /builds/slave/test/properties/build_url 05:45:14 INFO - Contents: 05:45:14 INFO - build_url:https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:45:14 INFO - Running post-action listener: _resource_record_post_action 05:45:14 INFO - Running post-action listener: set_extra_try_arguments 05:45:14 INFO - [mozharness: 2016-08-18 12:45:14.788915Z] Finished download-and-extract step (success) 05:45:14 INFO - [mozharness: 2016-08-18 12:45:14.789042Z] Running populate-webroot step. 05:45:14 INFO - Running pre-action listener: _resource_record_pre_action 05:45:14 INFO - Running main action method: populate_webroot 05:45:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:45:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:45:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:45:14 INFO - '--tppagecycles', 05:45:14 INFO - '7'], 05:45:14 INFO - 'tests': ['tsvgm']}}, 05:45:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:45:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:45:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:45:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:45:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:45:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:45:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:14 INFO - 'talos_options': ['--disable-e10s'], 05:45:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:14 INFO - 'talos_options': ['--disable-e10s'], 05:45:14 INFO - 'tests': ['damp', 'tps']}, 05:45:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:14 INFO - 'tests': ['damp', 'tps']}, 05:45:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:45:14 INFO - 'tests': ['dromaeo_dom']}, 05:45:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:45:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:45:14 INFO - 'tests': ['basic_compositor_video']}, 05:45:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:45:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:45:14 INFO - 'tests': ['a11yr', 05:45:14 INFO - 'ts_paint', 05:45:14 INFO - 'tpaint', 05:45:14 INFO - 'sessionrestore', 05:45:14 INFO - 'sessionrestore_no_auto_restore', 05:45:14 INFO - 'tabpaint']}, 05:45:14 INFO - 'other-e10s': {'tests': ['a11yr', 05:45:14 INFO - 'ts_paint', 05:45:14 INFO - 'tpaint', 05:45:14 INFO - 'sessionrestore', 05:45:14 INFO - 'sessionrestore_no_auto_restore', 05:45:14 INFO - 'tabpaint']}, 05:45:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:45:14 INFO - 'ts_paint', 05:45:14 INFO - 'tpaint', 05:45:14 INFO - 'sessionrestore', 05:45:14 INFO - 'sessionrestore_no_auto_restore', 05:45:14 INFO - 'tabpaint']}, 05:45:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:45:14 INFO - 'ts_paint', 05:45:14 INFO - 'tpaint', 05:45:14 INFO - 'sessionrestore', 05:45:14 INFO - 'sessionrestore_no_auto_restore', 05:45:14 INFO - 'tabpaint']}, 05:45:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:45:14 INFO - 'tests': ['a11yr', 05:45:14 INFO - 'ts_paint', 05:45:14 INFO - 'tpaint', 05:45:14 INFO - 'sessionrestore', 05:45:14 INFO - 'sessionrestore_no_auto_restore', 05:45:14 INFO - 'tabpaint']}, 05:45:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:45:14 INFO - 'tests': ['a11yr', 05:45:14 INFO - 'ts_paint', 05:45:14 INFO - 'tpaint', 05:45:14 INFO - 'sessionrestore', 05:45:14 INFO - 'sessionrestore_no_auto_restore', 05:45:14 INFO - 'tabpaint']}, 05:45:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:45:14 INFO - 'tests': ['tsvgx', 05:45:14 INFO - 'tsvgr_opacity', 05:45:14 INFO - 'tart', 05:45:14 INFO - 'tscrollx', 05:45:14 INFO - 'cart']}, 05:45:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:45:14 INFO - 'tsvgr_opacity', 05:45:14 INFO - 'tart', 05:45:14 INFO - 'tscrollx', 05:45:14 INFO - 'cart']}, 05:45:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:14 INFO - 'talos_options': ['--disable-e10s'], 05:45:14 INFO - 'tests': ['tp5o']}, 05:45:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:14 INFO - 'tests': ['tp5o']}, 05:45:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:14 INFO - 'talos_options': ['--disable-e10s', 05:45:14 INFO - '--xperf_path', 05:45:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:14 INFO - 'tests': ['tp5n']}, 05:45:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:14 INFO - 'talos_options': ['--xperf_path', 05:45:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:14 INFO - 'tests': ['tp5n']}}, 05:45:14 INFO - 'talos.zip': {'path': '', 05:45:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:45:14 INFO - Running post-action listener: _resource_record_post_action 05:45:14 INFO - [mozharness: 2016-08-18 12:45:14.807948Z] Finished populate-webroot step (success) 05:45:14 INFO - [mozharness: 2016-08-18 12:45:14.808080Z] Running create-virtualenv step. 05:45:14 INFO - Running pre-action listener: _resource_record_pre_action 05:45:14 INFO - Running main action method: create_virtualenv 05:45:14 INFO - Creating virtualenv /builds/slave/test/build/venv 05:45:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:45:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:45:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:45:14 INFO - Using real prefix '/tools/python27' 05:45:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:45:15 INFO - Installing distribute.............................................................................................................................................................................................done. 05:45:18 INFO - Installing pip.................done. 05:45:18 INFO - Return code: 0 05:45:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:18 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 0x10697ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0f2d5a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068e2d30>, '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 0x106647db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068dab20>, '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.reir3PrfXt/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.sQunNfDJDn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:45:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:45:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:45:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.reir3PrfXt/Render', 05:45:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:18 INFO - 'HOME': '/Users/cltbld', 05:45:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:18 INFO - 'LOGNAME': 'cltbld', 05:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:18 INFO - 'MOZ_NO_REMOTE': '1', 05:45:18 INFO - 'NO_EM_RESTART': '1', 05:45:18 INFO - 'PAGER': '/bin/cat', 05:45:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:18 INFO - 'PWD': '/builds/slave/test', 05:45:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:18 INFO - 'SHELL': '/bin/bash', 05:45:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQunNfDJDn/Listeners', 05:45:18 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:45:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:18 INFO - 'USER': 'cltbld', 05:45:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:18 INFO - 'XPC_FLAGS': '0x0', 05:45:18 INFO - 'XPC_SERVICE_NAME': '0', 05:45:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:45:18 INFO - Cleaning up... 05:45:18 INFO - Return code: 0 05:45:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:18 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 0x10697ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0f2d5a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068e2d30>, '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 0x106647db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068dab20>, '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.reir3PrfXt/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.sQunNfDJDn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:45:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:45:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:45:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.reir3PrfXt/Render', 05:45:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:18 INFO - 'HOME': '/Users/cltbld', 05:45:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:18 INFO - 'LOGNAME': 'cltbld', 05:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:18 INFO - 'MOZ_NO_REMOTE': '1', 05:45:18 INFO - 'NO_EM_RESTART': '1', 05:45:18 INFO - 'PAGER': '/bin/cat', 05:45:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:18 INFO - 'PWD': '/builds/slave/test', 05:45:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:18 INFO - 'SHELL': '/bin/bash', 05:45:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQunNfDJDn/Listeners', 05:45:18 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:45:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:18 INFO - 'USER': 'cltbld', 05:45:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:18 INFO - 'XPC_FLAGS': '0x0', 05:45:18 INFO - 'XPC_SERVICE_NAME': '0', 05:45:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:19 INFO - Downloading/unpacking psutil>=3.1.1 05:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:45:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:22 INFO - Installing collected packages: psutil 05:45:22 INFO - Running setup.py install for psutil 05:45:22 INFO - building 'psutil._psutil_osx' extension 05:45:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:45:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:45:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:45:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:45:23 INFO - building 'psutil._psutil_posix' extension 05:45:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:45:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:45:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:45:23 INFO - ^ 05:45:23 INFO - 1 warning generated. 05:45:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:45:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:23 INFO - Successfully installed psutil 05:45:23 INFO - Cleaning up... 05:45:23 INFO - Return code: 0 05:45:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:45: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')]} 05:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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')]} 05:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10697ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0f2d5a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068e2d30>, '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 0x106647db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068dab20>, '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.reir3PrfXt/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.sQunNfDJDn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:45:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.reir3PrfXt/Render', 05:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:23 INFO - 'HOME': '/Users/cltbld', 05:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:23 INFO - 'LOGNAME': 'cltbld', 05:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:23 INFO - 'MOZ_NO_REMOTE': '1', 05:45:23 INFO - 'NO_EM_RESTART': '1', 05:45:23 INFO - 'PAGER': '/bin/cat', 05:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:23 INFO - 'PWD': '/builds/slave/test', 05:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:23 INFO - 'SHELL': '/bin/bash', 05:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQunNfDJDn/Listeners', 05:45:23 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:23 INFO - 'USER': 'cltbld', 05:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:23 INFO - 'XPC_FLAGS': '0x0', 05:45:23 INFO - 'XPC_SERVICE_NAME': '0', 05:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:45:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:45:26 INFO - Installing collected packages: mozsystemmonitor 05:45:26 INFO - Running setup.py install for mozsystemmonitor 05:45:26 INFO - Successfully installed mozsystemmonitor 05:45:26 INFO - Cleaning up... 05:45:26 INFO - Return code: 0 05:45:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:26 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 0x10697ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0f2d5a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068e2d30>, '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 0x106647db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068dab20>, '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.reir3PrfXt/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.sQunNfDJDn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:45:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:45:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.reir3PrfXt/Render', 05:45:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:26 INFO - 'HOME': '/Users/cltbld', 05:45:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:26 INFO - 'LOGNAME': 'cltbld', 05:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:26 INFO - 'MOZ_NO_REMOTE': '1', 05:45:26 INFO - 'NO_EM_RESTART': '1', 05:45:26 INFO - 'PAGER': '/bin/cat', 05:45:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:26 INFO - 'PWD': '/builds/slave/test', 05:45:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:26 INFO - 'SHELL': '/bin/bash', 05:45:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQunNfDJDn/Listeners', 05:45:26 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:45:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:26 INFO - 'USER': 'cltbld', 05:45:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:26 INFO - 'XPC_FLAGS': '0x0', 05:45:26 INFO - 'XPC_SERVICE_NAME': '0', 05:45:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:26 INFO - Downloading/unpacking jsonschema==2.5.1 05:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:45:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:29 INFO - Downloading functools32-3.2.3-2.tar.gz 05:45:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:45:29 INFO - warning: no files found matching '*.txt' 05:45:29 INFO - no previously-included directories found matching 'build' 05:45:29 INFO - no previously-included directories found matching 'dist' 05:45:29 INFO - no previously-included directories found matching '.git*' 05:45:29 INFO - Installing collected packages: jsonschema, functools32 05:45:29 INFO - Running setup.py install for functools32 05:45:29 INFO - warning: no files found matching '*.txt' 05:45:29 INFO - no previously-included directories found matching 'build' 05:45:29 INFO - no previously-included directories found matching 'dist' 05:45:29 INFO - no previously-included directories found matching '.git*' 05:45:29 INFO - Successfully installed jsonschema functools32 05:45:29 INFO - Cleaning up... 05:45:30 INFO - Return code: 0 05:45:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:30 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 0x10697ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0f2d5a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068e2d30>, '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 0x106647db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068dab20>, '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.reir3PrfXt/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.sQunNfDJDn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:45:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:45:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.reir3PrfXt/Render', 05:45:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:30 INFO - 'HOME': '/Users/cltbld', 05:45:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:30 INFO - 'LOGNAME': 'cltbld', 05:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:30 INFO - 'MOZ_NO_REMOTE': '1', 05:45:30 INFO - 'NO_EM_RESTART': '1', 05:45:30 INFO - 'PAGER': '/bin/cat', 05:45:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:30 INFO - 'PWD': '/builds/slave/test', 05:45:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:30 INFO - 'SHELL': '/bin/bash', 05:45:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQunNfDJDn/Listeners', 05:45:30 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:45:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:30 INFO - 'USER': 'cltbld', 05:45:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:30 INFO - 'XPC_FLAGS': '0x0', 05:45:30 INFO - 'XPC_SERVICE_NAME': '0', 05:45:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:45:30 INFO - Cleaning up... 05:45:30 INFO - Return code: 0 05:45:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:30 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 0x10697ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0f2d5a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068e2d30>, '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 0x106647db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068dab20>, '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.reir3PrfXt/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.sQunNfDJDn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:45:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:45:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.reir3PrfXt/Render', 05:45:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:30 INFO - 'HOME': '/Users/cltbld', 05:45:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:30 INFO - 'LOGNAME': 'cltbld', 05:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:30 INFO - 'MOZ_NO_REMOTE': '1', 05:45:30 INFO - 'NO_EM_RESTART': '1', 05:45:30 INFO - 'PAGER': '/bin/cat', 05:45:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:30 INFO - 'PWD': '/builds/slave/test', 05:45:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:30 INFO - 'SHELL': '/bin/bash', 05:45:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQunNfDJDn/Listeners', 05:45:30 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:45:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:30 INFO - 'USER': 'cltbld', 05:45:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:30 INFO - 'XPC_FLAGS': '0x0', 05:45:30 INFO - 'XPC_SERVICE_NAME': '0', 05:45:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:30 INFO - Downloading/unpacking blobuploader==1.2.4 05:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:32 INFO - Downloading blobuploader-1.2.4.tar.gz 05:45:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:45:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:45:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:34 INFO - Downloading docopt-0.6.1.tar.gz 05:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:45:34 INFO - Installing collected packages: blobuploader, requests, docopt 05:45:34 INFO - Running setup.py install for blobuploader 05:45:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:45:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:45:34 INFO - Running setup.py install for requests 05:45:34 INFO - Running setup.py install for docopt 05:45:35 INFO - Successfully installed blobuploader requests docopt 05:45:35 INFO - Cleaning up... 05:45:35 INFO - Return code: 0 05:45:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:35 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 0x10697ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0f2d5a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068e2d30>, '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 0x106647db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068dab20>, '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.reir3PrfXt/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.sQunNfDJDn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:45:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:45:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.reir3PrfXt/Render', 05:45:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:35 INFO - 'HOME': '/Users/cltbld', 05:45:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:35 INFO - 'LOGNAME': 'cltbld', 05:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:35 INFO - 'MOZ_NO_REMOTE': '1', 05:45:35 INFO - 'NO_EM_RESTART': '1', 05:45:35 INFO - 'PAGER': '/bin/cat', 05:45:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:35 INFO - 'PWD': '/builds/slave/test', 05:45:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:35 INFO - 'SHELL': '/bin/bash', 05:45:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQunNfDJDn/Listeners', 05:45:35 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:45:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:35 INFO - 'USER': 'cltbld', 05:45:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:35 INFO - 'XPC_FLAGS': '0x0', 05:45:35 INFO - 'XPC_SERVICE_NAME': '0', 05:45:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:45:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-qcq1wH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:45:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-hRB6KJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:45:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-FEX_Dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:45:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-NwMFst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:45:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-PTwnIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:45:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-BsQkei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:45:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-u1OMx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:45:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-HJJ890-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:45:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-fsR6_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:45:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-TRaYki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:45:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-d4TBRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:45:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-e9EOt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:45:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-oWil7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:45:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-VixNEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-LgPLIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:45:37 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-GJZ8xX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:45:37 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-aUBKas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:45:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:45:37 INFO - Running setup.py install for manifestparser 05:45:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:45:37 INFO - Running setup.py install for mozcrash 05:45:37 INFO - Running setup.py install for mozdebug 05:45:37 INFO - Running setup.py install for mozdevice 05:45:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:45:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:45:37 INFO - Running setup.py install for mozfile 05:45:37 INFO - Running setup.py install for mozhttpd 05:45:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:45:38 INFO - Running setup.py install for mozinfo 05:45:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:45:38 INFO - Running setup.py install for mozInstall 05:45:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:45:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:45:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:45:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:45:38 INFO - Running setup.py install for mozleak 05:45:38 INFO - Running setup.py install for mozlog 05:45:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:45:38 INFO - Running setup.py install for moznetwork 05:45:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:45:38 INFO - Running setup.py install for mozprocess 05:45:39 INFO - Running setup.py install for mozprofile 05:45:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:45:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:45:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:45:39 INFO - Running setup.py install for mozrunner 05:45:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:45:39 INFO - Running setup.py install for mozscreenshot 05:45:39 INFO - Running setup.py install for moztest 05:45:39 INFO - Running setup.py install for mozversion 05:45:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:45:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:45:39 INFO - Cleaning up... 05:45:39 INFO - Return code: 0 05:45:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:39 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 0x10697ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0f2d5a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068e2d30>, '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 0x106647db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068dab20>, '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.reir3PrfXt/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.sQunNfDJDn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:45:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:45:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:45:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.reir3PrfXt/Render', 05:45:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:39 INFO - 'HOME': '/Users/cltbld', 05:45:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:39 INFO - 'LOGNAME': 'cltbld', 05:45:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:39 INFO - 'MOZ_NO_REMOTE': '1', 05:45:39 INFO - 'NO_EM_RESTART': '1', 05:45:39 INFO - 'PAGER': '/bin/cat', 05:45:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:39 INFO - 'PWD': '/builds/slave/test', 05:45:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:39 INFO - 'SHELL': '/bin/bash', 05:45:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQunNfDJDn/Listeners', 05:45:39 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:45:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:39 INFO - 'USER': 'cltbld', 05:45:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:39 INFO - 'XPC_FLAGS': '0x0', 05:45:39 INFO - 'XPC_SERVICE_NAME': '0', 05:45:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:45:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-EpXVie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:45:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:45:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-qPtPcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:45:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:45:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-c1JPI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:45:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:45:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-7P891Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:45:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:45:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-sYSkM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:45:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:45:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-BqZEXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:45:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:45:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-_fXiqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:45:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:45:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-s6cHUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:45:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:45:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-QWruYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:45:41 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-eBOYU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:45:41 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-jpLp5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:45:41 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ovs3CL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:45:41 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-MtAOap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:45:41 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-xCn6xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:41 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-umTrWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:45:41 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-H2f07e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:45:41 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-jTsruP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:45:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:44 INFO - Downloading blessings-1.6.tar.gz 05:45:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:45:44 INFO - Installing collected packages: blessings 05:45:44 INFO - Running setup.py install for blessings 05:45:44 INFO - Successfully installed blessings 05:45:44 INFO - Cleaning up... 05:45:44 INFO - Return code: 0 05:45:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:45:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:45:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:45:44 INFO - Reading from file tmpfile_stdout 05:45:44 INFO - Current package versions: 05:45:44 INFO - blessings == 1.6 05:45:44 INFO - blobuploader == 1.2.4 05:45:44 INFO - docopt == 0.6.1 05:45:44 INFO - functools32 == 3.2.3-2 05:45:44 INFO - jsonschema == 2.5.1 05:45:44 INFO - manifestparser == 1.1 05:45:44 INFO - mozInstall == 1.12 05:45:44 INFO - mozcrash == 0.17 05:45:44 INFO - mozdebug == 0.1 05:45:44 INFO - mozdevice == 0.48 05:45:44 INFO - mozfile == 1.2 05:45:44 INFO - mozhttpd == 0.7 05:45:44 INFO - mozinfo == 0.9 05:45:44 INFO - mozleak == 0.1 05:45:44 INFO - mozlog == 3.3 05:45:44 INFO - moznetwork == 0.27 05:45:44 INFO - mozprocess == 0.23 05:45:44 INFO - mozprofile == 0.28 05:45:44 INFO - mozrunner == 6.12 05:45:44 INFO - mozscreenshot == 0.1 05:45:44 INFO - mozsystemmonitor == 0.3 05:45:44 INFO - moztest == 0.7 05:45:44 INFO - mozversion == 1.4 05:45:44 INFO - psutil == 3.1.1 05:45:44 INFO - requests == 1.2.3 05:45:44 INFO - wsgiref == 0.1.2 05:45:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:44 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 0x10697ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0f2d5a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068e2d30>, '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 0x106647db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068dab20>, '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.reir3PrfXt/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.sQunNfDJDn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:45:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:45:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:45:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.reir3PrfXt/Render', 05:45:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:44 INFO - 'HOME': '/Users/cltbld', 05:45:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:44 INFO - 'LOGNAME': 'cltbld', 05:45:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:44 INFO - 'MOZ_NO_REMOTE': '1', 05:45:44 INFO - 'NO_EM_RESTART': '1', 05:45:44 INFO - 'PAGER': '/bin/cat', 05:45:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:44 INFO - 'PWD': '/builds/slave/test', 05:45:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:44 INFO - 'SHELL': '/bin/bash', 05:45:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQunNfDJDn/Listeners', 05:45:44 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:45:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:44 INFO - 'USER': 'cltbld', 05:45:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:44 INFO - 'XPC_FLAGS': '0x0', 05:45:44 INFO - 'XPC_SERVICE_NAME': '0', 05:45:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:45:45 INFO - Cleaning up... 05:45:45 INFO - Return code: 0 05:45:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10697ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0f2d5a730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068e2d30>, '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 0x106647db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068dab20>, '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.reir3PrfXt/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.sQunNfDJDn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:45:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:45:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:45:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.reir3PrfXt/Render', 05:45:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:45 INFO - 'HOME': '/Users/cltbld', 05:45:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:45 INFO - 'LOGNAME': 'cltbld', 05:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:45 INFO - 'MOZ_NO_REMOTE': '1', 05:45:45 INFO - 'NO_EM_RESTART': '1', 05:45:45 INFO - 'PAGER': '/bin/cat', 05:45:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:45 INFO - 'PWD': '/builds/slave/test', 05:45:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:45 INFO - 'SHELL': '/bin/bash', 05:45:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQunNfDJDn/Listeners', 05:45:45 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:45:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:45 INFO - 'USER': 'cltbld', 05:45:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:45 INFO - 'XPC_FLAGS': '0x0', 05:45:45 INFO - 'XPC_SERVICE_NAME': '0', 05:45:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:45:45 INFO - Cleaning up... 05:45:45 INFO - Return code: 0 05:45:45 INFO - Running post-action listener: _resource_record_post_action 05:45:45 INFO - Running post-action listener: _start_resource_monitoring 05:45:45 INFO - Starting resource monitoring. 05:45:45 INFO - [mozharness: 2016-08-18 12:45:45.531088Z] Finished create-virtualenv step (success) 05:45:45 INFO - [mozharness: 2016-08-18 12:45:45.532093Z] Running install step. 05:45:45 INFO - Running pre-action listener: _resource_record_pre_action 05:45:45 INFO - Running main action method: install 05:45:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:45:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:45:45 INFO - Reading from file tmpfile_stdout 05:45:45 INFO - Detecting whether we're running mozinstall >=1.0... 05:45:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:45:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:45:45 INFO - Reading from file tmpfile_stdout 05:45:45 INFO - Output received: 05:45:45 INFO - Usage: mozinstall [options] installer 05:45:45 INFO - Options: 05:45:45 INFO - -h, --help show this help message and exit 05:45:45 INFO - -d DEST, --destination=DEST 05:45:45 INFO - Directory to install application into. [default: 05:45:45 INFO - "/builds/slave/test"] 05:45:45 INFO - --app=APP Application being installed. [default: firefox] 05:45:45 INFO - mkdir: /builds/slave/test/build/application 05:45:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:45:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:46:19 INFO - Reading from file tmpfile_stdout 05:46:19 INFO - Output received: 05:46:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:46:19 INFO - Running post-action listener: _resource_record_post_action 05:46:19 INFO - [mozharness: 2016-08-18 12:46:19.989213Z] Finished install step (success) 05:46:19 INFO - [mozharness: 2016-08-18 12:46:19.989351Z] Running run-tests step. 05:46:19 INFO - Running pre-action listener: _resource_record_pre_action 05:46:19 INFO - Running main action method: run_tests 05:46:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:46:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:46:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:46:20 INFO - 2016-08-18 05:46:20.003 screenresolution[6598:106982] starting screenresolution argv=screenresolution get 05:46:20 INFO - 2016-08-18 05:46:20.006 screenresolution[6598:106982] Display 0: 1600x1200x32@60 05:46:20 INFO - 2016-08-18 05:46:20.012 screenresolution[6599:106984] starting screenresolution argv=screenresolution list 05:46:20 INFO - Available Modes on Display 0 05:46:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:46:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:46:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:46:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:46:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:46:20 INFO - Intel Iris: 05:46:20 INFO - Chipset Model: Intel Iris 05:46:20 INFO - Type: GPU 05:46:20 INFO - Bus: Built-In 05:46:20 INFO - VRAM (Dynamic, Max): 1536 MB 05:46:20 INFO - Vendor: Intel (0x8086) 05:46:20 INFO - Device ID: 0x0a2e 05:46:20 INFO - Revision ID: 0x0009 05:46:20 INFO - Displays: 05:46:20 INFO - Display: 05:46:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:46:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:46:20 INFO - Display Serial Number: 1600x1200 60 05:46:20 INFO - Main Display: Yes 05:46:20 INFO - Mirror: Off 05:46:20 INFO - Online: Yes 05:46:20 INFO - Rotation: Supported 05:46:20 INFO - Return code: 0 05:46:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:46:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:46:20 INFO - Python 2.7.3 05:46:20 INFO - Return code: 0 05:46:20 INFO - grabbing minidump binary from tooltool 05:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:20 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 0x1068e2d30>, '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 0x106647db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068dab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:46:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:46:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:46:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:46:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:46:20 INFO - Return code: 0 05:46:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:46:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:46:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:46:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:46:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:46:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:46:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0081 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:46:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.reir3PrfXt/Render', 05:46:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:20 INFO - 'HOME': '/Users/cltbld', 05:46:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:20 INFO - 'LOGNAME': 'cltbld', 05:46:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:46:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:20 INFO - 'MOZ_NO_REMOTE': '1', 05:46:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:46:20 INFO - 'NO_EM_RESTART': '1', 05:46:20 INFO - 'PAGER': '/bin/cat', 05:46:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:20 INFO - 'PWD': '/builds/slave/test', 05:46:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:46:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:20 INFO - 'SHELL': '/bin/bash', 05:46:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sQunNfDJDn/Listeners', 05:46:20 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:46:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:20 INFO - 'USER': 'cltbld', 05:46:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:20 INFO - 'XPC_FLAGS': '0x0', 05:46:20 INFO - 'XPC_SERVICE_NAME': '0', 05:46:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:46:20 INFO - mozversion application_buildid: 20160816194715 05:46:20 INFO - mozversion application_changeset: f0067001c059ff57d6927c6da5a1605f1d29a449 05:46:20 INFO - mozversion application_display_name: Nightly 05:46:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:46:20 INFO - mozversion application_name: Firefox 05:46:20 INFO - mozversion application_remotingname: firefox 05:46:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 05:46:20 INFO - mozversion application_vendor: Mozilla 05:46:20 INFO - mozversion application_version: 51.0a1 05:46:20 INFO - mozversion platform_buildid: 20160816194715 05:46:20 INFO - mozversion platform_changeset: f0067001c059ff57d6927c6da5a1605f1d29a449 05:46:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 05:46:20 INFO - mozversion platform_version: 51.0a1 05:46:20 INFO - using testdate: 1471524380 05:46:20 INFO - actual date: 1471524380 05:46:20 INFO - starting webserver on 'localhost:54675' 05:46:20 INFO - SUITE-START | Running 2 tests 05:46:20 INFO - TEST-START | dromaeo_css 05:46:20 INFO - operating with platform_type : mac_ 05:46:20 INFO - Initialising browser for dromaeo_css test... 05:46:20 INFO - TEST-INFO | started process 6609 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpQYGAY3/profile http://localhost:54675/getInfo.html) 05:46:22 INFO - PROCESS | 6609 | __metrics Screen width/height:1600/1200 05:46:22 INFO - PROCESS | 6609 | colorDepth:24 05:46:22 INFO - PROCESS | 6609 | Browser inner width/height: 1024/689 05:46:22 INFO - PROCESS | 6609 | __metrics 05:46:23 INFO - PROCESS | 6609 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:46:23 INFO - PROCESS | 6609 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:46:23 INFO - TEST-INFO | 6609: exit 0 05:46:23 INFO - Browser initialized. 05:46:23 INFO - Running cycle 1/1 for dromaeo_css test... 05:46:23 INFO - TEST-INFO | started process 6612 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpQYGAY3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:46:35 INFO - PROCESS | 6612 | RSS: Main: 228216832 05:46:35 INFO - PROCESS | 6612 | 05:47:26 INFO - PROCESS | 6612 | 1471524446950 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:49:39 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54675/tests/dromaeo/cssquery-ext.html) 05:49:41 INFO - PROCESS | 6612 | RSS: Main: 353599488 05:49:41 INFO - PROCESS | 6612 | 05:52:44 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-ext.html (next: http://localhost:54675/tests/dromaeo/cssquery-jquery.html) 05:52:44 INFO - PROCESS | 6612 | RSS: Main: 370909184 05:52:44 INFO - PROCESS | 6612 | 05:55:47 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54675/tests/dromaeo/cssquery-mootools.html) 05:55:47 INFO - PROCESS | 6612 | RSS: Main: 362418176 05:55:47 INFO - PROCESS | 6612 | 05:58:50 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54675/tests/dromaeo/cssquery-prototype.html) 05:58:50 INFO - PROCESS | 6612 | RSS: Main: 345083904 05:58:50 INFO - PROCESS | 6612 | 06:01:54 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54675/tests/dromaeo/cssquery-yui.html) 06:01:54 INFO - PROCESS | 6612 | RSS: Main: 337227776 06:01:54 INFO - PROCESS | 6612 | 06:04:58 INFO - PROCESS | 6612 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-yui.html (next: http://localhost:54675/tests/dromaeo/cssquery-dojo.html) 06:04:58 INFO - PROCESS | 6612 | RSS: Main: 540049408 06:04:58 INFO - PROCESS | 6612 | 06:04:58 INFO - PROCESS | 6612 | __start_tp_report 06:04:58 INFO - PROCESS | 6612 | _x_x_mozilla_page_load 06:04:58 INFO - PROCESS | 6612 | _x_x_mozilla_page_load_details 06:04:58 INFO - PROCESS | 6612 | |i|pagename|runs| 06:04:58 INFO - PROCESS | 6612 | |0;dojo.html;3340.2818394045275;3348.196432856114;3350.865965361386;3359.74801889858;3423.1442139465134;5764.5676574256995;5840.707964601769;5924.822255332336;5935.525157987356;5965.612235204714;5805.5936084474;5871.88256234875;6002.849928751783;6004.969975150129;6026.126211699312;5205.765740541685;5263.34208223972;5286.524212820865;5295.708736019517;5382.085045542248;5046.697198168122;5381.67709937405;5384.138537833947;5392.460753924615;5421.024215641183;5208.401033881114;5235.450277720861;5255.789768409259;5289.920651190255;5341.626086173969;5237.9785941741375;5273.578113750891;5291.920621190685;5299.231611416361;5313.44208858072;5212.4266330703595;5239.659422137549;5291.629585928987;5301.946980530222;5356.678599284055;5802.97098514509;5885.5585831062845;5911.172435858984;5935;5940;6131.50947924165;6170.81487555374;6193.907091393633;6222.377762223787;6242.906356404657;6135.006123009995;6201.441870231656;6203.875922481525;6204.875902481925;6216.87566248677;5960.642361458283;5979.103134529768;5986.640801551921;5988.640681559121;5991.131285963511;6094.45149936499;6180.629162250189;6251.374862513712;6254.843628909313;6281.59169653971;6204.441600255998;6220.098085777628;6222.408871157233;6236;6236.844078897973;6207;6209.2548894133;6213.720382582795;6235.719392627343;6238.594491358138;6127.387261273926;6132.263729246456;6139.369480960552;6205.968970155121;6220.564560480723;6210.813675589739;6220.968895155585;6259.311475737665;6270.811875643738;6273.96863015682;4959.677620954699;5014.222795466709;5026.648134630614;5034.697918124924;5034.874128146826;4463.754493502888;4468.285074388083;4499.842505512292;4520.909581808345;4533.750643714561;5768.74040668171;5800.622959507618;5801.593888427854;5825.38833422493;5828.0966450200285;6178.567500274938;6200.751969921243;6220.377962203834;6229.906551401733;6235.345288744707;5613.859653508695;5702.144678298288;5782.219400380979;5794.565407594448;5808.564357673192;5435.646682965595;5470.72646367688;5493.340799104053;5496.917546236809;5497.697626630574;6196.0705894116245;6223.8797016537455;6231.594946328474;6233.470155036873;6309.747610095545;6215.813525594059;6226.5641405101205;6236.095766113979;6241.625502469867;6246.250449946036;6337;6355.713992870239;6355.809325720235;6379.74481020754;6384.872302554016;6097.603655762361;6110.953680641605;6132;6191.628502289697;6224.533160013108;6142.775043102407;6154.876902461836;6167.321594624498;6180.351063138306;6200.7519699211525;6176.81469555914;6186.443220110212;6194.473469755121;6200.100985357189;6209.844753881189;6153.66154861469;6169.691515424301;6180.289266734203;6184.969075154595;6201.596896201732;5515.889682206413;5811.535077193899;5897.764089436546;6035.155078288955;6039.15451836752;6136.907946380898;6176.35148309421;6186.659733714509;6187.102870083902;6197.504199664107;6010.909836352371;6028.246469191351;6053.848653783691;6078.750862181021;6101.054336577838 06:04:58 INFO - PROCESS | 6612 | |1;ext.html;472.3175012107506;485.7619766314507;504.6013649217118;526.0058489454933;534.270720466563;30786;40566;41647.16705665885;41922.37116443256;42002.579974200286;41966.16067678652;42305.576944230546;42321.73034808953;42383;42565.78717106418;9616.759581010461;9729.610815567368;9797;9810.411375317504;9822.214222862154;8780.517071561062;8995.5052472114;11894.464749086312;12114.091443141755;12139.939300303486;11864.584739534119;11923.463444145034;11940.985016273584;11959.282443053444;11992.7601447971;11949.880501194964;12001.45993430289;12011.279323240633;12024.879751202463;12061.819072713917;11867.762644747143;11954.222975506651;11954.342511161884;11967.521299148024;11968.222065565711;39889.601103988876;40212.391504339794;40228.79885600582;40266.39600405996;40426.372285801626;3369.0903456066726;3423.3324501722172;3941.625545573166;4126.09225970286;4220.36694495825;31292;31834.363312733614;31846.522302165486;32219.550120244647;32263.35473290521;3228.1606782236554;3319.4356959316974;3345.5149003394613;3361.411752943225;3397.2695870387984;516650;517253;518458;520570;521076.39461802825;59222.11166832501;59677.40322596805;60036.39963600308;60195.69902150461;60207.69896150491;61981.450463738765;62093;62179;62207;62376.688116559126;16388.590285242964;16505.33978640841;16617.91691041537;16625.334986600643;16698.499045028424;11784.234024788362;11995.700107497383;12833.679158020937;12869.22784632925;12895.226286422845;16535.421260255833;16908;16973.321067157423;16990.575235618962;17122.486325410257;6660.56706314088;6769.800082124091;7237.710491580383;7319.780406587811;7363.521371110861;764.728521374915;924.7318277699392;961.9711408657752;1050.894910508943;1156.5894107591853;29463.558046629318;29755.553666695017;29872.55191172134;29990;30799;1031.6956497833157;1065.8081545321916;1091.1761619976867;1102.2284400919389;1178.3872386359046;12895.419706113247;15429.845701542841;15497.690046199014;15524.611884702746;15918.204089795696;649283;654426;654431;654703;655612;57489;57679.4232057674;57772;57816.42183578195;58032.70983645055;1266.4364357860848;1316.0984725463088;1329.454923481368;1337.5452346202337;1361.8025386319127;29478.410431790817;31269.530957036117;31451.344242405507;31507;32113.678863212004;49252.75373623109;49905.50094499008;49951;50145;50202.744931377005;513090;513863;514552;517416;518046;25855.224343269732;26362;26634.334141646614;26840.32899177536;40707.690308002304;47046.47959462774;47118.76440617775;47193.76403117963;47502;48286;37569.06077347979;38948.41577376398;39011.804940976246;39035.82892513229;40404.393934090425;32571.34857302888;38709.6129038706;38849.417258741705;39881.40177897276;39917.40123898202;33417.83291083529;35182;38230.42654360131;38648.2270354597;38849.41725874058;32097.679023209468;33869;34778.30443391169;34994.15064342971;35297.764578239636;25022.37444063913;25453.87273063697;25522;25983;26119.477610447306 06:04:58 INFO - PROCESS | 6612 | |2;jquery.html;357.36389227175636;371.1074864949797;379.2396245527717;386.35478750486686;408.4139260161666;15517.922410387946;15529.689406211883;20122.69815952762;20664.89667551664;20747.896260518693;20877.269295574657;21091.945402729878;21221.469463263427;21518.924053797327;21561.67657485135;5852.180694702745;5896.643771932437;6095.024796032636;6138.693065346715;6182.84542886427;4207.789610519477;6011.75952961881;6120.571559990803;6120.571559990803;6131.785387511438;6383.425491705745;6646.169228846395;6689.331066893296;6757.256701762803;6774.8306292342595;6597.1423714916755;6626.966865165691;6688.230853451867;6726.529142959995;6756.324367563254;5839.299284085937;5840.240768700084;5840.766369345221;5845.415458454163;5861.824145275648;13366.665833354147;13533.864661353358;23287.76712232873;24134.637980430307;25138.748612513824;2146.5170336674278;2225.5103877147003;2228.543148654522;2644.5239856825765;2989.5948904015077;10042.648507302209;10145.746356341077;10395.532201050972;10481.423521706303;10499.475026248658;1862.4505770797648;1885.764279465067;1937.3122541497705;1964.224131468062;2027.8986050697406;89494;107883;111424.4428777851;112199.43900280527;117022.41488792587;11144.944275278573;11307.095432365391;11420.37187954654;11535.942320288512;11634.26594750233;14354.354054067591;14382.712345753027;14416.567502974927;14434.927825360806;14448.711025779425;8435.240828325357;8578.957105214435;8603.483790972561;8618.698345557876;8717.30261579072;8582.742517724479;8602.612882420306;8619.525926074;8767.605457754416;8786.341024423195;4433.27353677486;4456.019675671347;4479.619232365286;4481.260592002348;4546.340780586796;1860.5255659806662;1944.1445763863856;1948.1915005272936;1951.677973134417;1953.9413817585496;827.668932427022;861.8104017116225;913.72588223534;956.8182045411489;983.179045497018;17836.55408614795;20359.898200508902;21421.67867481989;21451;21560.56878862414;891.2736120062128;905.9728208153766;909.417972497602;956.3837115275302;989.2284018465608;10719.19606029551;11050.226484146033;11169.771325154226;11260.605878794202;11279.379634120038;493570.53214734414;498315;500683;612858;617468;128553.35723321323;129031.35484322706;136931;139117;139921;940.1115945431502;1010.9696709098739;1023.1661196125134;1024.7489365105596;1025.3386565665105;18743.34398296053;19515.707264390763;19754.308599198957;19881.900590496956;19915.900420498383;57401;58063.70968145132;60477.39522604718;61171;61496.69251653713;75901.86147207685;81694.3661126786;86768;87500;89381;7539.698412063675;7889.329407000239;8097.433179677366;8238.670453182045;8239.629216685384;9260.907390926273;12575.245485270547;12829.422675979416;12912.096153269556;13165;9579.568919398504;10931.617393391192;11471.4264286787;11499.412632157697;11564.884351156381;10763.677089687322;11334.546618135182;11439.485223164811;11592.420378981185;11921.105917056082;8453.492790432594;11608.825867611824;11953.103517236068;11994.820077699016;12011.399430028288;8043.557604331818;8051.597420128853;8159.632816523152;8495.235428811437;8531.488110713377;11035;11278.774424511628;11322.490487928228;11783.587574434854;11832.408379580815 06:04:58 INFO - PROCESS | 6612 | |3;mootools.html;1359.1573224600736;1545.4281915691192;1559.345075068471;1666.9333226670942;1762.603414231798;6697.8995315070315;7978.92021079789;9094.63621455141;9105.726828195156;9192.816143677122;8909.777255568613;8981.820363592724;9011;9020.81958360834;9031.774205644862;2038.4903774056486;2400.4478969836905;2422.8546287222734;2429.4898071404937;2432.391902024485;2381.3213234228247;2417.468157005456;2434.379233295507;2437.9390515237174;2438.0979037756056;2389.2593296078185;2415.033986405443;2421.3462365161395;2427.9757202427927;2440.3289095498703;2376.1327115602853;2398.9760102398927;2409.0725070847675;2411.9276421707377;2416.3355077353694;2363.1138323128826;2383.7377888432256;2395.149721848736;2397.8081753459683;2406.7472915343988;8942.597583108776;9007.954960225223;9079.364444488889;9128.315376346734;9142.04008579103;5383.946160538384;5540.085885828834;5550.084236101038;5553.305836770403;5557.999560079184;3679.466477360768;3692.984429281969;3755.117547376364;3770.5663848657205;3785.659290663826;1091.1434523898781;1097.13874608432;1097.5786356668134;1118.9608363707312;1127.560251501915;11945.522179112824;11984.400779960966;12059.698507537294;12072.396380180955;12101.39493025354;4423.225935461346;4528.343390208402;4701.623870090384;4715.151272770866;4727.739974301447;9432.905670943202;9438.905610943802;9451.054894510495;9458.57436415363;9466.384684995453;5366.785328586813;5370.677759334452;5371.328583927009;5373.650712703662;5375.059364611177;4071.470708807836;4495.887602809956;4653.976730116327;4893.510648935078;5022.748862556858;3857.8649747259274;3859.131695368576;3937.5078115235597;3962.980185099114;3973.145773658677;1157.6179860645966;1183.4319526627266;1185.9525618975317;1192.540871764377;1197.479096592985;865.8398196333699;935.6678379175431;960.0303693269708;960.7357976556364;996.7059717383389;5969.343372229051;6013.639181649028;6045.667488288098;6053.576249662569;6239.656818875005;447.91265703187565;452.9524399938025;459.0383147306454;461.8683675152565;462.20731445570334;4013.2174226026227;4021.4772079630047;4030.6976976726864;4128.277551428488;4149.439825623563;10854.565817367375;11079.5568177272;11178.552857885594;11187.664370068911;11189.440527973731;11085.334879907232;11133.165012624086;11133.777324453466;11133.832992505118;11140.275882067639;6232.657203853741;7584.051993500812;7953.363730901514;8435.451695639764;8466.872996905051;5944.524438044859;6108.664023478751;6198.938010619836;6240.937590624218;6270.15352927349;10459.320144190604;10510.211734120125;10569.788604227719;10623.36259824413;10646.946765266124;7724.609643613674;10370.481475926324;10555.841662375224;10622.150227981589;10736.785264294827;7092.751753688596;7843.960780196063;7847.843043138991;7850.685972561034;7851.175626559358;8288.046874609256;8549.529775862387;8638.26574741154;8654.697085601974;8659.350548708746;7709.845803084019;7744.070711514654;7875.527468351917;7949.602519874099;8154.266116049347;7341.6696248670005;7487.251274872469;7555.508891922007;7699.576523291049;7705.576193309197;6935.514513983972;7162.033125528195;7240.1311842579225;7246.094238220223;7246.347828695442;5146.845594632167;5152;5175.534201921845;5246.895062098813;5293.100172970681;3907.5115610548683;5221;5258.789648414174;5286.497782710637;5401.972990135024 06:04:58 INFO - PROCESS | 6612 | |4;prototype.html;297.45268705581736;300.3527398456327;311.27472987938097;314.3226347221735;317.1010186122343;5290.894182116355;5293.735313234342;5309.654872433294;5315.415304316522;5315.867103322419;4142.585741425856;4519.435070616173;4783.760811959405;4939.654224204307;5168.302279192308;3091.196288964874;3595.388783906742;3990.8603198888045;4092.877213683594;4127.62851343379;3538.982305088471;4026.4765580474646;4091.672666186698;4108.445359876423;4108.77401742904;3491;3646.981765091171;3875.903102422448;4027.7784721840285;4093.406456063869;3509.2455122148604;4054.756714597119;4065.5121385433936;4078.714489985702;4090.2433049885867;3500.0049971516246;3529.2235708144167;3547.379208638478;3549.680528752399;3568.107973006748;4870.977094810094;5179.378474583036;5181;5195.636305458619;5211.03595834768;3927.9214415711813;4600.42494688164;4601.723896566216;4619.422572178478;4622.791974361162;4884.438289596706;5632.324121105453;5647.09646456569;5653.660780353151;5662.688552129631;3410.539577157077;3722.133956853208;3857.4213867919757;3866.8259928303296;3878.166194268246;5751.756814842215;5776;5797.014537513226;5808.070708686674;5874.41390824475;3425.3320602482777;3439.6782172033777;3826.4834247376803;3838.443425703313;3897.7661340319673;3333.683300086488;3956.2680904032845;3962.9405558916637;3980.940285895656;3982.4026396040244;4329;4485.573870482299;4527.7736113194205;4572.6799124061;4618.815247390066;4663.930041049387;5084.9745751271;5331.733413329318;5493.478119578634;5562.3881373048935;1162.5291756838494;1340.751960887239;1346.3036573916936;1348.0159483577045;1350.1763924006348;823.4112609484248;833.7244016654407;848.1857416879865;854.5001174312972;903.8644203369547;4467.754273514988;5138.897222055538;5182;5210.661307015108;5275.024120537712;4487.887802804956;5003.399592048978;5639.210510528529;5689.188243876612;5742.54059675225;3883.941740873889;4714.4106986626675;5058.747062646853;5157.226416037624;5366.838994830083;2650.6686664166978;2665.880624903207;2967.9703202967694;3044.2389402649337;3066;5660.518855897212;5729.713514324267;5976.970115149484;6004.969975150184;6020.337762846083;5326.707031113326;5630.408807075198;5937.673427961504;5983.19226904371;6057.212562366864;4854.417469903564;5899.557533185029;5919.904817608756;5942.64344139353;6042.546808989256;4608.746518941491;5410.48600382963;5827.484853937922;5887.20522729426;5925.200097986889;4757.976210118927;5368.597355198298;5669.137085771659;5691.8292451226525;5792.768289268551;5543.667379957215;5695.715214239354;5756.769729210785;5918.993771059017;6129.448349648553;3467.722582193469;3677.5403074615674;3853.3449313616193;3918.4318273849563;3968.6428221460205;4842.249451335048;5613.57898157648;5684.914726279027;5749.195112684311;5862.794802181903;5604.047311957059;5618.157276408571;5628.268325117709;5639.323281206118;5686.687232202268;4934.407871055496;5580.497755202051;5622.297212848394;5634.915476267777;5652.378238393856;4644.60520855731;5219.869503262297;5483.259759932358;5531.155846686514;5568.526675232651;4719.961608446137;5461.426550212171;5665.971670141623;5670.376258611632;5678.927191575128;5249.246788546207;5542.3903370630005;5543.6396634218645;5556.833295001156;5557.610967232255 06:04:58 INFO - PROCESS | 6612 | |5;yui.html;703.1000319590922;714.2964180282422;791.2245998921054;823.1026091456954;859.1794835931684;13780.586582402531;13962;14552.854471455283;15075.321610527526;15161.772573411408;14148.221847798366;14734.852651473482;15038.774418383733;15060.39758409665;15074.3216555255;702.4766548921061;775.4029397364027;786.5477350523444;787.5078076202374;789.4947233770391;712.4905692429911;844.4342290665238;872.4067634008871;876.2245412809677;880.506916126971;936.7189843047058;939.1125386509751;942.1661829281102;942.9905700943006;943.1323182671958;725.6553137259812;930.8045310484806;937.8968313485511;938.0853667674031;938.6433155400938;667.6795138333579;691.7233106757287;694.9687264073128;694.9826254343632;695.4957655699628;14937.850621493755;14973.775393369107;14976.026558273787;14978.47575334869;14989.175595342253;13796.655083622889;13904.443822247098;14018.36917338712;14433.855661443462;14445.855541444556;11502.827457588142;11806;12187.756244875141;12930.612081637566;13375.996800239924;4623.144718227139;4638.628909687217;4692.859214223545;4851.805927762885;4864.391951006124;95906;102697.4865125677;103966.48016759944;104704;106004.40993385106;8988.955055224813;10635.943694397758;10805.081568066646;10910;11163.218574699693;20501.487462813548;20546.589068218553;20557.383278501668;20605.896970515052;20649.380518584465;7751.418643601753;7934.523928564305;7947.761567152879;8055.033395992518;8063.6774529018185;4032.052467670095;4084.346504559256;4337.8264869404875;4402.669799765031;4517.570830771071;3361.6638336166675;3500.2649443617124;3500.8599656013985;3504.123969007748;3509.1051781795477;626.764963138823;629.3706293706293;640.7533099756628;649.3863299182227;651.7718798420515;619.2476141488023;628.629108825795;629.9842503937439;641.6695401868042;658.0721183131857;8074.636641351036;8793.736187914374;9105.2260557852;9157.862632060524;9217.44695318283;419.19394146114337;536.888640514132;576.6972339521784;599.6492052149541;657.5298661457081;3867.7292589519025;4258.564229854813;4308.590683885026;4598.494165641777;4620.653450991122;139753;157026.29737026207;162652;163332.3666763341;168126;130184.349078254;142096;142465;145677.5432245685;146459.80310295353;101073.49463252637;102322;121447.57104857854;122755.38622306832;124342;9337.688277831168;9373.625054997996;9735.951320243354;9984.800303994025;10126.544305506417;57704.711476442346;57822.7108864453;57941.4205857936;60345.26719961597;61647.383526164165;86908;87230;90452;90557.54721226351;90796.54601726949;13010.544630937871;13046.412911419198;13224.933875330562;13499.122557033761;13687.863121368659;11771.52913883435;14630.487932922297;14687;15109.697806044038;15587.76618350703;13720.382582784;13829.861701383259;14082.647933801736;14139.080959737585;14240.216788076756;13651.249181295125;14041.297935103408;14144;14311.856881431053;14610.19643919552;13430.664233394244;13553.661158470723;13673.521426750016;13847.307634618028;14015.439382424996;10822.56709731602;10990.340579565252;11094;11348;11803.173777835469;7937.517389403275;9927.453990030617;10538.84072752012;11239.60661376848;11537.769244615229 06:04:58 INFO - PROCESS | 6612 | __end_tp_report 06:04:58 INFO - PROCESS | 6612 | __start_cc_report 06:04:58 INFO - PROCESS | 6612 | _x_x_mozilla_cycle_collect,1204 06:04:58 INFO - PROCESS | 6612 | __end_cc_report 06:04:58 INFO - PROCESS | 6612 | __startTimestamp1471525498456__endTimestamp 06:04:58 INFO - PROCESS | 6612 | 06:04:58 INFO - PROCESS | 6612 | ------- Summary: start ------- 06:04:58 INFO - PROCESS | 6612 | Number of tests: 6 06:04:58 INFO - PROCESS | 6612 | 06:04:58 INFO - PROCESS | 6612 | [#0] dojo.html Cycles:165 Average:5797.94 Median:6053.85 stddev:620.87 (10.3%) stddev-sans-first:592.08 06:04:58 INFO - PROCESS | 6612 | Values: 3340.3 3348.2 3350.9 3359.7 3423.1 5764.6 5840.7 5924.8 5935.5 5965.6 5805.6 5871.9 6002.8 6005.0 6026.1 5205.8 5263.3 5286.5 5295.7 5382.1 5046.7 5381.7 5384.1 5392.5 5421.0 5208.4 5235.5 5255.8 5289.9 5341.6 5238.0 5273.6 5291.9 5299.2 5313.4 5212.4 5239.7 5291.6 5301.9 5356.7 5803.0 5885.6 5911.2 5935.0 5940.0 6131.5 6170.8 6193.9 6222.4 6242.9 6135.0 6201.4 6203.9 6204.9 6216.9 5960.6 5979.1 5986.6 5988.6 5991.1 6094.5 6180.6 6251.4 6254.8 6281.6 6204.4 6220.1 6222.4 6236.0 6236.8 6207.0 6209.3 6213.7 6235.7 6238.6 6127.4 6132.3 6139.4 6206.0 6220.6 6210.8 6221.0 6259.3 6270.8 6274.0 4959.7 5014.2 5026.6 5034.7 5034.9 4463.8 4468.3 4499.8 4520.9 4533.8 5768.7 5800.6 5801.6 5825.4 5828.1 6178.6 6200.8 6220.4 6229.9 6235.3 5613.9 5702.1 5782.2 5794.6 5808.6 5435.6 5470.7 5493.3 5496.9 5497.7 6196.1 6223.9 6231.6 6233.5 6309.7 6215.8 6226.6 6236.1 6241.6 6246.3 6337.0 6355.7 6355.8 6379.7 6384.9 6097.6 6111.0 6132.0 6191.6 6224.5 6142.8 6154.9 6167.3 6180.4 6200.8 6176.8 6186.4 6194.5 6200.1 6209.8 6153.7 6169.7 6180.3 6185.0 6201.6 5515.9 5811.5 5897.8 6035.2 6039.2 6136.9 6176.4 6186.7 6187.1 6197.5 6010.9 6028.2 6053.8 6078.8 6101.1 06:04:58 INFO - PROCESS | 6612 | 06:04:58 INFO - PROCESS | 6612 | [#1] ext.html Cycles:180 Average:69956.16 Median:37899.74 stddev:151163.06 (398.8%) stddev-sans-first:151497.09 06:04:58 INFO - PROCESS | 6612 | Values: 472.3 485.8 504.6 526.0 534.3 30786.0 40566.0 41647.2 41922.4 42002.6 41966.2 42305.6 42321.7 42383.0 42565.8 9616.8 9729.6 9797.0 9810.4 9822.2 8780.5 8995.5 11894.5 12114.1 12139.9 11864.6 11923.5 11941.0 11959.3 11992.8 11949.9 12001.5 12011.3 12024.9 12061.8 11867.8 11954.2 11954.3 11967.5 11968.2 39889.6 40212.4 40228.8 40266.4 40426.4 3369.1 3423.3 3941.6 4126.1 4220.4 31292.0 31834.4 31846.5 32219.6 32263.4 3228.2 3319.4 3345.5 3361.4 3397.3 516650.0 517253.0 518458.0 520570.0 521076.4 59222.1 59677.4 60036.4 60195.7 60207.7 61981.5 62093.0 62179.0 62207.0 62376.7 16388.6 16505.3 16617.9 16625.3 16698.5 11784.2 11995.7 12833.7 12869.2 12895.2 16535.4 16908.0 16973.3 16990.6 17122.5 6660.6 6769.8 7237.7 7319.8 7363.5 764.7 924.7 962.0 1050.9 1156.6 29463.6 29755.6 29872.6 29990.0 30799.0 1031.7 1065.8 1091.2 1102.2 1178.4 12895.4 15429.8 15497.7 15524.6 15918.2 649283.0 654426.0 654431.0 654703.0 655612.0 57489.0 57679.4 57772.0 57816.4 58032.7 1266.4 1316.1 1329.5 1337.5 1361.8 29478.4 31269.5 31451.3 31507.0 32113.7 49252.8 49905.5 49951.0 50145.0 50202.7 513090.0 513863.0 514552.0 517416.0 518046.0 25855.2 26362.0 26634.3 26840.3 40707.7 47046.5 47118.8 47193.8 47502.0 48286.0 37569.1 38948.4 39011.8 39035.8 40404.4 32571.3 38709.6 38849.4 39881.4 39917.4 33417.8 35182.0 38230.4 38648.2 38849.4 32097.7 33869.0 34778.3 34994.2 35297.8 25022.4 25453.9 25522.0 25983.0 26119.5 06:04:58 INFO - PROCESS | 6612 | 06:04:58 INFO - PROCESS | 6612 | [#2] jquery.html Cycles:180 Average:33709.12 Median:23711.20 stddev:92042.13 (388.2%) stddev-sans-first:92266.26 06:04:58 INFO - PROCESS | 6612 | Values: 357.4 371.1 379.2 386.4 408.4 15517.9 15529.7 20122.7 20664.9 20747.9 20877.3 21091.9 21221.5 21518.9 21561.7 5852.2 5896.6 6095.0 6138.7 6182.8 4207.8 6011.8 6120.6 6120.6 6131.8 6383.4 6646.2 6689.3 6757.3 6774.8 6597.1 6627.0 6688.2 6726.5 6756.3 5839.3 5840.2 5840.8 5845.4 5861.8 13366.7 13533.9 23287.8 24134.6 25138.7 2146.5 2225.5 2228.5 2644.5 2989.6 10042.6 10145.7 10395.5 10481.4 10499.5 1862.5 1885.8 1937.3 1964.2 2027.9 89494.0 107883.0 111424.4 112199.4 117022.4 11144.9 11307.1 11420.4 11535.9 11634.3 14354.4 14382.7 14416.6 14434.9 14448.7 8435.2 8579.0 8603.5 8618.7 8717.3 8582.7 8602.6 8619.5 8767.6 8786.3 4433.3 4456.0 4479.6 4481.3 4546.3 1860.5 1944.1 1948.2 1951.7 1953.9 827.7 861.8 913.7 956.8 983.2 17836.6 20359.9 21421.7 21451.0 21560.6 891.3 906.0 909.4 956.4 989.2 10719.2 11050.2 11169.8 11260.6 11279.4 493570.5 498315.0 500683.0 612858.0 617468.0 128553.4 129031.4 136931.0 139117.0 139921.0 940.1 1011.0 1023.2 1024.7 1025.3 18743.3 19515.7 19754.3 19881.9 19915.9 57401.0 58063.7 60477.4 61171.0 61496.7 75901.9 81694.4 86768.0 87500.0 89381.0 7539.7 7889.3 8097.4 8238.7 8239.6 9260.9 12575.2 12829.4 12912.1 13165.0 9579.6 10931.6 11471.4 11499.4 11564.9 10763.7 11334.5 11439.5 11592.4 11921.1 8453.5 11608.8 11953.1 11994.8 12011.4 8043.6 8051.6 8159.6 8495.2 8531.5 11035.0 11278.8 11322.5 11783.6 11832.4 06:04:58 INFO - PROCESS | 6612 | 06:04:58 INFO - PROCESS | 6612 | [#3] mootools.html Cycles:180 Average:5802.08 Median:4708.39 stddev:3303.61 (70.2%) stddev-sans-first:3296.01 06:04:58 INFO - PROCESS | 6612 | Values: 1359.2 1545.4 1559.3 1666.9 1762.6 6697.9 7978.9 9094.6 9105.7 9192.8 8909.8 8981.8 9011.0 9020.8 9031.8 2038.5 2400.4 2422.9 2429.5 2432.4 2381.3 2417.5 2434.4 2437.9 2438.1 2389.3 2415.0 2421.3 2428.0 2440.3 2376.1 2399.0 2409.1 2411.9 2416.3 2363.1 2383.7 2395.1 2397.8 2406.7 8942.6 9008.0 9079.4 9128.3 9142.0 5383.9 5540.1 5550.1 5553.3 5558.0 3679.5 3693.0 3755.1 3770.6 3785.7 1091.1 1097.1 1097.6 1119.0 1127.6 11945.5 11984.4 12059.7 12072.4 12101.4 4423.2 4528.3 4701.6 4715.2 4727.7 9432.9 9438.9 9451.1 9458.6 9466.4 5366.8 5370.7 5371.3 5373.7 5375.1 4071.5 4495.9 4654.0 4893.5 5022.7 3857.9 3859.1 3937.5 3963.0 3973.1 1157.6 1183.4 1186.0 1192.5 1197.5 865.8 935.7 960.0 960.7 996.7 5969.3 6013.6 6045.7 6053.6 6239.7 447.9 453.0 459.0 461.9 462.2 4013.2 4021.5 4030.7 4128.3 4149.4 10854.6 11079.6 11178.6 11187.7 11189.4 11085.3 11133.2 11133.8 11133.8 11140.3 6232.7 7584.1 7953.4 8435.5 8466.9 5944.5 6108.7 6198.9 6240.9 6270.2 10459.3 10510.2 10569.8 10623.4 10646.9 7724.6 10370.5 10555.8 10622.2 10736.8 7092.8 7844.0 7847.8 7850.7 7851.2 8288.0 8549.5 8638.3 8654.7 8659.4 7709.8 7744.1 7875.5 7949.6 8154.3 7341.7 7487.3 7555.5 7699.6 7705.6 6935.5 7162.0 7240.1 7246.1 7246.3 5146.8 5152.0 5175.5 5246.9 5293.1 3907.5 5221.0 5258.8 5286.5 5402.0 06:04:58 INFO - PROCESS | 6612 | 06:04:58 INFO - PROCESS | 6612 | [#4] prototype.html Cycles:180 Average:4511.64 Median:5111.94 stddev:1408.97 (27.6%) stddev-sans-first:1376.96 06:04:58 INFO - PROCESS | 6612 | Values: 297.5 300.4 311.3 314.3 317.1 5290.9 5293.7 5309.7 5315.4 5315.9 4142.6 4519.4 4783.8 4939.7 5168.3 3091.2 3595.4 3990.9 4092.9 4127.6 3539.0 4026.5 4091.7 4108.4 4108.8 3491.0 3647.0 3875.9 4027.8 4093.4 3509.2 4054.8 4065.5 4078.7 4090.2 3500.0 3529.2 3547.4 3549.7 3568.1 4871.0 5179.4 5181.0 5195.6 5211.0 3927.9 4600.4 4601.7 4619.4 4622.8 4884.4 5632.3 5647.1 5653.7 5662.7 3410.5 3722.1 3857.4 3866.8 3878.2 5751.8 5776.0 5797.0 5808.1 5874.4 3425.3 3439.7 3826.5 3838.4 3897.8 3333.7 3956.3 3962.9 3980.9 3982.4 4329.0 4485.6 4527.8 4572.7 4618.8 4663.9 5085.0 5331.7 5493.5 5562.4 1162.5 1340.8 1346.3 1348.0 1350.2 823.4 833.7 848.2 854.5 903.9 4467.8 5138.9 5182.0 5210.7 5275.0 4487.9 5003.4 5639.2 5689.2 5742.5 3883.9 4714.4 5058.7 5157.2 5366.8 2650.7 2665.9 2968.0 3044.2 3066.0 5660.5 5729.7 5977.0 6005.0 6020.3 5326.7 5630.4 5937.7 5983.2 6057.2 4854.4 5899.6 5919.9 5942.6 6042.5 4608.7 5410.5 5827.5 5887.2 5925.2 4758.0 5368.6 5669.1 5691.8 5792.8 5543.7 5695.7 5756.8 5919.0 6129.4 3467.7 3677.5 3853.3 3918.4 3968.6 4842.2 5613.6 5684.9 5749.2 5862.8 5604.0 5618.2 5628.3 5639.3 5686.7 4934.4 5580.5 5622.3 5634.9 5652.4 4644.6 5219.9 5483.3 5531.2 5568.5 4720.0 5461.4 5666.0 5670.4 5678.9 5249.2 5542.4 5543.6 5556.8 5557.6 06:04:58 INFO - PROCESS | 6612 | 06:04:58 INFO - PROCESS | 6612 | [#5] yui.html Cycles:180 Average:25215.74 Median:3506.61 stddev:41109.20 (1172.3%) stddev-sans-first:41183.32 06:04:58 INFO - PROCESS | 6612 | Values: 703.1 714.3 791.2 823.1 859.2 13780.6 13962.0 14552.9 15075.3 15161.8 14148.2 14734.9 15038.8 15060.4 15074.3 702.5 775.4 786.5 787.5 789.5 712.5 844.4 872.4 876.2 880.5 936.7 939.1 942.2 943.0 943.1 725.7 930.8 937.9 938.1 938.6 667.7 691.7 695.0 695.0 695.5 14937.9 14973.8 14976.0 14978.5 14989.2 13796.7 13904.4 14018.4 14433.9 14445.9 11502.8 11806.0 12187.8 12930.6 13376.0 4623.1 4638.6 4692.9 4851.8 4864.4 95906.0 102697.5 103966.5 104704.0 106004.4 8989.0 10635.9 10805.1 10910.0 11163.2 20501.5 20546.6 20557.4 20605.9 20649.4 7751.4 7934.5 7947.8 8055.0 8063.7 4032.1 4084.3 4337.8 4402.7 4517.6 3361.7 3500.3 3500.9 3504.1 3509.1 626.8 629.4 640.8 649.4 651.8 619.2 628.6 630.0 641.7 658.1 8074.6 8793.7 9105.2 9157.9 9217.4 419.2 536.9 576.7 599.6 657.5 3867.7 4258.6 4308.6 4598.5 4620.7 139753.0 157026.3 162652.0 163332.4 168126.0 130184.3 142096.0 142465.0 145677.5 146459.8 101073.5 102322.0 121447.6 122755.4 124342.0 9337.7 9373.6 9736.0 9984.8 10126.5 57704.7 57822.7 57941.4 60345.3 61647.4 86908.0 87230.0 90452.0 90557.5 90796.5 13010.5 13046.4 13224.9 13499.1 13687.9 11771.5 14630.5 14687.0 15109.7 15587.8 13720.4 13829.9 14082.6 14139.1 14240.2 13651.2 14041.3 14144.0 14311.9 14610.2 13430.7 13553.7 13673.5 13847.3 14015.4 10822.6 10990.3 11094.0 11348.0 11803.2 7937.5 9927.5 10538.8 11239.6 11537.8 06:04:58 INFO - PROCESS | 6612 | -------- Summary: end -------- 06:04:58 INFO - PROCESS | 6612 | 06:04:59 INFO - TEST-INFO | 6612: exit 0 06:04:59 INFO - TEST-OK | dromaeo_css | took 1118555ms 06:04:59 INFO - TEST-START | kraken 06:04:59 INFO - operating with platform_type : mac_ 06:04:59 INFO - Initialising browser for kraken test... 06:04:59 INFO - TEST-INFO | started process 6614 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpvmLGBC/profile http://localhost:54675/getInfo.html) 06:05:01 INFO - PROCESS | 6614 | __metrics Screen width/height:1600/1200 06:05:01 INFO - PROCESS | 6614 | colorDepth:24 06:05:01 INFO - PROCESS | 6614 | Browser inner width/height: 1024/689 06:05:01 INFO - PROCESS | 6614 | __metrics 06:05:01 INFO - PROCESS | 6614 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:05:01 INFO - PROCESS | 6614 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:05:02 INFO - TEST-INFO | 6614: exit 0 06:05:02 INFO - Browser initialized. 06:05:02 INFO - Running cycle 1/1 for kraken test... 06:05:02 INFO - TEST-INFO | started process 6615 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpvmLGBC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:05:14 INFO - PROCESS | 6615 | RSS: Main: 228442112 06:05:14 INFO - PROCESS | 6615 | 06:05:16 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54675/tests/kraken/driver.html?testName=audio-beat-detection) 06:05:17 INFO - PROCESS | 6615 | RSS: Main: 285646848 06:05:17 INFO - PROCESS | 6615 | 06:05:20 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54675/tests/kraken/driver.html?testName=audio-dft) 06:05:21 INFO - PROCESS | 6615 | RSS: Main: 293818368 06:05:21 INFO - PROCESS | 6615 | 06:05:24 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54675/tests/kraken/driver.html?testName=audio-fft) 06:05:25 INFO - PROCESS | 6615 | RSS: Main: 388460544 06:05:25 INFO - PROCESS | 6615 | 06:05:27 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54675/tests/kraken/driver.html?testName=audio-oscillator) 06:05:27 INFO - PROCESS | 6615 | RSS: Main: 288817152 06:05:27 INFO - PROCESS | 6615 | 06:05:30 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54675/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:05:30 INFO - PROCESS | 6615 | RSS: Main: 294682624 06:05:30 INFO - PROCESS | 6615 | 06:05:33 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54675/tests/kraken/driver.html?testName=imaging-darkroom) 06:05:34 INFO - PROCESS | 6615 | RSS: Main: 332283904 06:05:34 INFO - PROCESS | 6615 | 06:05:36 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54675/tests/kraken/driver.html?testName=imaging-desaturate) 06:05:37 INFO - PROCESS | 6615 | RSS: Main: 332988416 06:05:37 INFO - PROCESS | 6615 | 06:05:40 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54675/tests/kraken/driver.html?testName=json-parse-financial) 06:05:41 INFO - PROCESS | 6615 | RSS: Main: 333643776 06:05:41 INFO - PROCESS | 6615 | 06:05:42 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54675/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:05:43 INFO - PROCESS | 6615 | RSS: Main: 301719552 06:05:43 INFO - PROCESS | 6615 | 06:05:45 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:05:45 INFO - PROCESS | 6615 | RSS: Main: 308731904 06:05:45 INFO - PROCESS | 6615 | 06:05:47 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:05:48 INFO - PROCESS | 6615 | RSS: Main: 302788608 06:05:48 INFO - PROCESS | 6615 | 06:05:51 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:05:52 INFO - PROCESS | 6615 | RSS: Main: 312647680 06:05:52 INFO - PROCESS | 6615 | 06:05:54 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:05:55 INFO - PROCESS | 6615 | RSS: Main: 314105856 06:05:55 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54675/tests/kraken/driver.html?testName=ai-astar) 06:05:57 INFO - PROCESS | 6615 | RSS: Main: 357203968 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | __start_tp_report 06:05:57 INFO - PROCESS | 6615 | _x_x_mozilla_page_load 06:05:57 INFO - PROCESS | 6615 | _x_x_mozilla_page_load_details 06:05:57 INFO - PROCESS | 6615 | |i|pagename|runs| 06:05:57 INFO - PROCESS | 6615 | |0;ai-astar;103;113;112;113;108;112;113;113;113;115 06:05:57 INFO - PROCESS | 6615 | |1;audio-beat-detection;146;107;108;168;120;108;131;107;143;108 06:05:57 INFO - PROCESS | 6615 | |2;audio-dft;187;185;186;182;187;183;179;183;185;183 06:05:57 INFO - PROCESS | 6615 | |3;audio-fft;81;58;63;89;57;58;78;94;86;58 06:05:57 INFO - PROCESS | 6615 | |4;audio-oscillator;88;68;105;69;70;69;85;64;94;134 06:05:57 INFO - PROCESS | 6615 | |5;imaging-gaussian-blur;87;84;87;84;86;83;87;85;87;83 06:05:57 INFO - PROCESS | 6615 | |6;imaging-darkroom;93;93;93;92;93;92;92;93;120;92 06:05:57 INFO - PROCESS | 6615 | |7;imaging-desaturate;85;87;85;87;83;84;85;86;114;118 06:05:57 INFO - PROCESS | 6615 | |8;json-parse-financial;56;53;56;56;54;55;54;54;54;53 06:05:57 INFO - PROCESS | 6615 | |9;json-stringify-tinderbox;64;74;68;78;72;70;72;69;70;71 06:05:57 INFO - PROCESS | 6615 | |10;stanford-crypto-aes;84;89;81;96;84;85;84;84;89;88 06:05:57 INFO - PROCESS | 6615 | |11;stanford-crypto-ccm;140;137;140;137;153;152;138;134;158;140 06:05:57 INFO - PROCESS | 6615 | |12;stanford-crypto-pbkdf2;168;169;166;168;165;168;167;167;164;169 06:05:57 INFO - PROCESS | 6615 | |13;stanford-crypto-sha256-iterative;69;82;88;88;85;67;66;66;67;64 06:05:57 INFO - PROCESS | 6615 | __end_tp_report 06:05:57 INFO - PROCESS | 6615 | __start_cc_report 06:05:57 INFO - PROCESS | 6615 | _x_x_mozilla_cycle_collect,2112 06:05:57 INFO - PROCESS | 6615 | __end_cc_report 06:05:57 INFO - PROCESS | 6615 | __startTimestamp1471525557709__endTimestamp 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | ------- Summary: start ------- 06:05:57 INFO - PROCESS | 6615 | Number of tests: 14 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:3.47 (3.1%) stddev-sans-first:1.88 06:05:57 INFO - PROCESS | 6615 | Values: 103.0 113.0 112.0 113.0 108.0 112.0 113.0 113.0 113.0 115.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#1] audio-beat-detection Cycles:10 Average:124.60 Median:125.50 stddev:21.57 (17.2%) stddev-sans-first:21.45 06:05:57 INFO - PROCESS | 6615 | Values: 146.0 107.0 108.0 168.0 120.0 108.0 131.0 107.0 143.0 108.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#2] audio-dft Cycles:10 Average:184.00 Median:185.00 stddev:2.49 (1.3%) stddev-sans-first:2.40 06:05:57 INFO - PROCESS | 6615 | Values: 187.0 185.0 186.0 182.0 187.0 183.0 179.0 183.0 185.0 183.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#3] audio-fft Cycles:10 Average:72.20 Median:79.50 stddev:14.83 (18.7%) stddev-sans-first:15.38 06:05:57 INFO - PROCESS | 6615 | Values: 81.0 58.0 63.0 89.0 57.0 58.0 78.0 94.0 86.0 58.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:69.50 stddev:22.00 (31.7%) stddev-sans-first:23.30 06:05:57 INFO - PROCESS | 6615 | Values: 88.0 68.0 105.0 69.0 70.0 69.0 85.0 64.0 94.0 134.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.50 stddev:1.70 (2.0%) stddev-sans-first:1.69 06:05:57 INFO - PROCESS | 6615 | Values: 87.0 84.0 87.0 84.0 86.0 83.0 87.0 85.0 87.0 83.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#6] imaging-darkroom Cycles:10 Average:95.30 Median:93.00 stddev:8.69 (9.3%) stddev-sans-first:9.18 06:05:57 INFO - PROCESS | 6615 | Values: 93.0 93.0 93.0 92.0 93.0 92.0 92.0 93.0 120.0 92.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#7] imaging-desaturate Cycles:10 Average:91.40 Median:85.00 stddev:13.06 (15.4%) stddev-sans-first:13.64 06:05:57 INFO - PROCESS | 6615 | Values: 85.0 87.0 85.0 87.0 83.0 84.0 85.0 86.0 114.0 118.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.50 stddev:1.18 (2.2%) stddev-sans-first:1.12 06:05:57 INFO - PROCESS | 6615 | Values: 56.0 53.0 56.0 56.0 54.0 55.0 54.0 54.0 54.0 53.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.50 stddev:3.71 (5.2%) stddev-sans-first:3.00 06:05:57 INFO - PROCESS | 6615 | Values: 64.0 74.0 68.0 78.0 72.0 70.0 72.0 69.0 70.0 71.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:86.50 stddev:4.25 (4.9%) stddev-sans-first:4.42 06:05:57 INFO - PROCESS | 6615 | Values: 84.0 89.0 81.0 96.0 84.0 85.0 84.0 84.0 89.0 88.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:140.00 stddev:8.24 (5.9%) stddev-sans-first:8.67 06:05:57 INFO - PROCESS | 6615 | Values: 140.0 137.0 140.0 137.0 153.0 152.0 138.0 134.0 158.0 140.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:168.00 stddev:1.66 (1.0%) stddev-sans-first:1.73 06:05:57 INFO - PROCESS | 6615 | Values: 168.0 169.0 166.0 168.0 165.0 168.0 167.0 167.0 164.0 169.0 06:05:57 INFO - PROCESS | 6615 | 06:05:57 INFO - PROCESS | 6615 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:75.50 stddev:10.15 (13.4%) stddev-sans-first:10.59 06:05:57 INFO - PROCESS | 6615 | Values: 69.0 82.0 88.0 88.0 85.0 67.0 66.0 66.0 67.0 64.0 06:05:57 INFO - PROCESS | 6615 | -------- Summary: end -------- 06:05:57 INFO - PROCESS | 6615 | 06:05:58 INFO - TEST-INFO | 6615: exit 0 06:05:58 INFO - TEST-OK | kraken | took 59406ms 06:05:58 INFO - SUITE-END | took 1177s 06:05:58 INFO - Completed test suite (00:19:38) 06:05:58 INFO - javascript benchmark 06:05:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.2818394045275, 3348.196432856114, 3350.865965361386, 3359.74801889858, 3423.1442139465134, 5764.5676574256995, 5840.707964601769, 5924.822255332336, 5935.525157987356, 5965.612235204714, 5805.5936084474, 5871.88256234875, 6002.849928751783, 6004.969975150129, 6026.126211699312, 5205.765740541685, 5263.34208223972, 5286.524212820865, 5295.708736019517, 5382.085045542248, 5046.697198168122, 5381.67709937405, 5384.138537833947, 5392.460753924615, 5421.024215641183, 5208.401033881114, 5235.450277720861, 5255.789768409259, 5289.920651190255, 5341.626086173969, 5237.9785941741375, 5273.578113750891, 5291.920621190685, 5299.231611416361, 5313.44208858072, 5212.4266330703595, 5239.659422137549, 5291.629585928987, 5301.946980530222, 5356.678599284055, 5802.97098514509, 5885.5585831062845, 5911.172435858984, 5935.0, 5940.0, 6131.50947924165, 6170.81487555374, 6193.907091393633, 6222.377762223787, 6242.906356404657, 6135.006123009995, 6201.441870231656, 6203.875922481525, 6204.875902481925, 6216.87566248677, 5960.642361458283, 5979.103134529768, 5986.640801551921, 5988.640681559121, 5991.131285963511, 6094.45149936499, 6180.629162250189, 6251.374862513712, 6254.843628909313, 6281.59169653971, 6204.441600255998, 6220.098085777628, 6222.408871157233, 6236.0, 6236.844078897973, 6207.0, 6209.2548894133, 6213.720382582795, 6235.719392627343, 6238.594491358138, 6127.387261273926, 6132.263729246456, 6139.369480960552, 6205.968970155121, 6220.564560480723, 6210.813675589739, 6220.968895155585, 6259.311475737665, 6270.811875643738, 6273.96863015682, 4959.677620954699, 5014.222795466709, 5026.648134630614, 5034.697918124924, 5034.874128146826, 4463.754493502888, 4468.285074388083, 4499.842505512292, 4520.909581808345, 4533.750643714561, 5768.74040668171, 5800.622959507618, 5801.593888427854, 5825.38833422493, 5828.0966450200285, 6178.567500274938, 6200.751969921243, 6220.377962203834, 6229.906551401733, 6235.345288744707, 5613.859653508695, 5702.144678298288, 5782.219400380979, 5794.565407594448, 5808.564357673192, 5435.646682965595, 5470.72646367688, 5493.340799104053, 5496.917546236809, 5497.697626630574, 6196.0705894116245, 6223.8797016537455, 6231.594946328474, 6233.470155036873, 6309.747610095545, 6215.813525594059, 6226.5641405101205, 6236.095766113979, 6241.625502469867, 6246.250449946036, 6337.0, 6355.713992870239, 6355.809325720235, 6379.74481020754, 6384.872302554016, 6097.603655762361, 6110.953680641605, 6132.0, 6191.628502289697, 6224.533160013108, 6142.775043102407, 6154.876902461836, 6167.321594624498, 6180.351063138306, 6200.7519699211525, 6176.81469555914, 6186.443220110212, 6194.473469755121, 6200.100985357189, 6209.844753881189, 6153.66154861469, 6169.691515424301, 6180.289266734203, 6184.969075154595, 6201.596896201732, 5515.889682206413, 5811.535077193899, 5897.764089436546, 6035.155078288955, 6039.15451836752, 6136.907946380898, 6176.35148309421, 6186.659733714509, 6187.102870083902, 6197.504199664107, 6010.909836352371, 6028.246469191351, 6053.848653783691, 6078.750862181021, 6101.054336577838], "value": 5758.081912776115, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.3175012107506, 485.7619766314507, 504.6013649217118, 526.0058489454933, 534.270720466563, 30786.0, 40566.0, 41647.16705665885, 41922.37116443256, 42002.579974200286, 41966.16067678652, 42305.576944230546, 42321.73034808953, 42383.0, 42565.78717106418, 9616.759581010461, 9729.610815567368, 9797.0, 9810.411375317504, 9822.214222862154, 8780.517071561062, 8995.5052472114, 11894.464749086312, 12114.091443141755, 12139.939300303486, 11864.584739534119, 11923.463444145034, 11940.985016273584, 11959.282443053444, 11992.7601447971, 11949.880501194964, 12001.45993430289, 12011.279323240633, 12024.879751202463, 12061.819072713917, 11867.762644747143, 11954.222975506651, 11954.342511161884, 11967.521299148024, 11968.222065565711, 39889.601103988876, 40212.391504339794, 40228.79885600582, 40266.39600405996, 40426.372285801626, 3369.0903456066726, 3423.3324501722172, 3941.625545573166, 4126.09225970286, 4220.36694495825, 31292.0, 31834.363312733614, 31846.522302165486, 32219.550120244647, 32263.35473290521, 3228.1606782236554, 3319.4356959316974, 3345.5149003394613, 3361.411752943225, 3397.2695870387984, 516650.0, 517253.0, 518458.0, 520570.0, 521076.39461802825, 59222.11166832501, 59677.40322596805, 60036.39963600308, 60195.69902150461, 60207.69896150491, 61981.450463738765, 62093.0, 62179.0, 62207.0, 62376.688116559126, 16388.590285242964, 16505.33978640841, 16617.91691041537, 16625.334986600643, 16698.499045028424, 11784.234024788362, 11995.700107497383, 12833.679158020937, 12869.22784632925, 12895.226286422845, 16535.421260255833, 16908.0, 16973.321067157423, 16990.575235618962, 17122.486325410257, 6660.56706314088, 6769.800082124091, 7237.710491580383, 7319.780406587811, 7363.521371110861, 764.728521374915, 924.7318277699392, 961.9711408657752, 1050.894910508943, 1156.5894107591853, 29463.558046629318, 29755.553666695017, 29872.55191172134, 29990.0, 30799.0, 1031.6956497833157, 1065.8081545321916, 1091.1761619976867, 1102.2284400919389, 1178.3872386359046, 12895.419706113247, 15429.845701542841, 15497.690046199014, 15524.611884702746, 15918.204089795696, 649283.0, 654426.0, 654431.0, 654703.0, 655612.0, 57489.0, 57679.4232057674, 57772.0, 57816.42183578195, 58032.70983645055, 1266.4364357860848, 1316.0984725463088, 1329.454923481368, 1337.5452346202337, 1361.8025386319127, 29478.410431790817, 31269.530957036117, 31451.344242405507, 31507.0, 32113.678863212004, 49252.75373623109, 49905.50094499008, 49951.0, 50145.0, 50202.744931377005, 513090.0, 513863.0, 514552.0, 517416.0, 518046.0, 25855.224343269732, 26362.0, 26634.334141646614, 26840.32899177536, 40707.690308002304, 47046.47959462774, 47118.76440617775, 47193.76403117963, 47502.0, 48286.0, 37569.06077347979, 38948.41577376398, 39011.804940976246, 39035.82892513229, 40404.393934090425, 32571.34857302888, 38709.6129038706, 38849.417258741705, 39881.40177897276, 39917.40123898202, 33417.83291083529, 35182.0, 38230.42654360131, 38648.2270354597, 38849.41725874058, 32097.679023209468, 33869.0, 34778.30443391169, 34994.15064342971, 35297.764578239636, 25022.37444063913, 25453.87273063697, 25522.0, 25983.0, 26119.477610447306], "value": 20441.600886797063, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.36389227175636, 371.1074864949797, 379.2396245527717, 386.35478750486686, 408.4139260161666, 15517.922410387946, 15529.689406211883, 20122.69815952762, 20664.89667551664, 20747.896260518693, 20877.269295574657, 21091.945402729878, 21221.469463263427, 21518.924053797327, 21561.67657485135, 5852.180694702745, 5896.643771932437, 6095.024796032636, 6138.693065346715, 6182.84542886427, 4207.789610519477, 6011.75952961881, 6120.571559990803, 6120.571559990803, 6131.785387511438, 6383.425491705745, 6646.169228846395, 6689.331066893296, 6757.256701762803, 6774.8306292342595, 6597.1423714916755, 6626.966865165691, 6688.230853451867, 6726.529142959995, 6756.324367563254, 5839.299284085937, 5840.240768700084, 5840.766369345221, 5845.415458454163, 5861.824145275648, 13366.665833354147, 13533.864661353358, 23287.76712232873, 24134.637980430307, 25138.748612513824, 2146.5170336674278, 2225.5103877147003, 2228.543148654522, 2644.5239856825765, 2989.5948904015077, 10042.648507302209, 10145.746356341077, 10395.532201050972, 10481.423521706303, 10499.475026248658, 1862.4505770797648, 1885.764279465067, 1937.3122541497705, 1964.224131468062, 2027.8986050697406, 89494.0, 107883.0, 111424.4428777851, 112199.43900280527, 117022.41488792587, 11144.944275278573, 11307.095432365391, 11420.37187954654, 11535.942320288512, 11634.26594750233, 14354.354054067591, 14382.712345753027, 14416.567502974927, 14434.927825360806, 14448.711025779425, 8435.240828325357, 8578.957105214435, 8603.483790972561, 8618.698345557876, 8717.30261579072, 8582.742517724479, 8602.612882420306, 8619.525926074, 8767.605457754416, 8786.341024423195, 4433.27353677486, 4456.019675671347, 4479.619232365286, 4481.260592002348, 4546.340780586796, 1860.5255659806662, 1944.1445763863856, 1948.1915005272936, 1951.677973134417, 1953.9413817585496, 827.668932427022, 861.8104017116225, 913.72588223534, 956.8182045411489, 983.179045497018, 17836.55408614795, 20359.898200508902, 21421.67867481989, 21451.0, 21560.56878862414, 891.2736120062128, 905.9728208153766, 909.417972497602, 956.3837115275302, 989.2284018465608, 10719.19606029551, 11050.226484146033, 11169.771325154226, 11260.605878794202, 11279.379634120038, 493570.53214734414, 498315.0, 500683.0, 612858.0, 617468.0, 128553.35723321323, 129031.35484322706, 136931.0, 139117.0, 139921.0, 940.1115945431502, 1010.9696709098739, 1023.1661196125134, 1024.7489365105596, 1025.3386565665105, 18743.34398296053, 19515.707264390763, 19754.308599198957, 19881.900590496956, 19915.900420498383, 57401.0, 58063.70968145132, 60477.39522604718, 61171.0, 61496.69251653713, 75901.86147207685, 81694.3661126786, 86768.0, 87500.0, 89381.0, 7539.698412063675, 7889.329407000239, 8097.433179677366, 8238.670453182045, 8239.629216685384, 9260.907390926273, 12575.245485270547, 12829.422675979416, 12912.096153269556, 13165.0, 9579.568919398504, 10931.617393391192, 11471.4264286787, 11499.412632157697, 11564.884351156381, 10763.677089687322, 11334.546618135182, 11439.485223164811, 11592.420378981185, 11921.105917056082, 8453.492790432594, 11608.825867611824, 11953.103517236068, 11994.820077699016, 12011.399430028288, 8043.557604331818, 8051.597420128853, 8159.632816523152, 8495.235428811437, 8531.488110713377, 11035.0, 11278.774424511628, 11322.490487928228, 11783.587574434854, 11832.408379580815], "value": 9642.96506410742, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.1573224600736, 1545.4281915691192, 1559.345075068471, 1666.9333226670942, 1762.603414231798, 6697.8995315070315, 7978.92021079789, 9094.63621455141, 9105.726828195156, 9192.816143677122, 8909.777255568613, 8981.820363592724, 9011.0, 9020.81958360834, 9031.774205644862, 2038.4903774056486, 2400.4478969836905, 2422.8546287222734, 2429.4898071404937, 2432.391902024485, 2381.3213234228247, 2417.468157005456, 2434.379233295507, 2437.9390515237174, 2438.0979037756056, 2389.2593296078185, 2415.033986405443, 2421.3462365161395, 2427.9757202427927, 2440.3289095498703, 2376.1327115602853, 2398.9760102398927, 2409.0725070847675, 2411.9276421707377, 2416.3355077353694, 2363.1138323128826, 2383.7377888432256, 2395.149721848736, 2397.8081753459683, 2406.7472915343988, 8942.597583108776, 9007.954960225223, 9079.364444488889, 9128.315376346734, 9142.04008579103, 5383.946160538384, 5540.085885828834, 5550.084236101038, 5553.305836770403, 5557.999560079184, 3679.466477360768, 3692.984429281969, 3755.117547376364, 3770.5663848657205, 3785.659290663826, 1091.1434523898781, 1097.13874608432, 1097.5786356668134, 1118.9608363707312, 1127.560251501915, 11945.522179112824, 11984.400779960966, 12059.698507537294, 12072.396380180955, 12101.39493025354, 4423.225935461346, 4528.343390208402, 4701.623870090384, 4715.151272770866, 4727.739974301447, 9432.905670943202, 9438.905610943802, 9451.054894510495, 9458.57436415363, 9466.384684995453, 5366.785328586813, 5370.677759334452, 5371.328583927009, 5373.650712703662, 5375.059364611177, 4071.470708807836, 4495.887602809956, 4653.976730116327, 4893.510648935078, 5022.748862556858, 3857.8649747259274, 3859.131695368576, 3937.5078115235597, 3962.980185099114, 3973.145773658677, 1157.6179860645966, 1183.4319526627266, 1185.9525618975317, 1192.540871764377, 1197.479096592985, 865.8398196333699, 935.6678379175431, 960.0303693269708, 960.7357976556364, 996.7059717383389, 5969.343372229051, 6013.639181649028, 6045.667488288098, 6053.576249662569, 6239.656818875005, 447.91265703187565, 452.9524399938025, 459.0383147306454, 461.8683675152565, 462.20731445570334, 4013.2174226026227, 4021.4772079630047, 4030.6976976726864, 4128.277551428488, 4149.439825623563, 10854.565817367375, 11079.5568177272, 11178.552857885594, 11187.664370068911, 11189.440527973731, 11085.334879907232, 11133.165012624086, 11133.777324453466, 11133.832992505118, 11140.275882067639, 6232.657203853741, 7584.051993500812, 7953.363730901514, 8435.451695639764, 8466.872996905051, 5944.524438044859, 6108.664023478751, 6198.938010619836, 6240.937590624218, 6270.15352927349, 10459.320144190604, 10510.211734120125, 10569.788604227719, 10623.36259824413, 10646.946765266124, 7724.609643613674, 10370.481475926324, 10555.841662375224, 10622.150227981589, 10736.785264294827, 7092.751753688596, 7843.960780196063, 7847.843043138991, 7850.685972561034, 7851.175626559358, 8288.046874609256, 8549.529775862387, 8638.26574741154, 8654.697085601974, 8659.350548708746, 7709.845803084019, 7744.070711514654, 7875.527468351917, 7949.602519874099, 8154.266116049347, 7341.6696248670005, 7487.251274872469, 7555.508891922007, 7699.576523291049, 7705.576193309197, 6935.514513983972, 7162.033125528195, 7240.1311842579225, 7246.094238220223, 7246.347828695442, 5146.845594632167, 5152.0, 5175.534201921845, 5246.895062098813, 5293.100172970681, 3907.5115610548683, 5221.0, 5258.789648414174, 5286.497782710637, 5401.972990135024], "value": 4570.393325351035, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.45268705581736, 300.3527398456327, 311.27472987938097, 314.3226347221735, 317.1010186122343, 5290.894182116355, 5293.735313234342, 5309.654872433294, 5315.415304316522, 5315.867103322419, 4142.585741425856, 4519.435070616173, 4783.760811959405, 4939.654224204307, 5168.302279192308, 3091.196288964874, 3595.388783906742, 3990.8603198888045, 4092.877213683594, 4127.62851343379, 3538.982305088471, 4026.4765580474646, 4091.672666186698, 4108.445359876423, 4108.77401742904, 3491.0, 3646.981765091171, 3875.903102422448, 4027.7784721840285, 4093.406456063869, 3509.2455122148604, 4054.756714597119, 4065.5121385433936, 4078.714489985702, 4090.2433049885867, 3500.0049971516246, 3529.2235708144167, 3547.379208638478, 3549.680528752399, 3568.107973006748, 4870.977094810094, 5179.378474583036, 5181.0, 5195.636305458619, 5211.03595834768, 3927.9214415711813, 4600.42494688164, 4601.723896566216, 4619.422572178478, 4622.791974361162, 4884.438289596706, 5632.324121105453, 5647.09646456569, 5653.660780353151, 5662.688552129631, 3410.539577157077, 3722.133956853208, 3857.4213867919757, 3866.8259928303296, 3878.166194268246, 5751.756814842215, 5776.0, 5797.014537513226, 5808.070708686674, 5874.41390824475, 3425.3320602482777, 3439.6782172033777, 3826.4834247376803, 3838.443425703313, 3897.7661340319673, 3333.683300086488, 3956.2680904032845, 3962.9405558916637, 3980.940285895656, 3982.4026396040244, 4329.0, 4485.573870482299, 4527.7736113194205, 4572.6799124061, 4618.815247390066, 4663.930041049387, 5084.9745751271, 5331.733413329318, 5493.478119578634, 5562.3881373048935, 1162.5291756838494, 1340.751960887239, 1346.3036573916936, 1348.0159483577045, 1350.1763924006348, 823.4112609484248, 833.7244016654407, 848.1857416879865, 854.5001174312972, 903.8644203369547, 4467.754273514988, 5138.897222055538, 5182.0, 5210.661307015108, 5275.024120537712, 4487.887802804956, 5003.399592048978, 5639.210510528529, 5689.188243876612, 5742.54059675225, 3883.941740873889, 4714.4106986626675, 5058.747062646853, 5157.226416037624, 5366.838994830083, 2650.6686664166978, 2665.880624903207, 2967.9703202967694, 3044.2389402649337, 3066.0, 5660.518855897212, 5729.713514324267, 5976.970115149484, 6004.969975150184, 6020.337762846083, 5326.707031113326, 5630.408807075198, 5937.673427961504, 5983.19226904371, 6057.212562366864, 4854.417469903564, 5899.557533185029, 5919.904817608756, 5942.64344139353, 6042.546808989256, 4608.746518941491, 5410.48600382963, 5827.484853937922, 5887.20522729426, 5925.200097986889, 4757.976210118927, 5368.597355198298, 5669.137085771659, 5691.8292451226525, 5792.768289268551, 5543.667379957215, 5695.715214239354, 5756.769729210785, 5918.993771059017, 6129.448349648553, 3467.722582193469, 3677.5403074615674, 3853.3449313616193, 3918.4318273849563, 3968.6428221460205, 4842.249451335048, 5613.57898157648, 5684.914726279027, 5749.195112684311, 5862.794802181903, 5604.047311957059, 5618.157276408571, 5628.268325117709, 5639.323281206118, 5686.687232202268, 4934.407871055496, 5580.497755202051, 5622.297212848394, 5634.915476267777, 5652.378238393856, 4644.60520855731, 5219.869503262297, 5483.259759932358, 5531.155846686514, 5568.526675232651, 4719.961608446137, 5461.426550212171, 5665.971670141623, 5670.376258611632, 5678.927191575128, 5249.246788546207, 5542.3903370630005, 5543.6396634218645, 5556.833295001156, 5557.610967232255], "value": 4063.3983338741755, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.1000319590922, 714.2964180282422, 791.2245998921054, 823.1026091456954, 859.1794835931684, 13780.586582402531, 13962.0, 14552.854471455283, 15075.321610527526, 15161.772573411408, 14148.221847798366, 14734.852651473482, 15038.774418383733, 15060.39758409665, 15074.3216555255, 702.4766548921061, 775.4029397364027, 786.5477350523444, 787.5078076202374, 789.4947233770391, 712.4905692429911, 844.4342290665238, 872.4067634008871, 876.2245412809677, 880.506916126971, 936.7189843047058, 939.1125386509751, 942.1661829281102, 942.9905700943006, 943.1323182671958, 725.6553137259812, 930.8045310484806, 937.8968313485511, 938.0853667674031, 938.6433155400938, 667.6795138333579, 691.7233106757287, 694.9687264073128, 694.9826254343632, 695.4957655699628, 14937.850621493755, 14973.775393369107, 14976.026558273787, 14978.47575334869, 14989.175595342253, 13796.655083622889, 13904.443822247098, 14018.36917338712, 14433.855661443462, 14445.855541444556, 11502.827457588142, 11806.0, 12187.756244875141, 12930.612081637566, 13375.996800239924, 4623.144718227139, 4638.628909687217, 4692.859214223545, 4851.805927762885, 4864.391951006124, 95906.0, 102697.4865125677, 103966.48016759944, 104704.0, 106004.40993385106, 8988.955055224813, 10635.943694397758, 10805.081568066646, 10910.0, 11163.218574699693, 20501.487462813548, 20546.589068218553, 20557.383278501668, 20605.896970515052, 20649.380518584465, 7751.418643601753, 7934.523928564305, 7947.761567152879, 8055.033395992518, 8063.6774529018185, 4032.052467670095, 4084.346504559256, 4337.8264869404875, 4402.669799765031, 4517.570830771071, 3361.6638336166675, 3500.2649443617124, 3500.8599656013985, 3504.123969007748, 3509.1051781795477, 626.764963138823, 629.3706293706293, 640.7533099756628, 649.3863299182227, 651.7718798420515, 619.2476141488023, 628.629108825795, 629.9842503937439, 641.6695401868042, 658.0721183131857, 8074.636641351036, 8793.736187914374, 9105.2260557852, 9157.862632060524, 9217.44695318283, 419.19394146114337, 536.888640514132, 576.6972339521784, 599.6492052149541, 657.5298661457081, 3867.7292589519025, 4258.564229854813, 4308.590683885026, 4598.494165641777, 4620.653450991122, 139753.0, 157026.29737026207, 162652.0, 163332.3666763341, 168126.0, 130184.349078254, 142096.0, 142465.0, 145677.5432245685, 146459.80310295353, 101073.49463252637, 102322.0, 121447.57104857854, 122755.38622306832, 124342.0, 9337.688277831168, 9373.625054997996, 9735.951320243354, 9984.800303994025, 10126.544305506417, 57704.711476442346, 57822.7108864453, 57941.4205857936, 60345.26719961597, 61647.383526164165, 86908.0, 87230.0, 90452.0, 90557.54721226351, 90796.54601726949, 13010.544630937871, 13046.412911419198, 13224.933875330562, 13499.122557033761, 13687.863121368659, 11771.52913883435, 14630.487932922297, 14687.0, 15109.697806044038, 15587.76618350703, 13720.382582784, 13829.861701383259, 14082.647933801736, 14139.080959737585, 14240.216788076756, 13651.249181295125, 14041.297935103408, 14144.0, 14311.856881431053, 14610.19643919552, 13430.664233394244, 13553.661158470723, 13673.521426750016, 13847.307634618028, 14015.439382424996, 10822.56709731602, 10990.340579565252, 11094.0, 11348.0, 11803.173777835469, 7937.517389403275, 9927.453990030617, 10538.84072752012, 11239.60661376848, 11537.769244615229], "value": 7812.920685440225, "unit": "score"}], "name": "dromaeo_css", "value": 7403.740010193233, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 112.0, 113.0, 108.0, 112.0, 113.0, 113.0, 113.0, 115.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 107.0, 108.0, 168.0, 120.0, 108.0, 131.0, 107.0, 143.0, 108.0], "value": 124.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 186.0, 182.0, 187.0, 183.0, 179.0, 183.0, 185.0, 183.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 63.0, 89.0, 57.0, 58.0, 78.0, 94.0, 86.0, 58.0], "value": 72.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 105.0, 69.0, 70.0, 69.0, 85.0, 64.0, 94.0, 134.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 86.0, 83.0, 87.0, 85.0, 87.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 92.0, 93.0, 92.0, 92.0, 93.0, 120.0, 92.0], "value": 95.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 85.0, 87.0, 83.0, 84.0, 85.0, 86.0, 114.0, 118.0], "value": 91.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 56.0, 56.0, 54.0, 55.0, 54.0, 54.0, 54.0, 53.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 68.0, 78.0, 72.0, 70.0, 72.0, 69.0, 70.0, 71.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 81.0, 96.0, 84.0, 85.0, 84.0, 84.0, 89.0, 88.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 140.0, 137.0, 153.0, 152.0, 138.0, 134.0, 158.0, 140.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 166.0, 168.0, 165.0, 168.0, 167.0, 167.0, 164.0, 169.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 82.0, 88.0, 88.0, 85.0, 67.0, 66.0, 66.0, 67.0, 64.0], "value": 74.2, "unit": "score"}], "name": "kraken", "value": 1444.8, "alertThreshold": 2.0}]} 06:05:58 INFO - Return code: 0 06:05:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:05:59 INFO - # TBPL SUCCESS # 06:05:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:05:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:05:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:05:59 INFO - 2016-08-18 06:05:59.042 screenresolution[6619:109168] starting screenresolution argv=screenresolution get 06:05:59 INFO - 2016-08-18 06:05:59.045 screenresolution[6619:109168] Display 0: 1600x1200x32@60 06:05:59 INFO - 2016-08-18 06:05:59.051 screenresolution[6620:109171] starting screenresolution argv=screenresolution list 06:05:59 INFO - Available Modes on Display 0 06:05:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:05:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:05:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:05:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:05:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:05:59 INFO - Intel Iris: 06:05:59 INFO - Chipset Model: Intel Iris 06:05:59 INFO - Type: GPU 06:05:59 INFO - Bus: Built-In 06:05:59 INFO - VRAM (Dynamic, Max): 1536 MB 06:05:59 INFO - Vendor: Intel (0x8086) 06:05:59 INFO - Device ID: 0x0a2e 06:05:59 INFO - Revision ID: 0x0009 06:05:59 INFO - Displays: 06:05:59 INFO - Display: 06:05:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:05:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:05:59 INFO - Display Serial Number: 1600x1200 60 06:05:59 INFO - Main Display: Yes 06:05:59 INFO - Mirror: Off 06:05:59 INFO - Online: Yes 06:05:59 INFO - Rotation: Supported 06:05:59 INFO - Return code: 0 06:05:59 INFO - Running post-action listener: _resource_record_post_action 06:05:59 INFO - [mozharness: 2016-08-18 13:05:59.294190Z] Finished run-tests step (success) 06:05:59 INFO - Running post-run listener: _resource_record_post_run 06:05:59 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1338368; Write bytes: 706249728; Read time: 22; Write time: 2803 06:05:59 INFO - TinderboxPrint: CPU usage
53.6% 06:05:59 INFO - TinderboxPrint: I/O read bytes / time
1,338,368 / 22 06:05:59 INFO - TinderboxPrint: I/O write bytes / time
706,249,728 / 2,803 06:05:59 INFO - TinderboxPrint: CPU idle
2,252.6 (46.4%) 06:05:59 INFO - TinderboxPrint: CPU system
972.5 (20.0%) 06:05:59 INFO - TinderboxPrint: CPU user
1,628.5 (33.6%) 06:05:59 INFO - TinderboxPrint: Swap in / out
408,199,168 / 0 06:05:59 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398379520; Write bytes: 393361408; Read time: 29569; Write time: 717 06:05:59 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1297408; Write bytes: 296995840; Read time: 20; Write time: 2044 06:05:59 INFO - Running post-run listener: _upload_blobber_files 06:05:59 INFO - Blob upload gear active. 06:05:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:05:59 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 06:05:59 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'] 06:05:59 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 06:06:00 INFO - (blobuploader) - INFO - Open directory for files ... 06:06:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:06:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:06:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:01 INFO - (blobuploader) - INFO - Done attempting. 06:06:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:06:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:06:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:03 INFO - (blobuploader) - INFO - Done attempting. 06:06:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:06:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:06:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:05 INFO - (blobuploader) - INFO - Done attempting. 06:06:05 INFO - (blobuploader) - INFO - Iteration through files over. 06:06:05 INFO - Return code: 0 06:06:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:06:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:06:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f7d9b8a24c5ccbae2f3449fa3c05fe8489f50b9526f21b9ddf8db31ea42b664cde6c9b6f3e8618a6c0ea7715adc88389e56a69a989e437662e7ce119cca66ace", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/110998a1ad70af96d6d1b1ff9e9378cd67503ab1651409958eded36101d7fcf83f5a9558d7f1d1faa2f4cc0a36be9feceaef7d2e7ba816d721b718aec834da0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fbbdf361a6472f067f1e8c0ffc15082bd5958d821966d01339b50d6ea404ce20aaadccb04095d71ef474275fe9818a7cf15185180a59791c657a2c1ee0375351"} 06:06:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:06:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:06:05 INFO - Contents: 06:06:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f7d9b8a24c5ccbae2f3449fa3c05fe8489f50b9526f21b9ddf8db31ea42b664cde6c9b6f3e8618a6c0ea7715adc88389e56a69a989e437662e7ce119cca66ace", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/110998a1ad70af96d6d1b1ff9e9378cd67503ab1651409958eded36101d7fcf83f5a9558d7f1d1faa2f4cc0a36be9feceaef7d2e7ba816d721b718aec834da0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fbbdf361a6472f067f1e8c0ffc15082bd5958d821966d01339b50d6ea404ce20aaadccb04095d71ef474275fe9818a7cf15185180a59791c657a2c1ee0375351"} 06:06:05 INFO - Running post-run listener: copy_logs_to_upload_dir 06:06:05 INFO - Copying logs to upload dir... 06:06:05 INFO - mkdir: /builds/slave/test/build/upload/logs 06:06:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.984242 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-18 06:06:05.924059) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 06:06:05.928076) ========= 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.reir3PrfXt/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.sQunNfDJDn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f7d9b8a24c5ccbae2f3449fa3c05fe8489f50b9526f21b9ddf8db31ea42b664cde6c9b6f3e8618a6c0ea7715adc88389e56a69a989e437662e7ce119cca66ace", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/110998a1ad70af96d6d1b1ff9e9378cd67503ab1651409958eded36101d7fcf83f5a9558d7f1d1faa2f4cc0a36be9feceaef7d2e7ba816d721b718aec834da0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fbbdf361a6472f067f1e8c0ffc15082bd5958d821966d01339b50d6ea404ce20aaadccb04095d71ef474275fe9818a7cf15185180a59791c657a2c1ee0375351"} build_url:https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011568 build_url: 'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f7d9b8a24c5ccbae2f3449fa3c05fe8489f50b9526f21b9ddf8db31ea42b664cde6c9b6f3e8618a6c0ea7715adc88389e56a69a989e437662e7ce119cca66ace", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/110998a1ad70af96d6d1b1ff9e9378cd67503ab1651409958eded36101d7fcf83f5a9558d7f1d1faa2f4cc0a36be9feceaef7d2e7ba816d721b718aec834da0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fbbdf361a6472f067f1e8c0ffc15082bd5958d821966d01339b50d6ea404ce20aaadccb04095d71ef474275fe9818a7cf15185180a59791c657a2c1ee0375351"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 06:06:05.961955) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:06:05.962349) ========= 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.reir3PrfXt/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.sQunNfDJDn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.005408 ========= master_lag: 0.66 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:06:06.632038) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 06:06:06.632503) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 06:06:06.633019) ========= ========= Total master_lag: 0.85 =========