builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0005 starttime: 1472797659.77 results: success (0) buildid: 20160901215642 builduid: ad10029f6041452ebb85c5573b0fc16b revision: 4c4ce191fc9b444f1f47272293e001707538c105 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:39.770767) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:39.771181) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:39.771469) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:39.796637) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:39.796892) ========= 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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-09-01 23:27:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.0M=0.001s 2016-09-01 23:27:40 (16.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.480239 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:40.327091) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:40.327416) ========= 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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.027526 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:40.376196) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:40.376719) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4c4ce191fc9b444f1f47272293e001707538c105 --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 4c4ce191fc9b444f1f47272293e001707538c105 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rpaLvr1lmf/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.NQhBUtqFe2/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-09-01 23:27:40,454 truncating revision to first 12 chars 2016-09-01 23:27:40,454 Setting DEBUG logging. 2016-09-01 23:27:40,454 attempt 1/10 2016-09-01 23:27:40,454 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4c4ce191fc9b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 23:27:40,826 unpacking tar archive at: fx-team-4c4ce191fc9b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.616876 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:41.014833) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:41.015117) ========= script_repo_revision: 4c4ce191fc9b444f1f47272293e001707538c105 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:41.015523) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:41.015790) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 23:27:41.052908) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-01 23:27:41.053190) ========= /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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 23:27:41 INFO - MultiFileLogger online at 20160901 23:27:41 in /builds/slave/test 23:27:41 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 23:27:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:27:41 INFO - {'append_to_log': False, 23:27:41 INFO - 'base_work_dir': '/builds/slave/test', 23:27:41 INFO - 'blob_upload_branch': 'Fx-Team', 23:27:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:27:41 INFO - 'branch': 'Fx-Team', 23:27:41 INFO - 'buildbot_json_path': 'buildprops.json', 23:27:41 INFO - 'config_files': ('talos/mac_config.py',), 23:27:41 INFO - 'default_actions': ('clobber', 23:27:41 INFO - 'read-buildbot-config', 23:27:41 INFO - 'download-and-extract', 23:27:41 INFO - 'populate-webroot', 23:27:41 INFO - 'create-virtualenv', 23:27:41 INFO - 'install', 23:27:41 INFO - 'run-tests'), 23:27:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:27:41 INFO - 'download_minidump_stackwalk': True, 23:27:41 INFO - 'download_symbols': 'ondemand', 23:27:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:27:41 INFO - 'tooltool.py': '/tools/tooltool.py', 23:27:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:27:41 INFO - '/tools/misc-python/virtualenv.py')}, 23:27:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:27:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:27:41 INFO - 'installer_path': 'installer.exe', 23:27:41 INFO - 'log_level': 'info', 23:27:41 INFO - 'log_name': 'talos', 23:27:41 INFO - 'log_to_console': True, 23:27:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:27:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:27:41 INFO - 'opt_config_files': (), 23:27:41 INFO - 'pip_index': False, 23:27:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:27:41 INFO - 'cmd': ('bash', 23:27:41 INFO - '-c', 23:27:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:27:41 INFO - 'enabled': True, 23:27:41 INFO - 'halt_on_failure': False, 23:27:41 INFO - 'name': 'check_screen_resolution'},), 23:27:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:27:41 INFO - 'cmd': ('bash', 23:27:41 INFO - '-c', 23:27:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:27:41 INFO - 'enabled': True, 23:27:41 INFO - 'halt_on_failure': False, 23:27:41 INFO - 'name': 'check_screen_resolution'},), 23:27:41 INFO - 'run_cmd_checks_enabled': True, 23:27:41 INFO - 'sps_profile': False, 23:27:41 INFO - 'sps_profile_interval': 0, 23:27:41 INFO - 'suite': 'dromaeojs', 23:27:41 INFO - 'system_bits': '32', 23:27:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:27:41 INFO - 'title': 't-yosemite-r7-0005', 23:27:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:27:41 INFO - 'use_talos_json': True, 23:27:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:27:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:27:41 INFO - 'work_dir': 'build'} 23:27:41 INFO - [mozharness: 2016-09-02 06:27:41.252890Z] Running clobber step. 23:27:41 INFO - Running pre-action listener: _resource_record_pre_action 23:27:41 INFO - Running main action method: clobber 23:27:41 INFO - rmtree: /builds/slave/test/build 23:27:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:27:43 INFO - Running post-action listener: _resource_record_post_action 23:27:43 INFO - [mozharness: 2016-09-02 06:27:43.513236Z] Finished clobber step (success) 23:27:43 INFO - [mozharness: 2016-09-02 06:27:43.513372Z] Running read-buildbot-config step. 23:27:43 INFO - Running pre-action listener: _resource_record_pre_action 23:27:43 INFO - Running main action method: read_buildbot_config 23:27:43 INFO - Using buildbot properties: 23:27:43 INFO - { 23:27:43 INFO - "project": "", 23:27:43 INFO - "product": "firefox", 23:27:43 INFO - "script_repo_revision": "production", 23:27:43 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 23:27:43 INFO - "repository": "", 23:27:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 23:27:43 INFO - "buildid": "20160901215642", 23:27:43 INFO - "pgo_build": "False", 23:27:43 INFO - "basedir": "/builds/slave/test", 23:27:43 INFO - "buildnumber": 930, 23:27:43 INFO - "slavename": "t-yosemite-r7-0005", 23:27:43 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 23:27:43 INFO - "platform": "yosemite_r7", 23:27:43 INFO - "scheduler": "tests-fx-team-macosx64-talos", 23:27:43 INFO - "branch": "fx-team", 23:27:43 INFO - "repo_path": "integration/fx-team", 23:27:43 INFO - "revision": "4c4ce191fc9b444f1f47272293e001707538c105", 23:27:43 INFO - "stage_platform": "macosx64", 23:27:43 INFO - "builduid": "ad10029f6041452ebb85c5573b0fc16b", 23:27:43 INFO - "slavebuilddir": "test" 23:27:43 INFO - } 23:27:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 23:27:43 INFO - Running post-action listener: _resource_record_post_action 23:27:43 INFO - [mozharness: 2016-09-02 06:27:43.516674Z] Finished read-buildbot-config step (success) 23:27:43 INFO - [mozharness: 2016-09-02 06:27:43.516778Z] Running download-and-extract step. 23:27:43 INFO - Running pre-action listener: _resource_record_pre_action 23:27:43 INFO - Running main action method: download_and_extract 23:27:43 INFO - mkdir: /builds/slave/test/build/tests 23:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:43 INFO - trying https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 23:27:43 INFO - Downloading https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/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 23:27:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/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 23:27:44 INFO - Downloaded 1199 bytes. 23:27:44 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 23:27:44 INFO - Using the following test package requirements: 23:27:44 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:27:44 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:27:44 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 23:27:44 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:27:44 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 23:27:44 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:27:44 INFO - u'jsshell-mac.zip'], 23:27:44 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:27:44 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 23:27:44 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:27:44 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:27:44 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 23:27:44 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:27:44 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 23:27:44 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:27:44 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 23:27:44 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:27:44 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 23:27:44 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:44 INFO - trying https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:27:44 INFO - Downloading https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/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 23:27:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/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 23:27:46 INFO - Downloaded 16399190 bytes. 23:27:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 23:27:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:53 INFO - trying https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:27:53 INFO - Downloading https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/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 23:27:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/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 23:27:54 INFO - Downloaded 16399190 bytes. 23:27:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 23:28:01 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')]} 23:28:01 INFO - trying https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 23:28:01 INFO - Downloading https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/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 23:28:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/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 23:28:02 INFO - Downloaded 14413998 bytes. 23:28:02 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 23:28: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')]} 23:28:02 INFO - trying https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:28:02 INFO - Downloading https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 23:28:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/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 23:28:05 INFO - Downloaded 111591868 bytes. 23:28:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:28:05 INFO - mkdir: /builds/slave/test/properties 23:28:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:28:05 INFO - Writing to file /builds/slave/test/properties/build_url 23:28:05 INFO - Contents: 23:28:05 INFO - build_url:https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:28:06 INFO - Running post-action listener: _resource_record_post_action 23:28:06 INFO - Running post-action listener: set_extra_try_arguments 23:28:06 INFO - [mozharness: 2016-09-02 06:28:06.307738Z] Finished download-and-extract step (success) 23:28:06 INFO - [mozharness: 2016-09-02 06:28:06.307865Z] Running populate-webroot step. 23:28:06 INFO - Running pre-action listener: _resource_record_pre_action 23:28:06 INFO - Running main action method: populate_webroot 23:28:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:28:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:28:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:28:06 INFO - '--tppagecycles', 23:28:06 INFO - '7'], 23:28:06 INFO - 'tests': ['tsvgm']}}, 23:28:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:28:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:28:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:28:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:28:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:28:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:28:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:06 INFO - 'talos_options': ['--disable-e10s'], 23:28:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:28:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:28:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:06 INFO - 'talos_options': ['--disable-e10s'], 23:28:06 INFO - 'tests': ['damp', 'tps']}, 23:28:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:06 INFO - 'tests': ['damp', 'tps']}, 23:28:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:28:06 INFO - 'tests': ['dromaeo_dom']}, 23:28:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:28:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:28:06 INFO - 'tests': ['basic_compositor_video']}, 23:28:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:28:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:28:06 INFO - 'tests': ['a11yr', 23:28:06 INFO - 'ts_paint', 23:28:06 INFO - 'tpaint', 23:28:06 INFO - 'sessionrestore', 23:28:06 INFO - 'sessionrestore_no_auto_restore', 23:28:06 INFO - 'tabpaint']}, 23:28:06 INFO - 'other-e10s': {'tests': ['a11yr', 23:28:06 INFO - 'ts_paint', 23:28:06 INFO - 'tpaint', 23:28:06 INFO - 'sessionrestore', 23:28:06 INFO - 'sessionrestore_no_auto_restore', 23:28:06 INFO - 'tabpaint']}, 23:28:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:28:06 INFO - 'ts_paint', 23:28:06 INFO - 'tpaint', 23:28:06 INFO - 'sessionrestore', 23:28:06 INFO - 'sessionrestore_no_auto_restore', 23:28:06 INFO - 'tabpaint']}, 23:28:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:28:06 INFO - 'ts_paint', 23:28:06 INFO - 'tpaint', 23:28:06 INFO - 'sessionrestore', 23:28:06 INFO - 'sessionrestore_no_auto_restore', 23:28:06 INFO - 'tabpaint']}, 23:28:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:28:06 INFO - 'tests': ['a11yr', 23:28:06 INFO - 'ts_paint', 23:28:06 INFO - 'tpaint', 23:28:06 INFO - 'sessionrestore', 23:28:06 INFO - 'sessionrestore_no_auto_restore', 23:28:06 INFO - 'tabpaint']}, 23:28:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:28:06 INFO - 'tests': ['a11yr', 23:28:06 INFO - 'ts_paint', 23:28:06 INFO - 'tpaint', 23:28:06 INFO - 'sessionrestore', 23:28:06 INFO - 'sessionrestore_no_auto_restore', 23:28:06 INFO - 'tabpaint']}, 23:28:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:28:06 INFO - 'tests': ['tsvgx', 23:28:06 INFO - 'tsvgr_opacity', 23:28:06 INFO - 'tart', 23:28:06 INFO - 'tscrollx', 23:28:06 INFO - 'cart']}, 23:28:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:28:06 INFO - 'tsvgr_opacity', 23:28:06 INFO - 'tart', 23:28:06 INFO - 'tscrollx', 23:28:06 INFO - 'cart']}, 23:28:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:06 INFO - 'talos_options': ['--disable-e10s'], 23:28:06 INFO - 'tests': ['tp5o']}, 23:28:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:06 INFO - 'tests': ['tp5o']}, 23:28:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:06 INFO - 'talos_options': ['--disable-e10s', 23:28:06 INFO - '--xperf_path', 23:28:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:28:06 INFO - 'tests': ['tp5n']}, 23:28:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:06 INFO - 'talos_options': ['--xperf_path', 23:28:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:28:06 INFO - 'tests': ['tp5n']}}, 23:28:06 INFO - 'talos.zip': {'path': '', 23:28:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:28:06 INFO - Running post-action listener: _resource_record_post_action 23:28:06 INFO - [mozharness: 2016-09-02 06:28:06.326108Z] Finished populate-webroot step (success) 23:28:06 INFO - [mozharness: 2016-09-02 06:28:06.326239Z] Running create-virtualenv step. 23:28:06 INFO - Running pre-action listener: _resource_record_pre_action 23:28:06 INFO - Running main action method: create_virtualenv 23:28:06 INFO - Creating virtualenv /builds/slave/test/build/venv 23:28:06 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 23:28:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:28:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:28:06 INFO - Using real prefix '/tools/python27' 23:28:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:28:07 INFO - Installing distribute.............................................................................................................................................................................................done. 23:28:10 INFO - Installing pip.................done. 23:28:10 INFO - Return code: 0 23:28:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:28:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:10 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 0x109307a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10939c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d10d75170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10926bd30>, '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 0x108fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109263b20>, '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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 23:28:10 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 23:28:10 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 23:28:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rpaLvr1lmf/Render', 23:28:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:10 INFO - 'HOME': '/Users/cltbld', 23:28:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:10 INFO - 'LOGNAME': 'cltbld', 23:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:10 INFO - 'MOZ_NO_REMOTE': '1', 23:28:10 INFO - 'NO_EM_RESTART': '1', 23:28:10 INFO - 'PAGER': '/bin/cat', 23:28:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:10 INFO - 'PWD': '/builds/slave/test', 23:28:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:10 INFO - 'SHELL': '/bin/bash', 23:28:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NQhBUtqFe2/Listeners', 23:28:10 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 23:28:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:10 INFO - 'USER': 'cltbld', 23:28:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:10 INFO - 'XPC_FLAGS': '0x0', 23:28:10 INFO - 'XPC_SERVICE_NAME': '0', 23:28:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:10 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 23:28:10 INFO - Cleaning up... 23:28:10 INFO - Return code: 0 23:28:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:28:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:10 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 0x109307a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10939c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d10d75170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10926bd30>, '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 0x108fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109263b20>, '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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 23:28:10 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 23:28:10 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 23:28:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rpaLvr1lmf/Render', 23:28:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:10 INFO - 'HOME': '/Users/cltbld', 23:28:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:10 INFO - 'LOGNAME': 'cltbld', 23:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:10 INFO - 'MOZ_NO_REMOTE': '1', 23:28:10 INFO - 'NO_EM_RESTART': '1', 23:28:10 INFO - 'PAGER': '/bin/cat', 23:28:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:10 INFO - 'PWD': '/builds/slave/test', 23:28:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:10 INFO - 'SHELL': '/bin/bash', 23:28:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NQhBUtqFe2/Listeners', 23:28:10 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 23:28:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:10 INFO - 'USER': 'cltbld', 23:28:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:10 INFO - 'XPC_FLAGS': '0x0', 23:28:10 INFO - 'XPC_SERVICE_NAME': '0', 23:28:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:10 INFO - Downloading/unpacking psutil>=3.1.1 23:28:10 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 23:28:10 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 23:28:10 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 23:28:10 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 23:28:10 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 23:28:10 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 23:28:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:28:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:28:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:28:13 INFO - Installing collected packages: psutil 23:28:13 INFO - Running setup.py install for psutil 23:28:14 INFO - building 'psutil._psutil_osx' extension 23:28:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 23:28:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 23:28:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 23:28:14 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 23:28:14 INFO - building 'psutil._psutil_posix' extension 23:28:14 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 23:28:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:28:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:28:14 INFO - ^ 23:28:14 INFO - 1 warning generated. 23:28:14 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 23:28:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:28:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:28:15 INFO - Successfully installed psutil 23:28:15 INFO - Cleaning up... 23:28:15 INFO - Return code: 0 23:28:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109307a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10939c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d10d75170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10926bd30>, '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 0x108fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109263b20>, '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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 23:28:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:28:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rpaLvr1lmf/Render', 23:28:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:15 INFO - 'HOME': '/Users/cltbld', 23:28:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:15 INFO - 'LOGNAME': 'cltbld', 23:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:15 INFO - 'MOZ_NO_REMOTE': '1', 23:28:15 INFO - 'NO_EM_RESTART': '1', 23:28:15 INFO - 'PAGER': '/bin/cat', 23:28:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:15 INFO - 'PWD': '/builds/slave/test', 23:28:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:15 INFO - 'SHELL': '/bin/bash', 23:28:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NQhBUtqFe2/Listeners', 23:28:15 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 23:28:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:15 INFO - 'USER': 'cltbld', 23:28:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:15 INFO - 'XPC_FLAGS': '0x0', 23:28:15 INFO - 'XPC_SERVICE_NAME': '0', 23:28:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:28:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:28:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:28:17 INFO - Installing collected packages: mozsystemmonitor 23:28:17 INFO - Running setup.py install for mozsystemmonitor 23:28:18 INFO - Successfully installed mozsystemmonitor 23:28:18 INFO - Cleaning up... 23:28:18 INFO - Return code: 0 23:28:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109307a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10939c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d10d75170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10926bd30>, '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 0x108fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109263b20>, '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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 23:28:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:28:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:28:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rpaLvr1lmf/Render', 23:28:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:18 INFO - 'HOME': '/Users/cltbld', 23:28:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:18 INFO - 'LOGNAME': 'cltbld', 23:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:18 INFO - 'MOZ_NO_REMOTE': '1', 23:28:18 INFO - 'NO_EM_RESTART': '1', 23:28:18 INFO - 'PAGER': '/bin/cat', 23:28:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:18 INFO - 'PWD': '/builds/slave/test', 23:28:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:18 INFO - 'SHELL': '/bin/bash', 23:28:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NQhBUtqFe2/Listeners', 23:28:18 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 23:28:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:18 INFO - 'USER': 'cltbld', 23:28:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:18 INFO - 'XPC_FLAGS': '0x0', 23:28:18 INFO - 'XPC_SERVICE_NAME': '0', 23:28:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:18 INFO - Downloading/unpacking jsonschema==2.5.1 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:28:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:28: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 23:28: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 23:28: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 23:28: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 23:28: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 23:28: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 23:28:20 INFO - Downloading functools32-3.2.3-2.tar.gz 23:28:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:28:21 INFO - warning: no files found matching '*.txt' 23:28:21 INFO - no previously-included directories found matching 'build' 23:28:21 INFO - no previously-included directories found matching 'dist' 23:28:21 INFO - no previously-included directories found matching '.git*' 23:28:21 INFO - Installing collected packages: jsonschema, functools32 23:28:21 INFO - Running setup.py install for functools32 23:28:21 INFO - warning: no files found matching '*.txt' 23:28:21 INFO - no previously-included directories found matching 'build' 23:28:21 INFO - no previously-included directories found matching 'dist' 23:28:21 INFO - no previously-included directories found matching '.git*' 23:28:21 INFO - Successfully installed jsonschema functools32 23:28:21 INFO - Cleaning up... 23:28:21 INFO - Return code: 0 23:28:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:28:21 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')]} 23:28:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:21 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')]} 23:28:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:21 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 0x109307a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10939c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d10d75170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10926bd30>, '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 0x108fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109263b20>, '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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 23:28:21 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 23:28:21 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 23:28:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rpaLvr1lmf/Render', 23:28:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:21 INFO - 'HOME': '/Users/cltbld', 23:28:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:21 INFO - 'LOGNAME': 'cltbld', 23:28:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:21 INFO - 'MOZ_NO_REMOTE': '1', 23:28:21 INFO - 'NO_EM_RESTART': '1', 23:28:21 INFO - 'PAGER': '/bin/cat', 23:28:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:21 INFO - 'PWD': '/builds/slave/test', 23:28:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:21 INFO - 'SHELL': '/bin/bash', 23:28:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NQhBUtqFe2/Listeners', 23:28:21 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 23:28:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:21 INFO - 'USER': 'cltbld', 23:28:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:21 INFO - 'XPC_FLAGS': '0x0', 23:28:21 INFO - 'XPC_SERVICE_NAME': '0', 23:28:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:28:21 INFO - Cleaning up... 23:28:21 INFO - Return code: 0 23:28:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:28:21 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')]} 23:28:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:21 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')]} 23:28:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:21 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 0x109307a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10939c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d10d75170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10926bd30>, '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 0x108fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109263b20>, '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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 23:28:21 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 23:28:21 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 23:28:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rpaLvr1lmf/Render', 23:28:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:21 INFO - 'HOME': '/Users/cltbld', 23:28:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:21 INFO - 'LOGNAME': 'cltbld', 23:28:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:21 INFO - 'MOZ_NO_REMOTE': '1', 23:28:21 INFO - 'NO_EM_RESTART': '1', 23:28:21 INFO - 'PAGER': '/bin/cat', 23:28:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:21 INFO - 'PWD': '/builds/slave/test', 23:28:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:21 INFO - 'SHELL': '/bin/bash', 23:28:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NQhBUtqFe2/Listeners', 23:28:21 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 23:28:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:21 INFO - 'USER': 'cltbld', 23:28:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:21 INFO - 'XPC_FLAGS': '0x0', 23:28:21 INFO - 'XPC_SERVICE_NAME': '0', 23:28:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:21 INFO - Downloading/unpacking blobuploader==1.2.4 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:24 INFO - Downloading blobuploader-1.2.4.tar.gz 23:28:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:28:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:28: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 23:28: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 23:28: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 23:28: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 23:28: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 23:28: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 23:28:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:28:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:28:25 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 23:28:25 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 23:28:25 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 23:28:25 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 23:28:25 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 23:28:25 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 23:28:25 INFO - Downloading docopt-0.6.1.tar.gz 23:28:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:28:25 INFO - Installing collected packages: blobuploader, requests, docopt 23:28:25 INFO - Running setup.py install for blobuploader 23:28:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:28:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:28:25 INFO - Running setup.py install for requests 23:28:26 INFO - Running setup.py install for docopt 23:28:26 INFO - Successfully installed blobuploader requests docopt 23:28:26 INFO - Cleaning up... 23:28:26 INFO - Return code: 0 23:28:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:26 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 0x109307a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10939c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d10d75170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10926bd30>, '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 0x108fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109263b20>, '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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 23:28:26 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 23:28:26 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 23:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rpaLvr1lmf/Render', 23:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:26 INFO - 'HOME': '/Users/cltbld', 23:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:26 INFO - 'LOGNAME': 'cltbld', 23:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:26 INFO - 'MOZ_NO_REMOTE': '1', 23:28:26 INFO - 'NO_EM_RESTART': '1', 23:28:26 INFO - 'PAGER': '/bin/cat', 23:28:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:26 INFO - 'PWD': '/builds/slave/test', 23:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:26 INFO - 'SHELL': '/bin/bash', 23:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NQhBUtqFe2/Listeners', 23:28:26 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 23:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:26 INFO - 'USER': 'cltbld', 23:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:26 INFO - 'XPC_FLAGS': '0x0', 23:28:26 INFO - 'XPC_SERVICE_NAME': '0', 23:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:28:26 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-t8e4RS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:28:26 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-ETWb2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:28:26 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-UDGKW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:28:26 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-S_J4Ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:28:27 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-XuZ1t6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:28:27 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-E0oBDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:28:27 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-xJWE8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:28:27 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-A3PvtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:28:27 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-0Aktab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:28:27 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-YcLAr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:28:27 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-yj26ZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:28:27 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-mBBYdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:28:27 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-fQH0bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:28:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:28:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-A44mcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-SFkWL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:28:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-F_e91F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:28:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:28:28 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-M26Oqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:28:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:28:28 INFO - Running setup.py install for manifestparser 23:28:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:28:28 INFO - Running setup.py install for mozcrash 23:28:28 INFO - Running setup.py install for mozdebug 23:28:28 INFO - Running setup.py install for mozdevice 23:28:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:28:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:28:29 INFO - Running setup.py install for mozfile 23:28:29 INFO - Running setup.py install for mozhttpd 23:28:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:28:29 INFO - Running setup.py install for mozinfo 23:28:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:28:29 INFO - Running setup.py install for mozInstall 23:28:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:28:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:28:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:28:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:28:29 INFO - Running setup.py install for mozleak 23:28:29 INFO - Running setup.py install for mozlog 23:28:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:28:29 INFO - Running setup.py install for moznetwork 23:28:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:28:30 INFO - Running setup.py install for mozprocess 23:28:30 INFO - Running setup.py install for mozprofile 23:28:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:28:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:28:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:28:30 INFO - Running setup.py install for mozrunner 23:28:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:28:30 INFO - Running setup.py install for mozscreenshot 23:28:30 INFO - Running setup.py install for moztest 23:28:30 INFO - Running setup.py install for mozversion 23:28:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:28:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:28:30 INFO - Cleaning up... 23:28:30 INFO - Return code: 0 23:28:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:28:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:30 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 0x109307a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10939c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d10d75170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10926bd30>, '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 0x108fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109263b20>, '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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 23:28:30 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 23:28:30 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 23:28:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rpaLvr1lmf/Render', 23:28:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:30 INFO - 'HOME': '/Users/cltbld', 23:28:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:30 INFO - 'LOGNAME': 'cltbld', 23:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:30 INFO - 'MOZ_NO_REMOTE': '1', 23:28:30 INFO - 'NO_EM_RESTART': '1', 23:28:30 INFO - 'PAGER': '/bin/cat', 23:28:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:30 INFO - 'PWD': '/builds/slave/test', 23:28:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:30 INFO - 'SHELL': '/bin/bash', 23:28:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NQhBUtqFe2/Listeners', 23:28:30 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 23:28:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:30 INFO - 'USER': 'cltbld', 23:28:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:30 INFO - 'XPC_FLAGS': '0x0', 23:28:30 INFO - 'XPC_SERVICE_NAME': '0', 23:28:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:28:31 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-JVDkni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:28:31 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)) 23:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:28:31 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-rkotzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:28:31 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)) 23:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:28:31 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-ilXDDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:28:31 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)) 23:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:28:31 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-hDq_t1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:28:31 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)) 23:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:28:31 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-OHPyc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:28:31 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)) 23:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:28:31 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-JFP_Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:28:31 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)) 23:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:28:31 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-yxdSIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:28:31 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)) 23:28:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:28:31 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-gxBC40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:28:32 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)) 23:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:28:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-i0lgli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:28:32 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)) 23:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:28:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-VCNzSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:28:32 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)) 23:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:28:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-pTLG7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:28:32 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)) 23:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:28:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-OR5a5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:28:32 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)) 23:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:28:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-agSiAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:28:32 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)) 23:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:28:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-0FPaFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:28:32 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)) 23:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-2NhSsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:32 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)) 23:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:28:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-qOll_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:28:32 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)) 23:28:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:28:32 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-InBj_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:28:33 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)) 23:28:33 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)) 23:28:33 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)) 23:28:33 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)) 23:28:33 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)) 23:28:33 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)) 23:28:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:35 INFO - Downloading blessings-1.6.tar.gz 23:28:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:28:35 INFO - Installing collected packages: blessings 23:28:35 INFO - Running setup.py install for blessings 23:28:35 INFO - Successfully installed blessings 23:28:35 INFO - Cleaning up... 23:28:35 INFO - Return code: 0 23:28:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:28:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:28:36 INFO - Reading from file tmpfile_stdout 23:28:36 INFO - Current package versions: 23:28:36 INFO - blessings == 1.6 23:28:36 INFO - blobuploader == 1.2.4 23:28:36 INFO - docopt == 0.6.1 23:28:36 INFO - functools32 == 3.2.3-2 23:28:36 INFO - jsonschema == 2.5.1 23:28:36 INFO - manifestparser == 1.1 23:28:36 INFO - mozInstall == 1.12 23:28:36 INFO - mozcrash == 0.17 23:28:36 INFO - mozdebug == 0.1 23:28:36 INFO - mozdevice == 0.48 23:28:36 INFO - mozfile == 1.2 23:28:36 INFO - mozhttpd == 0.7 23:28:36 INFO - mozinfo == 0.9 23:28:36 INFO - mozleak == 0.1 23:28:36 INFO - mozlog == 3.3 23:28:36 INFO - moznetwork == 0.27 23:28:36 INFO - mozprocess == 0.23 23:28:36 INFO - mozprofile == 0.28 23:28:36 INFO - mozrunner == 6.12 23:28:36 INFO - mozscreenshot == 0.1 23:28:36 INFO - mozsystemmonitor == 0.3 23:28:36 INFO - moztest == 0.7 23:28:36 INFO - mozversion == 1.4 23:28:36 INFO - psutil == 3.1.1 23:28:36 INFO - requests == 1.2.3 23:28:36 INFO - wsgiref == 0.1.2 23:28:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:28:36 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')]} 23:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:36 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')]} 23:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:36 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 0x109307a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10939c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d10d75170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10926bd30>, '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 0x108fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109263b20>, '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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 23:28:36 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 23:28:36 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 23:28:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rpaLvr1lmf/Render', 23:28:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:36 INFO - 'HOME': '/Users/cltbld', 23:28:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:36 INFO - 'LOGNAME': 'cltbld', 23:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:36 INFO - 'MOZ_NO_REMOTE': '1', 23:28:36 INFO - 'NO_EM_RESTART': '1', 23:28:36 INFO - 'PAGER': '/bin/cat', 23:28:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:36 INFO - 'PWD': '/builds/slave/test', 23:28:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:36 INFO - 'SHELL': '/bin/bash', 23:28:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NQhBUtqFe2/Listeners', 23:28:36 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 23:28:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:36 INFO - 'USER': 'cltbld', 23:28:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:36 INFO - 'XPC_FLAGS': '0x0', 23:28:36 INFO - 'XPC_SERVICE_NAME': '0', 23:28:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:36 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)) 23:28:36 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)) 23:28:36 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)) 23:28:36 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)) 23:28:36 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)) 23:28:36 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)) 23:28:36 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)) 23:28:36 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)) 23:28:36 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)) 23:28:36 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)) 23:28:36 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)) 23:28:36 INFO - Cleaning up... 23:28:36 INFO - Return code: 0 23:28:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:28:36 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')]} 23:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:36 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')]} 23:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:36 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 0x109307a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10939c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d10d75170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10926bd30>, '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 0x108fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109263b20>, '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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 23:28:36 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 23:28:36 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 23:28:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rpaLvr1lmf/Render', 23:28:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:36 INFO - 'HOME': '/Users/cltbld', 23:28:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:36 INFO - 'LOGNAME': 'cltbld', 23:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:36 INFO - 'MOZ_NO_REMOTE': '1', 23:28:36 INFO - 'NO_EM_RESTART': '1', 23:28:36 INFO - 'PAGER': '/bin/cat', 23:28:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:36 INFO - 'PWD': '/builds/slave/test', 23:28:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:36 INFO - 'SHELL': '/bin/bash', 23:28:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NQhBUtqFe2/Listeners', 23:28:36 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 23:28:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:36 INFO - 'USER': 'cltbld', 23:28:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:36 INFO - 'XPC_FLAGS': '0x0', 23:28:36 INFO - 'XPC_SERVICE_NAME': '0', 23:28:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:28:36 INFO - Cleaning up... 23:28:36 INFO - Return code: 0 23:28:36 INFO - Running post-action listener: _resource_record_post_action 23:28:36 INFO - Running post-action listener: _start_resource_monitoring 23:28:36 INFO - Starting resource monitoring. 23:28:36 INFO - [mozharness: 2016-09-02 06:28:36.683533Z] Finished create-virtualenv step (success) 23:28:36 INFO - [mozharness: 2016-09-02 06:28:36.684516Z] Running install step. 23:28:36 INFO - Running pre-action listener: _resource_record_pre_action 23:28:36 INFO - Running main action method: install 23:28:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:28:36 INFO - Reading from file tmpfile_stdout 23:28:36 INFO - Detecting whether we're running mozinstall >=1.0... 23:28:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:28:37 INFO - Reading from file tmpfile_stdout 23:28:37 INFO - Output received: 23:28:37 INFO - Usage: mozinstall [options] installer 23:28:37 INFO - Options: 23:28:37 INFO - -h, --help show this help message and exit 23:28:37 INFO - -d DEST, --destination=DEST 23:28:37 INFO - Directory to install application into. [default: 23:28:37 INFO - "/builds/slave/test"] 23:28:37 INFO - --app=APP Application being installed. [default: firefox] 23:28:37 INFO - mkdir: /builds/slave/test/build/application 23:28:37 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'] 23:28:37 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 23:29:11 INFO - Reading from file tmpfile_stdout 23:29:11 INFO - Output received: 23:29:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:29:11 INFO - Running post-action listener: _resource_record_post_action 23:29:11 INFO - [mozharness: 2016-09-02 06:29:11.530852Z] Finished install step (success) 23:29:11 INFO - [mozharness: 2016-09-02 06:29:11.530977Z] Running run-tests step. 23:29:11 INFO - Running pre-action listener: _resource_record_pre_action 23:29:11 INFO - Running main action method: run_tests 23:29:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:29:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:29:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:29:11 INFO - 2016-09-01 23:29:11.545 screenresolution[1645:8338] starting screenresolution argv=screenresolution get 23:29:11 INFO - 2016-09-01 23:29:11.547 screenresolution[1645:8338] Display 0: 1600x1200x32@60 23:29:11 INFO - 2016-09-01 23:29:11.553 screenresolution[1646:8340] starting screenresolution argv=screenresolution list 23:29:11 INFO - Available Modes on Display 0 23:29:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:29:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:29:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:29:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:29:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:29:11 INFO - Intel Iris: 23:29:11 INFO - Chipset Model: Intel Iris 23:29:11 INFO - Type: GPU 23:29:11 INFO - Bus: Built-In 23:29:11 INFO - VRAM (Dynamic, Max): 1536 MB 23:29:11 INFO - Vendor: Intel (0x8086) 23:29:11 INFO - Device ID: 0x0a2e 23:29:11 INFO - Revision ID: 0x0009 23:29:11 INFO - Displays: 23:29:11 INFO - Display: 23:29:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:29:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:29:11 INFO - Display Serial Number: 1600x1200 60 23:29:11 INFO - Main Display: Yes 23:29:11 INFO - Mirror: Off 23:29:11 INFO - Online: Yes 23:29:11 INFO - Rotation: Supported 23:29:11 INFO - Return code: 0 23:29:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:29:11 INFO - Python 2.7.3 23:29:11 INFO - Return code: 0 23:29:11 INFO - grabbing minidump binary from tooltool 23:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:29:11 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 0x10926bd30>, '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 0x108fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109263b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:29:11 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 23:29:11 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 23:29:11 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 23:29:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:29:11 INFO - Return code: 0 23:29:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:29:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:29:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:29:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:29:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:29:11 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/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 23:29:11 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/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0005 --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 23:29:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rpaLvr1lmf/Render', 23:29:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:29:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:29:11 INFO - 'HOME': '/Users/cltbld', 23:29:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:29:11 INFO - 'LOGNAME': 'cltbld', 23:29:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:29:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:29:11 INFO - 'MOZ_NO_REMOTE': '1', 23:29:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:29:11 INFO - 'NO_EM_RESTART': '1', 23:29:11 INFO - 'PAGER': '/bin/cat', 23:29:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:29:11 INFO - 'PWD': '/builds/slave/test', 23:29:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:29:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:29:11 INFO - 'SHELL': '/bin/bash', 23:29:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NQhBUtqFe2/Listeners', 23:29:11 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 23:29:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:29:11 INFO - 'USER': 'cltbld', 23:29:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:29:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:29:11 INFO - 'XPC_FLAGS': '0x0', 23:29:11 INFO - 'XPC_SERVICE_NAME': '0', 23:29:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:29:11 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/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 23:29:12 INFO - mozversion application_buildid: 20160901215642 23:29:12 INFO - mozversion application_changeset: 4c4ce191fc9b444f1f47272293e001707538c105 23:29:12 INFO - mozversion application_display_name: Nightly 23:29:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:29:12 INFO - mozversion application_name: Firefox 23:29:12 INFO - mozversion application_remotingname: firefox 23:29:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:29:12 INFO - mozversion application_vendor: Mozilla 23:29:12 INFO - mozversion application_version: 51.0a1 23:29:12 INFO - mozversion platform_buildid: 20160901215642 23:29:12 INFO - mozversion platform_changeset: 4c4ce191fc9b444f1f47272293e001707538c105 23:29:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:29:12 INFO - mozversion platform_version: 51.0a1 23:29:12 INFO - using testdate: 1472797752 23:29:12 INFO - actual date: 1472797752 23:29:12 INFO - starting webserver on 'localhost:49273' 23:29:12 INFO - SUITE-START | Running 2 tests 23:29:12 INFO - TEST-START | dromaeo_css 23:29:12 INFO - operating with platform_type : mac_ 23:29:12 INFO - Initialising browser for dromaeo_css test... 23:29:12 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpsl6h1l/profile http://localhost:49273/getInfo.html) 23:29:14 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 23:29:14 INFO - PROCESS | 1656 | colorDepth:24 23:29:14 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 23:29:14 INFO - PROCESS | 1656 | __metrics 23:29:14 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" 23:29:14 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] 23:29:15 INFO - TEST-INFO | 1656: exit 0 23:29:15 INFO - Browser initialized. 23:29:15 INFO - Running cycle 1/1 for dromaeo_css test... 23:29:15 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpsl6h1l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:29:27 INFO - PROCESS | 1659 | RSS: Main: 233713664 23:29:27 INFO - PROCESS | 1659 | 23:30:19 INFO - PROCESS | 1659 | 1472797819025 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] 23:32:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 23:32:33 INFO - PROCESS | 1659 | RSS: Main: 398082048 23:32:33 INFO - PROCESS | 1659 | 23:35:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 23:35:36 INFO - PROCESS | 1659 | RSS: Main: 370446336 23:35:36 INFO - PROCESS | 1659 | 23:38:39 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 23:38:39 INFO - PROCESS | 1659 | RSS: Main: 335077376 23:38:39 INFO - PROCESS | 1659 | 23:41:42 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 23:41:42 INFO - PROCESS | 1659 | RSS: Main: 348229632 23:41:42 INFO - PROCESS | 1659 | 23:44:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 23:44:46 INFO - PROCESS | 1659 | RSS: Main: 331948032 23:44:46 INFO - PROCESS | 1659 | 23:47:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 23:47:50 INFO - PROCESS | 1659 | RSS: Main: 555208704 23:47:50 INFO - PROCESS | 1659 | 23:47:50 INFO - PROCESS | 1659 | __start_tp_report 23:47:50 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 23:47:50 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 23:47:50 INFO - PROCESS | 1659 | |i|pagename|runs| 23:47:50 INFO - PROCESS | 1659 | |0;dojo.html;3259;3293.440115180419;3324.7506437017228;3358.697717205454;3377.476491143872;5539.141433077874;5552;5601.915971260434;5627.662340259582;5641.548676105912;5591.217229587851;5647.548196144309;5652.180433837101;5662;5663.348714897777;5079.2635067915135;5149.794008239666;5157.123289040872;5166.741662916839;5189.221616757498;5193.740312984354;5202.791888324463;5204;5205.297284866532;5214.296070030536;5148.8712782180555;5151.150060240056;5187.818426355078;5189.662671926331;5197.090509160882;5201.479852014806;5233.790648374061;5242.580593552507;5245.160774276135;5311.760970756325;5046.596272298244;5059.418166910816;5079.5428411443145;5155.7679904404395;5173.948260517384;4696.553827386393;5586.581006424534;5592.944070559283;5615.2700148980375;5620.887582248373;6084.574079814415;6109.877802443971;6131;6131.47882429994;6133.294671112834;6004.369541198199;6125.816225513242;6137.171481849938;6146.078088286748;6220.906686399662;5711.514521265656;5731.856703582361;5733.885322293614;5735.139729040678;5738.196652468658;6301.369863013753;6318.526110541728;6330.778422755181;6330.778422755181;6336.936630633727;5793.2179155814265;5932.525397968152;5960.552958528127;5995.100734889801;5995.790147344909;6106.2061931948565;6157.168782214344;6179.721912513947;6186.690665466798;6189.257289125334;6033.693098092314;6092.939070609325;6115.235595550556;6132.6933653317155;6148.985417406078;5942.910856337158;5987.970060149672;5997.430244126801;6135.251223653945;6151.98492248774;4884.413870335582;4904.0191961607825;4907.730074845917;4912.631552633567;4915.361003069578;4447.888802779957;4458.7547684877645;4480.372747815309;4481.439820022497;4500;5585.776568937278;5650.632708873993;5655.236543066716;5680.715964201773;5693.544516438675;6132.478739307209;6183.443490085914;6196.81409557714;6207.5654704171175;6209.782657606962;5615.129654903497;5627.71861406928;5644.774209031592;5647.971760141173;5652.0391533439415;5269.525742683177;5321.467853214725;5343.492368225013;5366.6243362965;5367.2485851980755;5282.920756188737;6300.810975670737;6318.494520438262;6343.999999999907;6359.427651511386;6151.169592105009;6187.041008643668;6196.349383314687;6197.03945888388;6200.968995154995;6166.859131060768;6228.56400051992;6286.465650419766;6287.779927702509;6355.650439225887;6025.517958563392;6042.274927008787;6057.151998720271;6057.969710151421;6115.082737589397;6123.510119190365;6143.784967526115;6151.507879369729;6171.84570385744;6179.1040299155475;4591.311303304397;5804.303483581828;5805;5890.234269545103;5952.732127054206;4840.2255639097575;5873.03094989322;5883.588148829541;5918.911216331672;5926.970365148147;5831.300243970751;5839;5868.295804503487;5891.410858914074;5896.852578685567;5942.227510423617;5944.4352786485215;5946.316173640087;5960.314563825215;5964.97017514927;5684.914726279192;5728.828135155952;5735.082386818089;5796.971015144897;5826.883462330645 23:47:50 INFO - PROCESS | 1659 | |1;ext.html;475.3962467666062;490.0810979413597;530.1358785180157;537.2076187623256;537.5753155007543;31037.06888793337;38206.61793382066;39458.21083578327;39506.60493395065;41591.16817663645;41124.794376028156;41554.16891662165;41566.79216603913;41691.957701057414;41758.79120604393;9615.56729947154;9768.07203315684;9775.657851975182;9782.168515676176;9790.363626364298;9666.613335466573;9745.658901938434;9756.90243097567;9760.560774765117;9765.609375625001;9719.17387022104;9789.90210097897;9800.264980126483;9827.508624568778;9834.557444914995;9671.419714817099;9747.317687761859;9751.512424378752;9761.560729767107;9769.316147869651;9680;9730.318877678565;9731.075547822946;9732.07545283197;9740.220782337467;32812.8359358204;39217;39439.81680549588;39565.80217098925;39686.60313396858;2907.215051935965;3408.812515311657;3433.4506478963494;3652.433872749728;4067.2068946555432;29942.55086173709;29954.55068173979;29956.101316960525;30598.847005765052;31440.52839207414;3167.4298626247264;3203.6315823680343;3212.4538828399227;3212.534182543518;3295.571575695168;478827;484834;485509;489253;500652;55343.72328138333;55787.72106139443;55935;56081;56099;33061.3387732244;58012.70993645005;58037.41962580405;58077.70961145252;58114.418855811746;15305.770413443808;16004.199790010452;16016.839831601535;16047.919760401357;16062.277197526138;12263.325517096646;12374.381280935917;12390;12391;12393.876061239453;18430.07849607541;18482.537936551693;18483.353082642043;18490.075496225403;18531;7774.611269436618;7924.643391047417;7982.441229113906;7995.680172793023;8113.634886430124;824.3858325547506;890.5903284489078;908.4276905549461;983.5279066048337;1103.89254249288;29631.40737185244;29688.452673061107;29786;29813.85093074564;30130.84934575313;996.8853581838182;1026.6047571684956;1036.2176556699644;1041.3283432186197;1064.377339256528;14272;14889.255537223096;15232.695346093238;15252.466163684217;15278.38886444552;631455.1126932398;635626;636519;637406.8129659322;637534;52354;52735;52834;52910;52911.73544132255;1203.8134089216185;1319.2678063674562;1345.2130503655471;1345.656857501331;1362.9318534073257;25624.871875640503;28982;29198.978035768647;29218.123456296344;30136.547951780303;46810.06379872451;47434.051318972735;47468;47468.52531474641;47543.76228118837;496622;499622;499978;500808;503621;21785;24083.397915051562;24638.630420544065;24743.133990310973;24848.130315438873;42378;42573.78713106415;43239.56760432481;43864.34203486887;44208.11583768371;30751.53872691956;32458.675413245564;36113.63886361103;36317.636823632485;37750.43374349442;35997.64002359943;36157.63842361648;36937;37380.81309593435;37451.81274093612;35157.76947806814;35564.466533002545;35739.92780216598;35745.46381804223;36067.81966090153;28548.00491896725;31297.435487737115;31806.204844879063;32075.679243208204;32260;23444.648330275402;24143.034278628656;24227.515449690556;24823.751762482865;24856 23:47:50 INFO - PROCESS | 1659 | |2;jquery.html;323.1420578364442;346.5564077980186;366.1121779684265;374.4514286570174;403.75572778469024;12546.686332841684;17790.110494475273;20575;20699.896500517512;20843.583128337443;19510.804891951077;20176.293829715964;20211.191552337925;20389.898050509764;20918;5077.314562534048;5405.675659460425;5643.294588176478;5646.576506761989;5650.604457687963;6128.785492507763;6259.749610015594;6264.310925798159;6266.404691554295;6268.780592679257;5509.9255645149215;5609.747561359728;5777.768889244446;6236.501079913596;6239.937600624004;5480.6437581557075;5527.474889885454;5528.585356098309;5529.253550770634;5531.751071201766;5135.6918584885025;5362.142057270857;5392.730363481849;5412.404635490093;5428.131498960148;24811;25638.230853074252;25786.74213257862;25891.352716182057;26188.82150303241;2123.182574708733;2140.347194105797;2555.7316481769517;2997.8051426657416;3091.0572275455975;9456.810863782755;10239.795204095877;10384.688459346156;10408.479576021244;10422.21833362501;1833.3432486564855;1842.5577861313323;1870.8503319734525;1900.4203717866046;1910.4268719384268;123059;124522.37738811338;125291;127861.36069319594;128765.35617321946;10099.495025248854;10662.733431664115;10801.459927003618;10846.349219046882;11444.370559619134;14425.20661363614;14425.85574144245;14429.062110962754;14432.567022989328;14442.638934026523;8233.217844304781;8500.022497412752;8528.53093079874;8637.395382323302;8647.048824629286;7088.007678924954;8263.132371101068;8378.49729016261;8398.740188971704;8570.271526920282;3641.890743277706;4470.24005918988;4471.731696098244;4484.932726009112;4590;1735.8784885058178;1797.4787311679465;1842.63644902771;1851.5093500222451;1940.4857712706144;739.7115125101215;840.6175190288404;907.2061945797427;920.9815803683889;971.917387022111;18748.625027499373;20003.799962000485;22182.55634887293;22200;23051;889.8665200220019;914.2274777812737;924.861270809384;939.7180845746385;943.4480828715278;10599.152067834555;10983.61557345489;11478.368689722145;11487.42562871853;11525.596604118815;601065;603193;603835;626577;626795;102436.48781756192;111841;136040;137460;140471;974.6880998080546;994.7314225159312;1010.1918465227935;1012.1346248957036;1018.8828284747201;15090.622734431727;19432.514187145436;19553.70669439988;20520.486987825425;20588.176472941515;55314.89370212654;55673.60815979471;55961.16058259211;55992.44007560035;56354.71822640861;73974.15064623428;74184.9048680749;74757;77413.61293193723;79366.60316698611;8116.863639090414;8175.345972322081;8193.303569196685;8269.917300827155;8311.916880831115;12578.685532861386;13019.544315948897;13059.477620895057;13466.461341546228;13498.392572334073;11352;11625.069994400597;11633.069354451454;11804.409779511161;11808.468618912342;11153.051990580567;11305.886941130813;11596.596845614074;11698.70753231176;11758.706032348917;11031.450052848686;11226;11662.300261984308;11811.468483918072;11988.64034078979;7688.154303026775;7783.649735762017;8190.631421586162;8234.835303294021;8596.312295016509;10937.781244375226;11364;11636.010939070275;11638.88361116412;11765.823512647488 23:47:50 INFO - PROCESS | 1659 | |3;mootools.html;1506.0210862939089;1533.3023474319182;1738.8087310395854;1894.9767125752094;1918.875273107248;8017.639206235719;8277.875831862511;8506.404551681377;9047.230985366243;9047.63809447622;7511.774646760592;8487.830243395128;8723.781905452366;8753.16844899735;8893.421927574704;2372.122314743547;2392.8803559822027;2403.891824867885;2404.831661783676;2409.229046705055;1969.5863868587614;2111;2194.659827726705;2385.4632707640817;2404.723456802473;1973.802619738029;2166.902489387973;2182.6507758758603;2188.4528867783056;2384.308550520347;2391.210900402863;2392.07904956593;2399;2400.4718961828457;2403.4592216751266;1975.4073777866727;2376.857388556692;2385.3440303916386;2386.9641955370685;2391.9043238270447;8899.955500222522;8918.108189181095;8975.326850486239;8976.640934362618;8993.59528821198;5254.92117618236;5405.00007498614;5405.567554595623;5415.566754659618;5416.404195538487;3479.878204262864;3615.94576081359;3622.3298689742483;3630.0743310455928;3637.0907273181706;1029.7683021320217;1062.7715041266165;1081.9134469242442;1085.1698450685233;1088.8094583447864;11712.765744685144;11731.354775487345;11740.823887641693;11752.647420577396;11755.58855440064;4484.439445069366;4788.233882578771;4843.927341089887;4853.733044682576;4865.805367785249;9986.10125088731;9996.200303975664;9996.750081248027;10010.14913732341;10011;5396.028714831368;5404.162354835007;5404.945950540444;5419.051665958442;5419.701916394636;4797.592204662573;4874.2444921037295;4969.801207951714;4989.351384320015;5001.349824522788;3847.1728578355787;3935.9606403935595;3964.1675248198203;4032.858849940238;4074.6740260779065;1107.8338249262674;1264.279360764374;1282.3652292115462;1283.4352884730688;1290.2323117744927;757.6779868555842;849.3544905871582;885.6501681835639;892.4154678685471;925.606617187706;5380.488853558905;5601.403599973988;5835.854103647358;5843.2403787507355;6075.27096748393;883.7923088074298;927.0914503786328;928.8513837785922;936.3118108190474;941.3975055964188;3214.5981752280964;3783.829727662262;3850.8074596270076;3852.210296889131;4039.939400908989;9230.261579073658;10553.997370249968;10996.23026388161;11027.558897644165;11032.613858514913;9185.540722963826;10329.586816527404;10916.836247456295;10946.945265273622;10952.835707464394;7991.440599158003;8321.708740194184;8329.250367466957;8331.750047498585;8347.833043339098;4624.976875115737;5585.1901474286815;5734.569907257056;6145.051092564204;6253.343398943228;10042.347247428894;10309.278350515391;10336.483175841027;10337.534810933375;10346.844797327896;9949.253805964438;10346.948265258625;10351.948240258751;10430.217733669853;10529.736756581147;7424.777256682308;7575.772726818204;7671.8849217262905;7676.501027433199;7682.654280557276;8126.349892008743;8233.752987410388;8312.709055183039;8314.875276870973;8327.125651806715;7454.850902982019;7457.589832559262;7501.849963000818;7556.055493063367;7588.317051465395;7281.927180728125;7369;7396.667149978156;7427;7443.776686699408;6876.793696189123;6997.895031574429;7002.124734408199;7022;7025.68384422692;4837.2260438329695;4946.554810067111;4981;4996;5163.199704045879;4923.163062279349;4979.3526841510375;5036.1942089267;5049.368828896388;5163.561097306771 23:47:50 INFO - PROCESS | 1659 | |4;prototype.html;254.55961187146224;279.864265831072;300.5461752753342;311.3197663106113;311.6571771051843;5155.926817813927;5250.55370293525;5424.8372548823545;5454.754536045877;5464.9180262296095;4520.570545798152;4833.806647734095;5103;5360.249565060894;5397.811076612319;3383.983080084596;4097.119119389331;4136.627703506684;4149.107941792514;4158.64651504621;3674.9710081177313;3903.3169195390833;3933.8426462941447;3990.3016972029923;4144.544100148981;3349.665033496643;4037.3943908413826;4097.651699605537;4101.5488296287385;4106.3635136553885;3388.0513456232293;3693.6675699186935;4014.658754005913;4079;4113.239050775616;3452.8273586320583;3491.1496906639522;3539.2213712983103;3605.369060414443;3633.4368172933237;4664.743439110847;5229.843104706865;5236.293100431431;5251.422343542207;5270.893112446391;4201.802486291393;4294.7852607369505;4578.427696537933;4584.129015487047;4586.931196032062;5279.656822306576;5284.445133261029;5493.752781124859;5647.152927060933;5744.253247077895;3552.786832790041;3653.433717773723;3907.785071821049;3930.5479869815053;3933.2920074386598;4888.486708895551;5867;5917.254409949075;5928.7332070056955;5944.910826337608;3132.733717633981;3333.8160608436256;3821.9617803821607;3944.329463991071;3984.083660758042;3914.1388894443176;3961.485006949078;3973.0134932533733;3979.6219359161414;3987.501562304712;4321.848735294249;4474.574915383034;4489.159444627687;4512.713876545169;4595.954040459619;4804.375431193923;4868.95131048685;4891.755412229374;5305.973470132702;5360.437154098841;1362.448208475569;1385.2035079829138;1385.7874359935056;1402.6283034995733;1403.3123769353147;669.8727241824038;838.6980686952692;865.857068669363;873.951932643698;892.6875593542209;4325.307950727932;4361.825526978885;5182.378114626194;5237.711925844039;5273.446288139767;5262.3158989331905;5417.4311697272005;5704.771809127588;5777.7977770777825;5820.708964551755;4532.546745325441;5069.898602027939;5121.8719532012;5213.348331458567;5342.358916929993;2735.5486344752894;3030.3333266681293;3073.9077827665205;3076.7384772294604;3077.261457250244;6033.5474839387225;6094.6647934363145;6094.847628809227;6117.877642447126;6119.357467465941;4371.907023244189;5429.212764149176;5633.492985631231;5880.382559831241;5927.318358388754;5470.781168753365;5719.199312096227;5940;5962.34414214436;6065.666388348595;4788.976055119702;5766.866259484018;5777.566682498746;5902.970485147547;5907.645541267538;5085.593152547861;5802.100674395476;5827.358990511125;5855.887381397521;5860.2967643883;4963.975180124076;5598.1322894951345;6067.513459202523;6109.175261339662;6117.602355846856;3538.6107528172397;3541.8311957054625;3599.5500562429697;3645.4714066459683;3823.9617603823604;4819.975900120477;5687.857803554779;5705.429457054262;5741.770329186953;5769.68266745333;4510.526394728638;5148.716820574755;5447;5626.127950167731;5696.430356964271;5593.349961761209;5611.915821262603;5639.238702775073;5639.492445679744;5641;5037.715382577419;5221.937334752827;5293.364796224478;5540.418256083214;5566.749496272759;4531.501534831232;5586.0618264124505;5586.413426590149;5617;5656.547476201977;4645.651576131736;4672.509386514503;5487.9420217418465;5492.258545096361;5572.526335261548 23:47:50 INFO - PROCESS | 1659 | |5;yui.html;712.3837880233598;720.7296766657553;770.9730159444417;817.5952903312854;818.6438899078901;14992.475263365784;15693.11682598087;15727.270000299972;15735.213239338016;15744.527664170093;14237.216953067576;14266.358013889374;14269.215193164371;14280.714385712305;14689.706205875902;679.3037136934656;700.7617410080571;835.6907944060706;837.7193640130564;868.3140319147897;837.476577139288;856.7301300090483;857.6440777077505;857.7212405968054;858.999265855281;916.8258030974093;917.5274733512247;917.8623206519043;922.0594993107027;922.492629054021;719.3310221494008;885.5218182181615;893.4773157702751;908.1690253418114;912.1380295620675;664.4949838123013;666.1040899989506;666.1872515531045;667.1993607670814;667.9799606011827;14452.710945781131;14502.274886255751;14517.20155391453;14518.70962580753;14533.200673962929;13526.729465410735;13682.931585342109;13839.792403113961;13920.58238252854;14133.505327313494;12411.379431028412;12848.22910625356;12939.67650808728;12948.61154165367;12982.545610903668;4403.3174857897075;4442.955570444286;4563.498015218323;4610.170169369546;4639.396878405786;113249.43375283154;116678;117261;117365;117503.4124829379;9195;10712.78574428507;10834.512446939907;11140.888591114148;11149.442527873574;21308.147674093172;21334.78665213359;21335.359939201848;21340.359789206348;21368.14527418917;7553;7779.222077792176;7861.567613781297;7957.721479748181;7970.442069055226;3831.386978083549;4222.4299719538085;4403.295472724349;4444.777761111996;4480.663950203682;2791.5952186933187;3480.2343484433386;3489.476578513243;3497.510348551205;3498.650134986481;638.4732595608641;646.7768619826153;650.1320736816309;659.0312241005714;661.9536632435779;543.3344153412118;596.3023262782555;651.0527182948798;660.6069388713707;668.9966550167218;8959.955200223958;9181.954090229507;9420.703130811673;9448.622055117856;9789.510524473748;535.2814936336184;585.4759989809097;595.9910601340983;623.7162091248471;677.8339306869849;4516.07420478801;4665.883352916204;4773.403324584427;4783.95216047835;4800.495947925418;105472.94527054785;124818.3759081217;140192.29903850416;152689;164490;82523.5873820627;101874.02834138818;101895.4905225484;107682;119682;72698;77667.61166194132;78891.21108788953;88380;106124.46937765261;7513.511621744569;9183.540822958692;9563.569639366106;9674.903250967682;9701.70894873155;58463;61496.38503614906;62063;63911;65430.69138617106;61065;72819.63590182015;91195.54402227946;92100.53949730209;93201.62173241611;12822.807657884952;13143.737125257632;13204.933975330061;13229.603111906657;13760.449582016607;14334.928325358307;14795.334209960793;15128.319225634652;15441.459548915735;15762.527124186292;13517.256550889795;13712.382942767399;13836.792448113487;14320.283985800877;14718;14171.362288697239;14262.500812471513;14305.85694143087;14709.41162353494;14746.410143594565;13165.821615006273;13401.798973015608;13450.529231476852;13483.258420786951;13766.587002389944;10760.515776789907;10925.235233533576;10932.672019839418;11072.501737421675;11080.889191107986;8795.120487951153;10513.449862371324;11379.203455758017;11534.538618455168;11716.70708232267 23:47:50 INFO - PROCESS | 1659 | __end_tp_report 23:47:50 INFO - PROCESS | 1659 | __start_cc_report 23:47:50 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1533 23:47:50 INFO - PROCESS | 1659 | __end_cc_report 23:47:50 INFO - PROCESS | 1659 | __startTimestamp1472798870413__endTimestamp 23:47:50 INFO - PROCESS | 1659 | 23:47:50 INFO - PROCESS | 1659 | ------- Summary: start ------- 23:47:50 INFO - PROCESS | 1659 | Number of tests: 6 23:47:50 INFO - PROCESS | 1659 | 23:47:50 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5643.88 Median:5796.97 stddev:624.78 (10.8%) stddev-sans-first:598.03 23:47:50 INFO - PROCESS | 1659 | Values: 3259.0 3293.4 3324.8 3358.7 3377.5 5539.1 5552.0 5601.9 5627.7 5641.5 5591.2 5647.5 5652.2 5662.0 5663.3 5079.3 5149.8 5157.1 5166.7 5189.2 5193.7 5202.8 5204.0 5205.3 5214.3 5148.9 5151.2 5187.8 5189.7 5197.1 5201.5 5233.8 5242.6 5245.2 5311.8 5046.6 5059.4 5079.5 5155.8 5173.9 4696.6 5586.6 5592.9 5615.3 5620.9 6084.6 6109.9 6131.0 6131.5 6133.3 6004.4 6125.8 6137.2 6146.1 6220.9 5711.5 5731.9 5733.9 5735.1 5738.2 6301.4 6318.5 6330.8 6330.8 6336.9 5793.2 5932.5 5960.6 5995.1 5995.8 6106.2 6157.2 6179.7 6186.7 6189.3 6033.7 6092.9 6115.2 6132.7 6149.0 5942.9 5988.0 5997.4 6135.3 6152.0 4884.4 4904.0 4907.7 4912.6 4915.4 4447.9 4458.8 4480.4 4481.4 4500.0 5585.8 5650.6 5655.2 5680.7 5693.5 6132.5 6183.4 6196.8 6207.6 6209.8 5615.1 5627.7 5644.8 5648.0 5652.0 5269.5 5321.5 5343.5 5366.6 5367.2 5282.9 6300.8 6318.5 6344.0 6359.4 6151.2 6187.0 6196.3 6197.0 6201.0 6166.9 6228.6 6286.5 6287.8 6355.7 6025.5 6042.3 6057.2 6058.0 6115.1 6123.5 6143.8 6151.5 6171.8 6179.1 4591.3 5804.3 5805.0 5890.2 5952.7 4840.2 5873.0 5883.6 5918.9 5927.0 5831.3 5839.0 5868.3 5891.4 5896.9 5942.2 5944.4 5946.3 5960.3 5965.0 5684.9 5728.8 5735.1 5797.0 5826.9 23:47:50 INFO - PROCESS | 1659 | 23:47:50 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:66609.58 Median:39482.41 stddev:145728.40 (369.1%) stddev-sans-first:146052.61 23:47:50 INFO - PROCESS | 1659 | Values: 475.4 490.1 530.1 537.2 537.6 31037.1 38206.6 39458.2 39506.6 41591.2 41124.8 41554.2 41566.8 41692.0 41758.8 9615.6 9768.1 9775.7 9782.2 9790.4 9666.6 9745.7 9756.9 9760.6 9765.6 9719.2 9789.9 9800.3 9827.5 9834.6 9671.4 9747.3 9751.5 9761.6 9769.3 9680.0 9730.3 9731.1 9732.1 9740.2 32812.8 39217.0 39439.8 39565.8 39686.6 2907.2 3408.8 3433.5 3652.4 4067.2 29942.6 29954.6 29956.1 30598.8 31440.5 3167.4 3203.6 3212.5 3212.5 3295.6 478827.0 484834.0 485509.0 489253.0 500652.0 55343.7 55787.7 55935.0 56081.0 56099.0 33061.3 58012.7 58037.4 58077.7 58114.4 15305.8 16004.2 16016.8 16047.9 16062.3 12263.3 12374.4 12390.0 12391.0 12393.9 18430.1 18482.5 18483.4 18490.1 18531.0 7774.6 7924.6 7982.4 7995.7 8113.6 824.4 890.6 908.4 983.5 1103.9 29631.4 29688.5 29786.0 29813.9 30130.8 996.9 1026.6 1036.2 1041.3 1064.4 14272.0 14889.3 15232.7 15252.5 15278.4 631455.1 635626.0 636519.0 637406.8 637534.0 52354.0 52735.0 52834.0 52910.0 52911.7 1203.8 1319.3 1345.2 1345.7 1362.9 25624.9 28982.0 29199.0 29218.1 30136.5 46810.1 47434.1 47468.0 47468.5 47543.8 496622.0 499622.0 499978.0 500808.0 503621.0 21785.0 24083.4 24638.6 24743.1 24848.1 42378.0 42573.8 43239.6 43864.3 44208.1 30751.5 32458.7 36113.6 36317.6 37750.4 35997.6 36157.6 36937.0 37380.8 37451.8 35157.8 35564.5 35739.9 35745.5 36067.8 28548.0 31297.4 31806.2 32075.7 32260.0 23444.6 24143.0 24227.5 24823.8 24856.0 23:47:50 INFO - PROCESS | 1659 | 23:47:50 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35594.45 Median:14096.98 stddev:102234.20 (725.2%) stddev-sans-first:102486.69 23:47:50 INFO - PROCESS | 1659 | Values: 323.1 346.6 366.1 374.5 403.8 12546.7 17790.1 20575.0 20699.9 20843.6 19510.8 20176.3 20211.2 20389.9 20918.0 5077.3 5405.7 5643.3 5646.6 5650.6 6128.8 6259.7 6264.3 6266.4 6268.8 5509.9 5609.7 5777.8 6236.5 6239.9 5480.6 5527.5 5528.6 5529.3 5531.8 5135.7 5362.1 5392.7 5412.4 5428.1 24811.0 25638.2 25786.7 25891.4 26188.8 2123.2 2140.3 2555.7 2997.8 3091.1 9456.8 10239.8 10384.7 10408.5 10422.2 1833.3 1842.6 1870.9 1900.4 1910.4 123059.0 124522.4 125291.0 127861.4 128765.4 10099.5 10662.7 10801.5 10846.3 11444.4 14425.2 14425.9 14429.1 14432.6 14442.6 8233.2 8500.0 8528.5 8637.4 8647.0 7088.0 8263.1 8378.5 8398.7 8570.3 3641.9 4470.2 4471.7 4484.9 4590.0 1735.9 1797.5 1842.6 1851.5 1940.5 739.7 840.6 907.2 921.0 971.9 18748.6 20003.8 22182.6 22200.0 23051.0 889.9 914.2 924.9 939.7 943.4 10599.2 10983.6 11478.4 11487.4 11525.6 601065.0 603193.0 603835.0 626577.0 626795.0 102436.5 111841.0 136040.0 137460.0 140471.0 974.7 994.7 1010.2 1012.1 1018.9 15090.6 19432.5 19553.7 20520.5 20588.2 55314.9 55673.6 55961.2 55992.4 56354.7 73974.2 74184.9 74757.0 77413.6 79366.6 8116.9 8175.3 8193.3 8269.9 8311.9 12578.7 13019.5 13059.5 13466.5 13498.4 11352.0 11625.1 11633.1 11804.4 11808.5 11153.1 11305.9 11596.6 11698.7 11758.7 11031.5 11226.0 11662.3 11811.5 11988.6 7688.2 7783.6 8190.6 8234.8 8596.3 10937.8 11364.0 11636.0 11638.9 11765.8 23:47:50 INFO - PROCESS | 1659 | 23:47:50 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5720.21 Median:4974.58 stddev:3223.35 (64.8%) stddev-sans-first:3216.84 23:47:50 INFO - PROCESS | 1659 | Values: 1506.0 1533.3 1738.8 1895.0 1918.9 8017.6 8277.9 8506.4 9047.2 9047.6 7511.8 8487.8 8723.8 8753.2 8893.4 2372.1 2392.9 2403.9 2404.8 2409.2 1969.6 2111.0 2194.7 2385.5 2404.7 1973.8 2166.9 2182.7 2188.5 2384.3 2391.2 2392.1 2399.0 2400.5 2403.5 1975.4 2376.9 2385.3 2387.0 2391.9 8900.0 8918.1 8975.3 8976.6 8993.6 5254.9 5405.0 5405.6 5415.6 5416.4 3479.9 3615.9 3622.3 3630.1 3637.1 1029.8 1062.8 1081.9 1085.2 1088.8 11712.8 11731.4 11740.8 11752.6 11755.6 4484.4 4788.2 4843.9 4853.7 4865.8 9986.1 9996.2 9996.8 10010.1 10011.0 5396.0 5404.2 5404.9 5419.1 5419.7 4797.6 4874.2 4969.8 4989.4 5001.3 3847.2 3936.0 3964.2 4032.9 4074.7 1107.8 1264.3 1282.4 1283.4 1290.2 757.7 849.4 885.7 892.4 925.6 5380.5 5601.4 5835.9 5843.2 6075.3 883.8 927.1 928.9 936.3 941.4 3214.6 3783.8 3850.8 3852.2 4039.9 9230.3 10554.0 10996.2 11027.6 11032.6 9185.5 10329.6 10916.8 10946.9 10952.8 7991.4 8321.7 8329.3 8331.8 8347.8 4625.0 5585.2 5734.6 6145.1 6253.3 10042.3 10309.3 10336.5 10337.5 10346.8 9949.3 10346.9 10351.9 10430.2 10529.7 7424.8 7575.8 7671.9 7676.5 7682.7 8126.3 8233.8 8312.7 8314.9 8327.1 7454.9 7457.6 7501.8 7556.1 7588.3 7281.9 7369.0 7396.7 7427.0 7443.8 6876.8 6997.9 7002.1 7022.0 7025.7 4837.2 4946.6 4981.0 4996.0 5163.2 4923.2 4979.4 5036.2 5049.4 5163.6 23:47:50 INFO - PROCESS | 1659 | 23:47:50 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4523.21 Median:5094.30 stddev:1405.08 (27.6%) stddev-sans-first:1372.00 23:47:50 INFO - PROCESS | 1659 | Values: 254.6 279.9 300.5 311.3 311.7 5155.9 5250.6 5424.8 5454.8 5464.9 4520.6 4833.8 5103.0 5360.2 5397.8 3384.0 4097.1 4136.6 4149.1 4158.6 3675.0 3903.3 3933.8 3990.3 4144.5 3349.7 4037.4 4097.7 4101.5 4106.4 3388.1 3693.7 4014.7 4079.0 4113.2 3452.8 3491.1 3539.2 3605.4 3633.4 4664.7 5229.8 5236.3 5251.4 5270.9 4201.8 4294.8 4578.4 4584.1 4586.9 5279.7 5284.4 5493.8 5647.2 5744.3 3552.8 3653.4 3907.8 3930.5 3933.3 4888.5 5867.0 5917.3 5928.7 5944.9 3132.7 3333.8 3822.0 3944.3 3984.1 3914.1 3961.5 3973.0 3979.6 3987.5 4321.8 4474.6 4489.2 4512.7 4596.0 4804.4 4869.0 4891.8 5306.0 5360.4 1362.4 1385.2 1385.8 1402.6 1403.3 669.9 838.7 865.9 874.0 892.7 4325.3 4361.8 5182.4 5237.7 5273.4 5262.3 5417.4 5704.8 5777.8 5820.7 4532.5 5069.9 5121.9 5213.3 5342.4 2735.5 3030.3 3073.9 3076.7 3077.3 6033.5 6094.7 6094.8 6117.9 6119.4 4371.9 5429.2 5633.5 5880.4 5927.3 5470.8 5719.2 5940.0 5962.3 6065.7 4789.0 5766.9 5777.6 5903.0 5907.6 5085.6 5802.1 5827.4 5855.9 5860.3 4964.0 5598.1 6067.5 6109.2 6117.6 3538.6 3541.8 3599.6 3645.5 3824.0 4820.0 5687.9 5705.4 5741.8 5769.7 4510.5 5148.7 5447.0 5626.1 5696.4 5593.3 5611.9 5639.2 5639.5 5641.0 5037.7 5221.9 5293.4 5540.4 5566.7 4531.5 5586.1 5586.4 5617.0 5656.5 4645.7 4672.5 5487.9 5492.3 5572.5 23:47:50 INFO - PROCESS | 1659 | 23:47:50 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:23074.85 Median:4403.31 stddev:35713.13 (811.1%) stddev-sans-first:35773.85 23:47:50 INFO - PROCESS | 1659 | Values: 712.4 720.7 771.0 817.6 818.6 14992.5 15693.1 15727.3 15735.2 15744.5 14237.2 14266.4 14269.2 14280.7 14689.7 679.3 700.8 835.7 837.7 868.3 837.5 856.7 857.6 857.7 859.0 916.8 917.5 917.9 922.1 922.5 719.3 885.5 893.5 908.2 912.1 664.5 666.1 666.2 667.2 668.0 14452.7 14502.3 14517.2 14518.7 14533.2 13526.7 13682.9 13839.8 13920.6 14133.5 12411.4 12848.2 12939.7 12948.6 12982.5 4403.3 4443.0 4563.5 4610.2 4639.4 113249.4 116678.0 117261.0 117365.0 117503.4 9195.0 10712.8 10834.5 11140.9 11149.4 21308.1 21334.8 21335.4 21340.4 21368.1 7553.0 7779.2 7861.6 7957.7 7970.4 3831.4 4222.4 4403.3 4444.8 4480.7 2791.6 3480.2 3489.5 3497.5 3498.7 638.5 646.8 650.1 659.0 662.0 543.3 596.3 651.1 660.6 669.0 8960.0 9182.0 9420.7 9448.6 9789.5 535.3 585.5 596.0 623.7 677.8 4516.1 4665.9 4773.4 4784.0 4800.5 105472.9 124818.4 140192.3 152689.0 164490.0 82523.6 101874.0 101895.5 107682.0 119682.0 72698.0 77667.6 78891.2 88380.0 106124.5 7513.5 9183.5 9563.6 9674.9 9701.7 58463.0 61496.4 62063.0 63911.0 65430.7 61065.0 72819.6 91195.5 92100.5 93201.6 12822.8 13143.7 13204.9 13229.6 13760.4 14334.9 14795.3 15128.3 15441.5 15762.5 13517.3 13712.4 13836.8 14320.3 14718.0 14171.4 14262.5 14305.9 14709.4 14746.4 13165.8 13401.8 13450.5 13483.3 13766.6 10760.5 10925.2 10932.7 11072.5 11080.9 8795.1 10513.4 11379.2 11534.5 11716.7 23:47:50 INFO - PROCESS | 1659 | -------- Summary: end -------- 23:47:50 INFO - PROCESS | 1659 | 23:47:51 INFO - TEST-INFO | 1659: exit 0 23:47:51 INFO - TEST-OK | dromaeo_css | took 1118914ms 23:47:51 INFO - TEST-START | kraken 23:47:51 INFO - operating with platform_type : mac_ 23:47:51 INFO - Initialising browser for kraken test... 23:47:51 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp8LSYaJ/profile http://localhost:49273/getInfo.html) 23:47:53 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 23:47:53 INFO - PROCESS | 1665 | colorDepth:24 23:47:53 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 23:47:53 INFO - PROCESS | 1665 | __metrics 23:47:53 INFO - PROCESS | 1665 | 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" 23:47:53 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:47:53 INFO - TEST-INFO | 1665: exit 0 23:47:53 INFO - Browser initialized. 23:47:53 INFO - Running cycle 1/1 for kraken test... 23:47:54 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp8LSYaJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:48:05 INFO - PROCESS | 1666 | RSS: Main: 233955328 23:48:05 INFO - PROCESS | 1666 | 23:48:08 INFO - PROCESS | 1666 | 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) 23:48:09 INFO - PROCESS | 1666 | RSS: Main: 292581376 23:48:09 INFO - PROCESS | 1666 | 23:48:11 INFO - PROCESS | 1666 | 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) 23:48:12 INFO - PROCESS | 1666 | RSS: Main: 288665600 23:48:12 INFO - PROCESS | 1666 | 23:48:16 INFO - PROCESS | 1666 | 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) 23:48:16 INFO - PROCESS | 1666 | RSS: Main: 380219392 23:48:16 INFO - PROCESS | 1666 | 23:48:18 INFO - PROCESS | 1666 | 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) 23:48:19 INFO - PROCESS | 1666 | RSS: Main: 283172864 23:48:19 INFO - PROCESS | 1666 | 23:48:21 INFO - PROCESS | 1666 | 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) 23:48:22 INFO - PROCESS | 1666 | RSS: Main: 286429184 23:48:22 INFO - PROCESS | 1666 | 23:48:25 INFO - PROCESS | 1666 | 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) 23:48:25 INFO - PROCESS | 1666 | RSS: Main: 324931584 23:48:25 INFO - PROCESS | 1666 | 23:48:28 INFO - PROCESS | 1666 | 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) 23:48:29 INFO - PROCESS | 1666 | RSS: Main: 326254592 23:48:29 INFO - PROCESS | 1666 | 23:48:32 INFO - PROCESS | 1666 | 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) 23:48:32 INFO - PROCESS | 1666 | RSS: Main: 324612096 23:48:32 INFO - PROCESS | 1666 | 23:48:34 INFO - PROCESS | 1666 | 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) 23:48:35 INFO - PROCESS | 1666 | RSS: Main: 297906176 23:48:35 INFO - PROCESS | 1666 | 23:48:36 INFO - PROCESS | 1666 | 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) 23:48:37 INFO - PROCESS | 1666 | RSS: Main: 302686208 23:48:37 INFO - PROCESS | 1666 | 23:48:39 INFO - PROCESS | 1666 | 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) 23:48:40 INFO - PROCESS | 1666 | RSS: Main: 308965376 23:48:40 INFO - PROCESS | 1666 | 23:48:43 INFO - PROCESS | 1666 | 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) 23:48:44 INFO - PROCESS | 1666 | RSS: Main: 322236416 23:48:44 INFO - PROCESS | 1666 | 23:48:47 INFO - PROCESS | 1666 | 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) 23:48:47 INFO - PROCESS | 1666 | RSS: Main: 318369792 23:48:47 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | 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) 23:48:49 INFO - PROCESS | 1666 | RSS: Main: 362033152 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | __start_tp_report 23:48:49 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 23:48:49 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 23:48:49 INFO - PROCESS | 1666 | |i|pagename|runs| 23:48:49 INFO - PROCESS | 1666 | |0;ai-astar;113;114;113;112;114;113;114;111;113;122 23:48:49 INFO - PROCESS | 1666 | |1;audio-beat-detection;148;116;131;164;109;129;130;107;163;107 23:48:49 INFO - PROCESS | 1666 | |2;audio-dft;187;183;184;182;184;184;185;180;185;162 23:48:49 INFO - PROCESS | 1666 | |3;audio-fft;73;79;75;155;58;58;58;67;140;58 23:48:49 INFO - PROCESS | 1666 | |4;audio-oscillator;82;82;133;89;112;116;117;74;100;159 23:48:49 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;88;85;87;85;87;90;88;86;88;82 23:48:49 INFO - PROCESS | 1666 | |6;imaging-darkroom;94;112;164;96;95;95;110;162;95;95 23:48:49 INFO - PROCESS | 1666 | |7;imaging-desaturate;84;86;85;98;134;136;84;85;84;86 23:48:49 INFO - PROCESS | 1666 | |8;json-parse-financial;54;54;56;55;55;55;56;55;58;55 23:48:49 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;69;65;81;76;72;69;67;69;68;73 23:48:49 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;87;85;88;86;87;87;89;86;88;89 23:48:49 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;126;165;154;143;141;155;150;154;190;141 23:48:49 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;173;171;171;171;170;170;168;174;169;170 23:48:49 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;69;68;69;67;69;68;69;70;90;88 23:48:49 INFO - PROCESS | 1666 | __end_tp_report 23:48:49 INFO - PROCESS | 1666 | __start_cc_report 23:48:49 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2162 23:48:49 INFO - PROCESS | 1666 | __end_cc_report 23:48:49 INFO - PROCESS | 1666 | __startTimestamp1472798929849__endTimestamp 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | ------- Summary: start ------- 23:48:49 INFO - PROCESS | 1666 | Number of tests: 14 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:113.90 Median:113.50 stddev:3.00 (2.6%) stddev-sans-first:3.16 23:48:49 INFO - PROCESS | 1666 | Values: 113.0 114.0 113.0 112.0 114.0 113.0 114.0 111.0 113.0 122.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:130.50 stddev:21.77 (16.7%) stddev-sans-first:22.14 23:48:49 INFO - PROCESS | 1666 | Values: 148.0 116.0 131.0 164.0 109.0 129.0 130.0 107.0 163.0 107.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:181.60 Median:184.00 stddev:7.14 (3.9%) stddev-sans-first:7.30 23:48:49 INFO - PROCESS | 1666 | Values: 187.0 183.0 184.0 182.0 184.0 184.0 185.0 180.0 185.0 162.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:82.10 Median:62.50 stddev:35.53 (56.8%) stddev-sans-first:37.53 23:48:49 INFO - PROCESS | 1666 | Values: 73.0 79.0 75.0 155.0 58.0 58.0 58.0 67.0 140.0 58.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:106.40 Median:116.50 stddev:26.45 (22.7%) stddev-sans-first:26.54 23:48:49 INFO - PROCESS | 1666 | Values: 82.0 82.0 133.0 89.0 112.0 116.0 117.0 74.0 100.0 159.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:2.22 (2.5%) stddev-sans-first:2.30 23:48:49 INFO - PROCESS | 1666 | Values: 88.0 85.0 87.0 85.0 87.0 90.0 88.0 86.0 88.0 82.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:111.80 Median:95.00 stddev:27.78 (29.2%) stddev-sans-first:28.70 23:48:49 INFO - PROCESS | 1666 | Values: 94.0 112.0 164.0 96.0 95.0 95.0 110.0 162.0 95.0 95.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:85.00 stddev:20.87 (24.6%) stddev-sans-first:21.67 23:48:49 INFO - PROCESS | 1666 | Values: 84.0 86.0 85.0 98.0 134.0 136.0 84.0 85.0 84.0 86.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:1.16 (2.1%) stddev-sans-first:1.13 23:48:49 INFO - PROCESS | 1666 | Values: 54.0 54.0 56.0 55.0 55.0 55.0 56.0 55.0 58.0 55.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:70.50 stddev:4.75 (6.7%) stddev-sans-first:4.99 23:48:49 INFO - PROCESS | 1666 | Values: 69.0 65.0 81.0 76.0 72.0 69.0 67.0 69.0 68.0 73.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:87.50 stddev:1.32 (1.5%) stddev-sans-first:1.39 23:48:49 INFO - PROCESS | 1666 | Values: 87.0 85.0 88.0 86.0 87.0 87.0 89.0 86.0 88.0 89.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:154.00 stddev:17.10 (11.1%) stddev-sans-first:15.36 23:48:49 INFO - PROCESS | 1666 | Values: 126.0 165.0 154.0 143.0 141.0 155.0 150.0 154.0 190.0 141.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:171.00 stddev:1.77 (1.0%) stddev-sans-first:1.67 23:48:49 INFO - PROCESS | 1666 | Values: 173.0 171.0 171.0 171.0 170.0 170.0 168.0 174.0 169.0 170.0 23:48:49 INFO - PROCESS | 1666 | 23:48:49 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:69.00 stddev:8.64 (12.5%) stddev-sans-first:9.06 23:48:49 INFO - PROCESS | 1666 | Values: 69.0 68.0 69.0 67.0 69.0 68.0 69.0 70.0 90.0 88.0 23:48:49 INFO - PROCESS | 1666 | -------- Summary: end -------- 23:48:49 INFO - PROCESS | 1666 | 23:48:50 INFO - TEST-INFO | 1666: exit 0 23:48:50 INFO - TEST-OK | kraken | took 59672ms 23:48:50 INFO - SUITE-END | took 1178s 23:48:50 INFO - Completed test suite (00:19:38) 23:48:50 INFO - javascript benchmark 23:48:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3259.0, 3293.440115180419, 3324.7506437017228, 3358.697717205454, 3377.476491143872, 5539.141433077874, 5552.0, 5601.915971260434, 5627.662340259582, 5641.548676105912, 5591.217229587851, 5647.548196144309, 5652.180433837101, 5662.0, 5663.348714897777, 5079.2635067915135, 5149.794008239666, 5157.123289040872, 5166.741662916839, 5189.221616757498, 5193.740312984354, 5202.791888324463, 5204.0, 5205.297284866532, 5214.296070030536, 5148.8712782180555, 5151.150060240056, 5187.818426355078, 5189.662671926331, 5197.090509160882, 5201.479852014806, 5233.790648374061, 5242.580593552507, 5245.160774276135, 5311.760970756325, 5046.596272298244, 5059.418166910816, 5079.5428411443145, 5155.7679904404395, 5173.948260517384, 4696.553827386393, 5586.581006424534, 5592.944070559283, 5615.2700148980375, 5620.887582248373, 6084.574079814415, 6109.877802443971, 6131.0, 6131.47882429994, 6133.294671112834, 6004.369541198199, 6125.816225513242, 6137.171481849938, 6146.078088286748, 6220.906686399662, 5711.514521265656, 5731.856703582361, 5733.885322293614, 5735.139729040678, 5738.196652468658, 6301.369863013753, 6318.526110541728, 6330.778422755181, 6330.778422755181, 6336.936630633727, 5793.2179155814265, 5932.525397968152, 5960.552958528127, 5995.100734889801, 5995.790147344909, 6106.2061931948565, 6157.168782214344, 6179.721912513947, 6186.690665466798, 6189.257289125334, 6033.693098092314, 6092.939070609325, 6115.235595550556, 6132.6933653317155, 6148.985417406078, 5942.910856337158, 5987.970060149672, 5997.430244126801, 6135.251223653945, 6151.98492248774, 4884.413870335582, 4904.0191961607825, 4907.730074845917, 4912.631552633567, 4915.361003069578, 4447.888802779957, 4458.7547684877645, 4480.372747815309, 4481.439820022497, 4500.0, 5585.776568937278, 5650.632708873993, 5655.236543066716, 5680.715964201773, 5693.544516438675, 6132.478739307209, 6183.443490085914, 6196.81409557714, 6207.5654704171175, 6209.782657606962, 5615.129654903497, 5627.71861406928, 5644.774209031592, 5647.971760141173, 5652.0391533439415, 5269.525742683177, 5321.467853214725, 5343.492368225013, 5366.6243362965, 5367.2485851980755, 5282.920756188737, 6300.810975670737, 6318.494520438262, 6343.999999999907, 6359.427651511386, 6151.169592105009, 6187.041008643668, 6196.349383314687, 6197.03945888388, 6200.968995154995, 6166.859131060768, 6228.56400051992, 6286.465650419766, 6287.779927702509, 6355.650439225887, 6025.517958563392, 6042.274927008787, 6057.151998720271, 6057.969710151421, 6115.082737589397, 6123.510119190365, 6143.784967526115, 6151.507879369729, 6171.84570385744, 6179.1040299155475, 4591.311303304397, 5804.303483581828, 5805.0, 5890.234269545103, 5952.732127054206, 4840.2255639097575, 5873.03094989322, 5883.588148829541, 5918.911216331672, 5926.970365148147, 5831.300243970751, 5839.0, 5868.295804503487, 5891.410858914074, 5896.852578685567, 5942.227510423617, 5944.4352786485215, 5946.316173640087, 5960.314563825215, 5964.97017514927, 5684.914726279192, 5728.828135155952, 5735.082386818089, 5796.971015144897, 5826.883462330645], "value": 5605.5579430767575, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.3962467666062, 490.0810979413597, 530.1358785180157, 537.2076187623256, 537.5753155007543, 31037.06888793337, 38206.61793382066, 39458.21083578327, 39506.60493395065, 41591.16817663645, 41124.794376028156, 41554.16891662165, 41566.79216603913, 41691.957701057414, 41758.79120604393, 9615.56729947154, 9768.07203315684, 9775.657851975182, 9782.168515676176, 9790.363626364298, 9666.613335466573, 9745.658901938434, 9756.90243097567, 9760.560774765117, 9765.609375625001, 9719.17387022104, 9789.90210097897, 9800.264980126483, 9827.508624568778, 9834.557444914995, 9671.419714817099, 9747.317687761859, 9751.512424378752, 9761.560729767107, 9769.316147869651, 9680.0, 9730.318877678565, 9731.075547822946, 9732.07545283197, 9740.220782337467, 32812.8359358204, 39217.0, 39439.81680549588, 39565.80217098925, 39686.60313396858, 2907.215051935965, 3408.812515311657, 3433.4506478963494, 3652.433872749728, 4067.2068946555432, 29942.55086173709, 29954.55068173979, 29956.101316960525, 30598.847005765052, 31440.52839207414, 3167.4298626247264, 3203.6315823680343, 3212.4538828399227, 3212.534182543518, 3295.571575695168, 478827.0, 484834.0, 485509.0, 489253.0, 500652.0, 55343.72328138333, 55787.72106139443, 55935.0, 56081.0, 56099.0, 33061.3387732244, 58012.70993645005, 58037.41962580405, 58077.70961145252, 58114.418855811746, 15305.770413443808, 16004.199790010452, 16016.839831601535, 16047.919760401357, 16062.277197526138, 12263.325517096646, 12374.381280935917, 12390.0, 12391.0, 12393.876061239453, 18430.07849607541, 18482.537936551693, 18483.353082642043, 18490.075496225403, 18531.0, 7774.611269436618, 7924.643391047417, 7982.441229113906, 7995.680172793023, 8113.634886430124, 824.3858325547506, 890.5903284489078, 908.4276905549461, 983.5279066048337, 1103.89254249288, 29631.40737185244, 29688.452673061107, 29786.0, 29813.85093074564, 30130.84934575313, 996.8853581838182, 1026.6047571684956, 1036.2176556699644, 1041.3283432186197, 1064.377339256528, 14272.0, 14889.255537223096, 15232.695346093238, 15252.466163684217, 15278.38886444552, 631455.1126932398, 635626.0, 636519.0, 637406.8129659322, 637534.0, 52354.0, 52735.0, 52834.0, 52910.0, 52911.73544132255, 1203.8134089216185, 1319.2678063674562, 1345.2130503655471, 1345.656857501331, 1362.9318534073257, 25624.871875640503, 28982.0, 29198.978035768647, 29218.123456296344, 30136.547951780303, 46810.06379872451, 47434.051318972735, 47468.0, 47468.52531474641, 47543.76228118837, 496622.0, 499622.0, 499978.0, 500808.0, 503621.0, 21785.0, 24083.397915051562, 24638.630420544065, 24743.133990310973, 24848.130315438873, 42378.0, 42573.78713106415, 43239.56760432481, 43864.34203486887, 44208.11583768371, 30751.53872691956, 32458.675413245564, 36113.63886361103, 36317.636823632485, 37750.43374349442, 35997.64002359943, 36157.63842361648, 36937.0, 37380.81309593435, 37451.81274093612, 35157.76947806814, 35564.466533002545, 35739.92780216598, 35745.46381804223, 36067.81966090153, 28548.00491896725, 31297.435487737115, 31806.204844879063, 32075.679243208204, 32260.0, 23444.648330275402, 24143.034278628656, 24227.515449690556, 24823.751762482865, 24856.0], "value": 19274.623126354534, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.1420578364442, 346.5564077980186, 366.1121779684265, 374.4514286570174, 403.75572778469024, 12546.686332841684, 17790.110494475273, 20575.0, 20699.896500517512, 20843.583128337443, 19510.804891951077, 20176.293829715964, 20211.191552337925, 20389.898050509764, 20918.0, 5077.314562534048, 5405.675659460425, 5643.294588176478, 5646.576506761989, 5650.604457687963, 6128.785492507763, 6259.749610015594, 6264.310925798159, 6266.404691554295, 6268.780592679257, 5509.9255645149215, 5609.747561359728, 5777.768889244446, 6236.501079913596, 6239.937600624004, 5480.6437581557075, 5527.474889885454, 5528.585356098309, 5529.253550770634, 5531.751071201766, 5135.6918584885025, 5362.142057270857, 5392.730363481849, 5412.404635490093, 5428.131498960148, 24811.0, 25638.230853074252, 25786.74213257862, 25891.352716182057, 26188.82150303241, 2123.182574708733, 2140.347194105797, 2555.7316481769517, 2997.8051426657416, 3091.0572275455975, 9456.810863782755, 10239.795204095877, 10384.688459346156, 10408.479576021244, 10422.21833362501, 1833.3432486564855, 1842.5577861313323, 1870.8503319734525, 1900.4203717866046, 1910.4268719384268, 123059.0, 124522.37738811338, 125291.0, 127861.36069319594, 128765.35617321946, 10099.495025248854, 10662.733431664115, 10801.459927003618, 10846.349219046882, 11444.370559619134, 14425.20661363614, 14425.85574144245, 14429.062110962754, 14432.567022989328, 14442.638934026523, 8233.217844304781, 8500.022497412752, 8528.53093079874, 8637.395382323302, 8647.048824629286, 7088.007678924954, 8263.132371101068, 8378.49729016261, 8398.740188971704, 8570.271526920282, 3641.890743277706, 4470.24005918988, 4471.731696098244, 4484.932726009112, 4590.0, 1735.8784885058178, 1797.4787311679465, 1842.63644902771, 1851.5093500222451, 1940.4857712706144, 739.7115125101215, 840.6175190288404, 907.2061945797427, 920.9815803683889, 971.917387022111, 18748.625027499373, 20003.799962000485, 22182.55634887293, 22200.0, 23051.0, 889.8665200220019, 914.2274777812737, 924.861270809384, 939.7180845746385, 943.4480828715278, 10599.152067834555, 10983.61557345489, 11478.368689722145, 11487.42562871853, 11525.596604118815, 601065.0, 603193.0, 603835.0, 626577.0, 626795.0, 102436.48781756192, 111841.0, 136040.0, 137460.0, 140471.0, 974.6880998080546, 994.7314225159312, 1010.1918465227935, 1012.1346248957036, 1018.8828284747201, 15090.622734431727, 19432.514187145436, 19553.70669439988, 20520.486987825425, 20588.176472941515, 55314.89370212654, 55673.60815979471, 55961.16058259211, 55992.44007560035, 56354.71822640861, 73974.15064623428, 74184.9048680749, 74757.0, 77413.61293193723, 79366.60316698611, 8116.863639090414, 8175.345972322081, 8193.303569196685, 8269.917300827155, 8311.916880831115, 12578.685532861386, 13019.544315948897, 13059.477620895057, 13466.461341546228, 13498.392572334073, 11352.0, 11625.069994400597, 11633.069354451454, 11804.409779511161, 11808.468618912342, 11153.051990580567, 11305.886941130813, 11596.596845614074, 11698.70753231176, 11758.706032348917, 11031.450052848686, 11226.0, 11662.300261984308, 11811.468483918072, 11988.64034078979, 7688.154303026775, 7783.649735762017, 8190.631421586162, 8234.835303294021, 8596.312295016509, 10937.781244375226, 11364.0, 11636.010939070275, 11638.88361116412, 11765.823512647488], "value": 9568.518950887916, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1506.0210862939089, 1533.3023474319182, 1738.8087310395854, 1894.9767125752094, 1918.875273107248, 8017.639206235719, 8277.875831862511, 8506.404551681377, 9047.230985366243, 9047.63809447622, 7511.774646760592, 8487.830243395128, 8723.781905452366, 8753.16844899735, 8893.421927574704, 2372.122314743547, 2392.8803559822027, 2403.891824867885, 2404.831661783676, 2409.229046705055, 1969.5863868587614, 2111.0, 2194.659827726705, 2385.4632707640817, 2404.723456802473, 1973.802619738029, 2166.902489387973, 2182.6507758758603, 2188.4528867783056, 2384.308550520347, 2391.210900402863, 2392.07904956593, 2399.0, 2400.4718961828457, 2403.4592216751266, 1975.4073777866727, 2376.857388556692, 2385.3440303916386, 2386.9641955370685, 2391.9043238270447, 8899.955500222522, 8918.108189181095, 8975.326850486239, 8976.640934362618, 8993.59528821198, 5254.92117618236, 5405.00007498614, 5405.567554595623, 5415.566754659618, 5416.404195538487, 3479.878204262864, 3615.94576081359, 3622.3298689742483, 3630.0743310455928, 3637.0907273181706, 1029.7683021320217, 1062.7715041266165, 1081.9134469242442, 1085.1698450685233, 1088.8094583447864, 11712.765744685144, 11731.354775487345, 11740.823887641693, 11752.647420577396, 11755.58855440064, 4484.439445069366, 4788.233882578771, 4843.927341089887, 4853.733044682576, 4865.805367785249, 9986.10125088731, 9996.200303975664, 9996.750081248027, 10010.14913732341, 10011.0, 5396.028714831368, 5404.162354835007, 5404.945950540444, 5419.051665958442, 5419.701916394636, 4797.592204662573, 4874.2444921037295, 4969.801207951714, 4989.351384320015, 5001.349824522788, 3847.1728578355787, 3935.9606403935595, 3964.1675248198203, 4032.858849940238, 4074.6740260779065, 1107.8338249262674, 1264.279360764374, 1282.3652292115462, 1283.4352884730688, 1290.2323117744927, 757.6779868555842, 849.3544905871582, 885.6501681835639, 892.4154678685471, 925.606617187706, 5380.488853558905, 5601.403599973988, 5835.854103647358, 5843.2403787507355, 6075.27096748393, 883.7923088074298, 927.0914503786328, 928.8513837785922, 936.3118108190474, 941.3975055964188, 3214.5981752280964, 3783.829727662262, 3850.8074596270076, 3852.210296889131, 4039.939400908989, 9230.261579073658, 10553.997370249968, 10996.23026388161, 11027.558897644165, 11032.613858514913, 9185.540722963826, 10329.586816527404, 10916.836247456295, 10946.945265273622, 10952.835707464394, 7991.440599158003, 8321.708740194184, 8329.250367466957, 8331.750047498585, 8347.833043339098, 4624.976875115737, 5585.1901474286815, 5734.569907257056, 6145.051092564204, 6253.343398943228, 10042.347247428894, 10309.278350515391, 10336.483175841027, 10337.534810933375, 10346.844797327896, 9949.253805964438, 10346.948265258625, 10351.948240258751, 10430.217733669853, 10529.736756581147, 7424.777256682308, 7575.772726818204, 7671.8849217262905, 7676.501027433199, 7682.654280557276, 8126.349892008743, 8233.752987410388, 8312.709055183039, 8314.875276870973, 8327.125651806715, 7454.850902982019, 7457.589832559262, 7501.849963000818, 7556.055493063367, 7588.317051465395, 7281.927180728125, 7369.0, 7396.667149978156, 7427.0, 7443.776686699408, 6876.793696189123, 6997.895031574429, 7002.124734408199, 7022.0, 7025.68384422692, 4837.2260438329695, 4946.554810067111, 4981.0, 4996.0, 5163.199704045879, 4923.163062279349, 4979.3526841510375, 5036.1942089267, 5049.368828896388, 5163.561097306771], "value": 4582.184884126515, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.55961187146224, 279.864265831072, 300.5461752753342, 311.3197663106113, 311.6571771051843, 5155.926817813927, 5250.55370293525, 5424.8372548823545, 5454.754536045877, 5464.9180262296095, 4520.570545798152, 4833.806647734095, 5103.0, 5360.249565060894, 5397.811076612319, 3383.983080084596, 4097.119119389331, 4136.627703506684, 4149.107941792514, 4158.64651504621, 3674.9710081177313, 3903.3169195390833, 3933.8426462941447, 3990.3016972029923, 4144.544100148981, 3349.665033496643, 4037.3943908413826, 4097.651699605537, 4101.5488296287385, 4106.3635136553885, 3388.0513456232293, 3693.6675699186935, 4014.658754005913, 4079.0, 4113.239050775616, 3452.8273586320583, 3491.1496906639522, 3539.2213712983103, 3605.369060414443, 3633.4368172933237, 4664.743439110847, 5229.843104706865, 5236.293100431431, 5251.422343542207, 5270.893112446391, 4201.802486291393, 4294.7852607369505, 4578.427696537933, 4584.129015487047, 4586.931196032062, 5279.656822306576, 5284.445133261029, 5493.752781124859, 5647.152927060933, 5744.253247077895, 3552.786832790041, 3653.433717773723, 3907.785071821049, 3930.5479869815053, 3933.2920074386598, 4888.486708895551, 5867.0, 5917.254409949075, 5928.7332070056955, 5944.910826337608, 3132.733717633981, 3333.8160608436256, 3821.9617803821607, 3944.329463991071, 3984.083660758042, 3914.1388894443176, 3961.485006949078, 3973.0134932533733, 3979.6219359161414, 3987.501562304712, 4321.848735294249, 4474.574915383034, 4489.159444627687, 4512.713876545169, 4595.954040459619, 4804.375431193923, 4868.95131048685, 4891.755412229374, 5305.973470132702, 5360.437154098841, 1362.448208475569, 1385.2035079829138, 1385.7874359935056, 1402.6283034995733, 1403.3123769353147, 669.8727241824038, 838.6980686952692, 865.857068669363, 873.951932643698, 892.6875593542209, 4325.307950727932, 4361.825526978885, 5182.378114626194, 5237.711925844039, 5273.446288139767, 5262.3158989331905, 5417.4311697272005, 5704.771809127588, 5777.7977770777825, 5820.708964551755, 4532.546745325441, 5069.898602027939, 5121.8719532012, 5213.348331458567, 5342.358916929993, 2735.5486344752894, 3030.3333266681293, 3073.9077827665205, 3076.7384772294604, 3077.261457250244, 6033.5474839387225, 6094.6647934363145, 6094.847628809227, 6117.877642447126, 6119.357467465941, 4371.907023244189, 5429.212764149176, 5633.492985631231, 5880.382559831241, 5927.318358388754, 5470.781168753365, 5719.199312096227, 5940.0, 5962.34414214436, 6065.666388348595, 4788.976055119702, 5766.866259484018, 5777.566682498746, 5902.970485147547, 5907.645541267538, 5085.593152547861, 5802.100674395476, 5827.358990511125, 5855.887381397521, 5860.2967643883, 4963.975180124076, 5598.1322894951345, 6067.513459202523, 6109.175261339662, 6117.602355846856, 3538.6107528172397, 3541.8311957054625, 3599.5500562429697, 3645.4714066459683, 3823.9617603823604, 4819.975900120477, 5687.857803554779, 5705.429457054262, 5741.770329186953, 5769.68266745333, 4510.526394728638, 5148.716820574755, 5447.0, 5626.127950167731, 5696.430356964271, 5593.349961761209, 5611.915821262603, 5639.238702775073, 5639.492445679744, 5641.0, 5037.715382577419, 5221.937334752827, 5293.364796224478, 5540.418256083214, 5566.749496272759, 4531.501534831232, 5586.0618264124505, 5586.413426590149, 5617.0, 5656.547476201977, 4645.651576131736, 4672.509386514503, 5487.9420217418465, 5492.258545096361, 5572.526335261548], "value": 4071.5292080440204, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.3837880233598, 720.7296766657553, 770.9730159444417, 817.5952903312854, 818.6438899078901, 14992.475263365784, 15693.11682598087, 15727.270000299972, 15735.213239338016, 15744.527664170093, 14237.216953067576, 14266.358013889374, 14269.215193164371, 14280.714385712305, 14689.706205875902, 679.3037136934656, 700.7617410080571, 835.6907944060706, 837.7193640130564, 868.3140319147897, 837.476577139288, 856.7301300090483, 857.6440777077505, 857.7212405968054, 858.999265855281, 916.8258030974093, 917.5274733512247, 917.8623206519043, 922.0594993107027, 922.492629054021, 719.3310221494008, 885.5218182181615, 893.4773157702751, 908.1690253418114, 912.1380295620675, 664.4949838123013, 666.1040899989506, 666.1872515531045, 667.1993607670814, 667.9799606011827, 14452.710945781131, 14502.274886255751, 14517.20155391453, 14518.70962580753, 14533.200673962929, 13526.729465410735, 13682.931585342109, 13839.792403113961, 13920.58238252854, 14133.505327313494, 12411.379431028412, 12848.22910625356, 12939.67650808728, 12948.61154165367, 12982.545610903668, 4403.3174857897075, 4442.955570444286, 4563.498015218323, 4610.170169369546, 4639.396878405786, 113249.43375283154, 116678.0, 117261.0, 117365.0, 117503.4124829379, 9195.0, 10712.78574428507, 10834.512446939907, 11140.888591114148, 11149.442527873574, 21308.147674093172, 21334.78665213359, 21335.359939201848, 21340.359789206348, 21368.14527418917, 7553.0, 7779.222077792176, 7861.567613781297, 7957.721479748181, 7970.442069055226, 3831.386978083549, 4222.4299719538085, 4403.295472724349, 4444.777761111996, 4480.663950203682, 2791.5952186933187, 3480.2343484433386, 3489.476578513243, 3497.510348551205, 3498.650134986481, 638.4732595608641, 646.7768619826153, 650.1320736816309, 659.0312241005714, 661.9536632435779, 543.3344153412118, 596.3023262782555, 651.0527182948798, 660.6069388713707, 668.9966550167218, 8959.955200223958, 9181.954090229507, 9420.703130811673, 9448.622055117856, 9789.510524473748, 535.2814936336184, 585.4759989809097, 595.9910601340983, 623.7162091248471, 677.8339306869849, 4516.07420478801, 4665.883352916204, 4773.403324584427, 4783.95216047835, 4800.495947925418, 105472.94527054785, 124818.3759081217, 140192.29903850416, 152689.0, 164490.0, 82523.5873820627, 101874.02834138818, 101895.4905225484, 107682.0, 119682.0, 72698.0, 77667.61166194132, 78891.21108788953, 88380.0, 106124.46937765261, 7513.511621744569, 9183.540822958692, 9563.569639366106, 9674.903250967682, 9701.70894873155, 58463.0, 61496.38503614906, 62063.0, 63911.0, 65430.69138617106, 61065.0, 72819.63590182015, 91195.54402227946, 92100.53949730209, 93201.62173241611, 12822.807657884952, 13143.737125257632, 13204.933975330061, 13229.603111906657, 13760.449582016607, 14334.928325358307, 14795.334209960793, 15128.319225634652, 15441.459548915735, 15762.527124186292, 13517.256550889795, 13712.382942767399, 13836.792448113487, 14320.283985800877, 14718.0, 14171.362288697239, 14262.500812471513, 14305.85694143087, 14709.41162353494, 14746.410143594565, 13165.821615006273, 13401.798973015608, 13450.529231476852, 13483.258420786951, 13766.587002389944, 10760.515776789907, 10925.235233533576, 10932.672019839418, 11072.501737421675, 11080.889191107986, 8795.120487951153, 10513.449862371324, 11379.203455758017, 11534.538618455168, 11716.70708232267], "value": 7700.4104826957, "unit": "score"}], "name": "dromaeo_css", "value": 7277.347453742737, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 112.0, 114.0, 113.0, 114.0, 111.0, 113.0, 122.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 131.0, 164.0, 109.0, 129.0, 130.0, 107.0, 163.0, 107.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 184.0, 182.0, 184.0, 184.0, 185.0, 180.0, 185.0, 162.0], "value": 181.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 79.0, 75.0, 155.0, 58.0, 58.0, 58.0, 67.0, 140.0, 58.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 133.0, 89.0, 112.0, 116.0, 117.0, 74.0, 100.0, 159.0], "value": 106.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 87.0, 90.0, 88.0, 86.0, 88.0, 82.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 112.0, 164.0, 96.0, 95.0, 95.0, 110.0, 162.0, 95.0, 95.0], "value": 111.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 98.0, 134.0, 136.0, 84.0, 85.0, 84.0, 86.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 55.0, 55.0, 55.0, 56.0, 55.0, 58.0, 55.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 81.0, 76.0, 72.0, 69.0, 67.0, 69.0, 68.0, 73.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 86.0, 87.0, 87.0, 89.0, 86.0, 88.0, 89.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 165.0, 154.0, 143.0, 141.0, 155.0, 150.0, 154.0, 190.0, 141.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 171.0, 171.0, 170.0, 170.0, 168.0, 174.0, 169.0, 170.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 67.0, 69.0, 68.0, 69.0, 70.0, 90.0, 88.0], "value": 72.7, "unit": "score"}], "name": "kraken", "value": 1517.7000000000003, "alertThreshold": 2.0}]} 23:48:51 INFO - Return code: 0 23:48:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:48:51 INFO - # TBPL SUCCESS # 23:48:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:48:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:48:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:48:51 INFO - 2016-09-01 23:48:51.131 screenresolution[1671:10494] starting screenresolution argv=screenresolution get 23:48:51 INFO - 2016-09-01 23:48:51.134 screenresolution[1671:10494] Display 0: 1600x1200x32@60 23:48:51 INFO - 2016-09-01 23:48:51.140 screenresolution[1672:10497] starting screenresolution argv=screenresolution list 23:48:51 INFO - Available Modes on Display 0 23:48:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:48:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:48:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:48:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:48:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:48:51 INFO - Intel Iris: 23:48:51 INFO - Chipset Model: Intel Iris 23:48:51 INFO - Type: GPU 23:48:51 INFO - Bus: Built-In 23:48:51 INFO - VRAM (Dynamic, Max): 1536 MB 23:48:51 INFO - Vendor: Intel (0x8086) 23:48:51 INFO - Device ID: 0x0a2e 23:48:51 INFO - Revision ID: 0x0009 23:48:51 INFO - Displays: 23:48:51 INFO - Display: 23:48:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:48:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:48:51 INFO - Display Serial Number: 1600x1200 60 23:48:51 INFO - Main Display: Yes 23:48:51 INFO - Mirror: Off 23:48:51 INFO - Online: Yes 23:48:51 INFO - Rotation: Supported 23:48:51 INFO - Return code: 0 23:48:51 INFO - Running post-action listener: _resource_record_post_action 23:48:51 INFO - [mozharness: 2016-09-02 06:48:51.375514Z] Finished run-tests step (success) 23:48:51 INFO - Running post-run listener: _resource_record_post_run 23:48:51 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 31443968; Write bytes: 707493376; Read time: 303; Write time: 1955 23:48:51 INFO - TinderboxPrint: CPU usage
53.5% 23:48:51 INFO - TinderboxPrint: I/O read bytes / time
31,443,968 / 303 23:48:51 INFO - TinderboxPrint: I/O write bytes / time
707,493,376 / 1,955 23:48:51 INFO - TinderboxPrint: CPU idle
2,259.9 (46.5%) 23:48:51 INFO - TinderboxPrint: CPU system
970.9 (20.0%) 23:48:51 INFO - TinderboxPrint: CPU user
1,626.9 (33.5%) 23:48:51 INFO - TinderboxPrint: Swap in / out
434,167,808 / 0 23:48:51 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 388368896; Write bytes: 382579200; Read time: 29283; Write time: 733 23:48:51 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 30505984; Write bytes: 298683392; Read time: 288; Write time: 1171 23:48:51 INFO - Running post-run listener: _upload_blobber_files 23:48:51 INFO - Blob upload gear active. 23:48:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:48:51 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 23:48:51 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'] 23:48:51 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 23:48:52 INFO - (blobuploader) - INFO - Open directory for files ... 23:48:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:48:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:48:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:48:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:48:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:48:53 INFO - (blobuploader) - INFO - Done attempting. 23:48:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:48:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:48:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:48:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:48:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:48:54 INFO - (blobuploader) - INFO - Done attempting. 23:48:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:48:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:48:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:48:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:48:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:48:56 INFO - (blobuploader) - INFO - Done attempting. 23:48:56 INFO - (blobuploader) - INFO - Iteration through files over. 23:48:56 INFO - Return code: 0 23:48:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:48:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:48:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f07032aedb10b0bb6838e18ec448fd69024dd39f50a814f371d86f6f90b1dc03a63cdfece189789806d5ef293cb9494db38872815a47da48b7dbb9e78911290", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/155d926994f731fc4f0ed4212c7f0d15837670af66d7afe462a46370793fc17af01980d9c162f42975b74542e28eaa274f96b60c2e8ddb06d66717c91d24d513", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad187ba0ddbf5cee751e966645c166e71450b0759b0dac5cc79bd75aed09d9aafac1e49f6ce3fcb68c1c6d9697cef5cd8c6e846313ac52a4d7169db4588857a8"} 23:48:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:48:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:48:56 INFO - Contents: 23:48:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f07032aedb10b0bb6838e18ec448fd69024dd39f50a814f371d86f6f90b1dc03a63cdfece189789806d5ef293cb9494db38872815a47da48b7dbb9e78911290", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/155d926994f731fc4f0ed4212c7f0d15837670af66d7afe462a46370793fc17af01980d9c162f42975b74542e28eaa274f96b60c2e8ddb06d66717c91d24d513", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad187ba0ddbf5cee751e966645c166e71450b0759b0dac5cc79bd75aed09d9aafac1e49f6ce3fcb68c1c6d9697cef5cd8c6e846313ac52a4d7169db4588857a8"} 23:48:56 INFO - Running post-run listener: copy_logs_to_upload_dir 23:48:56 INFO - Copying logs to upload dir... 23:48:56 INFO - mkdir: /builds/slave/test/build/upload/logs 23:48:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.803281 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-01 23:48:56.899123) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 23:48:56.902899) ========= 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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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/0f07032aedb10b0bb6838e18ec448fd69024dd39f50a814f371d86f6f90b1dc03a63cdfece189789806d5ef293cb9494db38872815a47da48b7dbb9e78911290", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/155d926994f731fc4f0ed4212c7f0d15837670af66d7afe462a46370793fc17af01980d9c162f42975b74542e28eaa274f96b60c2e8ddb06d66717c91d24d513", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad187ba0ddbf5cee751e966645c166e71450b0759b0dac5cc79bd75aed09d9aafac1e49f6ce3fcb68c1c6d9697cef5cd8c6e846313ac52a4d7169db4588857a8"} build_url:https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011220 build_url: 'https://queue.taskcluster.net/v1/task/VYSpcX1PRzuPiuYgiap7iQ/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/0f07032aedb10b0bb6838e18ec448fd69024dd39f50a814f371d86f6f90b1dc03a63cdfece189789806d5ef293cb9494db38872815a47da48b7dbb9e78911290", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/155d926994f731fc4f0ed4212c7f0d15837670af66d7afe462a46370793fc17af01980d9c162f42975b74542e28eaa274f96b60c2e8ddb06d66717c91d24d513", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad187ba0ddbf5cee751e966645c166e71450b0759b0dac5cc79bd75aed09d9aafac1e49f6ce3fcb68c1c6d9697cef5cd8c6e846313ac52a4d7169db4588857a8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 23:48:56.941361) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:48:56.942053) ========= 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.rpaLvr1lmf/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.NQhBUtqFe2/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.005161 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:48:56.995462) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 23:48:56.996209) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 23:48:56.997021) ========= ========= Total master_lag: 0.21 =========