builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0191 starttime: 1472582321.36 results: success (0) buildid: 20160830093621 builduid: c60f967b5ba847459154198a0ca5f0d3 revision: 6a5af5a4c2efd7df3bd8eb6f87ad6ef654854090 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:41.364742) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:41.365205) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:41.365493) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:41.390468) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:41.390846) ========= 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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-30 11:38:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.51M=0.001s 2016-08-30 11:38:41 (9.51 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083181 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:41.497169) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:41.497514) ========= 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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.038235 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:41.554620) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 11:38:41.555209) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6a5af5a4c2efd7df3bd8eb6f87ad6ef654854090 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 6a5af5a4c2efd7df3bd8eb6f87ad6ef654854090 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7jPXRVfWUP/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.QYSXNxNCdN/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-30 11:38:41,635 truncating revision to first 12 chars 2016-08-30 11:38:41,635 Setting DEBUG logging. 2016-08-30 11:38:41,635 attempt 1/10 2016-08-30 11:38:41,635 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6a5af5a4c2ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 11:38:43,137 unpacking tar archive at: fx-team-6a5af5a4c2ef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.773122 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 11:38:43.351693) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:43.352012) ========= script_repo_revision: 6a5af5a4c2efd7df3bd8eb6f87ad6ef654854090 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:43.352402) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:43.352669) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 11:38:43.371374) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-30 11:38:43.371804) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7jPXRVfWUP/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.QYSXNxNCdN/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 11:38:43 INFO - MultiFileLogger online at 20160830 11:38:43 in /builds/slave/test 11:38:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:38:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:38:43 INFO - {'append_to_log': False, 11:38:43 INFO - 'base_work_dir': '/builds/slave/test', 11:38:43 INFO - 'blob_upload_branch': 'Fx-Team', 11:38:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:38:43 INFO - 'branch': 'Fx-Team', 11:38:43 INFO - 'buildbot_json_path': 'buildprops.json', 11:38:43 INFO - 'config_files': ('talos/mac_config.py',), 11:38:43 INFO - 'default_actions': ('clobber', 11:38:43 INFO - 'read-buildbot-config', 11:38:43 INFO - 'download-and-extract', 11:38:43 INFO - 'populate-webroot', 11:38:43 INFO - 'create-virtualenv', 11:38:43 INFO - 'install', 11:38:43 INFO - 'run-tests'), 11:38:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:38:43 INFO - 'download_minidump_stackwalk': True, 11:38:43 INFO - 'download_symbols': 'ondemand', 11:38:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:38:43 INFO - 'tooltool.py': '/tools/tooltool.py', 11:38:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:38:43 INFO - '/tools/misc-python/virtualenv.py')}, 11:38:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:38:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:38:43 INFO - 'installer_path': 'installer.exe', 11:38:43 INFO - 'log_level': 'info', 11:38:43 INFO - 'log_name': 'talos', 11:38:43 INFO - 'log_to_console': True, 11:38:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:38:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:38:43 INFO - 'opt_config_files': (), 11:38:43 INFO - 'pip_index': False, 11:38:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:38:43 INFO - 'cmd': ('bash', 11:38:43 INFO - '-c', 11:38:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:38:43 INFO - 'enabled': True, 11:38:43 INFO - 'halt_on_failure': False, 11:38:43 INFO - 'name': 'check_screen_resolution'},), 11:38:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:38:43 INFO - 'cmd': ('bash', 11:38:43 INFO - '-c', 11:38:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:38:43 INFO - 'enabled': True, 11:38:43 INFO - 'halt_on_failure': False, 11:38:43 INFO - 'name': 'check_screen_resolution'},), 11:38:43 INFO - 'run_cmd_checks_enabled': True, 11:38:43 INFO - 'sps_profile': False, 11:38:43 INFO - 'sps_profile_interval': 0, 11:38:43 INFO - 'suite': 'dromaeojs', 11:38:43 INFO - 'system_bits': '32', 11:38:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:38:43 INFO - 'title': 't-yosemite-r7-0191', 11:38:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:38:43 INFO - 'use_talos_json': True, 11:38:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:38:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:38:43 INFO - 'work_dir': 'build'} 11:38:43 INFO - [mozharness: 2016-08-30 18:38:43.571678Z] Running clobber step. 11:38:43 INFO - Running pre-action listener: _resource_record_pre_action 11:38:43 INFO - Running main action method: clobber 11:38:43 INFO - rmtree: /builds/slave/test/build 11:38:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:38:45 INFO - Running post-action listener: _resource_record_post_action 11:38:45 INFO - [mozharness: 2016-08-30 18:38:45.704646Z] Finished clobber step (success) 11:38:45 INFO - [mozharness: 2016-08-30 18:38:45.704810Z] Running read-buildbot-config step. 11:38:45 INFO - Running pre-action listener: _resource_record_pre_action 11:38:45 INFO - Running main action method: read_buildbot_config 11:38:45 INFO - Using buildbot properties: 11:38:45 INFO - { 11:38:45 INFO - "project": "", 11:38:45 INFO - "product": "firefox", 11:38:45 INFO - "script_repo_revision": "production", 11:38:45 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:38:45 INFO - "repository": "", 11:38:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:38:45 INFO - "buildid": "20160830093621", 11:38:45 INFO - "pgo_build": "False", 11:38:45 INFO - "basedir": "/builds/slave/test", 11:38:45 INFO - "buildnumber": 130, 11:38:45 INFO - "slavename": "t-yosemite-r7-0191", 11:38:45 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 11:38:45 INFO - "platform": "yosemite_r7", 11:38:45 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:38:45 INFO - "branch": "fx-team", 11:38:45 INFO - "repo_path": "integration/fx-team", 11:38:45 INFO - "revision": "6a5af5a4c2efd7df3bd8eb6f87ad6ef654854090", 11:38:45 INFO - "stage_platform": "macosx64", 11:38:45 INFO - "builduid": "c60f967b5ba847459154198a0ca5f0d3", 11:38:45 INFO - "slavebuilddir": "test" 11:38:45 INFO - } 11:38:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:38:45 INFO - Running post-action listener: _resource_record_post_action 11:38:45 INFO - [mozharness: 2016-08-30 18:38:45.708233Z] Finished read-buildbot-config step (success) 11:38:45 INFO - [mozharness: 2016-08-30 18:38:45.708367Z] Running download-and-extract step. 11:38:45 INFO - Running pre-action listener: _resource_record_pre_action 11:38:45 INFO - Running main action method: download_and_extract 11:38:45 INFO - mkdir: /builds/slave/test/build/tests 11:38: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')]} 11:38:45 INFO - trying https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:38:45 INFO - Downloading https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/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 11:38:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/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 11:38:46 INFO - Downloaded 1199 bytes. 11:38:46 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:38:46 INFO - Using the following test package requirements: 11:38:46 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:38:46 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:38:46 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:38:46 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:38:46 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:38:46 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:38:46 INFO - u'jsshell-mac.zip'], 11:38:46 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:38:46 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:38:46 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:38:46 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:38:46 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:38:46 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:38:46 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:38:46 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:38:46 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:38:46 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:38:46 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:38:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:38:46 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')]} 11:38:46 INFO - trying https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:38:46 INFO - Downloading https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/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 11:38:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/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 11:38:47 INFO - Downloaded 16380156 bytes. 11:38:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:38:54 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 11:38: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')]} 11:38:54 INFO - trying https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:38:54 INFO - Downloading https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/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 11:38:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/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 11:38:55 INFO - Downloaded 16380156 bytes. 11:38: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 11:39: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')]} 11:39:02 INFO - trying https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:39:02 INFO - Downloading https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/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 11:39:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/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 11:39:03 INFO - Downloaded 14413998 bytes. 11:39:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:39:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:03 INFO - trying https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:39:03 INFO - Downloading https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:39:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/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 11:39:07 INFO - Downloaded 111561224 bytes. 11:39:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:39:07 INFO - mkdir: /builds/slave/test/properties 11:39:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:39:07 INFO - Writing to file /builds/slave/test/properties/build_url 11:39:07 INFO - Contents: 11:39:07 INFO - build_url:https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:39:08 INFO - Running post-action listener: _resource_record_post_action 11:39:08 INFO - Running post-action listener: set_extra_try_arguments 11:39:08 INFO - [mozharness: 2016-08-30 18:39:08.714989Z] Finished download-and-extract step (success) 11:39:08 INFO - [mozharness: 2016-08-30 18:39:08.715107Z] Running populate-webroot step. 11:39:08 INFO - Running pre-action listener: _resource_record_pre_action 11:39:08 INFO - Running main action method: populate_webroot 11:39:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:39:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:39:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:39:08 INFO - '--tppagecycles', 11:39:08 INFO - '7'], 11:39:08 INFO - 'tests': ['tsvgm']}}, 11:39:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:39:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:39:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:39:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:39:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:39:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:39:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:08 INFO - 'talos_options': ['--disable-e10s'], 11:39:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:39:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:08 INFO - 'talos_options': ['--disable-e10s'], 11:39:08 INFO - 'tests': ['damp', 'tps']}, 11:39:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:08 INFO - 'tests': ['damp', 'tps']}, 11:39:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:39:08 INFO - 'tests': ['dromaeo_dom']}, 11:39:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:39:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:39:08 INFO - 'tests': ['basic_compositor_video']}, 11:39:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:39:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:39:08 INFO - 'tests': ['a11yr', 11:39:08 INFO - 'ts_paint', 11:39:08 INFO - 'tpaint', 11:39:08 INFO - 'sessionrestore', 11:39:08 INFO - 'sessionrestore_no_auto_restore', 11:39:08 INFO - 'tabpaint']}, 11:39:08 INFO - 'other-e10s': {'tests': ['a11yr', 11:39:08 INFO - 'ts_paint', 11:39:08 INFO - 'tpaint', 11:39:08 INFO - 'sessionrestore', 11:39:08 INFO - 'sessionrestore_no_auto_restore', 11:39:08 INFO - 'tabpaint']}, 11:39:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:39:08 INFO - 'ts_paint', 11:39:08 INFO - 'tpaint', 11:39:08 INFO - 'sessionrestore', 11:39:08 INFO - 'sessionrestore_no_auto_restore', 11:39:08 INFO - 'tabpaint']}, 11:39:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:39:08 INFO - 'ts_paint', 11:39:08 INFO - 'tpaint', 11:39:08 INFO - 'sessionrestore', 11:39:08 INFO - 'sessionrestore_no_auto_restore', 11:39:08 INFO - 'tabpaint']}, 11:39:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:39:08 INFO - 'tests': ['a11yr', 11:39:08 INFO - 'ts_paint', 11:39:08 INFO - 'tpaint', 11:39:08 INFO - 'sessionrestore', 11:39:08 INFO - 'sessionrestore_no_auto_restore', 11:39:08 INFO - 'tabpaint']}, 11:39:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:39:08 INFO - 'tests': ['a11yr', 11:39:08 INFO - 'ts_paint', 11:39:08 INFO - 'tpaint', 11:39:08 INFO - 'sessionrestore', 11:39:08 INFO - 'sessionrestore_no_auto_restore', 11:39:08 INFO - 'tabpaint']}, 11:39:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:39:08 INFO - 'tests': ['tsvgx', 11:39:08 INFO - 'tsvgr_opacity', 11:39:08 INFO - 'tart', 11:39:08 INFO - 'tscrollx', 11:39:08 INFO - 'cart']}, 11:39:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:39:08 INFO - 'tsvgr_opacity', 11:39:08 INFO - 'tart', 11:39:08 INFO - 'tscrollx', 11:39:08 INFO - 'cart']}, 11:39:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:08 INFO - 'talos_options': ['--disable-e10s'], 11:39:08 INFO - 'tests': ['tp5o']}, 11:39:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:08 INFO - 'tests': ['tp5o']}, 11:39:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:08 INFO - 'talos_options': ['--disable-e10s', 11:39:08 INFO - '--xperf_path', 11:39:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:08 INFO - 'tests': ['tp5n']}, 11:39:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:39:08 INFO - 'talos_options': ['--xperf_path', 11:39:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:39:08 INFO - 'tests': ['tp5n']}}, 11:39:08 INFO - 'talos.zip': {'path': '', 11:39:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:39:08 INFO - Running post-action listener: _resource_record_post_action 11:39:08 INFO - [mozharness: 2016-08-30 18:39:08.734131Z] Finished populate-webroot step (success) 11:39:08 INFO - [mozharness: 2016-08-30 18:39:08.734266Z] Running create-virtualenv step. 11:39:08 INFO - Running pre-action listener: _resource_record_pre_action 11:39:08 INFO - Running main action method: create_virtualenv 11:39:08 INFO - Creating virtualenv /builds/slave/test/build/venv 11:39:08 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 11:39:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:39:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:39:08 INFO - Using real prefix '/tools/python27' 11:39:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:39:09 INFO - Installing distribute.............................................................................................................................................................................................done. 11:39:12 INFO - Installing pip.................done. 11:39:12 INFO - Return code: 0 11:39:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103175a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd83b4768d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d9d30>, '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 0x102e3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030d1b20>, '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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 11:39:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:39:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:39:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jPXRVfWUP/Render', 11:39:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:12 INFO - 'HOME': '/Users/cltbld', 11:39:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:12 INFO - 'LOGNAME': 'cltbld', 11:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:12 INFO - 'MOZ_NO_REMOTE': '1', 11:39:12 INFO - 'NO_EM_RESTART': '1', 11:39:12 INFO - 'PAGER': '/bin/cat', 11:39:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:12 INFO - 'PWD': '/builds/slave/test', 11:39:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:12 INFO - 'SHELL': '/bin/bash', 11:39:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYSXNxNCdN/Listeners', 11:39:12 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 11:39:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:12 INFO - 'USER': 'cltbld', 11:39:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:12 INFO - 'XPC_FLAGS': '0x0', 11:39:12 INFO - 'XPC_SERVICE_NAME': '0', 11:39:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:39:12 INFO - Cleaning up... 11:39:12 INFO - Return code: 0 11:39:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103175a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd83b4768d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d9d30>, '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 0x102e3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030d1b20>, '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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 11:39:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:39:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:39:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jPXRVfWUP/Render', 11:39:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:12 INFO - 'HOME': '/Users/cltbld', 11:39:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:12 INFO - 'LOGNAME': 'cltbld', 11:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:12 INFO - 'MOZ_NO_REMOTE': '1', 11:39:12 INFO - 'NO_EM_RESTART': '1', 11:39:12 INFO - 'PAGER': '/bin/cat', 11:39:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:12 INFO - 'PWD': '/builds/slave/test', 11:39:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:12 INFO - 'SHELL': '/bin/bash', 11:39:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYSXNxNCdN/Listeners', 11:39:12 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 11:39:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:12 INFO - 'USER': 'cltbld', 11:39:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:12 INFO - 'XPC_FLAGS': '0x0', 11:39:12 INFO - 'XPC_SERVICE_NAME': '0', 11:39:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:12 INFO - Downloading/unpacking psutil>=3.1.1 11:39:12 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 11:39:12 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 11:39:12 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 11:39:12 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 11:39:12 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 11:39:12 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 11:39:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:39:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:39:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:39:16 INFO - Installing collected packages: psutil 11:39:16 INFO - Running setup.py install for psutil 11:39:16 INFO - building 'psutil._psutil_osx' extension 11:39:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:39:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:39:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:39:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:39:17 INFO - building 'psutil._psutil_posix' extension 11:39:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:39:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:39:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:39:17 INFO - ^ 11:39:17 INFO - 1 warning generated. 11:39:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:39:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:39:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:39:17 INFO - Successfully installed psutil 11:39:17 INFO - Cleaning up... 11:39:17 INFO - Return code: 0 11:39:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103175a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd83b4768d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d9d30>, '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 0x102e3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030d1b20>, '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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 11:39:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:39:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jPXRVfWUP/Render', 11:39:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:17 INFO - 'HOME': '/Users/cltbld', 11:39:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:17 INFO - 'LOGNAME': 'cltbld', 11:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:17 INFO - 'MOZ_NO_REMOTE': '1', 11:39:17 INFO - 'NO_EM_RESTART': '1', 11:39:17 INFO - 'PAGER': '/bin/cat', 11:39:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:17 INFO - 'PWD': '/builds/slave/test', 11:39:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:17 INFO - 'SHELL': '/bin/bash', 11:39:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYSXNxNCdN/Listeners', 11:39:17 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 11:39:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:17 INFO - 'USER': 'cltbld', 11:39:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:17 INFO - 'XPC_FLAGS': '0x0', 11:39:17 INFO - 'XPC_SERVICE_NAME': '0', 11:39:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:39:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:39:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:39:20 INFO - Installing collected packages: mozsystemmonitor 11:39:20 INFO - Running setup.py install for mozsystemmonitor 11:39:20 INFO - Successfully installed mozsystemmonitor 11:39:20 INFO - Cleaning up... 11:39:20 INFO - Return code: 0 11:39:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:39: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')]} 11:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39: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')]} 11:39:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103175a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd83b4768d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d9d30>, '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 0x102e3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030d1b20>, '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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 11:39:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:39:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:39:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jPXRVfWUP/Render', 11:39:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:20 INFO - 'HOME': '/Users/cltbld', 11:39:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:20 INFO - 'LOGNAME': 'cltbld', 11:39:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:20 INFO - 'MOZ_NO_REMOTE': '1', 11:39:20 INFO - 'NO_EM_RESTART': '1', 11:39:20 INFO - 'PAGER': '/bin/cat', 11:39:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:20 INFO - 'PWD': '/builds/slave/test', 11:39:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:20 INFO - 'SHELL': '/bin/bash', 11:39:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYSXNxNCdN/Listeners', 11:39:20 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 11:39:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:20 INFO - 'USER': 'cltbld', 11:39:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:20 INFO - 'XPC_FLAGS': '0x0', 11:39:20 INFO - 'XPC_SERVICE_NAME': '0', 11:39:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:20 INFO - Downloading/unpacking jsonschema==2.5.1 11:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:39:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:39: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 11:39: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 11:39: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 11:39: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 11:39: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 11:39: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 11:39:23 INFO - Downloading functools32-3.2.3-2.tar.gz 11:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:39:23 INFO - warning: no files found matching '*.txt' 11:39:23 INFO - no previously-included directories found matching 'build' 11:39:23 INFO - no previously-included directories found matching 'dist' 11:39:23 INFO - no previously-included directories found matching '.git*' 11:39:23 INFO - Installing collected packages: jsonschema, functools32 11:39:23 INFO - Running setup.py install for functools32 11:39:23 INFO - warning: no files found matching '*.txt' 11:39:23 INFO - no previously-included directories found matching 'build' 11:39:23 INFO - no previously-included directories found matching 'dist' 11:39:23 INFO - no previously-included directories found matching '.git*' 11:39:24 INFO - Successfully installed jsonschema functools32 11:39:24 INFO - Cleaning up... 11:39:24 INFO - Return code: 0 11:39:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103175a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd83b4768d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d9d30>, '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 0x102e3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030d1b20>, '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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 11:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:39:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jPXRVfWUP/Render', 11:39:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:24 INFO - 'HOME': '/Users/cltbld', 11:39:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:24 INFO - 'LOGNAME': 'cltbld', 11:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:24 INFO - 'MOZ_NO_REMOTE': '1', 11:39:24 INFO - 'NO_EM_RESTART': '1', 11:39:24 INFO - 'PAGER': '/bin/cat', 11:39:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:24 INFO - 'PWD': '/builds/slave/test', 11:39:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:24 INFO - 'SHELL': '/bin/bash', 11:39:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYSXNxNCdN/Listeners', 11:39:24 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 11:39:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:24 INFO - 'USER': 'cltbld', 11:39:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:24 INFO - 'XPC_FLAGS': '0x0', 11:39:24 INFO - 'XPC_SERVICE_NAME': '0', 11:39:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:39:24 INFO - Cleaning up... 11:39:24 INFO - Return code: 0 11:39:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103175a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd83b4768d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d9d30>, '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 0x102e3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030d1b20>, '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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 11:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:39:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jPXRVfWUP/Render', 11:39:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:24 INFO - 'HOME': '/Users/cltbld', 11:39:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:24 INFO - 'LOGNAME': 'cltbld', 11:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:24 INFO - 'MOZ_NO_REMOTE': '1', 11:39:24 INFO - 'NO_EM_RESTART': '1', 11:39:24 INFO - 'PAGER': '/bin/cat', 11:39:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:24 INFO - 'PWD': '/builds/slave/test', 11:39:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:24 INFO - 'SHELL': '/bin/bash', 11:39:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYSXNxNCdN/Listeners', 11:39:24 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 11:39:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:24 INFO - 'USER': 'cltbld', 11:39:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:24 INFO - 'XPC_FLAGS': '0x0', 11:39:24 INFO - 'XPC_SERVICE_NAME': '0', 11:39:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:24 INFO - Downloading/unpacking blobuploader==1.2.4 11:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:26 INFO - Downloading blobuploader-1.2.4.tar.gz 11:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:39:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:39:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:28 INFO - Downloading docopt-0.6.1.tar.gz 11:39:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:39:28 INFO - Installing collected packages: blobuploader, requests, docopt 11:39:28 INFO - Running setup.py install for blobuploader 11:39:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:39:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:39:28 INFO - Running setup.py install for requests 11:39:28 INFO - Running setup.py install for docopt 11:39:29 INFO - Successfully installed blobuploader requests docopt 11:39:29 INFO - Cleaning up... 11:39:29 INFO - Return code: 0 11:39:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:39:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103175a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd83b4768d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d9d30>, '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 0x102e3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030d1b20>, '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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 11:39:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:39:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:39:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jPXRVfWUP/Render', 11:39:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:29 INFO - 'HOME': '/Users/cltbld', 11:39:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:29 INFO - 'LOGNAME': 'cltbld', 11:39:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:29 INFO - 'MOZ_NO_REMOTE': '1', 11:39:29 INFO - 'NO_EM_RESTART': '1', 11:39:29 INFO - 'PAGER': '/bin/cat', 11:39:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:29 INFO - 'PWD': '/builds/slave/test', 11:39:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:29 INFO - 'SHELL': '/bin/bash', 11:39:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYSXNxNCdN/Listeners', 11:39:29 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 11:39:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:29 INFO - 'USER': 'cltbld', 11:39:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:29 INFO - 'XPC_FLAGS': '0x0', 11:39:29 INFO - 'XPC_SERVICE_NAME': '0', 11:39:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:39:29 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-FTC6y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:39:29 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-0SrVkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:39:29 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-kUS14U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:39:29 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-io7Xvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:39:29 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-nyScHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:39:30 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-ASLYnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:39:30 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-n87lrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:39:30 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-EgWbDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:39:30 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-7MULj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:39:30 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-E7i2Un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:39:30 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-pwSE6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:39:30 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-LgRSbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:39:30 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-vVb206-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:39:30 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-ekEFCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:30 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-6FUkd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:39:31 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-J0wNWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:39:31 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-jSIK23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:39:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:39:31 INFO - Running setup.py install for manifestparser 11:39:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:39:31 INFO - Running setup.py install for mozcrash 11:39:31 INFO - Running setup.py install for mozdebug 11:39:31 INFO - Running setup.py install for mozdevice 11:39:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:39:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:39:31 INFO - Running setup.py install for mozfile 11:39:32 INFO - Running setup.py install for mozhttpd 11:39:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:39:32 INFO - Running setup.py install for mozinfo 11:39:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:39:32 INFO - Running setup.py install for mozInstall 11:39:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:39:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:39:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:39:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:39:32 INFO - Running setup.py install for mozleak 11:39:32 INFO - Running setup.py install for mozlog 11:39:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:39:32 INFO - Running setup.py install for moznetwork 11:39:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:39:32 INFO - Running setup.py install for mozprocess 11:39:33 INFO - Running setup.py install for mozprofile 11:39:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Running setup.py install for mozrunner 11:39:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Running setup.py install for mozscreenshot 11:39:33 INFO - Running setup.py install for moztest 11:39:33 INFO - Running setup.py install for mozversion 11:39:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:39:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:39:33 INFO - Cleaning up... 11:39:33 INFO - Return code: 0 11:39:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:39:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103175a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd83b4768d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d9d30>, '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 0x102e3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030d1b20>, '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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 11:39:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:39:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jPXRVfWUP/Render', 11:39:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:33 INFO - 'HOME': '/Users/cltbld', 11:39:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:33 INFO - 'LOGNAME': 'cltbld', 11:39:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:33 INFO - 'MOZ_NO_REMOTE': '1', 11:39:33 INFO - 'NO_EM_RESTART': '1', 11:39:33 INFO - 'PAGER': '/bin/cat', 11:39:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:33 INFO - 'PWD': '/builds/slave/test', 11:39:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:33 INFO - 'SHELL': '/bin/bash', 11:39:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYSXNxNCdN/Listeners', 11:39:33 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 11:39:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:33 INFO - 'USER': 'cltbld', 11:39:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:33 INFO - 'XPC_FLAGS': '0x0', 11:39:33 INFO - 'XPC_SERVICE_NAME': '0', 11:39:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:39:34 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-MaWJ2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:39:34 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-TMJpio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:39:34 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-nd4DrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:39:34 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Z_fXYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:39:34 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-a4E2TM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:39:34 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-jdmn1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:39:34 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-ARvLaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:39:34 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-7IPRFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:39:34 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-xKYc9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:39:35 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-re1Sgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:39:35 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-_U5zWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:39:35 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-9QhuoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:39:35 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-50GTuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:39:35 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-gnErYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:35 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-zcodZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:39:35 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-XOrzrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:39:35 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-iwXkEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:39:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:39:38 INFO - Downloading blessings-1.6.tar.gz 11:39:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:39:38 INFO - Installing collected packages: blessings 11:39:38 INFO - Running setup.py install for blessings 11:39:38 INFO - Successfully installed blessings 11:39:38 INFO - Cleaning up... 11:39:38 INFO - Return code: 0 11:39:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:39:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:39:39 INFO - Reading from file tmpfile_stdout 11:39:39 INFO - Current package versions: 11:39:39 INFO - blessings == 1.6 11:39:39 INFO - blobuploader == 1.2.4 11:39:39 INFO - docopt == 0.6.1 11:39:39 INFO - functools32 == 3.2.3-2 11:39:39 INFO - jsonschema == 2.5.1 11:39:39 INFO - manifestparser == 1.1 11:39:39 INFO - mozInstall == 1.12 11:39:39 INFO - mozcrash == 0.17 11:39:39 INFO - mozdebug == 0.1 11:39:39 INFO - mozdevice == 0.48 11:39:39 INFO - mozfile == 1.2 11:39:39 INFO - mozhttpd == 0.7 11:39:39 INFO - mozinfo == 0.9 11:39:39 INFO - mozleak == 0.1 11:39:39 INFO - mozlog == 3.3 11:39:39 INFO - moznetwork == 0.27 11:39:39 INFO - mozprocess == 0.23 11:39:39 INFO - mozprofile == 0.28 11:39:39 INFO - mozrunner == 6.12 11:39:39 INFO - mozscreenshot == 0.1 11:39:39 INFO - mozsystemmonitor == 0.3 11:39:39 INFO - moztest == 0.7 11:39:39 INFO - mozversion == 1.4 11:39:39 INFO - psutil == 3.1.1 11:39:39 INFO - requests == 1.2.3 11:39:39 INFO - wsgiref == 0.1.2 11:39:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:39: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')]} 11:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39: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')]} 11:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103175a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd83b4768d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d9d30>, '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 0x102e3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030d1b20>, '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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 11:39:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jPXRVfWUP/Render', 11:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:39 INFO - 'HOME': '/Users/cltbld', 11:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:39 INFO - 'LOGNAME': 'cltbld', 11:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:39 INFO - 'MOZ_NO_REMOTE': '1', 11:39:39 INFO - 'NO_EM_RESTART': '1', 11:39:39 INFO - 'PAGER': '/bin/cat', 11:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:39 INFO - 'PWD': '/builds/slave/test', 11:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:39 INFO - 'SHELL': '/bin/bash', 11:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYSXNxNCdN/Listeners', 11:39:39 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 11:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:39 INFO - 'USER': 'cltbld', 11:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:39 INFO - 'XPC_FLAGS': '0x0', 11:39:39 INFO - 'XPC_SERVICE_NAME': '0', 11:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:39:39 INFO - Cleaning up... 11:39:39 INFO - Return code: 0 11:39:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:39: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')]} 11:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39: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')]} 11:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:39:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103175a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10320a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd83b4768d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d9d30>, '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 0x102e3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030d1b20>, '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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 11:39:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jPXRVfWUP/Render', 11:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:39 INFO - 'HOME': '/Users/cltbld', 11:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:39 INFO - 'LOGNAME': 'cltbld', 11:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:39 INFO - 'MOZ_NO_REMOTE': '1', 11:39:39 INFO - 'NO_EM_RESTART': '1', 11:39:39 INFO - 'PAGER': '/bin/cat', 11:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:39 INFO - 'PWD': '/builds/slave/test', 11:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:39 INFO - 'SHELL': '/bin/bash', 11:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYSXNxNCdN/Listeners', 11:39:39 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 11:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:39 INFO - 'USER': 'cltbld', 11:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:39 INFO - 'XPC_FLAGS': '0x0', 11:39:39 INFO - 'XPC_SERVICE_NAME': '0', 11:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:39:39 INFO - Cleaning up... 11:39:39 INFO - Return code: 0 11:39:39 INFO - Running post-action listener: _resource_record_post_action 11:39:39 INFO - Running post-action listener: _start_resource_monitoring 11:39:39 INFO - Starting resource monitoring. 11:39:39 INFO - [mozharness: 2016-08-30 18:39:39.977546Z] Finished create-virtualenv step (success) 11:39:39 INFO - [mozharness: 2016-08-30 18:39:39.978598Z] Running install step. 11:39:39 INFO - Running pre-action listener: _resource_record_pre_action 11:39:39 INFO - Running main action method: install 11:39:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:39:40 INFO - Reading from file tmpfile_stdout 11:39:40 INFO - Detecting whether we're running mozinstall >=1.0... 11:39:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:39:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:39:40 INFO - Reading from file tmpfile_stdout 11:39:40 INFO - Output received: 11:39:40 INFO - Usage: mozinstall [options] installer 11:39:40 INFO - Options: 11:39:40 INFO - -h, --help show this help message and exit 11:39:40 INFO - -d DEST, --destination=DEST 11:39:40 INFO - Directory to install application into. [default: 11:39:40 INFO - "/builds/slave/test"] 11:39:40 INFO - --app=APP Application being installed. [default: firefox] 11:39:40 INFO - mkdir: /builds/slave/test/build/application 11:39:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:39:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:40:14 INFO - Reading from file tmpfile_stdout 11:40:14 INFO - Output received: 11:40:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:40:14 INFO - Running post-action listener: _resource_record_post_action 11:40:14 INFO - [mozharness: 2016-08-30 18:40:14.718045Z] Finished install step (success) 11:40:14 INFO - [mozharness: 2016-08-30 18:40:14.718190Z] Running run-tests step. 11:40:14 INFO - Running pre-action listener: _resource_record_pre_action 11:40:14 INFO - Running main action method: run_tests 11:40:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:40:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:40:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:40:14 INFO - 2016-08-30 11:40:14.732 screenresolution[1633:7641] starting screenresolution argv=screenresolution get 11:40:14 INFO - 2016-08-30 11:40:14.735 screenresolution[1633:7641] Display 0: 1600x1200x32@60 11:40:14 INFO - 2016-08-30 11:40:14.741 screenresolution[1634:7643] starting screenresolution argv=screenresolution list 11:40:14 INFO - Available Modes on Display 0 11:40:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:40:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:40:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:40:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:40:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:40:14 INFO - Intel Iris: 11:40:14 INFO - Chipset Model: Intel Iris 11:40:14 INFO - Type: GPU 11:40:14 INFO - Bus: Built-In 11:40:14 INFO - VRAM (Dynamic, Max): 1536 MB 11:40:14 INFO - Vendor: Intel (0x8086) 11:40:14 INFO - Device ID: 0x0a2e 11:40:14 INFO - Revision ID: 0x0009 11:40:14 INFO - Displays: 11:40:14 INFO - Display: 11:40:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:40:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:40:14 INFO - Display Serial Number: 1600x1200 60 11:40:14 INFO - Main Display: Yes 11:40:14 INFO - Mirror: Off 11:40:14 INFO - Online: Yes 11:40:14 INFO - Rotation: Supported 11:40:14 INFO - Return code: 0 11:40:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:40:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:40:14 INFO - Python 2.7.3 11:40:14 INFO - Return code: 0 11:40:14 INFO - grabbing minidump binary from tooltool 11:40:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:14 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d9d30>, '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 0x102e3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030d1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:40:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:40:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:40:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:40:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:40:15 INFO - Return code: 0 11:40:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:40:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:40:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:40:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:40:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:40:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--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 11:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0191 --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 11:40:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jPXRVfWUP/Render', 11:40:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:15 INFO - 'HOME': '/Users/cltbld', 11:40:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:15 INFO - 'LOGNAME': 'cltbld', 11:40:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:40:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:15 INFO - 'MOZ_NO_REMOTE': '1', 11:40:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:40:15 INFO - 'NO_EM_RESTART': '1', 11:40:15 INFO - 'PAGER': '/bin/cat', 11:40:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:15 INFO - 'PWD': '/builds/slave/test', 11:40:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:40:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:15 INFO - 'SHELL': '/bin/bash', 11:40:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYSXNxNCdN/Listeners', 11:40:15 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 11:40:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:15 INFO - 'USER': 'cltbld', 11:40:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:15 INFO - 'XPC_FLAGS': '0x0', 11:40:15 INFO - 'XPC_SERVICE_NAME': '0', 11:40:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--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 11:40:15 INFO - mozversion application_buildid: 20160830093621 11:40:15 INFO - mozversion application_changeset: 6a5af5a4c2efd7df3bd8eb6f87ad6ef654854090 11:40:15 INFO - mozversion application_display_name: Nightly 11:40:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:40:15 INFO - mozversion application_name: Firefox 11:40:15 INFO - mozversion application_remotingname: firefox 11:40:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:40:15 INFO - mozversion application_vendor: Mozilla 11:40:15 INFO - mozversion application_version: 51.0a1 11:40:15 INFO - mozversion platform_buildid: 20160830093621 11:40:15 INFO - mozversion platform_changeset: 6a5af5a4c2efd7df3bd8eb6f87ad6ef654854090 11:40:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:40:15 INFO - mozversion platform_version: 51.0a1 11:40:15 INFO - using testdate: 1472582415 11:40:15 INFO - actual date: 1472582415 11:40:15 INFO - starting webserver on 'localhost:49273' 11:40:15 INFO - SUITE-START | Running 2 tests 11:40:15 INFO - TEST-START | dromaeo_css 11:40:15 INFO - operating with platform_type : mac_ 11:40:15 INFO - Initialising browser for dromaeo_css test... 11:40:15 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpv6ZKvy/profile http://localhost:49273/getInfo.html) 11:40:17 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 11:40:17 INFO - PROCESS | 1644 | colorDepth:24 11:40:17 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 11:40:17 INFO - PROCESS | 1644 | __metrics 11:40:17 INFO - PROCESS | 1644 | 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" 11:40:17 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:40:18 INFO - TEST-INFO | 1644: exit 0 11:40:18 INFO - Browser initialized. 11:40:18 INFO - Running cycle 1/1 for dromaeo_css test... 11:40:18 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpv6ZKvy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:40:30 INFO - PROCESS | 1646 | RSS: Main: 234987520 11:40:30 INFO - PROCESS | 1646 | 11:41:22 INFO - PROCESS | 1646 | 1472582482368 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] 11:43:34 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 11:43:35 INFO - PROCESS | 1646 | RSS: Main: 395399168 11:43:35 INFO - PROCESS | 1646 | 11:46:39 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 11:46:39 INFO - PROCESS | 1646 | RSS: Main: 371474432 11:46:39 INFO - PROCESS | 1646 | 11:49:41 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 11:49:42 INFO - PROCESS | 1646 | RSS: Main: 339922944 11:49:42 INFO - PROCESS | 1646 | 11:52:44 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 11:52:45 INFO - PROCESS | 1646 | RSS: Main: 347758592 11:52:45 INFO - PROCESS | 1646 | 11:55:48 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 11:55:49 INFO - PROCESS | 1646 | RSS: Main: 326135808 11:55:49 INFO - PROCESS | 1646 | 11:58:52 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 11:58:52 INFO - PROCESS | 1646 | RSS: Main: 510550016 11:58:52 INFO - PROCESS | 1646 | 11:58:52 INFO - PROCESS | 1646 | __start_tp_report 11:58:52 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 11:58:52 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 11:58:52 INFO - PROCESS | 1646 | |i|pagename|runs| 11:58:52 INFO - PROCESS | 1646 | |0;dojo.html;3353.1784712745375;3358.764886457948;3374.611919629242;3413.6757008084255;3439.363717712223;5675.744591493382;5720.6853623050765;5805.970970145148;5828.154917536955;5832.854178645536;5801.738921748521;5852.502537284336;5865.736041878115;5878.52971762259;5887.7939272125595;5251.816186433475;5276.92084618731;5313.840584782443;5327.041132596131;5368.946310536903;4352.129574085196;5076.873078173038;5361.544268737142;5364.892702145955;5375.16684913839;5217.113090774558;5274.129768588178;5299.099153143975;5307.310049693534;5330.866728331784;5341.278927344798;5360.678359298455;5369.24830523727;5382.757775900055;5382.757775900055;5162.974185129088;5176.974115129438;5194.065068287707;5299.205119232108;5304.973475132639;5819.156222347779;5840.561957853136;5859.2382990211;5860.531157507389;5972.970135149297;6179.752809887599;6192.566520343577;6205.503559715212;6210.130581718563;6224.719887605069;5882.647041177543;6095.408112664863;6154.630722156685;6213.627182369027;6226.906596401058;5328.040952628487;5897;5952.315483719428;5955.285365756137;5958.463738263577;6307.180066591405;6310.2743184534365;6327.873442531123;6333.050042493663;6340.809775706736;5927.73325200367;6147.84630384244;6198.721057552421;6199.5660303779205;6210.565260431817;6246.812595622138;6315.052742088632;6327.430531252209;6333.303336632966;6334.588251763621;6301.086342480315;6309.211348581427;6312.779052733133;6312.905306420407;6336.398042185985;6139.676133582418;6193.071039344134;6213.316535181127;6319.273283572356;6325.65208913505;4968.950310496921;4973.602111831045;4976.029674213566;4982.850514484571;5012.824551140766;4471.619912307425;4539.75031373271;4542.863714088517;4558.521355257716;4598.218302888517;5800.796972106041;5849.093390524476;5864.149698293723;5869.325027621793;5870.119482077637;5284.550813180846;6276.905846412307;6294.622322660655;6309.936900630935;6317.684115794192;5713.914291285634;5769.913451298234;5783;5808.5933984621515;5825.621334613236;5438.646487978269;5499.395066542677;5502.779888804483;5506.91739623906;5575.832725018256;6338.841528961812;6383.234011918599;6396.680165991589;6402.327755585597;6416.294207637249;6284.685765711604;6290.559660823698;6296.275928268308;6310.368963103653;6330.556861019684;6185.41238582334;6253.280872699515;6259.3427690091885;6376.298607153117;6391.96804015977;5353.866153346197;6281.12064311006;6303.873922521616;6306.054091886253;6318.462930650762;6212.689365531615;6290.559660823881;6297.874042519215;6303.306636269915;6328.46208072319;6079.878402432015;6130.153553065376;6135.814547850547;6246.531510136849;6248.562600617913;6156.969215153896;6185.319614842454;6211.068339748893;6223.844403889939;6233.937660623335;6109.84725381869;6115.755369785159;6126.111713801563;6135.877282454237;6140.1710769047395;5289.894202116013;6030.427109424598;6199.442050215503;6209.192804935329;6230.221222347207;6084.117802918464;6095.274004544494;6141.570090093651;6143.815685529441;6157.32269450369 11:58:52 INFO - PROCESS | 1646 | |1;ext.html;469.690004596966;535.4859335038363;539.1212323912023;546.9863253418664;557.6041010882274;24633.384165395873;36301.273974520496;40601.79699101504;40696.79651601741;44090.779546102225;42885.35671964923;43101;43145.7842710786;43266;43315.13369732603;9723.27075469339;9894.802103957916;9914.851277230848;9921.652742154027;9925.454100024495;8851.911480885205;8963.372563920528;11986;12275.508979640803;12310.384480775925;12222.877771222307;12319.876801232007;12322.137450378477;12331.5067397304;12337.383130843467;12202.267863928193;12261.754764904652;12281.57014504497;12289.03375459931;12305.261684298926;12047.57833475833;12143.817842732366;12150.696232594255;12154.817677734753;12174.634760957186;40344.39483407751;40491.98770030773;40497.98755031118;40588;40636.39045414321;3599.8380072896784;3650;3860.7876566788814;4076.7553946763287;4193.161367726467;29607.779416543846;31481.74073037076;31769.84115079433;31798.88703895376;32054.6794532054;2740.4656092062055;3061.1431985660315;3176.6022949901967;3225.580674512322;3282.770206085575;378523;379462;379546;379629;385743;51311;61806;62041.68979155165;62074.689626551575;62197;64947.35052649507;65001;65062.024069638996;65063.67468162629;65100;16482.175891205392;16709.6658066838;16723.83276167247;16724.080175590458;16733.330666773436;11636.185467017227;11860.991815695763;12963.287019213845;12984.935075324564;13030.478780848849;16655.583610409838;18342.165783421817;18533.443996680122;18538.907305463657;18639.813601863807;6407.199100112486;6485.870282594322;6539.771108011197;6543.411093001652;6598.73605055802;904.3850181876255;989.8861630912537;1019.1948360794889;1022.7187523430968;1082.631905152252;26424.339391515365;29755.404891902042;30313;30638.234044149074;30691.38617227643;1003.4781913404988;1038.9480525973822;1048.9091345001266;1091.24158709696;1101.2896681640457;13798;15520.223988800515;15576.454824081104;15833.129177895325;15970.121643309729;648716;662823.6858815675;662941.167647457;663315;663807.6809615921;58051.41948580545;58373.70813145992;58394;58398.4160158393;58618.827623447294;1219.201423067892;1273.5988163728396;1325.0856908732944;1339.5378594384922;1391.3321605629355;26737.12840101174;30488.08535743931;30749;31022.84488577633;33248.66751332456;50304;56414;56974.715126424104;57001;57045;538374;615256;618777;619846;619898;26037.73962260429;26533.469330612894;26550.734492654825;40616.39075413812;40995.590044099175;39410.40884386794;39438.01404964899;46174.30738538857;47626.285605715246;48053.75973120112;38906.61093389143;39609.603903961746;39788.20423591454;40806.59193408028;41344.586554134075;30339.544906826857;33123.66876331206;37045.25909481741;39168.80415598018;40305.596944030185;38613.613863861;39247.80376098101;39292.41061384024;39469.80265098656;39507.40738888976;33649.99050028503;33927.4910876342;34629.82685086558;34787.826060869535;36519.26961460703;25249.11628093008;25689.357766055997;25701.485970280115;25856.965721370936;26387.944482221264 11:58:52 INFO - PROCESS | 1646 | |2;jquery.html;368.32228699193485;375.5812269319706;381.7499537802739;395.9742616729911;407.78998815609964;20125.698114528277;21780.782192178074;21876.8906155469;22252.777472225294;22594.54810903781;20586.897065514688;21634.675479867776;21724.8913755431;22480.77519224807;22675.659865102036;5788.884222315552;6381.521385896065;6774.051632771416;6798.320167983212;6822.7612033578835;5959.791407300745;6714.294999025105;6800.557963732366;6808.0809090772855;6823.624700641462;6592.406683398493;6654.334566543331;6724.991251312318;6794.864102717943;6803.55776874504;6808.9659551701925;6848.280930502325;6902.861942761142;6937.653117344163;6954.3393377629045;6582.17722784652;6791.151106111736;6801.693923773442;6807.149106361705;6807.353301436356;24044.234912985055;28639.427211455655;29544.704552954412;29574.70425295741;29929.551056734166;2320.7447180810095;2325.5930212212793;2337.661039149331;2440.2923152285816;3206.88775892845;15819.130296272762;16337.428190013292;16626.085565293903;16990.405335813186;17011.724120690997;1978.307592342683;1991.4921694967836;2013.989930050355;2040.6734922412343;2087.7912208779;101309.49345253226;137719.31140344235;138391.61608383988;141372.29313853467;157894.94733369094;15369;20722.378328649862;20750.896245518677;20956.37130886076;21291.57416851654;34945.30109397849;35048.64951350454;35072.64927350694;35084;35098.82450587782;10508.106810921005;10719.946400267949;10902.569401665172;10937.617183398543;11124.55501779936;7411.88882166768;9016.18854303116;9196.172344489029;9209.493477858648;9224.354295199271;9221.53892305382;9467.242620590336;9591.820416974755;9604.183644390305;9634.855477167708;3585.964140358615;3659.963400365962;3665.8533658653887;3685.852565897334;3798.8860334190017;703.7642389799372;945.1540870920487;966.9033096690415;967.1537404770826;1029.330934892326;19894.303699370743;20739.58520829575;20743;20854.687179692315;20895.582088358147;861.039940466388;913.6254135804288;920.9401388909699;952.9428234305825;993.0913214408735;8993.370464067453;10777.83833242502;10789.62236321725;11161.330320180816;11206.047485963785;581335;589715;590521;603565;604553;71561;112734;129188;139018;139604;949.6011675096338;982.729749318943;1011.8836333821557;1012.1953047327393;1014.0467960117466;18753;19372.709409358587;19450.416487505398;19571;19611.273857966942;41615;42883.571164289206;55253;56813;58297;58375.70812145912;83508.58245708732;83982;84703.15296846953;86786;7740.419468539994;7872.881906771518;8065.395095367753;8164.061132969785;8379.748607541784;9071.727848164566;11913.702157446132;12640.87359126397;12842.486300547873;12890.41993110331;10925.781484370427;11083.556657733601;11251.718707032389;11272.154588405738;11353.716157096138;10924.617638382619;10977.28647637901;11121.944390277997;11248.043916267208;11283.548658053585;9593.65688803554;11249.606263780728;11280.661580152608;11408.942955285502;11430.199886008233;5680.7443665036;7900;7908.683652653829;8090.1909809018625;8271.17288271168;9121.680741174026;10208.540615672164;10394.272400931863;11154.721131971766;11475.196736228383 11:58:52 INFO - PROCESS | 1646 | |3;mootools.html;1292.7802273613486;1325.5890673891088;1506.1264466609368;1620.424749214029;1844.7140693192553;7830.451868369217;8344.99860016798;8753.737387878358;8763.95618021909;8768.430052046628;8425.452345597532;8642.567871606427;8751.3436492263;8756.78108047299;8761.299096072315;2391.3184742348435;2441.645961335605;2441.951160976779;2444.6577479152934;2445.058652418814;2403.399150212447;2435.2329016359877;2438.2563318187945;2447.0578827151585;2457.778799908008;2045.836333093349;2046.5395286060666;2393.3537944754908;2426.259990702835;2426.8301218914685;2373.2998765364086;2396.376941995085;2405;2406.602910519762;2414.6740190074293;2375.821813364005;2389.4504264019374;2407.9277621671204;2412.119576354618;2415.0098459631467;7527.811804704871;8554.70058547954;8745.562721863946;8835.293176545862;8854.513001784899;5221.086309895791;5423.403425623178;5429.131338985743;5438.293021907127;5442.836714898559;3628.4375921732176;3651.7626354286886;3706.684931780802;3709.0356507308024;3717.6839968602703;1097.1223021582703;1110.0509064749604;1112.8330750387427;1128.68961035715;1138.595798491532;11574.53701851925;11614.593489227836;11743;11744;11795.174337796272;4728.692634978715;4732.634049732241;4826.8793280168275;4909.828156014522;4909.950900491021;10130.949345253226;10141.363245556062;10141.797164056825;10147.543360548645;10199.592016319264;5409.513143817076;5410.702411367412;5414.242006119146;5419.566434685215;5422.213779002022;4026.2350153470748;4396.560343965642;4588.311753237042;4903.5586797188425;4943.950560494421;3997.620226078518;4029.4761680981283;4118.5263694674895;4125.1749650070105;4130.215259100762;1274.7259339242107;1320.2276668149048;1321.2732996851694;1327.9336033198301;1334.8531661517225;746.268656716421;836.3768992100802;878.1832895407332;889.1908363389284;938.1978408460668;5609.27079479665;5621.690807005572;5696.287964004499;5802.564807639359;6011.609245399035;888.3337496877342;911.7538264668635;917.6100157433065;921.9262459003263;928.6981730937472;3813.637704418076;3865.8646947356706;3906.277338692351;3926.391409331558;3936.881893543198;10439.739006524898;10700.571977120984;10764;10769.246152769387;10801.78396432067;10323.432211228304;10520.947395262974;10702.250842440955;10709.357438553712;10772.138228941665;8070.596470176468;8214.876776848352;8217.917820821835;8221.136780638066;8241.8351632967;5593.748281327432;5691.430856914276;5730.541556675539;5840.2115714378015;5844.327902291291;9540.713778586654;9968;10028.64899728506;10029.398236105857;10060.346077504939;9229.169374756304;9627.277954153327;10048.397096174253;10051.748706282402;10183.490825458846;6919.792406227822;7095.290470952863;7105.786826395217;7108;7125.394341481032;8031.437799354223;8047.919520804951;8199.22107399796;8313.459625124347;8400.327973762207;7419.258074192538;7432.21961694036;7432.628368581657;7473.364763995121;7480.850382992201;7219.494635375473;7288.67200975968;7321.597312147883;7338.633068346669;7358.190599034209;6900.896486552806;7021.122359705037;7034.331738484836;7040.190378106378;7040.683169257498;4808.134535783593;4908.852734417973;4930.137225985366;4978.950210497994;5093.2105523643895;4845;4854.2233242681;5030.019146266589;5055.9494405055475;5121.871953201051 11:58:52 INFO - PROCESS | 1646 | |4;prototype.html;277.21962675069676;294.1044519438309;306.9349357728681;309.5975232198142;311.7957737681819;4808.567228949394;4910.975445122778;5362.329708786402;5385.569154467643;5425.674459532431;4241.939515121219;5113.667611605243;5155.948440515594;5240.737963101848;5322.148456247001;3361.310931259098;3364.2094107884627;4021.9945013746565;4059.1881623675235;4069.69477289203;3241.8216998065095;3782.5082739244;3999.3201155803436;4024.094578719794;4047.1096358801015;3316.369889720945;3461.809600471973;3638.6361363863534;4005.978475488761;4013.5183777946686;3940.4877365942243;3967.821448034845;3974.324364857981;3974.5031871015976;3997.8600748973645;3264.314493956272;3475.58293004841;3503.2292895562937;3506.5266189064405;3508.824558772051;5060.621507891286;5130.948690513122;5138.2549530318265;5141.640085194038;5144.151215049513;4552.453705555321;4571.862844114648;4581.266997280419;4599.31010348447;4610.2854057621125;4412.911741765179;5391.649542779707;5420.756065977041;5459.645123067027;5468.578169675873;3213.5661685672367;3437.7795882461614;3776.773393596393;3803.220339830328;3814.256220037094;4386.780660966939;5113.923291150636;5484.957858006966;5731.656100633933;5806.912896306559;3727.459518369821;3863.632954869283;3870.43878637596;3891.4357418174204;3899.5710471848074;3477.2523907360037;3678.5401824771902;3872.9349428907276;3881.96118038816;3930.449737036817;3759.774413535197;4511.323301504801;4541.977290113528;4585.463869603654;4589.609883159902;5522.2544956431175;5539.196816461671;5592.077307244259;5620.606557541015;5690.2033715279895;1053.9894601054045;1086.8913108689069;1228.336698182971;1307.3071272225736;1313.7438199550995;724.4168444402306;725.7931489525565;729.8213385382569;796.8804679298152;812.6789917982364;3666.926661466756;3944.684425245973;4163.938195760062;4190.769507677046;4235.4917409911;4102.118044620421;4151.972586591002;4701.858944231679;4780.354652121919;4784.928226076611;4193.287141186006;4328.9134217316105;4341.175176716477;4367.388565600819;4377.205345808204;2208.5754688226325;2345.3081341004445;2410.638404239378;2445.963310550343;2486.9927679289917;4143.647789937829;4567.566081222279;4704.694194877322;4749.298194787136;4919.508049195123;4234.950835398508;4380.956190438055;4535.682502224812;4832.405306248929;4882.6572692509135;4009.919801603952;4313.784310784448;4683.644210883911;4868.810928841614;4979.775910084055;4258.361245813153;4612.884677883079;4739.644526660583;4798.7840547174765;4868;4074.5517993021335;4180.372944058416;4389.817382397864;4669.797182535745;4751.168545504592;4541.023679908835;4711.458182308941;4721.763911804464;4732.100900828969;5428.945710542844;3604.7023071693643;3664.029032306494;3686.465462507868;3858.6913046955615;3859.8456061757215;5137.51193636604;5667.971660141673;5700.828975130753;5715.542756579381;5736.368999410145;4454.57681520255;5346.037713211504;5659.773609055592;5664.886702266014;5698.686572238401;4210.3159735231675;4486.737599280631;5609.23309157614;5621.269234999424;5757.8560535986935;4437.57843004927;4954.232094025432;5424.159255315432;5488.259085023471;5494.066008778597;5483.369412517612;5662.575306852085;5680.6591604503865;5720.910708217516;5794.913076303943;5224.268602395744;5470.152126420411;5584.916226256691;5590.385057643737;5595.824876775922 11:58:52 INFO - PROCESS | 1646 | |5;yui.html;706.6784613001083;724.9057622509076;762.7139822566537;801.7795106345751;802.8474589827932;15603.921980390096;16261.593460163502;16272.186390680477;16280.348786048544;16283.837161628395;14180.149191048544;14287.714245715106;14294.070885392443;14300.713985720306;14301;621.1087090025827;678.5013015433652;827.1315119124924;835.5822088955522;860.3375400941271;842.4397775479299;854.2098558833086;854.4744981836158;854.645322191293;857.7812657772259;905.741863568883;908.8591268353383;908.9818203635923;910.0626354854477;911.1071150272737;707.6108140522743;861.9224269815684;899.3479727197795;902.2285945516587;902.5893218585561;675.139197523157;675.6250281093991;676.7326905872202;676.9120014398146;678.6403206300669;15024.774628380583;15049.172295523742;15054.473093441677;15061.17163556004;15087.24563771818;13273;13445.19328840263;13541.45834166632;13768.518101866386;13947.302634868216;12085.214461060092;12440.626781196579;12449.75100497985;12461.875381246162;12932.612021639367;4463.531329210451;4489.438820147481;4544.500104988449;4668.299755036738;4676.3453116563705;109802.9999999992;109812.28075405689;110651;111389;113992;10616.469176541143;10944.288621239593;11165.385903775208;11178.45451840105;11424;21797.67303490449;22417.775822241896;22420.775792241868;22972.195973141195;23009;7644.082710074716;7749.573773442402;7779.844403111906;7890.171531989174;8107.432479726481;4331.133773245364;4392.758398288125;4412.2499175139665;4417.60241578253;4530.365748795171;3500.5974312953826;3605.747597668138;3606.513120728668;3610.7472476926364;3612.0066981580276;657.6448717692392;664.0338307762195;677.6036018928982;681;681.1961884975677;508.7125773937713;538.5314776144779;575.1660092865362;616.9105479705418;629.6285191736897;8516.40385173032;9093.272538196929;9132.497712625736;9169.495677737656;9216.815663686823;506.59978616892977;613.1538476901846;614.1555361378105;626.2391194698463;644.3974883483958;3508.526348942911;4022.2960981828064;4415.646748260131;4441.71128876622;4480.820767169342;99775;144164;163833;165913;177198.11400942912;108043;115820;116233;146202.2689886565;151552;71597;83831;112385.4380728091;112807.4359628213;113242.86757132324;9052.185303322733;9157.633694652406;9191.816163676824;9285.48929808867;9486.810263794823;59451.10823337567;62714.37285627086;62779;64756.6762166205;65419;85121;89485.55257223672;90472;92926.60610090719;92969.5351523238;12675.239485630891;13081.280529570964;13128.934355328161;13548.52580159729;13685.384157712728;14626.707465850835;15000.774988375402;15062.171580563174;15215.619609509851;15260.771088433461;12709.872901270868;12803.61589152327;12809.167404118702;12938.417771200131;14218.805452391776;13584.184948903097;13971.930140349234;13992.370343334778;14054.367553459915;14179.574612761633;11484.712882178012;13559;13669.453221871412;13675.179489230679;13883.444662213398;9452.810943781224;10649.361038337725;11435.942820285845;11541.36522491271;11844;9200.401973871676;11395.544178232778;11485.885141148816;11546.711332216762;11700.70748231267 11:58:52 INFO - PROCESS | 1646 | __end_tp_report 11:58:52 INFO - PROCESS | 1646 | __start_cc_report 11:58:52 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,1378 11:58:52 INFO - PROCESS | 1646 | __end_cc_report 11:58:52 INFO - PROCESS | 1646 | __startTimestamp1472583532765__endTimestamp 11:58:52 INFO - PROCESS | 1646 | 11:58:52 INFO - PROCESS | 1646 | ------- Summary: start ------- 11:58:52 INFO - PROCESS | 1646 | Number of tests: 6 11:58:52 INFO - PROCESS | 1646 | 11:58:52 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5799.86 Median:6084.12 stddev:643.22 (10.6%) stddev-sans-first:615.89 11:58:52 INFO - PROCESS | 1646 | Values: 3353.2 3358.8 3374.6 3413.7 3439.4 5675.7 5720.7 5806.0 5828.2 5832.9 5801.7 5852.5 5865.7 5878.5 5887.8 5251.8 5276.9 5313.8 5327.0 5368.9 4352.1 5076.9 5361.5 5364.9 5375.2 5217.1 5274.1 5299.1 5307.3 5330.9 5341.3 5360.7 5369.2 5382.8 5382.8 5163.0 5177.0 5194.1 5299.2 5305.0 5819.2 5840.6 5859.2 5860.5 5973.0 6179.8 6192.6 6205.5 6210.1 6224.7 5882.6 6095.4 6154.6 6213.6 6226.9 5328.0 5897.0 5952.3 5955.3 5958.5 6307.2 6310.3 6327.9 6333.1 6340.8 5927.7 6147.8 6198.7 6199.6 6210.6 6246.8 6315.1 6327.4 6333.3 6334.6 6301.1 6309.2 6312.8 6312.9 6336.4 6139.7 6193.1 6213.3 6319.3 6325.7 4969.0 4973.6 4976.0 4982.9 5012.8 4471.6 4539.8 4542.9 4558.5 4598.2 5800.8 5849.1 5864.1 5869.3 5870.1 5284.6 6276.9 6294.6 6309.9 6317.7 5713.9 5769.9 5783.0 5808.6 5825.6 5438.6 5499.4 5502.8 5506.9 5575.8 6338.8 6383.2 6396.7 6402.3 6416.3 6284.7 6290.6 6296.3 6310.4 6330.6 6185.4 6253.3 6259.3 6376.3 6392.0 5353.9 6281.1 6303.9 6306.1 6318.5 6212.7 6290.6 6297.9 6303.3 6328.5 6079.9 6130.2 6135.8 6246.5 6248.6 6157.0 6185.3 6211.1 6223.8 6233.9 6109.8 6115.8 6126.1 6135.9 6140.2 5289.9 6030.4 6199.4 6209.2 6230.2 6084.1 6095.3 6141.6 6143.8 6157.3 11:58:52 INFO - PROCESS | 1646 | 11:58:52 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:69000.73 Median:207784.13 stddev:150105.32 (72.2%) stddev-sans-first:150438.22 11:58:52 INFO - PROCESS | 1646 | Values: 469.7 535.5 539.1 547.0 557.6 24633.4 36301.3 40601.8 40696.8 44090.8 42885.4 43101.0 43145.8 43266.0 43315.1 9723.3 9894.8 9914.9 9921.7 9925.5 8851.9 8963.4 11986.0 12275.5 12310.4 12222.9 12319.9 12322.1 12331.5 12337.4 12202.3 12261.8 12281.6 12289.0 12305.3 12047.6 12143.8 12150.7 12154.8 12174.6 40344.4 40492.0 40498.0 40588.0 40636.4 3599.8 3650.0 3860.8 4076.8 4193.2 29607.8 31481.7 31769.8 31798.9 32054.7 2740.5 3061.1 3176.6 3225.6 3282.8 378523.0 379462.0 379546.0 379629.0 385743.0 51311.0 61806.0 62041.7 62074.7 62197.0 64947.4 65001.0 65062.0 65063.7 65100.0 16482.2 16709.7 16723.8 16724.1 16733.3 11636.2 11861.0 12963.3 12984.9 13030.5 16655.6 18342.2 18533.4 18538.9 18639.8 6407.2 6485.9 6539.8 6543.4 6598.7 904.4 989.9 1019.2 1022.7 1082.6 26424.3 29755.4 30313.0 30638.2 30691.4 1003.5 1038.9 1048.9 1091.2 1101.3 13798.0 15520.2 15576.5 15833.1 15970.1 648716.0 662823.7 662941.2 663315.0 663807.7 58051.4 58373.7 58394.0 58398.4 58618.8 1219.2 1273.6 1325.1 1339.5 1391.3 26737.1 30488.1 30749.0 31022.8 33248.7 50304.0 56414.0 56974.7 57001.0 57045.0 538374.0 615256.0 618777.0 619846.0 619898.0 26037.7 26533.5 26550.7 40616.4 40995.6 39410.4 39438.0 46174.3 47626.3 48053.8 38906.6 39609.6 39788.2 40806.6 41344.6 30339.5 33123.7 37045.3 39168.8 40305.6 38613.6 39247.8 39292.4 39469.8 39507.4 33650.0 33927.5 34629.8 34787.8 36519.3 25249.1 25689.4 25701.5 25857.0 26387.9 11:58:52 INFO - PROCESS | 1646 | 11:58:52 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:36593.57 Median:35060.65 stddev:99284.26 (283.2%) stddev-sans-first:99525.52 11:58:52 INFO - PROCESS | 1646 | Values: 368.3 375.6 381.7 396.0 407.8 20125.7 21780.8 21876.9 22252.8 22594.5 20586.9 21634.7 21724.9 22480.8 22675.7 5788.9 6381.5 6774.1 6798.3 6822.8 5959.8 6714.3 6800.6 6808.1 6823.6 6592.4 6654.3 6725.0 6794.9 6803.6 6809.0 6848.3 6902.9 6937.7 6954.3 6582.2 6791.2 6801.7 6807.1 6807.4 24044.2 28639.4 29544.7 29574.7 29929.6 2320.7 2325.6 2337.7 2440.3 3206.9 15819.1 16337.4 16626.1 16990.4 17011.7 1978.3 1991.5 2014.0 2040.7 2087.8 101309.5 137719.3 138391.6 141372.3 157894.9 15369.0 20722.4 20750.9 20956.4 21291.6 34945.3 35048.6 35072.6 35084.0 35098.8 10508.1 10719.9 10902.6 10937.6 11124.6 7411.9 9016.2 9196.2 9209.5 9224.4 9221.5 9467.2 9591.8 9604.2 9634.9 3586.0 3660.0 3665.9 3685.9 3798.9 703.8 945.2 966.9 967.2 1029.3 19894.3 20739.6 20743.0 20854.7 20895.6 861.0 913.6 920.9 952.9 993.1 8993.4 10777.8 10789.6 11161.3 11206.0 581335.0 589715.0 590521.0 603565.0 604553.0 71561.0 112734.0 129188.0 139018.0 139604.0 949.6 982.7 1011.9 1012.2 1014.0 18753.0 19372.7 19450.4 19571.0 19611.3 41615.0 42883.6 55253.0 56813.0 58297.0 58375.7 83508.6 83982.0 84703.2 86786.0 7740.4 7872.9 8065.4 8164.1 8379.7 9071.7 11913.7 12640.9 12842.5 12890.4 10925.8 11083.6 11251.7 11272.2 11353.7 10924.6 10977.3 11121.9 11248.0 11283.5 9593.7 11249.6 11280.7 11408.9 11430.2 5680.7 7900.0 7908.7 8090.2 8271.2 9121.7 10208.5 10394.3 11154.7 11475.2 11:58:52 INFO - PROCESS | 1646 | 11:58:52 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5686.44 Median:4906.21 stddev:3166.52 (64.5%) stddev-sans-first:3158.19 11:58:52 INFO - PROCESS | 1646 | Values: 1292.8 1325.6 1506.1 1620.4 1844.7 7830.5 8345.0 8753.7 8764.0 8768.4 8425.5 8642.6 8751.3 8756.8 8761.3 2391.3 2441.6 2442.0 2444.7 2445.1 2403.4 2435.2 2438.3 2447.1 2457.8 2045.8 2046.5 2393.4 2426.3 2426.8 2373.3 2396.4 2405.0 2406.6 2414.7 2375.8 2389.5 2407.9 2412.1 2415.0 7527.8 8554.7 8745.6 8835.3 8854.5 5221.1 5423.4 5429.1 5438.3 5442.8 3628.4 3651.8 3706.7 3709.0 3717.7 1097.1 1110.1 1112.8 1128.7 1138.6 11574.5 11614.6 11743.0 11744.0 11795.2 4728.7 4732.6 4826.9 4909.8 4910.0 10130.9 10141.4 10141.8 10147.5 10199.6 5409.5 5410.7 5414.2 5419.6 5422.2 4026.2 4396.6 4588.3 4903.6 4944.0 3997.6 4029.5 4118.5 4125.2 4130.2 1274.7 1320.2 1321.3 1327.9 1334.9 746.3 836.4 878.2 889.2 938.2 5609.3 5621.7 5696.3 5802.6 6011.6 888.3 911.8 917.6 921.9 928.7 3813.6 3865.9 3906.3 3926.4 3936.9 10439.7 10700.6 10764.0 10769.2 10801.8 10323.4 10520.9 10702.3 10709.4 10772.1 8070.6 8214.9 8217.9 8221.1 8241.8 5593.7 5691.4 5730.5 5840.2 5844.3 9540.7 9968.0 10028.6 10029.4 10060.3 9229.2 9627.3 10048.4 10051.7 10183.5 6919.8 7095.3 7105.8 7108.0 7125.4 8031.4 8047.9 8199.2 8313.5 8400.3 7419.3 7432.2 7432.6 7473.4 7480.9 7219.5 7288.7 7321.6 7338.6 7358.2 6900.9 7021.1 7034.3 7040.2 7040.7 4808.1 4908.9 4930.1 4979.0 5093.2 4845.0 4854.2 5030.0 5055.9 5121.9 11:58:52 INFO - PROCESS | 1646 | 11:58:52 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4212.95 Median:4538.35 stddev:1307.44 (28.8%) stddev-sans-first:1277.30 11:58:52 INFO - PROCESS | 1646 | Values: 277.2 294.1 306.9 309.6 311.8 4808.6 4911.0 5362.3 5385.6 5425.7 4241.9 5113.7 5155.9 5240.7 5322.1 3361.3 3364.2 4022.0 4059.2 4069.7 3241.8 3782.5 3999.3 4024.1 4047.1 3316.4 3461.8 3638.6 4006.0 4013.5 3940.5 3967.8 3974.3 3974.5 3997.9 3264.3 3475.6 3503.2 3506.5 3508.8 5060.6 5130.9 5138.3 5141.6 5144.2 4552.5 4571.9 4581.3 4599.3 4610.3 4412.9 5391.6 5420.8 5459.6 5468.6 3213.6 3437.8 3776.8 3803.2 3814.3 4386.8 5113.9 5485.0 5731.7 5806.9 3727.5 3863.6 3870.4 3891.4 3899.6 3477.3 3678.5 3872.9 3882.0 3930.4 3759.8 4511.3 4542.0 4585.5 4589.6 5522.3 5539.2 5592.1 5620.6 5690.2 1054.0 1086.9 1228.3 1307.3 1313.7 724.4 725.8 729.8 796.9 812.7 3666.9 3944.7 4163.9 4190.8 4235.5 4102.1 4152.0 4701.9 4780.4 4784.9 4193.3 4328.9 4341.2 4367.4 4377.2 2208.6 2345.3 2410.6 2446.0 2487.0 4143.6 4567.6 4704.7 4749.3 4919.5 4235.0 4381.0 4535.7 4832.4 4882.7 4009.9 4313.8 4683.6 4868.8 4979.8 4258.4 4612.9 4739.6 4798.8 4868.0 4074.6 4180.4 4389.8 4669.8 4751.2 4541.0 4711.5 4721.8 4732.1 5428.9 3604.7 3664.0 3686.5 3858.7 3859.8 5137.5 5668.0 5700.8 5715.5 5736.4 4454.6 5346.0 5659.8 5664.9 5698.7 4210.3 4486.7 5609.2 5621.3 5757.9 4437.6 4954.2 5424.2 5488.3 5494.1 5483.4 5662.6 5680.7 5720.9 5794.9 5224.3 5470.2 5584.9 5590.4 5595.8 11:58:52 INFO - PROCESS | 1646 | 11:58:52 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:24614.29 Median:3817.15 stddev:39469.78 (1034.0%) stddev-sans-first:39539.68 11:58:52 INFO - PROCESS | 1646 | Values: 706.7 724.9 762.7 801.8 802.8 15603.9 16261.6 16272.2 16280.3 16283.8 14180.1 14287.7 14294.1 14300.7 14301.0 621.1 678.5 827.1 835.6 860.3 842.4 854.2 854.5 854.6 857.8 905.7 908.9 909.0 910.1 911.1 707.6 861.9 899.3 902.2 902.6 675.1 675.6 676.7 676.9 678.6 15024.8 15049.2 15054.5 15061.2 15087.2 13273.0 13445.2 13541.5 13768.5 13947.3 12085.2 12440.6 12449.8 12461.9 12932.6 4463.5 4489.4 4544.5 4668.3 4676.3 109803.0 109812.3 110651.0 111389.0 113992.0 10616.5 10944.3 11165.4 11178.5 11424.0 21797.7 22417.8 22420.8 22972.2 23009.0 7644.1 7749.6 7779.8 7890.2 8107.4 4331.1 4392.8 4412.2 4417.6 4530.4 3500.6 3605.7 3606.5 3610.7 3612.0 657.6 664.0 677.6 681.0 681.2 508.7 538.5 575.2 616.9 629.6 8516.4 9093.3 9132.5 9169.5 9216.8 506.6 613.2 614.2 626.2 644.4 3508.5 4022.3 4415.6 4441.7 4480.8 99775.0 144164.0 163833.0 165913.0 177198.1 108043.0 115820.0 116233.0 146202.3 151552.0 71597.0 83831.0 112385.4 112807.4 113242.9 9052.2 9157.6 9191.8 9285.5 9486.8 59451.1 62714.4 62779.0 64756.7 65419.0 85121.0 89485.6 90472.0 92926.6 92969.5 12675.2 13081.3 13128.9 13548.5 13685.4 14626.7 15000.8 15062.2 15215.6 15260.8 12709.9 12803.6 12809.2 12938.4 14218.8 13584.2 13971.9 13992.4 14054.4 14179.6 11484.7 13559.0 13669.5 13675.2 13883.4 9452.8 10649.4 11435.9 11541.4 11844.0 9200.4 11395.5 11485.9 11546.7 11700.7 11:58:52 INFO - PROCESS | 1646 | -------- Summary: end -------- 11:58:52 INFO - PROCESS | 1646 | 11:58:53 INFO - TEST-INFO | 1646: exit 0 11:58:53 INFO - TEST-OK | dromaeo_css | took 1118075ms 11:58:53 INFO - TEST-START | kraken 11:58:53 INFO - operating with platform_type : mac_ 11:58:53 INFO - Initialising browser for kraken test... 11:58:53 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpJteR4A/profile http://localhost:49273/getInfo.html) 11:58:55 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 11:58:55 INFO - PROCESS | 1656 | colorDepth:24 11:58:55 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 11:58:55 INFO - PROCESS | 1656 | __metrics 11:58:55 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:58:55 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:58:56 INFO - TEST-INFO | 1656: exit 0 11:58:56 INFO - Browser initialized. 11:58:56 INFO - Running cycle 1/1 for kraken test... 11:58:56 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpJteR4A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:59:08 INFO - PROCESS | 1657 | RSS: Main: 234938368 11:59:08 INFO - PROCESS | 1657 | 11:59:11 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 11:59:12 INFO - PROCESS | 1657 | RSS: Main: 292192256 11:59:12 INFO - PROCESS | 1657 | 11:59:14 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 11:59:15 INFO - PROCESS | 1657 | RSS: Main: 288206848 11:59:15 INFO - PROCESS | 1657 | 11:59:18 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 11:59:19 INFO - PROCESS | 1657 | RSS: Main: 379383808 11:59:19 INFO - PROCESS | 1657 | 11:59:21 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 11:59:22 INFO - PROCESS | 1657 | RSS: Main: 280031232 11:59:22 INFO - PROCESS | 1657 | 11:59:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:59:24 INFO - PROCESS | 1657 | RSS: Main: 284332032 11:59:24 INFO - PROCESS | 1657 | 11:59:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 11:59:28 INFO - PROCESS | 1657 | RSS: Main: 321830912 11:59:28 INFO - PROCESS | 1657 | 11:59:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 11:59:31 INFO - PROCESS | 1657 | RSS: Main: 322506752 11:59:31 INFO - PROCESS | 1657 | 11:59:34 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 11:59:35 INFO - PROCESS | 1657 | RSS: Main: 321998848 11:59:35 INFO - PROCESS | 1657 | 11:59:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:59:37 INFO - PROCESS | 1657 | RSS: Main: 297934848 11:59:37 INFO - PROCESS | 1657 | 11:59:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:59:39 INFO - PROCESS | 1657 | RSS: Main: 302817280 11:59:39 INFO - PROCESS | 1657 | 11:59:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:59:42 INFO - PROCESS | 1657 | RSS: Main: 305795072 11:59:42 INFO - PROCESS | 1657 | 11:59:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:59:46 INFO - PROCESS | 1657 | RSS: Main: 319639552 11:59:46 INFO - PROCESS | 1657 | 11:59:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:59:49 INFO - PROCESS | 1657 | RSS: Main: 316039168 11:59:49 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 11:59:51 INFO - PROCESS | 1657 | RSS: Main: 359133184 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | __start_tp_report 11:59:51 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 11:59:51 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 11:59:51 INFO - PROCESS | 1657 | |i|pagename|runs| 11:59:51 INFO - PROCESS | 1657 | |0;ai-astar;159;165;133;113;114;112;111;112;114;113 11:59:51 INFO - PROCESS | 1657 | |1;audio-beat-detection;149;116;130;161;109;129;130;107;160;108 11:59:51 INFO - PROCESS | 1657 | |2;audio-dft;186;184;184;185;185;184;181;180;183;179 11:59:51 INFO - PROCESS | 1657 | |3;audio-fft;77;74;79;150;57;58;60;91;163;58 11:59:51 INFO - PROCESS | 1657 | |4;audio-oscillator;82;82;135;71;85;78;115;69;70;119 11:59:51 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;87;85;88;85;88;89;88;86;88;84 11:59:51 INFO - PROCESS | 1657 | |6;imaging-darkroom;93;93;93;94;92;93;94;94;93;94 11:59:51 INFO - PROCESS | 1657 | |7;imaging-desaturate;86;86;84;84;93;89;85;86;83;85 11:59:51 INFO - PROCESS | 1657 | |8;json-parse-financial;55;56;55;54;54;55;54;54;55;55 11:59:51 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;68;65;72;74;79;74;72;69;74;72 11:59:51 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;87;86;88;85;84;86;90;90;87;99 11:59:51 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;135;140;137;148;145;148;139;139;179;136 11:59:51 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;172;171;170;172;170;176;170;167;170;169 11:59:51 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;68;67;67;68;68;67;67;65;67 11:59:51 INFO - PROCESS | 1657 | __end_tp_report 11:59:51 INFO - PROCESS | 1657 | __start_cc_report 11:59:51 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2064 11:59:51 INFO - PROCESS | 1657 | __end_cc_report 11:59:51 INFO - PROCESS | 1657 | __startTimestamp1472583591680__endTimestamp 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | ------- Summary: start ------- 11:59:51 INFO - PROCESS | 1657 | Number of tests: 14 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:124.60 Median:114.00 stddev:20.77 (18.2%) stddev-sans-first:17.91 11:59:51 INFO - PROCESS | 1657 | Values: 159.0 165.0 133.0 113.0 114.0 112.0 111.0 112.0 114.0 113.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:130.00 stddev:20.74 (16.0%) stddev-sans-first:20.82 11:59:51 INFO - PROCESS | 1657 | Values: 149.0 116.0 130.0 161.0 109.0 129.0 130.0 107.0 160.0 108.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:2.33 (1.3%) stddev-sans-first:2.22 11:59:51 INFO - PROCESS | 1657 | Values: 186.0 184.0 184.0 185.0 185.0 184.0 181.0 180.0 183.0 179.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:86.70 Median:67.00 stddev:38.59 (57.6%) stddev-sans-first:40.77 11:59:51 INFO - PROCESS | 1657 | Values: 77.0 74.0 79.0 150.0 57.0 58.0 60.0 91.0 163.0 58.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:74.50 stddev:23.54 (31.6%) stddev-sans-first:24.76 11:59:51 INFO - PROCESS | 1657 | Values: 82.0 82.0 135.0 71.0 85.0 78.0 115.0 69.0 70.0 119.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:1.69 (1.9%) stddev-sans-first:1.79 11:59:51 INFO - PROCESS | 1657 | Values: 87.0 85.0 88.0 85.0 88.0 89.0 88.0 86.0 88.0 84.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 11:59:51 INFO - PROCESS | 1657 | Values: 93.0 93.0 93.0 94.0 92.0 93.0 94.0 94.0 93.0 94.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:86.00 stddev:2.92 (3.4%) stddev-sans-first:3.10 11:59:51 INFO - PROCESS | 1657 | Values: 86.0 86.0 84.0 84.0 93.0 89.0 85.0 86.0 83.0 85.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 11:59:51 INFO - PROCESS | 1657 | Values: 55.0 56.0 55.0 54.0 54.0 55.0 54.0 54.0 55.0 55.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:73.00 stddev:3.87 (5.3%) stddev-sans-first:3.84 11:59:51 INFO - PROCESS | 1657 | Values: 68.0 65.0 72.0 74.0 79.0 74.0 72.0 69.0 74.0 72.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:87.50 stddev:4.26 (4.9%) stddev-sans-first:4.50 11:59:51 INFO - PROCESS | 1657 | Values: 87.0 86.0 88.0 85.0 84.0 86.0 90.0 90.0 87.0 99.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:142.50 stddev:12.97 (9.1%) stddev-sans-first:13.29 11:59:51 INFO - PROCESS | 1657 | Values: 135.0 140.0 137.0 148.0 145.0 148.0 139.0 139.0 179.0 136.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:170.50 stddev:2.36 (1.4%) stddev-sans-first:2.46 11:59:51 INFO - PROCESS | 1657 | Values: 172.0 171.0 170.0 172.0 170.0 176.0 170.0 167.0 170.0 169.0 11:59:51 INFO - PROCESS | 1657 | 11:59:51 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 11:59:51 INFO - PROCESS | 1657 | Values: 67.0 68.0 67.0 67.0 68.0 68.0 67.0 67.0 65.0 67.0 11:59:51 INFO - PROCESS | 1657 | -------- Summary: end -------- 11:59:51 INFO - PROCESS | 1657 | 11:59:52 INFO - TEST-INFO | 1657: exit 0 11:59:52 INFO - TEST-OK | kraken | took 59107ms 11:59:52 INFO - SUITE-END | took 1177s 11:59:52 INFO - Completed test suite (00:19:37) 11:59:52 INFO - javascript benchmark 11:59:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3353.1784712745375, 3358.764886457948, 3374.611919629242, 3413.6757008084255, 3439.363717712223, 5675.744591493382, 5720.6853623050765, 5805.970970145148, 5828.154917536955, 5832.854178645536, 5801.738921748521, 5852.502537284336, 5865.736041878115, 5878.52971762259, 5887.7939272125595, 5251.816186433475, 5276.92084618731, 5313.840584782443, 5327.041132596131, 5368.946310536903, 4352.129574085196, 5076.873078173038, 5361.544268737142, 5364.892702145955, 5375.16684913839, 5217.113090774558, 5274.129768588178, 5299.099153143975, 5307.310049693534, 5330.866728331784, 5341.278927344798, 5360.678359298455, 5369.24830523727, 5382.757775900055, 5382.757775900055, 5162.974185129088, 5176.974115129438, 5194.065068287707, 5299.205119232108, 5304.973475132639, 5819.156222347779, 5840.561957853136, 5859.2382990211, 5860.531157507389, 5972.970135149297, 6179.752809887599, 6192.566520343577, 6205.503559715212, 6210.130581718563, 6224.719887605069, 5882.647041177543, 6095.408112664863, 6154.630722156685, 6213.627182369027, 6226.906596401058, 5328.040952628487, 5897.0, 5952.315483719428, 5955.285365756137, 5958.463738263577, 6307.180066591405, 6310.2743184534365, 6327.873442531123, 6333.050042493663, 6340.809775706736, 5927.73325200367, 6147.84630384244, 6198.721057552421, 6199.5660303779205, 6210.565260431817, 6246.812595622138, 6315.052742088632, 6327.430531252209, 6333.303336632966, 6334.588251763621, 6301.086342480315, 6309.211348581427, 6312.779052733133, 6312.905306420407, 6336.398042185985, 6139.676133582418, 6193.071039344134, 6213.316535181127, 6319.273283572356, 6325.65208913505, 4968.950310496921, 4973.602111831045, 4976.029674213566, 4982.850514484571, 5012.824551140766, 4471.619912307425, 4539.75031373271, 4542.863714088517, 4558.521355257716, 4598.218302888517, 5800.796972106041, 5849.093390524476, 5864.149698293723, 5869.325027621793, 5870.119482077637, 5284.550813180846, 6276.905846412307, 6294.622322660655, 6309.936900630935, 6317.684115794192, 5713.914291285634, 5769.913451298234, 5783.0, 5808.5933984621515, 5825.621334613236, 5438.646487978269, 5499.395066542677, 5502.779888804483, 5506.91739623906, 5575.832725018256, 6338.841528961812, 6383.234011918599, 6396.680165991589, 6402.327755585597, 6416.294207637249, 6284.685765711604, 6290.559660823698, 6296.275928268308, 6310.368963103653, 6330.556861019684, 6185.41238582334, 6253.280872699515, 6259.3427690091885, 6376.298607153117, 6391.96804015977, 5353.866153346197, 6281.12064311006, 6303.873922521616, 6306.054091886253, 6318.462930650762, 6212.689365531615, 6290.559660823881, 6297.874042519215, 6303.306636269915, 6328.46208072319, 6079.878402432015, 6130.153553065376, 6135.814547850547, 6246.531510136849, 6248.562600617913, 6156.969215153896, 6185.319614842454, 6211.068339748893, 6223.844403889939, 6233.937660623335, 6109.84725381869, 6115.755369785159, 6126.111713801563, 6135.877282454237, 6140.1710769047395, 5289.894202116013, 6030.427109424598, 6199.442050215503, 6209.192804935329, 6230.221222347207, 6084.117802918464, 6095.274004544494, 6141.570090093651, 6143.815685529441, 6157.32269450369], "value": 5759.467578450598, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.690004596966, 535.4859335038363, 539.1212323912023, 546.9863253418664, 557.6041010882274, 24633.384165395873, 36301.273974520496, 40601.79699101504, 40696.79651601741, 44090.779546102225, 42885.35671964923, 43101.0, 43145.7842710786, 43266.0, 43315.13369732603, 9723.27075469339, 9894.802103957916, 9914.851277230848, 9921.652742154027, 9925.454100024495, 8851.911480885205, 8963.372563920528, 11986.0, 12275.508979640803, 12310.384480775925, 12222.877771222307, 12319.876801232007, 12322.137450378477, 12331.5067397304, 12337.383130843467, 12202.267863928193, 12261.754764904652, 12281.57014504497, 12289.03375459931, 12305.261684298926, 12047.57833475833, 12143.817842732366, 12150.696232594255, 12154.817677734753, 12174.634760957186, 40344.39483407751, 40491.98770030773, 40497.98755031118, 40588.0, 40636.39045414321, 3599.8380072896784, 3650.0, 3860.7876566788814, 4076.7553946763287, 4193.161367726467, 29607.779416543846, 31481.74073037076, 31769.84115079433, 31798.88703895376, 32054.6794532054, 2740.4656092062055, 3061.1431985660315, 3176.6022949901967, 3225.580674512322, 3282.770206085575, 378523.0, 379462.0, 379546.0, 379629.0, 385743.0, 51311.0, 61806.0, 62041.68979155165, 62074.689626551575, 62197.0, 64947.35052649507, 65001.0, 65062.024069638996, 65063.67468162629, 65100.0, 16482.175891205392, 16709.6658066838, 16723.83276167247, 16724.080175590458, 16733.330666773436, 11636.185467017227, 11860.991815695763, 12963.287019213845, 12984.935075324564, 13030.478780848849, 16655.583610409838, 18342.165783421817, 18533.443996680122, 18538.907305463657, 18639.813601863807, 6407.199100112486, 6485.870282594322, 6539.771108011197, 6543.411093001652, 6598.73605055802, 904.3850181876255, 989.8861630912537, 1019.1948360794889, 1022.7187523430968, 1082.631905152252, 26424.339391515365, 29755.404891902042, 30313.0, 30638.234044149074, 30691.38617227643, 1003.4781913404988, 1038.9480525973822, 1048.9091345001266, 1091.24158709696, 1101.2896681640457, 13798.0, 15520.223988800515, 15576.454824081104, 15833.129177895325, 15970.121643309729, 648716.0, 662823.6858815675, 662941.167647457, 663315.0, 663807.6809615921, 58051.41948580545, 58373.70813145992, 58394.0, 58398.4160158393, 58618.827623447294, 1219.201423067892, 1273.5988163728396, 1325.0856908732944, 1339.5378594384922, 1391.3321605629355, 26737.12840101174, 30488.08535743931, 30749.0, 31022.84488577633, 33248.66751332456, 50304.0, 56414.0, 56974.715126424104, 57001.0, 57045.0, 538374.0, 615256.0, 618777.0, 619846.0, 619898.0, 26037.73962260429, 26533.469330612894, 26550.734492654825, 40616.39075413812, 40995.590044099175, 39410.40884386794, 39438.01404964899, 46174.30738538857, 47626.285605715246, 48053.75973120112, 38906.61093389143, 39609.603903961746, 39788.20423591454, 40806.59193408028, 41344.586554134075, 30339.544906826857, 33123.66876331206, 37045.25909481741, 39168.80415598018, 40305.596944030185, 38613.613863861, 39247.80376098101, 39292.41061384024, 39469.80265098656, 39507.40738888976, 33649.99050028503, 33927.4910876342, 34629.82685086558, 34787.826060869535, 36519.26961460703, 25249.11628093008, 25689.357766055997, 25701.485970280115, 25856.965721370936, 26387.944482221264], "value": 20498.132782092303, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.32228699193485, 375.5812269319706, 381.7499537802739, 395.9742616729911, 407.78998815609964, 20125.698114528277, 21780.782192178074, 21876.8906155469, 22252.777472225294, 22594.54810903781, 20586.897065514688, 21634.675479867776, 21724.8913755431, 22480.77519224807, 22675.659865102036, 5788.884222315552, 6381.521385896065, 6774.051632771416, 6798.320167983212, 6822.7612033578835, 5959.791407300745, 6714.294999025105, 6800.557963732366, 6808.0809090772855, 6823.624700641462, 6592.406683398493, 6654.334566543331, 6724.991251312318, 6794.864102717943, 6803.55776874504, 6808.9659551701925, 6848.280930502325, 6902.861942761142, 6937.653117344163, 6954.3393377629045, 6582.17722784652, 6791.151106111736, 6801.693923773442, 6807.149106361705, 6807.353301436356, 24044.234912985055, 28639.427211455655, 29544.704552954412, 29574.70425295741, 29929.551056734166, 2320.7447180810095, 2325.5930212212793, 2337.661039149331, 2440.2923152285816, 3206.88775892845, 15819.130296272762, 16337.428190013292, 16626.085565293903, 16990.405335813186, 17011.724120690997, 1978.307592342683, 1991.4921694967836, 2013.989930050355, 2040.6734922412343, 2087.7912208779, 101309.49345253226, 137719.31140344235, 138391.61608383988, 141372.29313853467, 157894.94733369094, 15369.0, 20722.378328649862, 20750.896245518677, 20956.37130886076, 21291.57416851654, 34945.30109397849, 35048.64951350454, 35072.64927350694, 35084.0, 35098.82450587782, 10508.106810921005, 10719.946400267949, 10902.569401665172, 10937.617183398543, 11124.55501779936, 7411.88882166768, 9016.18854303116, 9196.172344489029, 9209.493477858648, 9224.354295199271, 9221.53892305382, 9467.242620590336, 9591.820416974755, 9604.183644390305, 9634.855477167708, 3585.964140358615, 3659.963400365962, 3665.8533658653887, 3685.852565897334, 3798.8860334190017, 703.7642389799372, 945.1540870920487, 966.9033096690415, 967.1537404770826, 1029.330934892326, 19894.303699370743, 20739.58520829575, 20743.0, 20854.687179692315, 20895.582088358147, 861.039940466388, 913.6254135804288, 920.9401388909699, 952.9428234305825, 993.0913214408735, 8993.370464067453, 10777.83833242502, 10789.62236321725, 11161.330320180816, 11206.047485963785, 581335.0, 589715.0, 590521.0, 603565.0, 604553.0, 71561.0, 112734.0, 129188.0, 139018.0, 139604.0, 949.6011675096338, 982.729749318943, 1011.8836333821557, 1012.1953047327393, 1014.0467960117466, 18753.0, 19372.709409358587, 19450.416487505398, 19571.0, 19611.273857966942, 41615.0, 42883.571164289206, 55253.0, 56813.0, 58297.0, 58375.70812145912, 83508.58245708732, 83982.0, 84703.15296846953, 86786.0, 7740.419468539994, 7872.881906771518, 8065.395095367753, 8164.061132969785, 8379.748607541784, 9071.727848164566, 11913.702157446132, 12640.87359126397, 12842.486300547873, 12890.41993110331, 10925.781484370427, 11083.556657733601, 11251.718707032389, 11272.154588405738, 11353.716157096138, 10924.617638382619, 10977.28647637901, 11121.944390277997, 11248.043916267208, 11283.548658053585, 9593.65688803554, 11249.606263780728, 11280.661580152608, 11408.942955285502, 11430.199886008233, 5680.7443665036, 7900.0, 7908.683652653829, 8090.1909809018625, 8271.17288271168, 9121.680741174026, 10208.540615672164, 10394.272400931863, 11154.721131971766, 11475.196736228383], "value": 10864.432186796725, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.7802273613486, 1325.5890673891088, 1506.1264466609368, 1620.424749214029, 1844.7140693192553, 7830.451868369217, 8344.99860016798, 8753.737387878358, 8763.95618021909, 8768.430052046628, 8425.452345597532, 8642.567871606427, 8751.3436492263, 8756.78108047299, 8761.299096072315, 2391.3184742348435, 2441.645961335605, 2441.951160976779, 2444.6577479152934, 2445.058652418814, 2403.399150212447, 2435.2329016359877, 2438.2563318187945, 2447.0578827151585, 2457.778799908008, 2045.836333093349, 2046.5395286060666, 2393.3537944754908, 2426.259990702835, 2426.8301218914685, 2373.2998765364086, 2396.376941995085, 2405.0, 2406.602910519762, 2414.6740190074293, 2375.821813364005, 2389.4504264019374, 2407.9277621671204, 2412.119576354618, 2415.0098459631467, 7527.811804704871, 8554.70058547954, 8745.562721863946, 8835.293176545862, 8854.513001784899, 5221.086309895791, 5423.403425623178, 5429.131338985743, 5438.293021907127, 5442.836714898559, 3628.4375921732176, 3651.7626354286886, 3706.684931780802, 3709.0356507308024, 3717.6839968602703, 1097.1223021582703, 1110.0509064749604, 1112.8330750387427, 1128.68961035715, 1138.595798491532, 11574.53701851925, 11614.593489227836, 11743.0, 11744.0, 11795.174337796272, 4728.692634978715, 4732.634049732241, 4826.8793280168275, 4909.828156014522, 4909.950900491021, 10130.949345253226, 10141.363245556062, 10141.797164056825, 10147.543360548645, 10199.592016319264, 5409.513143817076, 5410.702411367412, 5414.242006119146, 5419.566434685215, 5422.213779002022, 4026.2350153470748, 4396.560343965642, 4588.311753237042, 4903.5586797188425, 4943.950560494421, 3997.620226078518, 4029.4761680981283, 4118.5263694674895, 4125.1749650070105, 4130.215259100762, 1274.7259339242107, 1320.2276668149048, 1321.2732996851694, 1327.9336033198301, 1334.8531661517225, 746.268656716421, 836.3768992100802, 878.1832895407332, 889.1908363389284, 938.1978408460668, 5609.27079479665, 5621.690807005572, 5696.287964004499, 5802.564807639359, 6011.609245399035, 888.3337496877342, 911.7538264668635, 917.6100157433065, 921.9262459003263, 928.6981730937472, 3813.637704418076, 3865.8646947356706, 3906.277338692351, 3926.391409331558, 3936.881893543198, 10439.739006524898, 10700.571977120984, 10764.0, 10769.246152769387, 10801.78396432067, 10323.432211228304, 10520.947395262974, 10702.250842440955, 10709.357438553712, 10772.138228941665, 8070.596470176468, 8214.876776848352, 8217.917820821835, 8221.136780638066, 8241.8351632967, 5593.748281327432, 5691.430856914276, 5730.541556675539, 5840.2115714378015, 5844.327902291291, 9540.713778586654, 9968.0, 10028.64899728506, 10029.398236105857, 10060.346077504939, 9229.169374756304, 9627.277954153327, 10048.397096174253, 10051.748706282402, 10183.490825458846, 6919.792406227822, 7095.290470952863, 7105.786826395217, 7108.0, 7125.394341481032, 8031.437799354223, 8047.919520804951, 8199.22107399796, 8313.459625124347, 8400.327973762207, 7419.258074192538, 7432.21961694036, 7432.628368581657, 7473.364763995121, 7480.850382992201, 7219.494635375473, 7288.67200975968, 7321.597312147883, 7338.633068346669, 7358.190599034209, 6900.896486552806, 7021.122359705037, 7034.331738484836, 7040.190378106378, 7040.683169257498, 4808.134535783593, 4908.852734417973, 4930.137225985366, 4978.950210497994, 5093.2105523643895, 4845.0, 4854.2233242681, 5030.019146266589, 5055.9494405055475, 5121.871953201051], "value": 4579.906165812579, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.21962675069676, 294.1044519438309, 306.9349357728681, 309.5975232198142, 311.7957737681819, 4808.567228949394, 4910.975445122778, 5362.329708786402, 5385.569154467643, 5425.674459532431, 4241.939515121219, 5113.667611605243, 5155.948440515594, 5240.737963101848, 5322.148456247001, 3361.310931259098, 3364.2094107884627, 4021.9945013746565, 4059.1881623675235, 4069.69477289203, 3241.8216998065095, 3782.5082739244, 3999.3201155803436, 4024.094578719794, 4047.1096358801015, 3316.369889720945, 3461.809600471973, 3638.6361363863534, 4005.978475488761, 4013.5183777946686, 3940.4877365942243, 3967.821448034845, 3974.324364857981, 3974.5031871015976, 3997.8600748973645, 3264.314493956272, 3475.58293004841, 3503.2292895562937, 3506.5266189064405, 3508.824558772051, 5060.621507891286, 5130.948690513122, 5138.2549530318265, 5141.640085194038, 5144.151215049513, 4552.453705555321, 4571.862844114648, 4581.266997280419, 4599.31010348447, 4610.2854057621125, 4412.911741765179, 5391.649542779707, 5420.756065977041, 5459.645123067027, 5468.578169675873, 3213.5661685672367, 3437.7795882461614, 3776.773393596393, 3803.220339830328, 3814.256220037094, 4386.780660966939, 5113.923291150636, 5484.957858006966, 5731.656100633933, 5806.912896306559, 3727.459518369821, 3863.632954869283, 3870.43878637596, 3891.4357418174204, 3899.5710471848074, 3477.2523907360037, 3678.5401824771902, 3872.9349428907276, 3881.96118038816, 3930.449737036817, 3759.774413535197, 4511.323301504801, 4541.977290113528, 4585.463869603654, 4589.609883159902, 5522.2544956431175, 5539.196816461671, 5592.077307244259, 5620.606557541015, 5690.2033715279895, 1053.9894601054045, 1086.8913108689069, 1228.336698182971, 1307.3071272225736, 1313.7438199550995, 724.4168444402306, 725.7931489525565, 729.8213385382569, 796.8804679298152, 812.6789917982364, 3666.926661466756, 3944.684425245973, 4163.938195760062, 4190.769507677046, 4235.4917409911, 4102.118044620421, 4151.972586591002, 4701.858944231679, 4780.354652121919, 4784.928226076611, 4193.287141186006, 4328.9134217316105, 4341.175176716477, 4367.388565600819, 4377.205345808204, 2208.5754688226325, 2345.3081341004445, 2410.638404239378, 2445.963310550343, 2486.9927679289917, 4143.647789937829, 4567.566081222279, 4704.694194877322, 4749.298194787136, 4919.508049195123, 4234.950835398508, 4380.956190438055, 4535.682502224812, 4832.405306248929, 4882.6572692509135, 4009.919801603952, 4313.784310784448, 4683.644210883911, 4868.810928841614, 4979.775910084055, 4258.361245813153, 4612.884677883079, 4739.644526660583, 4798.7840547174765, 4868.0, 4074.5517993021335, 4180.372944058416, 4389.817382397864, 4669.797182535745, 4751.168545504592, 4541.023679908835, 4711.458182308941, 4721.763911804464, 4732.100900828969, 5428.945710542844, 3604.7023071693643, 3664.029032306494, 3686.465462507868, 3858.6913046955615, 3859.8456061757215, 5137.51193636604, 5667.971660141673, 5700.828975130753, 5715.542756579381, 5736.368999410145, 4454.57681520255, 5346.037713211504, 5659.773609055592, 5664.886702266014, 5698.686572238401, 4210.3159735231675, 4486.737599280631, 5609.23309157614, 5621.269234999424, 5757.8560535986935, 4437.57843004927, 4954.232094025432, 5424.159255315432, 5488.259085023471, 5494.066008778597, 5483.369412517612, 5662.575306852085, 5680.6591604503865, 5720.910708217516, 5794.913076303943, 5224.268602395744, 5470.152126420411, 5584.916226256691, 5590.385057643737, 5595.824876775922], "value": 3796.221808138851, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.6784613001083, 724.9057622509076, 762.7139822566537, 801.7795106345751, 802.8474589827932, 15603.921980390096, 16261.593460163502, 16272.186390680477, 16280.348786048544, 16283.837161628395, 14180.149191048544, 14287.714245715106, 14294.070885392443, 14300.713985720306, 14301.0, 621.1087090025827, 678.5013015433652, 827.1315119124924, 835.5822088955522, 860.3375400941271, 842.4397775479299, 854.2098558833086, 854.4744981836158, 854.645322191293, 857.7812657772259, 905.741863568883, 908.8591268353383, 908.9818203635923, 910.0626354854477, 911.1071150272737, 707.6108140522743, 861.9224269815684, 899.3479727197795, 902.2285945516587, 902.5893218585561, 675.139197523157, 675.6250281093991, 676.7326905872202, 676.9120014398146, 678.6403206300669, 15024.774628380583, 15049.172295523742, 15054.473093441677, 15061.17163556004, 15087.24563771818, 13273.0, 13445.19328840263, 13541.45834166632, 13768.518101866386, 13947.302634868216, 12085.214461060092, 12440.626781196579, 12449.75100497985, 12461.875381246162, 12932.612021639367, 4463.531329210451, 4489.438820147481, 4544.500104988449, 4668.299755036738, 4676.3453116563705, 109802.9999999992, 109812.28075405689, 110651.0, 111389.0, 113992.0, 10616.469176541143, 10944.288621239593, 11165.385903775208, 11178.45451840105, 11424.0, 21797.67303490449, 22417.775822241896, 22420.775792241868, 22972.195973141195, 23009.0, 7644.082710074716, 7749.573773442402, 7779.844403111906, 7890.171531989174, 8107.432479726481, 4331.133773245364, 4392.758398288125, 4412.2499175139665, 4417.60241578253, 4530.365748795171, 3500.5974312953826, 3605.747597668138, 3606.513120728668, 3610.7472476926364, 3612.0066981580276, 657.6448717692392, 664.0338307762195, 677.6036018928982, 681.0, 681.1961884975677, 508.7125773937713, 538.5314776144779, 575.1660092865362, 616.9105479705418, 629.6285191736897, 8516.40385173032, 9093.272538196929, 9132.497712625736, 9169.495677737656, 9216.815663686823, 506.59978616892977, 613.1538476901846, 614.1555361378105, 626.2391194698463, 644.3974883483958, 3508.526348942911, 4022.2960981828064, 4415.646748260131, 4441.71128876622, 4480.820767169342, 99775.0, 144164.0, 163833.0, 165913.0, 177198.11400942912, 108043.0, 115820.0, 116233.0, 146202.2689886565, 151552.0, 71597.0, 83831.0, 112385.4380728091, 112807.4359628213, 113242.86757132324, 9052.185303322733, 9157.633694652406, 9191.816163676824, 9285.48929808867, 9486.810263794823, 59451.10823337567, 62714.37285627086, 62779.0, 64756.6762166205, 65419.0, 85121.0, 89485.55257223672, 90472.0, 92926.60610090719, 92969.5351523238, 12675.239485630891, 13081.280529570964, 13128.934355328161, 13548.52580159729, 13685.384157712728, 14626.707465850835, 15000.774988375402, 15062.171580563174, 15215.619609509851, 15260.771088433461, 12709.872901270868, 12803.61589152327, 12809.167404118702, 12938.417771200131, 14218.805452391776, 13584.184948903097, 13971.930140349234, 13992.370343334778, 14054.367553459915, 14179.574612761633, 11484.712882178012, 13559.0, 13669.453221871412, 13675.179489230679, 13883.444662213398, 9452.810943781224, 10649.361038337725, 11435.942820285845, 11541.36522491271, 11844.0, 9200.401973871676, 11395.544178232778, 11485.885141148816, 11546.711332216762, 11700.70748231267], "value": 7787.979547212124, "unit": "score"}], "name": "dromaeo_css", "value": 7469.615067474646, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 165.0, 133.0, 113.0, 114.0, 112.0, 111.0, 112.0, 114.0, 113.0], "value": 124.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 130.0, 161.0, 109.0, 129.0, 130.0, 107.0, 160.0, 108.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 184.0, 185.0, 185.0, 184.0, 181.0, 180.0, 183.0, 179.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 79.0, 150.0, 57.0, 58.0, 60.0, 91.0, 163.0, 58.0], "value": 86.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 135.0, 71.0, 85.0, 78.0, 115.0, 69.0, 70.0, 119.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 88.0, 89.0, 88.0, 86.0, 88.0, 84.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 92.0, 93.0, 94.0, 94.0, 93.0, 94.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 84.0, 93.0, 89.0, 85.0, 86.0, 83.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 54.0, 54.0, 55.0, 54.0, 54.0, 55.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 72.0, 74.0, 79.0, 74.0, 72.0, 69.0, 74.0, 72.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 85.0, 84.0, 86.0, 90.0, 90.0, 87.0, 99.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 140.0, 137.0, 148.0, 145.0, 148.0, 139.0, 139.0, 179.0, 136.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 171.0, 170.0, 172.0, 170.0, 176.0, 170.0, 167.0, 170.0, 169.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 68.0, 68.0, 67.0, 67.0, 65.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1478.3, "alertThreshold": 2.0}]} 11:59:52 INFO - Return code: 0 11:59:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:59:52 INFO - # TBPL SUCCESS # 11:59:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:59:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:59:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:59:52 INFO - 2016-08-30 11:59:52.995 screenresolution[1661:10161] starting screenresolution argv=screenresolution get 11:59:52 INFO - 2016-08-30 11:59:52.998 screenresolution[1661:10161] Display 0: 1600x1200x32@60 11:59:53 INFO - 2016-08-30 11:59:53.004 screenresolution[1662:10164] starting screenresolution argv=screenresolution list 11:59:53 INFO - Available Modes on Display 0 11:59:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:59:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:59:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:59:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:59:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:59:53 INFO - Intel Iris: 11:59:53 INFO - Chipset Model: Intel Iris 11:59:53 INFO - Type: GPU 11:59:53 INFO - Bus: Built-In 11:59:53 INFO - VRAM (Dynamic, Max): 1536 MB 11:59:53 INFO - Vendor: Intel (0x8086) 11:59:53 INFO - Device ID: 0x0a2e 11:59:53 INFO - Revision ID: 0x0009 11:59:53 INFO - Displays: 11:59:53 INFO - Display: 11:59:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:59:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:59:53 INFO - Display Serial Number: 1600x1200 60 11:59:53 INFO - Main Display: Yes 11:59:53 INFO - Mirror: Off 11:59:53 INFO - Online: Yes 11:59:53 INFO - Rotation: Supported 11:59:53 INFO - Return code: 0 11:59:53 INFO - Running post-action listener: _resource_record_post_action 11:59:53 INFO - [mozharness: 2016-08-30 18:59:53.237348Z] Finished run-tests step (success) 11:59:53 INFO - Running post-run listener: _resource_record_post_run 11:59:53 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37741568; Write bytes: 705189888; Read time: 386; Write time: 1898 11:59:53 INFO - TinderboxPrint: CPU usage
53.6% 11:59:53 INFO - TinderboxPrint: I/O read bytes / time
37,741,568 / 386 11:59:53 INFO - TinderboxPrint: I/O write bytes / time
705,189,888 / 1,898 11:59:53 INFO - TinderboxPrint: CPU idle
2,249.4 (46.4%) 11:59:53 INFO - TinderboxPrint: CPU system
967.3 (19.9%) 11:59:53 INFO - TinderboxPrint: CPU user
1,633.0 (33.7%) 11:59:53 INFO - TinderboxPrint: Swap in / out
443,998,208 / 0 11:59:53 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390377984; Write bytes: 384382976; Read time: 29429; Write time: 734 11:59:53 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36774912; Write bytes: 296677376; Read time: 371; Write time: 1119 11:59:53 INFO - Running post-run listener: _upload_blobber_files 11:59:53 INFO - Blob upload gear active. 11:59:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:59:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:59:54 INFO - (blobuploader) - INFO - Open directory for files ... 11:59:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:59:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:59:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:59:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:59:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:59:55 INFO - (blobuploader) - INFO - Done attempting. 11:59:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:59:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:59:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:59:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:59:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:59:56 INFO - (blobuploader) - INFO - Done attempting. 11:59:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:59:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:59:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:59:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:59:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:59:58 INFO - (blobuploader) - INFO - Done attempting. 11:59:58 INFO - (blobuploader) - INFO - Iteration through files over. 11:59:58 INFO - Return code: 0 11:59:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:59:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:59:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d12a4f475f8a6f15242c51929aa0fa6d210a8d9e2e3171749a461474710215f84b0cbbc65715207b48b58180ce776f5d560ef87efbcc5ffa550ad5dd6e4c460", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f296829e7e81c6f8dc209cea9c7bafee918c7b594733bdc60c959bf0f6145004f04ebc9751dd78874c9af24458415c699b40ca4d964a92229e83a1e7d32783f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49941dcc980f323c914a6f56a434d0ecd414a2c4298a402c0dd849b03613b778d96cf107edf0a4268efe43bcaf6db276e5915578c193f257c9d9727ce88955ea"} 11:59:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:59:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:59:58 INFO - Contents: 11:59:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d12a4f475f8a6f15242c51929aa0fa6d210a8d9e2e3171749a461474710215f84b0cbbc65715207b48b58180ce776f5d560ef87efbcc5ffa550ad5dd6e4c460", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f296829e7e81c6f8dc209cea9c7bafee918c7b594733bdc60c959bf0f6145004f04ebc9751dd78874c9af24458415c699b40ca4d964a92229e83a1e7d32783f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49941dcc980f323c914a6f56a434d0ecd414a2c4298a402c0dd849b03613b778d96cf107edf0a4268efe43bcaf6db276e5915578c193f257c9d9727ce88955ea"} 11:59:58 INFO - Running post-run listener: copy_logs_to_upload_dir 11:59:58 INFO - Copying logs to upload dir... 11:59:58 INFO - mkdir: /builds/slave/test/build/upload/logs 11:59:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.197490 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-30 11:59:58.831130) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 11:59:58.836414) ========= 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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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/Fx-Team/sha512/5d12a4f475f8a6f15242c51929aa0fa6d210a8d9e2e3171749a461474710215f84b0cbbc65715207b48b58180ce776f5d560ef87efbcc5ffa550ad5dd6e4c460", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f296829e7e81c6f8dc209cea9c7bafee918c7b594733bdc60c959bf0f6145004f04ebc9751dd78874c9af24458415c699b40ca4d964a92229e83a1e7d32783f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49941dcc980f323c914a6f56a434d0ecd414a2c4298a402c0dd849b03613b778d96cf107edf0a4268efe43bcaf6db276e5915578c193f257c9d9727ce88955ea"} build_url:https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011118 build_url: 'https://queue.taskcluster.net/v1/task/FXy-zEo_SPaudZaAlkq25w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d12a4f475f8a6f15242c51929aa0fa6d210a8d9e2e3171749a461474710215f84b0cbbc65715207b48b58180ce776f5d560ef87efbcc5ffa550ad5dd6e4c460", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f296829e7e81c6f8dc209cea9c7bafee918c7b594733bdc60c959bf0f6145004f04ebc9751dd78874c9af24458415c699b40ca4d964a92229e83a1e7d32783f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49941dcc980f323c914a6f56a434d0ecd414a2c4298a402c0dd849b03613b778d96cf107edf0a4268efe43bcaf6db276e5915578c193f257c9d9727ce88955ea"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 11:59:58.874307) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-08-30 11:59:58.874609) ========= 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.7jPXRVfWUP/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.QYSXNxNCdN/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.005137 ========= master_lag: 7.60 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-08-30 12:00:06.475248) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 12:00:06.475610) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 12:00:06.487149) ========= ========= Total master_lag: 7.95 =========